Mar 17 17:51:54.216502 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Mon Mar 17 16:09:25 -00 2025 Mar 17 17:51:54.216532 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=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.216542 kernel: BIOS-provided physical RAM map: Mar 17 17:51:54.216549 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 17 17:51:54.216556 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 17 17:51:54.216562 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 17 17:51:54.216570 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 17 17:51:54.216581 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 17 17:51:54.216588 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 17 17:51:54.216594 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 17 17:51:54.216601 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Mar 17 17:51:54.216608 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Mar 17 17:51:54.216615 kernel: printk: bootconsole [earlyser0] enabled Mar 17 17:51:54.216622 kernel: NX (Execute Disable) protection: active Mar 17 17:51:54.216634 kernel: APIC: Static calls initialized Mar 17 17:51:54.216652 kernel: efi: EFI v2.7 by Microsoft Mar 17 17:51:54.216661 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bea98 RNG=0x3ffd1018 Mar 17 17:51:54.216669 kernel: random: crng init done Mar 17 17:51:54.216677 kernel: secureboot: Secure boot disabled Mar 17 17:51:54.216684 kernel: SMBIOS 3.1.0 present. Mar 17 17:51:54.216692 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 17 17:51:54.216699 kernel: Hypervisor detected: Microsoft Hyper-V Mar 17 17:51:54.216707 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Mar 17 17:51:54.216715 kernel: Hyper-V: Host Build 10.0.20348.1799-1-0 Mar 17 17:51:54.216725 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 17 17:51:54.216733 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 17 17:51:54.216741 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 17 17:51:54.216749 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 17 17:51:54.216757 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 17 17:51:54.216765 kernel: tsc: Detected 2593.993 MHz processor Mar 17 17:51:54.216773 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 17 17:51:54.216782 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 17 17:51:54.216789 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Mar 17 17:51:54.216800 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Mar 17 17:51:54.216808 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 17 17:51:54.216816 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 17 17:51:54.216824 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Mar 17 17:51:54.216831 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 17 17:51:54.216839 kernel: Using GB pages for direct mapping Mar 17 17:51:54.216847 kernel: ACPI: Early table checksum verification disabled Mar 17 17:51:54.216859 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 17 17:51:54.216870 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216879 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216887 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 17 17:51:54.216895 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 17 17:51:54.216903 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216911 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216922 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216931 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216939 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216947 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216956 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.216964 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 17 17:51:54.216972 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 17 17:51:54.216980 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 17 17:51:54.216991 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 17 17:51:54.216999 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 17 17:51:54.217008 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 17 17:51:54.217016 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Mar 17 17:51:54.217024 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Mar 17 17:51:54.217032 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 17 17:51:54.217040 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 17 17:51:54.217048 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 17 17:51:54.217057 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 17 17:51:54.217067 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Mar 17 17:51:54.217076 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Mar 17 17:51:54.217084 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Mar 17 17:51:54.217092 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Mar 17 17:51:54.217100 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 17 17:51:54.217108 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Mar 17 17:51:54.217116 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Mar 17 17:51:54.217125 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Mar 17 17:51:54.217133 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Mar 17 17:51:54.217144 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Mar 17 17:51:54.217152 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Mar 17 17:51:54.217161 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Mar 17 17:51:54.217169 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Mar 17 17:51:54.217177 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Mar 17 17:51:54.217186 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Mar 17 17:51:54.217194 kernel: Zone ranges: Mar 17 17:51:54.217202 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 17 17:51:54.217210 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 17 17:51:54.217221 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Mar 17 17:51:54.217230 kernel: Movable zone start for each node Mar 17 17:51:54.217238 kernel: Early memory node ranges Mar 17 17:51:54.217246 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 17 17:51:54.217254 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 17 17:51:54.217262 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 17 17:51:54.217271 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Mar 17 17:51:54.217279 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Mar 17 17:51:54.217287 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Mar 17 17:51:54.217298 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 17 17:51:54.217328 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 17 17:51:54.217339 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 17 17:51:54.217368 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 17 17:51:54.217377 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 17 17:51:54.217386 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Mar 17 17:51:54.217394 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 17 17:51:54.217402 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 17 17:51:54.217411 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 17 17:51:54.217424 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Mar 17 17:51:54.217433 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 17 17:51:54.217441 kernel: Booting paravirtualized kernel on Hyper-V Mar 17 17:51:54.217450 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 17 17:51:54.217458 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Mar 17 17:51:54.217467 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Mar 17 17:51:54.217475 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Mar 17 17:51:54.217483 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Mar 17 17:51:54.217491 kernel: Hyper-V: PV spinlocks enabled Mar 17 17:51:54.217503 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 17 17:51:54.217512 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=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.217521 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 17:51:54.217529 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Mar 17 17:51:54.217538 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Mar 17 17:51:54.217546 kernel: Fallback order for Node 0: 0 Mar 17 17:51:54.217554 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Mar 17 17:51:54.217562 kernel: Policy zone: Normal Mar 17 17:51:54.217574 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 17:51:54.217591 kernel: software IO TLB: area num 8. Mar 17 17:51:54.217600 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2303K rwdata, 22860K rodata, 43476K init, 1596K bss, 2103120K reserved, 0K cma-reserved) Mar 17 17:51:54.217612 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 17 17:51:54.217621 kernel: Kernel/User page tables isolation: enabled Mar 17 17:51:54.217629 kernel: ftrace: allocating 37910 entries in 149 pages Mar 17 17:51:54.217638 kernel: ftrace: allocated 149 pages with 4 groups Mar 17 17:51:54.217658 kernel: Dynamic Preempt: voluntary Mar 17 17:51:54.217667 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 17:51:54.217676 kernel: rcu: RCU event tracing is enabled. Mar 17 17:51:54.217685 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Mar 17 17:51:54.217694 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 17:51:54.217706 kernel: Rude variant of Tasks RCU enabled. Mar 17 17:51:54.217715 kernel: Tracing variant of Tasks RCU enabled. Mar 17 17:51:54.217724 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 17:51:54.217733 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Mar 17 17:51:54.217744 kernel: Using NULL legacy PIC Mar 17 17:51:54.217753 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Mar 17 17:51:54.217762 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 17 17:51:54.217770 kernel: Console: colour dummy device 80x25 Mar 17 17:51:54.217779 kernel: printk: console [tty1] enabled Mar 17 17:51:54.217787 kernel: printk: console [ttyS0] enabled Mar 17 17:51:54.217796 kernel: printk: bootconsole [earlyser0] disabled Mar 17 17:51:54.217805 kernel: ACPI: Core revision 20230628 Mar 17 17:51:54.217814 kernel: Failed to register legacy timer interrupt Mar 17 17:51:54.217822 kernel: APIC: Switch to symmetric I/O mode setup Mar 17 17:51:54.217834 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 17 17:51:54.217842 kernel: Hyper-V: Using IPI hypercalls Mar 17 17:51:54.217851 kernel: APIC: send_IPI() replaced with hv_send_ipi() Mar 17 17:51:54.217859 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Mar 17 17:51:54.217868 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Mar 17 17:51:54.217877 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Mar 17 17:51:54.217885 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Mar 17 17:51:54.217894 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Mar 17 17:51:54.217902 kernel: Hyper-V: Using enlightened APIC (xapic mode) Mar 17 17:51:54.217914 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Mar 17 17:51:54.217922 kernel: APIC: read() replaced with hv_apic_read() Mar 17 17:51:54.217931 kernel: APIC: write() replaced with hv_apic_write() Mar 17 17:51:54.217940 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Mar 17 17:51:54.217948 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Mar 17 17:51:54.217957 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Mar 17 17:51:54.217966 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Mar 17 17:51:54.217975 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 17 17:51:54.217983 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 17 17:51:54.217995 kernel: Spectre V2 : Mitigation: Retpolines Mar 17 17:51:54.218004 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 17 17:51:54.218012 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 17 17:51:54.218021 kernel: Speculative Store Bypass: Vulnerable Mar 17 17:51:54.218029 kernel: MDS: Mitigation: Clear CPU buffers Mar 17 17:51:54.218038 kernel: TAA: Mitigation: Clear CPU buffers Mar 17 17:51:54.218053 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 17 17:51:54.218062 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 17 17:51:54.218071 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 17 17:51:54.218079 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 17 17:51:54.218088 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 17 17:51:54.218101 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Mar 17 17:51:54.218109 kernel: Freeing SMP alternatives memory: 32K Mar 17 17:51:54.218120 kernel: pid_max: default: 32768 minimum: 301 Mar 17 17:51:54.218129 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 17 17:51:54.218137 kernel: landlock: Up and running. Mar 17 17:51:54.218146 kernel: SELinux: Initializing. Mar 17 17:51:54.218154 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.218163 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.218172 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Mar 17 17:51:54.218180 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.218189 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.218201 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.218210 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Mar 17 17:51:54.218219 kernel: signal: max sigframe size: 1776 Mar 17 17:51:54.218227 kernel: rcu: Hierarchical SRCU implementation. Mar 17 17:51:54.218236 kernel: rcu: Max phase no-delay instances is 400. Mar 17 17:51:54.218245 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 17 17:51:54.218254 kernel: smp: Bringing up secondary CPUs ... Mar 17 17:51:54.218262 kernel: smpboot: x86: Booting SMP configuration: Mar 17 17:51:54.218271 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Mar 17 17:51:54.218283 kernel: smp: Brought up 1 node, 6 CPUs Mar 17 17:51:54.218291 kernel: smpboot: Max logical packages: 1 Mar 17 17:51:54.218300 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Mar 17 17:51:54.218309 kernel: devtmpfs: initialized Mar 17 17:51:54.218317 kernel: x86/mm: Memory block size: 1024MB Mar 17 17:51:54.218326 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 17 17:51:54.218335 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 17:51:54.218344 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Mar 17 17:51:54.218353 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 17:51:54.218364 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 17:51:54.218373 kernel: audit: initializing netlink subsys (disabled) Mar 17 17:51:54.218382 kernel: audit: type=2000 audit(1742233913.029:1): state=initialized audit_enabled=0 res=1 Mar 17 17:51:54.218390 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 17:51:54.218399 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 17 17:51:54.218407 kernel: cpuidle: using governor menu Mar 17 17:51:54.218416 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 17:51:54.218425 kernel: dca service started, version 1.12.1 Mar 17 17:51:54.218433 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Mar 17 17:51:54.218445 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 17 17:51:54.218454 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 17:51:54.218462 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 17 17:51:54.218471 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 17:51:54.218480 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 17 17:51:54.218488 kernel: ACPI: Added _OSI(Module Device) Mar 17 17:51:54.218497 kernel: ACPI: Added _OSI(Processor Device) Mar 17 17:51:54.218506 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 17:51:54.218514 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 17:51:54.218526 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 17:51:54.218534 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 17 17:51:54.218543 kernel: ACPI: Interpreter enabled Mar 17 17:51:54.218552 kernel: ACPI: PM: (supports S0 S5) Mar 17 17:51:54.218560 kernel: ACPI: Using IOAPIC for interrupt routing Mar 17 17:51:54.218569 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 17 17:51:54.218577 kernel: PCI: Ignoring E820 reservations for host bridge windows Mar 17 17:51:54.218586 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 17 17:51:54.218594 kernel: iommu: Default domain type: Translated Mar 17 17:51:54.218606 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 17 17:51:54.218615 kernel: efivars: Registered efivars operations Mar 17 17:51:54.218627 kernel: PCI: Using ACPI for IRQ routing Mar 17 17:51:54.218635 kernel: PCI: System does not support PCI Mar 17 17:51:54.218651 kernel: vgaarb: loaded Mar 17 17:51:54.218660 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 17 17:51:54.218669 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 17:51:54.218678 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 17:51:54.218686 kernel: pnp: PnP ACPI init Mar 17 17:51:54.218699 kernel: pnp: PnP ACPI: found 3 devices Mar 17 17:51:54.218708 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 17 17:51:54.218716 kernel: NET: Registered PF_INET protocol family Mar 17 17:51:54.218725 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.218734 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.218742 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 17:51:54.218751 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 17:51:54.218760 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.218773 kernel: TCP: Hash tables configured (established 524288 bind 65536) Mar 17 17:51:54.218782 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.218790 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.218799 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 17:51:54.218808 kernel: NET: Registered PF_XDP protocol family Mar 17 17:51:54.218816 kernel: PCI: CLS 0 bytes, default 64 Mar 17 17:51:54.218825 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 17 17:51:54.218834 kernel: software IO TLB: mapped [mem 0x000000003b5be000-0x000000003f5be000] (64MB) Mar 17 17:51:54.218843 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 17 17:51:54.218854 kernel: Initialise system trusted keyrings Mar 17 17:51:54.218863 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Mar 17 17:51:54.218871 kernel: Key type asymmetric registered Mar 17 17:51:54.218879 kernel: Asymmetric key parser 'x509' registered Mar 17 17:51:54.218888 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 17 17:51:54.218897 kernel: io scheduler mq-deadline registered Mar 17 17:51:54.218905 kernel: io scheduler kyber registered Mar 17 17:51:54.218914 kernel: io scheduler bfq registered Mar 17 17:51:54.218922 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 17 17:51:54.218931 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 17:51:54.218943 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 17 17:51:54.218951 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 17 17:51:54.218960 kernel: i8042: PNP: No PS/2 controller found. Mar 17 17:51:54.219128 kernel: rtc_cmos 00:02: registered as rtc0 Mar 17 17:51:54.219235 kernel: rtc_cmos 00:02: setting system clock to 2025-03-17T17:51:53 UTC (1742233913) Mar 17 17:51:54.219333 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 17 17:51:54.219345 kernel: intel_pstate: CPU model not supported Mar 17 17:51:54.219359 kernel: efifb: probing for efifb Mar 17 17:51:54.219368 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 17:51:54.219377 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 17:51:54.219385 kernel: efifb: scrolling: redraw Mar 17 17:51:54.219394 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 17:51:54.219403 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:51:54.219411 kernel: fb0: EFI VGA frame buffer device Mar 17 17:51:54.219420 kernel: pstore: Using crash dump compression: deflate Mar 17 17:51:54.219429 kernel: pstore: Registered efi_pstore as persistent store backend Mar 17 17:51:54.219440 kernel: NET: Registered PF_INET6 protocol family Mar 17 17:51:54.219449 kernel: Segment Routing with IPv6 Mar 17 17:51:54.219458 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 17:51:54.219466 kernel: NET: Registered PF_PACKET protocol family Mar 17 17:51:54.219475 kernel: Key type dns_resolver registered Mar 17 17:51:54.219483 kernel: IPI shorthand broadcast: enabled Mar 17 17:51:54.219492 kernel: sched_clock: Marking stable (1803004800, 101805300)->(2240856700, -336046600) Mar 17 17:51:54.219501 kernel: registered taskstats version 1 Mar 17 17:51:54.219509 kernel: Loading compiled-in X.509 certificates Mar 17 17:51:54.219521 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 2d438fc13e28f87f3f580874887bade2e2b0c7dd' Mar 17 17:51:54.219530 kernel: Key type .fscrypt registered Mar 17 17:51:54.219538 kernel: Key type fscrypt-provisioning registered Mar 17 17:51:54.219547 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 17:51:54.219555 kernel: ima: Allocated hash algorithm: sha1 Mar 17 17:51:54.219564 kernel: ima: No architecture policies found Mar 17 17:51:54.219572 kernel: clk: Disabling unused clocks Mar 17 17:51:54.219581 kernel: Freeing unused kernel image (initmem) memory: 43476K Mar 17 17:51:54.219590 kernel: Write protecting the kernel read-only data: 38912k Mar 17 17:51:54.219601 kernel: Freeing unused kernel image (rodata/data gap) memory: 1716K Mar 17 17:51:54.219610 kernel: Run /init as init process Mar 17 17:51:54.219619 kernel: with arguments: Mar 17 17:51:54.219627 kernel: /init Mar 17 17:51:54.219636 kernel: with environment: Mar 17 17:51:54.219656 kernel: HOME=/ Mar 17 17:51:54.219665 kernel: TERM=linux Mar 17 17:51:54.219674 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 17:51:54.219683 systemd[1]: Successfully made /usr/ read-only. Mar 17 17:51:54.219699 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) Mar 17 17:51:54.219709 systemd[1]: Detected virtualization microsoft. Mar 17 17:51:54.219718 systemd[1]: Detected architecture x86-64. Mar 17 17:51:54.219727 systemd[1]: Running in initrd. Mar 17 17:51:54.219735 systemd[1]: No hostname configured, using default hostname. Mar 17 17:51:54.219745 systemd[1]: Hostname set to . Mar 17 17:51:54.219754 systemd[1]: Initializing machine ID from random generator. Mar 17 17:51:54.219765 systemd[1]: Queued start job for default target initrd.target. Mar 17 17:51:54.219775 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:51:54.219784 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:51:54.219793 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 17 17:51:54.219803 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:51:54.219812 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 17 17:51:54.219822 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 17 17:51:54.219835 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 17 17:51:54.219844 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 17 17:51:54.219854 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:51:54.219863 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:51:54.219872 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:51:54.219881 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:51:54.219890 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:51:54.219899 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:51:54.219911 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:51:54.219921 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:51:54.219930 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 17 17:51:54.219939 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 17 17:51:54.219948 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:51:54.219957 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:51:54.219966 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:51:54.219975 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:51:54.219985 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 17 17:51:54.219997 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:51:54.220006 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 17 17:51:54.220015 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 17:51:54.220024 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:51:54.220033 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:51:54.220065 systemd-journald[204]: Collecting audit messages is disabled. Mar 17 17:51:54.220091 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:54.220101 systemd-journald[204]: Journal started Mar 17 17:51:54.220121 systemd-journald[204]: Runtime Journal (/run/log/journal/66c2ef2494f94a688749cc76d3d9c1f5) is 8M, max 2.2G, 2.1G free. Mar 17 17:51:54.228219 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:51:54.228597 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 17 17:51:54.229093 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:51:54.229800 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 17:51:54.232106 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:51:54.233058 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:51:54.273521 systemd-modules-load[207]: Inserted module 'overlay' Mar 17 17:51:54.277244 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:54.279105 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:51:54.297151 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:51:54.318823 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:54.327120 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 17:51:54.335028 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:51:54.341033 kernel: Bridge firewalling registered Mar 17 17:51:54.337518 systemd-modules-load[207]: Inserted module 'br_netfilter' Mar 17 17:51:54.339180 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:51:54.340691 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:51:54.369488 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:54.379333 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:51:54.380921 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:51:54.391883 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 17 17:51:54.399747 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:51:54.415784 dracut-cmdline[240]: dracut-dracut-053 Mar 17 17:51:54.420696 dracut-cmdline[240]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.440225 systemd-resolved[241]: Positive Trust Anchors: Mar 17 17:51:54.440233 systemd-resolved[241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:51:54.440264 systemd-resolved[241]: 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 Mar 17 17:51:54.442805 systemd-resolved[241]: Defaulting to hostname 'linux'. Mar 17 17:51:54.443902 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:51:54.449787 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:51:54.565668 kernel: SCSI subsystem initialized Mar 17 17:51:54.575661 kernel: Loading iSCSI transport class v2.0-870. Mar 17 17:51:54.587663 kernel: iscsi: registered transport (tcp) Mar 17 17:51:54.611109 kernel: iscsi: registered transport (qla4xxx) Mar 17 17:51:54.611137 kernel: QLogic iSCSI HBA Driver Mar 17 17:51:54.651054 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 17 17:51:54.666786 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 17 17:51:54.704131 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 17:51:54.704176 kernel: device-mapper: uevent: version 1.0.3 Mar 17 17:51:54.708605 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 17 17:51:54.749660 kernel: raid6: avx2x4 gen() 27186 MB/s Mar 17 17:51:54.769664 kernel: raid6: avx2x2 gen() 27043 MB/s Mar 17 17:51:54.791630 kernel: raid6: avx2x1 gen() 22716 MB/s Mar 17 17:51:54.791663 kernel: raid6: using algorithm avx2x4 gen() 27186 MB/s Mar 17 17:51:54.813799 kernel: raid6: .... xor() 7588 MB/s, rmw enabled Mar 17 17:51:54.813823 kernel: raid6: using avx2x2 recovery algorithm Mar 17 17:51:54.837664 kernel: xor: automatically using best checksumming function avx Mar 17 17:51:54.981670 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 17 17:51:54.992229 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:51:55.010799 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:51:55.032544 systemd-udevd[425]: Using default interface naming scheme 'v255'. Mar 17 17:51:55.038462 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:51:55.057786 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 17 17:51:55.073476 dracut-pre-trigger[434]: rd.md=0: removing MD RAID activation Mar 17 17:51:55.104092 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:51:55.126805 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:51:55.183852 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:51:55.203839 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 17 17:51:55.218989 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 17:51:55.220162 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 17 17:51:55.229793 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:51:55.234731 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:51:55.244037 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:51:55.265264 kernel: AVX2 version of gcm_enc/dec engaged. Mar 17 17:51:55.265314 kernel: AES CTR mode by8 optimization enabled Mar 17 17:51:55.266778 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 17 17:51:55.286220 kernel: hv_vmbus: Vmbus version:4.0 Mar 17 17:51:55.291111 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 17:51:55.291127 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 Mar 17 17:51:55.291140 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 17:51:55.285954 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:51:55.302497 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 17:51:55.302517 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 17:51:55.286138 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:55.298639 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:55.306434 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:51:55.306627 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:55.324256 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:55.349733 kernel: PTP clock support registered Mar 17 17:51:55.349767 kernel: hv_vmbus: registering driver hv_pci Mar 17 17:51:55.349781 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 17:51:55.349792 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Mar 17 17:51:55.992796 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Mar 17 17:51:55.992963 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 17:51:55.992986 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Mar 17 17:51:55.993141 kernel: hv_vmbus: registering driver hv_utils Mar 17 17:51:55.993155 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 17:51:55.993167 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 17:51:55.993179 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 17:51:55.993191 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 17:51:55.993202 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Mar 17 17:51:55.993335 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 17 17:51:55.993373 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 17:51:55.993510 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 17:51:55.993523 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 17:51:55.993651 kernel: scsi host0: storvsc_host_t Mar 17 17:51:55.993793 kernel: scsi host1: storvsc_host_t Mar 17 17:51:55.993936 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Mar 17 17:51:55.994092 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 17:51:55.994238 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Mar 17 17:51:55.994427 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 17:51:55.994581 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 17 17:51:55.994714 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 17:51:55.994851 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 17 17:51:55.994981 kernel: pci 0001:00:00.0: Enabling HDA controller Mar 17 17:51:55.995110 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 17:51:55.995249 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 17:51:55.995263 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 17:51:55.995399 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 17 17:51:55.995538 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 17:51:55.995682 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 17 17:51:55.995819 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Mar 17 17:51:55.354205 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:56.017206 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 17:51:56.043006 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 17 17:51:56.043171 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Mar 17 17:51:56.043327 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Mar 17 17:51:56.043491 kernel: sd 0:0:0:1: [sdb] Write Protect is off Mar 17 17:51:56.043635 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 17:51:56.043773 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Mar 17 17:51:56.043917 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 17 17:51:56.044052 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 17:51:56.044184 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 17:51:56.044317 kernel: sdb: sdb1 Mar 17 17:51:56.044331 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:51:56.044343 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Mar 17 17:51:56.044518 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 17:51:55.361606 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:51:55.903721 systemd-resolved[241]: Clock change detected. Flushing caches. Mar 17 17:51:55.935057 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:51:55.935173 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:55.959537 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:51:55.999720 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:56.049044 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:56.088476 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:56.108266 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:56.664703 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 17 17:51:56.688372 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (478) Mar 17 17:51:56.716442 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:51:56.748699 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 17 17:51:56.758054 kernel: BTRFS: device fsid 16b3954e-2e86-4c7f-a948-d3d3817b1bdc devid 1 transid 42 /dev/sda3 scanned by (udev-worker) (480) Mar 17 17:51:56.777161 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 17 17:51:56.778706 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 17 17:51:56.809471 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 17 17:51:56.828365 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:51:56.836366 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:51:57.843152 disk-uuid[644]: The operation has completed successfully. Mar 17 17:51:57.847275 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:51:57.887257 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 17:51:57.887383 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 17 17:51:57.949461 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 17 17:51:57.958783 sh[731]: Success Mar 17 17:51:58.027372 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 17 17:51:58.248947 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 17 17:51:58.269769 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 17 17:51:58.271765 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 17 17:51:58.295929 kernel: BTRFS info (device dm-0): first mount of filesystem 16b3954e-2e86-4c7f-a948-d3d3817b1bdc Mar 17 17:51:58.295965 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:51:58.300741 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 17 17:51:58.304189 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 17 17:51:58.307173 kernel: BTRFS info (device dm-0): using free space tree Mar 17 17:51:58.838469 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 17 17:51:58.840822 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 17 17:51:58.860482 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 17 17:51:58.864612 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 17 17:51:58.889112 kernel: BTRFS info (device sda6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:51:58.889142 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:51:58.889155 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:51:58.906369 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:51:58.913986 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 17:51:58.921731 kernel: BTRFS info (device sda6): last unmount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:51:58.926042 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 17 17:51:58.936501 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 17 17:51:58.986824 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:51:59.007478 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:51:59.035863 systemd-networkd[916]: lo: Link UP Mar 17 17:51:59.035875 systemd-networkd[916]: lo: Gained carrier Mar 17 17:51:59.037025 systemd-networkd[916]: Enumeration completed Mar 17 17:51:59.037126 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:51:59.037396 systemd-networkd[916]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:51:59.037401 systemd-networkd[916]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:51:59.038219 systemd-networkd[916]: eth0: Link UP Mar 17 17:51:59.042663 systemd[1]: Reached target network.target - Network. Mar 17 17:51:59.048674 systemd-networkd[916]: eth0: Gained carrier Mar 17 17:51:59.048685 systemd-networkd[916]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:51:59.097393 systemd-networkd[916]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:00.266538 ignition[849]: Ignition 2.20.0 Mar 17 17:52:00.266551 ignition[849]: Stage: fetch-offline Mar 17 17:52:00.266593 ignition[849]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.266604 ignition[849]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.266729 ignition[849]: parsed url from cmdline: "" Mar 17 17:52:00.266734 ignition[849]: no config URL provided Mar 17 17:52:00.266739 ignition[849]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:52:00.266750 ignition[849]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:52:00.266755 ignition[849]: failed to fetch config: resource requires networking Mar 17 17:52:00.266938 ignition[849]: Ignition finished successfully Mar 17 17:52:00.293779 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:52:00.314500 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 17 17:52:00.328254 ignition[929]: Ignition 2.20.0 Mar 17 17:52:00.328265 ignition[929]: Stage: fetch Mar 17 17:52:00.328431 ignition[929]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.328443 ignition[929]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.328541 ignition[929]: parsed url from cmdline: "" Mar 17 17:52:00.328545 ignition[929]: no config URL provided Mar 17 17:52:00.328551 ignition[929]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:52:00.328562 ignition[929]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:52:00.328586 ignition[929]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 17:52:00.396893 ignition[929]: GET result: OK Mar 17 17:52:00.396924 ignition[929]: failed to retrieve userdata from IMDS, falling back to custom data: not a config (empty) Mar 17 17:52:00.436221 ignition[929]: opening config device: "/dev/sr0" Mar 17 17:52:00.436665 ignition[929]: getting drive status for "/dev/sr0" Mar 17 17:52:00.436740 ignition[929]: drive status: OK Mar 17 17:52:00.436772 ignition[929]: mounting config device Mar 17 17:52:00.436781 ignition[929]: op(1): [started] mounting "/dev/sr0" at "/tmp/ignition-azure3758889423" Mar 17 17:52:00.455291 ignition[929]: op(1): [finished] mounting "/dev/sr0" at "/tmp/ignition-azure3758889423" Mar 17 17:52:00.460238 kernel: UDF-fs: INFO Mounting volume 'UDF Volume', timestamp 2025/03/18 00:00 (1000) Mar 17 17:52:00.457367 systemd[1]: tmp-ignition\x2dazure3758889423.mount: Deactivated successfully. Mar 17 17:52:00.455300 ignition[929]: checking for config drive Mar 17 17:52:00.455679 ignition[929]: reading config Mar 17 17:52:00.456053 ignition[929]: op(2): [started] unmounting "/dev/sr0" at "/tmp/ignition-azure3758889423" Mar 17 17:52:00.460277 ignition[929]: op(2): [finished] unmounting "/dev/sr0" at "/tmp/ignition-azure3758889423" Mar 17 17:52:00.460294 ignition[929]: config has been read from custom data Mar 17 17:52:00.474178 unknown[929]: fetched base config from "system" Mar 17 17:52:00.460307 ignition[929]: parsing config with SHA512: df3c0660de8792ffb66ea05c3804787c4867f4215f5886293640bb892bac5ae0ad6f01e720ba3c305ef19c4347a233f6d8c5dcb3118a2af6220b111ab96d33b3 Mar 17 17:52:00.474186 unknown[929]: fetched base config from "system" Mar 17 17:52:00.474401 ignition[929]: fetch: fetch complete Mar 17 17:52:00.474192 unknown[929]: fetched user config from "azure" Mar 17 17:52:00.474407 ignition[929]: fetch: fetch passed Mar 17 17:52:00.476549 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 17 17:52:00.474454 ignition[929]: Ignition finished successfully Mar 17 17:52:00.500535 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 17 17:52:00.522937 ignition[940]: Ignition 2.20.0 Mar 17 17:52:00.522949 ignition[940]: Stage: kargs Mar 17 17:52:00.523109 ignition[940]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.528260 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 17 17:52:00.523119 ignition[940]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.523659 ignition[940]: kargs: kargs passed Mar 17 17:52:00.523701 ignition[940]: Ignition finished successfully Mar 17 17:52:00.553498 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 17 17:52:00.565304 ignition[950]: Ignition 2.20.0 Mar 17 17:52:00.565315 ignition[950]: Stage: disks Mar 17 17:52:00.565482 ignition[950]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.565493 ignition[950]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.566042 ignition[950]: disks: disks passed Mar 17 17:52:00.573937 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 17 17:52:00.566085 ignition[950]: Ignition finished successfully Mar 17 17:52:00.581288 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 17 17:52:00.587952 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 17 17:52:00.596535 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:52:00.597967 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:52:00.605458 systemd-networkd[916]: eth0: Gained IPv6LL Mar 17 17:52:00.611708 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:52:00.631482 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 17 17:52:00.714462 systemd-fsck[961]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 17 17:52:00.719491 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 17 17:52:00.739433 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 17 17:52:00.830363 kernel: EXT4-fs (sda9): mounted filesystem 21764504-a65e-45eb-84e1-376b55b62aba r/w with ordered data mode. Quota mode: none. Mar 17 17:52:00.830496 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 17 17:52:00.832459 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 17 17:52:00.902478 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:52:00.904899 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 17 17:52:00.919959 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (972) Mar 17 17:52:00.916088 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 17 17:52:00.931405 kernel: BTRFS info (device sda6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:00.931427 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:52:00.931440 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:52:00.921395 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 17:52:00.921430 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:52:00.935937 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 17 17:52:00.955441 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 17 17:52:00.977365 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:52:00.978824 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:52:01.850466 coreos-metadata[974]: Mar 17 17:52:01.850 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:52:01.857979 coreos-metadata[974]: Mar 17 17:52:01.857 INFO Fetch successful Mar 17 17:52:01.861602 coreos-metadata[974]: Mar 17 17:52:01.858 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:52:01.869455 coreos-metadata[974]: Mar 17 17:52:01.869 INFO Fetch successful Mar 17 17:52:01.882257 coreos-metadata[974]: Mar 17 17:52:01.882 INFO wrote hostname ci-4230.1.0-a-3a37bf9124 to /sysroot/etc/hostname Mar 17 17:52:01.884112 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:52:01.974316 initrd-setup-root[1003]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 17:52:02.030676 initrd-setup-root[1010]: cut: /sysroot/etc/group: No such file or directory Mar 17 17:52:02.037938 initrd-setup-root[1017]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 17:52:02.042455 initrd-setup-root[1024]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 17:52:03.066071 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 17 17:52:03.096443 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 17 17:52:03.097991 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 17 17:52:03.115690 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 17 17:52:03.124540 kernel: BTRFS info (device sda6): last unmount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:03.138126 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 17 17:52:03.150530 ignition[1095]: INFO : Ignition 2.20.0 Mar 17 17:52:03.150530 ignition[1095]: INFO : Stage: mount Mar 17 17:52:03.155155 ignition[1095]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.155155 ignition[1095]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.155155 ignition[1095]: INFO : mount: mount passed Mar 17 17:52:03.155155 ignition[1095]: INFO : Ignition finished successfully Mar 17 17:52:03.154665 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 17 17:52:03.172509 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 17 17:52:03.190288 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:52:03.213335 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1108) Mar 17 17:52:03.213384 kernel: BTRFS info (device sda6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:03.217553 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:52:03.220569 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:52:03.226363 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:52:03.227440 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:52:03.255985 ignition[1124]: INFO : Ignition 2.20.0 Mar 17 17:52:03.255985 ignition[1124]: INFO : Stage: files Mar 17 17:52:03.261540 ignition[1124]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.261540 ignition[1124]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.261540 ignition[1124]: DEBUG : files: compiled without relabeling support, skipping Mar 17 17:52:03.290661 ignition[1124]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 17:52:03.290661 ignition[1124]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 17:52:03.393942 ignition[1124]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 17:52:03.399300 ignition[1124]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 17:52:03.404430 ignition[1124]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 17:52:03.399918 unknown[1124]: wrote ssh authorized keys file for user: core Mar 17 17:52:03.428855 ignition[1124]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:52:03.435566 ignition[1124]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:52:03.435566 ignition[1124]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:52:03.435566 ignition[1124]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:52:03.435566 ignition[1124]: INFO : files: files passed Mar 17 17:52:03.435566 ignition[1124]: INFO : Ignition finished successfully Mar 17 17:52:03.450124 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 17 17:52:03.478496 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 17 17:52:03.479985 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 17 17:52:03.489100 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 17:52:03.489405 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 17 17:52:03.513603 initrd-setup-root-after-ignition[1156]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.513603 initrd-setup-root-after-ignition[1156]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.526157 initrd-setup-root-after-ignition[1160]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.527070 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:52:03.529050 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 17 17:52:03.556479 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 17 17:52:03.578764 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 17:52:03.578871 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 17 17:52:03.587508 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 17 17:52:03.589456 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 17 17:52:03.601328 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 17 17:52:03.603004 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 17 17:52:03.627936 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:52:03.630712 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 17 17:52:03.647327 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:52:03.656322 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:52:03.657186 systemd[1]: Stopped target timers.target - Timer Units. Mar 17 17:52:03.668981 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 17:52:03.669100 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:52:03.680682 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 17 17:52:03.689516 systemd[1]: Stopped target basic.target - Basic System. Mar 17 17:52:03.696619 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 17 17:52:03.704177 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:52:03.712650 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 17 17:52:03.721225 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 17 17:52:03.729512 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:52:03.738006 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 17 17:52:03.746758 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 17 17:52:03.754452 systemd[1]: Stopped target swap.target - Swaps. Mar 17 17:52:03.761310 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 17:52:03.761449 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:52:03.771944 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:52:03.780143 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:52:03.788815 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 17 17:52:03.788935 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:52:03.798321 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 17:52:03.798462 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 17 17:52:03.811223 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 17:52:03.811367 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:52:03.819715 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 17:52:03.819827 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 17 17:52:03.827176 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 17:52:03.827294 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:52:03.848480 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 17 17:52:03.855533 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 17 17:52:03.862760 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 17:52:03.862893 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:52:03.871374 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 17:52:03.895640 ignition[1182]: INFO : Ignition 2.20.0 Mar 17 17:52:03.895640 ignition[1182]: INFO : Stage: umount Mar 17 17:52:03.895640 ignition[1182]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.895640 ignition[1182]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.895640 ignition[1182]: INFO : umount: umount passed Mar 17 17:52:03.895640 ignition[1182]: INFO : Ignition finished successfully Mar 17 17:52:03.871492 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:52:03.886497 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 17:52:03.886594 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 17 17:52:03.896571 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 17:52:03.896690 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 17 17:52:03.902694 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 17:52:03.902742 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 17 17:52:03.909017 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 17:52:03.909065 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 17 17:52:03.911189 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 17:52:03.911236 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 17 17:52:03.922723 systemd[1]: Stopped target network.target - Network. Mar 17 17:52:03.930409 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 17:52:03.930463 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:52:03.937310 systemd[1]: Stopped target paths.target - Path Units. Mar 17 17:52:03.945970 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 17:52:03.955414 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:52:03.957250 systemd[1]: Stopped target slices.target - Slice Units. Mar 17 17:52:03.970414 systemd[1]: Stopped target sockets.target - Socket Units. Mar 17 17:52:03.971883 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 17:52:03.971929 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:52:03.982547 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 17:52:03.982588 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:52:03.989722 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 17:52:03.989774 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 17 17:52:03.997178 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 17 17:52:03.997223 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 17 17:52:04.004761 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 17 17:52:04.012275 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 17 17:52:04.021522 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 17:52:04.022194 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 17:52:04.022290 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 17 17:52:04.026802 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 17:52:04.026907 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 17 17:52:04.042242 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 17:52:04.042300 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 17 17:52:04.048315 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 17 17:52:04.048386 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:52:04.058323 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 17:52:04.058448 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 17 17:52:04.071530 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 17 17:52:04.071592 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 17 17:52:04.071639 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:52:04.072142 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 17:52:04.072197 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:52:04.096466 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 17 17:52:04.103323 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 17:52:04.103397 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:52:04.111993 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 17:52:04.112041 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:52:04.120341 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 17:52:04.120402 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 17 17:52:04.127802 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:52:04.138462 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 17:52:04.156134 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 17:52:04.156276 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:52:04.164487 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 17:52:04.164579 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 17 17:52:04.172525 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 17:52:04.172581 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 17 17:52:04.180100 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 17:52:04.180137 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:52:04.182235 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 17:52:04.182286 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:52:04.194597 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 17:52:04.194649 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 17 17:52:04.213041 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:52:04.213096 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:52:04.248471 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 17 17:52:04.251507 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 17:52:04.251564 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:52:04.266900 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 17 17:52:04.266947 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:52:04.276748 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 17:52:04.276797 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:52:04.286313 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:52:04.286377 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:04.299965 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 17 17:52:04.300026 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:52:04.300433 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 17:52:04.300522 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 17 17:52:04.307722 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 17 17:52:04.328476 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 17 17:52:04.367683 systemd[1]: Switching root. Mar 17 17:52:04.507055 systemd-journald[204]: Journal stopped Mar 17 17:52:09.075883 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Mar 17 17:52:09.075918 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 17:52:09.075932 kernel: SELinux: policy capability open_perms=1 Mar 17 17:52:09.075941 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 17:52:09.075950 kernel: SELinux: policy capability always_check_network=0 Mar 17 17:52:09.075960 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 17:52:09.075970 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 17:52:09.075983 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 17:52:09.075992 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 17:52:09.076002 kernel: audit: type=1403 audit(1742233925.338:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 17:52:09.076012 systemd[1]: Successfully loaded SELinux policy in 203.797ms. Mar 17 17:52:09.076024 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.088ms. Mar 17 17:52:09.076035 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) Mar 17 17:52:09.076045 systemd[1]: Detected virtualization microsoft. Mar 17 17:52:09.076059 systemd[1]: Detected architecture x86-64. Mar 17 17:52:09.076070 systemd[1]: Detected first boot. Mar 17 17:52:09.076081 systemd[1]: Hostname set to . Mar 17 17:52:09.076091 systemd[1]: Initializing machine ID from random generator. Mar 17 17:52:09.076106 zram_generator::config[1229]: No configuration found. Mar 17 17:52:09.076120 kernel: Guest personality initialized and is inactive Mar 17 17:52:09.076130 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Mar 17 17:52:09.076140 kernel: Initialized host personality Mar 17 17:52:09.076150 kernel: NET: Registered PF_VSOCK protocol family Mar 17 17:52:09.076160 systemd[1]: Populated /etc with preset unit settings. Mar 17 17:52:09.076172 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 17 17:52:09.076183 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 17:52:09.076193 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 17 17:52:09.076206 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 17:52:09.076217 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 17 17:52:09.076228 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 17 17:52:09.076239 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 17 17:52:09.076249 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 17 17:52:09.076260 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 17 17:52:09.076271 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 17 17:52:09.076284 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 17 17:52:09.076295 systemd[1]: Created slice user.slice - User and Session Slice. Mar 17 17:52:09.076305 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:52:09.076316 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:52:09.076327 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 17 17:52:09.076341 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 17 17:52:09.076365 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 17 17:52:09.076378 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:52:09.076391 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 17 17:52:09.076403 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:52:09.076414 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 17 17:52:09.076425 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 17 17:52:09.076436 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 17 17:52:09.076447 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 17 17:52:09.076458 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:52:09.076469 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:52:09.076482 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:52:09.076493 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:52:09.076504 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 17 17:52:09.076515 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 17 17:52:09.076526 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 17 17:52:09.076540 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:52:09.076551 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:52:09.076562 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:52:09.076573 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 17 17:52:09.076584 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 17 17:52:09.076599 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 17 17:52:09.076610 systemd[1]: Mounting media.mount - External Media Directory... Mar 17 17:52:09.076621 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:09.076635 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 17 17:52:09.076646 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 17 17:52:09.076658 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 17 17:52:09.076669 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). Mar 17 17:52:09.076681 systemd[1]: Reached target machines.target - Containers. Mar 17 17:52:09.076692 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 17 17:52:09.076703 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:52:09.076714 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:52:09.076728 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 17 17:52:09.076739 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:52:09.076750 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:52:09.076761 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:52:09.076772 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 17 17:52:09.076783 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:52:09.076795 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 17:52:09.076806 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 17:52:09.076819 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 17 17:52:09.076831 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 17:52:09.076845 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 17:52:09.076857 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). Mar 17 17:52:09.076868 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:52:09.076879 kernel: fuse: init (API version 7.39) Mar 17 17:52:09.076890 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:52:09.076901 kernel: loop: module loaded Mar 17 17:52:09.076915 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 17 17:52:09.076926 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 17 17:52:09.076937 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 17 17:52:09.076948 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:52:09.076959 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 17:52:09.076970 systemd[1]: Stopped verity-setup.service. Mar 17 17:52:09.076982 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:09.076993 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 17 17:52:09.077006 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 17 17:52:09.077038 systemd-journald[1329]: Collecting audit messages is disabled. Mar 17 17:52:09.077063 systemd[1]: Mounted media.mount - External Media Directory. Mar 17 17:52:09.077075 systemd-journald[1329]: Journal started Mar 17 17:52:09.077101 systemd-journald[1329]: Runtime Journal (/run/log/journal/f17ced8bda1c4655b13db3bdc243bea6) is 8M, max 2.2G, 2.1G free. Mar 17 17:52:08.373471 systemd[1]: Queued start job for default target multi-user.target. Mar 17 17:52:08.385768 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 17 17:52:08.386232 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 17:52:09.083026 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:52:09.087059 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 17 17:52:09.090378 kernel: ACPI: bus type drm_connector registered Mar 17 17:52:09.094143 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 17 17:52:09.098640 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 17 17:52:09.102749 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 17 17:52:09.107754 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:52:09.113046 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 17:52:09.113247 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 17 17:52:09.118156 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:52:09.118361 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:52:09.124215 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:52:09.124432 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:52:09.128843 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:52:09.129044 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:52:09.135177 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 17:52:09.135386 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 17 17:52:09.141319 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:52:09.141599 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:52:09.146155 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:52:09.150862 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 17 17:52:09.156402 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 17 17:52:09.162035 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 17 17:52:09.177083 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 17 17:52:09.193450 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 17 17:52:09.200204 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 17 17:52:09.204808 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 17:52:09.204851 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:52:09.209991 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 17 17:52:09.215779 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 17 17:52:09.221243 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 17 17:52:09.225279 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:52:09.226628 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 17 17:52:09.231815 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 17 17:52:09.236282 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:52:09.237211 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 17 17:52:09.241328 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:52:09.242485 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:52:09.247471 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 17 17:52:09.253126 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:52:09.255452 systemd-journald[1329]: Time spent on flushing to /var/log/journal/f17ced8bda1c4655b13db3bdc243bea6 is 14.232ms for 954 entries. Mar 17 17:52:09.255452 systemd-journald[1329]: System Journal (/var/log/journal/f17ced8bda1c4655b13db3bdc243bea6) is 8M, max 2.6G, 2.6G free. Mar 17 17:52:09.290892 systemd-journald[1329]: Received client request to flush runtime journal. Mar 17 17:52:09.263579 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:52:09.269202 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 17 17:52:09.275103 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 17 17:52:09.280977 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 17 17:52:09.286148 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 17 17:52:09.291921 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 17 17:52:09.299428 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 17 17:52:09.314517 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 17 17:52:09.323723 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 17 17:52:09.338107 udevadm[1385]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 17 17:52:09.355056 systemd-tmpfiles[1373]: ACLs are not supported, ignoring. Mar 17 17:52:09.355079 systemd-tmpfiles[1373]: ACLs are not supported, ignoring. Mar 17 17:52:09.360600 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:52:09.374370 kernel: loop0: detected capacity change from 0 to 147912 Mar 17 17:52:09.378540 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 17 17:52:09.385390 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:52:09.413340 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 17:52:09.414116 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 17 17:52:09.721273 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 17 17:52:09.736574 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:52:09.755029 systemd-tmpfiles[1392]: ACLs are not supported, ignoring. Mar 17 17:52:09.755050 systemd-tmpfiles[1392]: ACLs are not supported, ignoring. Mar 17 17:52:09.759685 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:52:10.076387 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 17:52:10.143377 kernel: loop1: detected capacity change from 0 to 138176 Mar 17 17:52:10.724382 kernel: loop2: detected capacity change from 0 to 28272 Mar 17 17:52:10.784690 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 17 17:52:10.803551 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:52:10.828298 systemd-udevd[1400]: Using default interface naming scheme 'v255'. Mar 17 17:52:10.996119 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:52:11.012640 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:52:11.021897 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 17 17:52:11.084372 kernel: hv_vmbus: registering driver hv_balloon Mar 17 17:52:11.084468 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 17:52:11.089839 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 17:52:11.094377 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 17:52:11.094435 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 17:52:11.101241 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 17:52:11.101516 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 17 17:52:11.105964 kernel: Console: switching to colour dummy device 80x25 Mar 17 17:52:11.110416 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:52:11.141512 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:52:11.152161 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:52:11.152390 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:11.158241 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:52:11.158439 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 17 17:52:11.165784 kernel: EDAC MC: Ver: 3.0.0 Mar 17 17:52:11.174527 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:52:11.183079 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1406) Mar 17 17:52:11.237463 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:52:11.255576 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 17 17:52:11.258978 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 17 17:52:11.262287 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 17 17:52:11.290293 systemd-networkd[1417]: lo: Link UP Mar 17 17:52:11.290303 systemd-networkd[1417]: lo: Gained carrier Mar 17 17:52:11.291745 systemd-networkd[1417]: Enumeration completed Mar 17 17:52:11.291877 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:52:11.292142 systemd-networkd[1417]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:11.292152 systemd-networkd[1417]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:52:11.292928 systemd-networkd[1417]: eth0: Link UP Mar 17 17:52:11.292939 systemd-networkd[1417]: eth0: Gained carrier Mar 17 17:52:11.292953 systemd-networkd[1417]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:11.310541 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 17 17:52:11.315268 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 17 17:52:11.321559 kernel: loop3: detected capacity change from 0 to 147912 Mar 17 17:52:11.326438 systemd-networkd[1417]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:11.327791 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 17 17:52:11.328368 kernel: loop4: detected capacity change from 0 to 138176 Mar 17 17:52:11.358376 kernel: loop5: detected capacity change from 0 to 28272 Mar 17 17:52:11.362521 (sd-merge)[1532]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 17 17:52:11.363098 (sd-merge)[1532]: Merged extensions into '/usr'. Mar 17 17:52:11.365781 lvm[1528]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:52:11.367140 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 17 17:52:11.369011 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 17 17:52:11.391532 systemd[1]: Starting ensure-sysext.service... Mar 17 17:52:11.394423 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:52:11.401587 systemd[1]: Reload requested from client PID 1537 ('systemctl') (unit ensure-sysext.service)... Mar 17 17:52:11.401607 systemd[1]: Reloading... Mar 17 17:52:11.449375 zram_generator::config[1575]: No configuration found. Mar 17 17:52:11.449454 systemd-tmpfiles[1538]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 17:52:11.449751 systemd-tmpfiles[1538]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 17 17:52:11.450691 systemd-tmpfiles[1538]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 17:52:11.451039 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Mar 17 17:52:11.451110 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Mar 17 17:52:11.454848 systemd-tmpfiles[1538]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:52:11.454862 systemd-tmpfiles[1538]: Skipping /boot Mar 17 17:52:11.466904 systemd-tmpfiles[1538]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:52:11.466919 systemd-tmpfiles[1538]: Skipping /boot Mar 17 17:52:11.589074 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. Mar 17 17:52:11.728556 systemd[1]: Reloading finished in 326 ms. Mar 17 17:52:11.750138 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:11.755207 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 17 17:52:11.778466 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:52:11.790536 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:52:11.806630 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:52:11.861597 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 17 17:52:11.867416 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 17 17:52:11.873154 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 17 17:52:11.876650 lvm[1644]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:52:11.879489 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:52:11.884860 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 17 17:52:11.900002 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 17 17:52:11.907677 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:52:11.909145 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:52:11.914187 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:52:11.916711 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:52:11.923245 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:52:11.923869 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). Mar 17 17:52:11.925140 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:52:11.925404 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:52:11.930703 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 17 17:52:11.936069 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:52:11.936249 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:52:11.941510 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:52:11.941692 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:52:11.955194 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:52:11.963569 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:52:11.968729 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:52:11.973403 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:52:11.978861 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:52:11.983026 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:52:11.983186 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). Mar 17 17:52:11.983395 systemd[1]: Reached target time-set.target - System Time Set. Mar 17 17:52:11.989116 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:52:11.989315 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:52:11.994398 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:52:11.994585 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:52:11.999393 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:52:11.999583 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:52:12.004880 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:52:12.005072 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:52:12.012424 systemd[1]: Finished ensure-sysext.service. Mar 17 17:52:12.018741 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:52:12.018806 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:52:12.050031 augenrules[1679]: No rules Mar 17 17:52:12.051098 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:52:12.051330 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:52:12.074943 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 17 17:52:12.136660 systemd-resolved[1646]: Positive Trust Anchors: Mar 17 17:52:12.136674 systemd-resolved[1646]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:52:12.136705 systemd-resolved[1646]: 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 Mar 17 17:52:12.140673 systemd-resolved[1646]: Using system hostname 'ci-4230.1.0-a-3a37bf9124'. Mar 17 17:52:12.143089 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:52:12.147547 systemd[1]: Reached target network.target - Network. Mar 17 17:52:12.151245 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:52:12.157973 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:12.157995 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:12.445491 systemd-networkd[1417]: eth0: Gained IPv6LL Mar 17 17:52:12.448181 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 17 17:52:12.453342 systemd[1]: Reached target network-online.target - Network is Online. Mar 17 17:52:12.716223 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 17 17:52:12.721545 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). Mar 17 17:52:15.852773 ldconfig[1366]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:52:15.861688 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 17 17:52:15.877513 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 17 17:52:15.914780 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 17 17:52:15.919691 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:52:15.924123 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 17 17:52:15.929043 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 17 17:52:15.934252 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 17 17:52:15.938718 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 17 17:52:15.943934 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 17 17:52:15.948857 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 17:52:15.948888 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:52:15.952376 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:52:15.956782 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 17 17:52:15.962539 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 17 17:52:15.969094 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 17 17:52:15.974250 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 17 17:52:15.979260 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 17 17:52:15.985517 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 17 17:52:15.989921 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 17 17:52:15.995210 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 17 17:52:15.999640 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:52:16.003417 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:52:16.007097 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:52:16.007132 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:52:16.009759 systemd[1]: Starting chronyd.service - NTP client/server... Mar 17 17:52:16.014037 systemd[1]: Starting containerd.service - containerd container runtime... Mar 17 17:52:16.019446 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 17 17:52:16.024597 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 17 17:52:16.029224 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 17 17:52:16.035271 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 17 17:52:16.039303 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). Mar 17 17:52:16.039361 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 17 17:52:16.040407 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 17 17:52:16.044699 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 17 17:52:16.045736 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 17 17:52:16.047315 KVP[1702]: KVP starting; pid is:1702 Mar 17 17:52:16.050383 KVP[1702]: KVP LIC Version: 3.1 Mar 17 17:52:16.052367 kernel: hv_utils: KVP IC version 4.0 Mar 17 17:52:16.052763 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 17 17:52:16.059088 jq[1700]: false Mar 17 17:52:16.059217 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 17 17:52:16.065920 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 17 17:52:16.066237 (chronyd)[1693]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 17 17:52:16.071749 chronyd[1711]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 17 17:52:16.075008 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 17 17:52:16.080563 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 17:52:16.081014 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 17:52:16.081738 systemd[1]: Starting update-engine.service - Update Engine... Mar 17 17:52:16.086136 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 17 17:52:16.092402 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 17:52:16.094292 jq[1717]: true Mar 17 17:52:16.092627 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 17 17:52:16.092938 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 17:52:16.093120 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 17 17:52:16.099446 chronyd[1711]: Timezone right/UTC failed leap second check, ignoring Mar 17 17:52:16.099610 chronyd[1711]: Loaded seccomp filter (level 2) Mar 17 17:52:16.102721 systemd[1]: Started chronyd.service - NTP client/server. Mar 17 17:52:16.106028 jq[1723]: true Mar 17 17:52:16.107047 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 17:52:16.107301 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 17 17:52:16.115982 (ntainerd)[1725]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 17 17:52:16.118242 extend-filesystems[1701]: Found loop3 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found loop4 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found loop5 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda1 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda2 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda3 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found usr Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda4 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda6 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda7 Mar 17 17:52:16.120929 extend-filesystems[1701]: Found sda9 Mar 17 17:52:16.120929 extend-filesystems[1701]: Checking size of /dev/sda9 Mar 17 17:52:16.160953 extend-filesystems[1701]: Old size kept for /dev/sda9 Mar 17 17:52:16.166453 extend-filesystems[1701]: Found sdb Mar 17 17:52:16.166453 extend-filesystems[1701]: Found sdb1 Mar 17 17:52:16.166453 extend-filesystems[1701]: Found sr0 Mar 17 17:52:16.162107 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 17:52:16.162450 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 17 17:52:16.181150 systemd-logind[1714]: New seat seat0. Mar 17 17:52:16.183462 systemd-logind[1714]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 17:52:16.183663 systemd[1]: Started systemd-logind.service - User Login Management. Mar 17 17:52:16.186151 update_engine[1716]: I20250317 17:52:16.186072 1716 main.cc:92] Flatcar Update Engine starting Mar 17 17:52:16.196175 dbus-daemon[1696]: [system] SELinux support is enabled Mar 17 17:52:16.196389 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 17 17:52:16.199037 update_engine[1716]: I20250317 17:52:16.198984 1716 update_check_scheduler.cc:74] Next update check in 10m6s Mar 17 17:52:16.203842 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). Mar 17 17:52:16.203897 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 17 17:52:16.204920 dbus-daemon[1696]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 17 17:52:16.214386 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1768) Mar 17 17:52:16.214531 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). Mar 17 17:52:16.214560 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 17 17:52:16.222383 systemd[1]: Started update-engine.service - Update Engine. Mar 17 17:52:16.247540 coreos-metadata[1695]: Mar 17 17:52:16.247 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:52:16.253182 coreos-metadata[1695]: Mar 17 17:52:16.250 INFO Fetch successful Mar 17 17:52:16.253182 coreos-metadata[1695]: Mar 17 17:52:16.250 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 17 17:52:16.252704 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 17 17:52:16.257119 coreos-metadata[1695]: Mar 17 17:52:16.255 INFO Fetch successful Mar 17 17:52:16.257119 coreos-metadata[1695]: Mar 17 17:52:16.256 INFO Fetching http://168.63.129.16/machine/fd54ef65-4cd1-4141-99fd-d34f39a7746e/085ef4b7%2D9938%2D4f35%2Da6d4%2D6d17fb558355.%5Fci%2D4230.1.0%2Da%2D3a37bf9124?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 17 17:52:16.258026 coreos-metadata[1695]: Mar 17 17:52:16.257 INFO Fetch successful Mar 17 17:52:16.258176 coreos-metadata[1695]: Mar 17 17:52:16.258 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:52:16.264995 coreos-metadata[1695]: Mar 17 17:52:16.264 INFO Fetch successful Mar 17 17:52:16.298200 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 17 17:52:16.302918 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 17 17:52:16.309240 setup-nvidia[1705]: Downloading Flatcar Container Linux Developer Container for version: 4230.1.0 Mar 17 17:52:16.350917 sshd_keygen[1724]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 17:52:16.369100 bash[1752]: Updated "/home/core/.ssh/authorized_keys" Mar 17 17:52:16.370570 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 17 17:52:16.376204 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 17 17:52:16.388617 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 17 17:52:16.392493 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 17 17:52:16.393767 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 17 17:52:16.398312 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 17:52:16.398549 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 17 17:52:16.404621 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 17 17:52:16.417807 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 17 17:52:16.423364 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 17 17:52:16.430900 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 17 17:52:16.436140 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 17 17:52:16.440689 systemd[1]: Reached target getty.target - Login Prompts. Mar 17 17:52:16.590865 locksmithd[1808]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 17:52:16.909429 setup-nvidia[1866]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 17 17:52:16.909773 setup-nvidia[1866]: Dload Upload Total Spent Left Speed Mar 17 17:52:17.293920 containerd[1725]: time="2025-03-17T17:52:17.293780400Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Mar 17 17:52:17.316101 containerd[1725]: time="2025-03-17T17:52:17.316036500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.317716 containerd[1725]: time="2025-03-17T17:52:17.317679100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.83-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.317716 containerd[1725]: time="2025-03-17T17:52:17.317710200Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 17:52:17.317822 containerd[1725]: time="2025-03-17T17:52:17.317726100Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.317919 containerd[1725]: time="2025-03-17T17:52:17.317897300Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 17 17:52:17.317957 containerd[1725]: time="2025-03-17T17:52:17.317920700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318013 containerd[1725]: time="2025-03-17T17:52:17.317992500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318043 containerd[1725]: time="2025-03-17T17:52:17.318012800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318254 containerd[1725]: time="2025-03-17T17:52:17.318231400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318285 containerd[1725]: time="2025-03-17T17:52:17.318253000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318285 containerd[1725]: time="2025-03-17T17:52:17.318266800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318285 containerd[1725]: time="2025-03-17T17:52:17.318275900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318463 containerd[1725]: time="2025-03-17T17:52:17.318438300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318710 containerd[1725]: time="2025-03-17T17:52:17.318689800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318842 containerd[1725]: time="2025-03-17T17:52:17.318822600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.318872 containerd[1725]: time="2025-03-17T17:52:17.318841900Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 17:52:17.318951 containerd[1725]: time="2025-03-17T17:52:17.318933400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 17:52:17.319009 containerd[1725]: time="2025-03-17T17:52:17.318992200Z" level=info msg="metadata content store policy set" policy=shared Mar 17 17:52:17.332156 containerd[1725]: time="2025-03-17T17:52:17.332131200Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 17:52:17.332238 containerd[1725]: time="2025-03-17T17:52:17.332175300Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 17:52:17.332238 containerd[1725]: time="2025-03-17T17:52:17.332193100Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 17 17:52:17.332238 containerd[1725]: time="2025-03-17T17:52:17.332208000Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 17 17:52:17.332238 containerd[1725]: time="2025-03-17T17:52:17.332221700Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 17:52:17.332381 containerd[1725]: time="2025-03-17T17:52:17.332345100Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 17:52:17.332624 containerd[1725]: time="2025-03-17T17:52:17.332593100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 17:52:17.332772 containerd[1725]: time="2025-03-17T17:52:17.332747100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 17 17:52:17.332813 containerd[1725]: time="2025-03-17T17:52:17.332774300Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 17 17:52:17.332813 containerd[1725]: time="2025-03-17T17:52:17.332789700Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 17 17:52:17.332813 containerd[1725]: time="2025-03-17T17:52:17.332804800Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332881 containerd[1725]: time="2025-03-17T17:52:17.332817700Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332881 containerd[1725]: time="2025-03-17T17:52:17.332830000Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332881 containerd[1725]: time="2025-03-17T17:52:17.332843900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332881 containerd[1725]: time="2025-03-17T17:52:17.332857600Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332881 containerd[1725]: time="2025-03-17T17:52:17.332871000Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332883000Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332894100Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332914900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332929000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332940300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332952200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332963600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.332982 containerd[1725]: time="2025-03-17T17:52:17.332976100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.332988500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333000300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333012300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333026200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333036900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333048200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333059300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333074800Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333108900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333129100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333145 containerd[1725]: time="2025-03-17T17:52:17.333142000Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333192600Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333210600Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333221200Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333232500Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333241800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333253700Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333263200Z" level=info msg="NRI interface is disabled by configuration." Mar 17 17:52:17.333379 containerd[1725]: time="2025-03-17T17:52:17.333272900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.333587 containerd[1725]: time="2025-03-17T17:52:17.333539000Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 17 17:52:17.333587 containerd[1725]: time="2025-03-17T17:52:17.333591200Z" level=info msg="Connect containerd service" Mar 17 17:52:17.333755 containerd[1725]: time="2025-03-17T17:52:17.333617500Z" level=info msg="using legacy CRI server" Mar 17 17:52:17.333755 containerd[1725]: time="2025-03-17T17:52:17.333625000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 17 17:52:17.333755 containerd[1725]: time="2025-03-17T17:52:17.333743700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 17:52:17.334382 containerd[1725]: time="2025-03-17T17:52:17.334340700Z" 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" Mar 17 17:52:17.334521 containerd[1725]: time="2025-03-17T17:52:17.334487900Z" level=info msg="Start subscribing containerd event" Mar 17 17:52:17.334559 containerd[1725]: time="2025-03-17T17:52:17.334533900Z" level=info msg="Start recovering state" Mar 17 17:52:17.334801 containerd[1725]: time="2025-03-17T17:52:17.334761100Z" level=info msg="Start event monitor" Mar 17 17:52:17.334801 containerd[1725]: time="2025-03-17T17:52:17.334793100Z" level=info msg="Start snapshots syncer" Mar 17 17:52:17.334801 containerd[1725]: time="2025-03-17T17:52:17.334804600Z" level=info msg="Start cni network conf syncer for default" Mar 17 17:52:17.334920 containerd[1725]: time="2025-03-17T17:52:17.334813300Z" level=info msg="Start streaming server" Mar 17 17:52:17.334920 containerd[1725]: time="2025-03-17T17:52:17.334863800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 17:52:17.334965 containerd[1725]: time="2025-03-17T17:52:17.334941000Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 17:52:17.335034 containerd[1725]: time="2025-03-17T17:52:17.335012600Z" level=info msg="containerd successfully booted in 0.042271s" Mar 17 17:52:17.335223 systemd[1]: Started containerd.service - containerd container runtime. Mar 17 17:52:18.158052 waagent[1853]: 2025-03-17T17:52:18.157960Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Mar 17 17:52:18.162346 waagent[1853]: 2025-03-17T17:52:18.162243Z INFO Daemon Daemon OS: flatcar 4230.1.0 Mar 17 17:52:18.165859 waagent[1853]: 2025-03-17T17:52:18.165799Z INFO Daemon Daemon Python: 3.11.11 Mar 17 17:52:18.169053 waagent[1853]: 2025-03-17T17:52:18.168995Z INFO Daemon Daemon Run daemon Mar 17 17:52:18.172042 waagent[1853]: 2025-03-17T17:52:18.171990Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.0' Mar 17 17:52:18.178913 waagent[1853]: 2025-03-17T17:52:18.178851Z INFO Daemon Daemon Using waagent for provisioning Mar 17 17:52:18.183113 waagent[1853]: 2025-03-17T17:52:18.183063Z INFO Daemon Daemon Activate resource disk Mar 17 17:52:18.186529 waagent[1853]: 2025-03-17T17:52:18.186471Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 17:52:18.193614 waagent[1853]: 2025-03-17T17:52:18.193558Z INFO Daemon Daemon Found device: sdb Mar 17 17:52:18.202813 waagent[1853]: 2025-03-17T17:52:18.202753Z INFO Daemon Daemon Examining partition table Mar 17 17:52:18.242063 waagent[1853]: 2025-03-17T17:52:18.242006Z INFO Daemon Daemon GPT not detected, determining filesystem Mar 17 17:52:18.348370 waagent[1853]: 2025-03-17T17:52:18.348265Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Mar 17 17:52:18.353042 waagent[1853]: 2025-03-17T17:52:18.352981Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Mar 17 17:52:18.711380 kernel: sdb: sdb1 Mar 17 17:52:18.723827 waagent[1853]: 2025-03-17T17:52:18.723732Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Mar 17 17:52:18.737950 waagent[1853]: 2025-03-17T17:52:18.737895Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Mar 17 17:52:18.738400 kernel: sdb: sdb1 Mar 17 17:52:24.688810 login[1856]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Mar 17 17:52:24.689308 login[1855]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 17:52:24.703065 systemd-logind[1714]: New session 2 of user core. Mar 17 17:52:24.704301 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 17 17:52:24.720678 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 17 17:52:24.722822 waagent[1853]: 2025-03-17T17:52:24.722731Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Mar 17 17:52:24.731302 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 17 17:52:24.733815 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 17 17:52:24.741951 waagent[1853]: 2025-03-17T17:52:24.741880Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Mar 17 17:52:24.742367 kernel: EXT4-fs (sdb1): mounted filesystem 7d6a0d3d-9bfa-4675-bfe0-8cd92b89721f r/w with ordered data mode. Quota mode: none. Mar 17 17:52:24.744084 waagent[1853]: 2025-03-17T17:52:24.744031Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:52:24.744971 waagent[1853]: 2025-03-17T17:52:24.744919Z INFO Daemon Daemon Running default provisioning handler Mar 17 17:52:24.764651 waagent[1853]: 2025-03-17T17:52:24.763567Z 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. Mar 17 17:52:24.766639 waagent[1853]: 2025-03-17T17:52:24.766586Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 17:52:24.766921 waagent[1853]: 2025-03-17T17:52:24.766880Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 17:52:24.768334 waagent[1853]: 2025-03-17T17:52:24.768288Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 17:52:24.782783 waagent[1853]: 2025-03-17T17:52:24.782724Z INFO Daemon Daemon Successfully mounted dvd Mar 17 17:52:24.799903 (systemd)[1942]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 17:52:24.802053 systemd-logind[1714]: New session c1 of user core. Mar 17 17:52:24.828764 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 17:52:24.828940 waagent[1853]: 2025-03-17T17:52:24.828879Z INFO Daemon Daemon Detect protocol endpoint Mar 17 17:52:24.830583 waagent[1853]: 2025-03-17T17:52:24.830528Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:52:24.831321 waagent[1853]: 2025-03-17T17:52:24.831277Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 17:52:24.832027 waagent[1853]: 2025-03-17T17:52:24.831985Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 17:52:24.832903 waagent[1853]: 2025-03-17T17:52:24.832841Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 17:52:24.834224 waagent[1853]: 2025-03-17T17:52:24.834179Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 17:52:24.868343 waagent[1853]: 2025-03-17T17:52:24.868291Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 17:52:24.870150 waagent[1853]: 2025-03-17T17:52:24.870113Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 17:52:24.871326 waagent[1853]: 2025-03-17T17:52:24.871278Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 17:52:24.946056 waagent[1853]: 2025-03-17T17:52:24.945925Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 17:52:24.950898 waagent[1853]: 2025-03-17T17:52:24.950833Z INFO Daemon Daemon Forcing an update of the goal state. Mar 17 17:52:24.955368 waagent[1853]: 2025-03-17T17:52:24.955306Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:52:24.996034 waagent[1853]: 2025-03-17T17:52:24.995979Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.166 Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:24.997925Z INFO Daemon Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:24.998262Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 146543ab-c568-4822-801c-4c042b9ae700 eTag: 6985950761349445385 source: Fabric] Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:25.000021Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:25.001641Z INFO Daemon Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:25.001814Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:52:25.023770 waagent[1853]: 2025-03-17T17:52:25.006308Z INFO Daemon Daemon Downloading artifacts profile blob Mar 17 17:52:25.038290 systemd[1942]: Queued start job for default target default.target. Mar 17 17:52:25.056170 systemd[1942]: Created slice app.slice - User Application Slice. Mar 17 17:52:25.056203 systemd[1942]: Reached target paths.target - Paths. Mar 17 17:52:25.056253 systemd[1942]: Reached target timers.target - Timers. Mar 17 17:52:25.057567 systemd[1942]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:52:25.069000 systemd[1942]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:52:25.069294 systemd[1942]: Reached target sockets.target - Sockets. Mar 17 17:52:25.069367 systemd[1942]: Reached target basic.target - Basic System. Mar 17 17:52:25.069421 systemd[1942]: Reached target default.target - Main User Target. Mar 17 17:52:25.069455 systemd[1942]: Startup finished in 261ms. Mar 17 17:52:25.069509 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 17 17:52:25.070910 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 17 17:52:25.146002 waagent[1853]: 2025-03-17T17:52:25.145929Z INFO Daemon Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:52:25.148059 waagent[1853]: 2025-03-17T17:52:25.148002Z INFO Daemon Fetch goal state completed Mar 17 17:52:25.184201 waagent[1853]: 2025-03-17T17:52:25.184157Z INFO Daemon Daemon Starting provisioning Mar 17 17:52:25.185775 waagent[1853]: 2025-03-17T17:52:25.185712Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 17:52:25.187204 waagent[1853]: 2025-03-17T17:52:25.187157Z INFO Daemon Daemon Set hostname [ci-4230.1.0-a-3a37bf9124] Mar 17 17:52:25.238209 waagent[1853]: 2025-03-17T17:52:25.238154Z INFO Daemon Daemon Publish hostname [ci-4230.1.0-a-3a37bf9124] Mar 17 17:52:25.239941 waagent[1853]: 2025-03-17T17:52:25.239882Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 17:52:25.241487 waagent[1853]: 2025-03-17T17:52:25.241434Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 17:52:25.250375 systemd-networkd[1417]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:25.250386 systemd-networkd[1417]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:52:25.250413 systemd-networkd[1417]: eth0: DHCP lease lost Mar 17 17:52:25.250809 waagent[1853]: 2025-03-17T17:52:25.250752Z INFO Daemon Daemon Create user account if not exists Mar 17 17:52:25.255194 waagent[1853]: 2025-03-17T17:52:25.255141Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 17:52:25.256839 waagent[1853]: 2025-03-17T17:52:25.256791Z INFO Daemon Daemon Configure sudoer Mar 17 17:52:25.258615 waagent[1853]: 2025-03-17T17:52:25.258569Z INFO Daemon Daemon Configure sshd Mar 17 17:52:25.259331 waagent[1853]: 2025-03-17T17:52:25.259286Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 17 17:52:25.259824 waagent[1853]: 2025-03-17T17:52:25.259785Z INFO Daemon Daemon Deploy ssh public key. Mar 17 17:52:25.305210 waagent[1853]: 2025-03-17T17:52:25.305151Z INFO Daemon Daemon Decode custom data Mar 17 17:52:25.306809 waagent[1853]: 2025-03-17T17:52:25.306756Z INFO Daemon Daemon Save custom data Mar 17 17:52:25.325394 systemd-networkd[1417]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:25.690690 login[1856]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 17:52:25.695184 systemd-logind[1714]: New session 1 of user core. Mar 17 17:52:25.703472 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 17 17:52:38.662912 setup-nvidia[1866]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 478M 0 65536 0 0 84775 0 1:38:43 --:--:-- 1:38:43 84781\u000d 1 478M 1 6736k 0 0 3988k 0 0:02:02 0:00:01 0:02:01 3988k\u000d 5 478M 5 27.0M 0 0 10.0M 0 0:00:47 0:00:02 0:00:45 10.0M\u000d 10 478M 10 50.2M 0 0 13.3M 0 0:00:35 0:00:03 0:00:32 13.3M\u000d 15 478M 15 74.6M 0 0 15.5M 0 0:00:30 0:00:04 0:00:26 15.5M\u000d 20 478M 20 96.4M 0 0 16.8M 0 0:00:28 0:00:05 0:00:23 19.5M\u000d 25 478M 25 121M 0 0 17.9M 0 0:00:26 0:00:06 0:00:20 22.6M\u000d 29 478M 29 142M 0 0 18.4M 0 0:00:25 0:00:07 0:00:18 23.0M\u000d 34 478M 34 167M 0 0 19.1M 0 0:00:25 0:00:08 0:00:17 23.3M\u000d 39 478M 39 190M 0 0 19.6M 0 0:00:24 0:00:09 0:00:15 23.7M\u000d 44 478M 44 214M 0 0 20.0M 0 0:00:23 0:00:10 0:00:13 23.5M\u000d 50 478M 50 240M 0 0 20.3M 0 0:00:23 0:00:11 0:00:12 23.5M\u000d 54 478M 54 261M 0 0 20.6M 0 0:00:23 0:00:12 0:00:11 23.9M\u000d 59 478M 59 287M 0 0 20.8M 0 0:00:22 0:00:13 0:00:09 24.0M\u000d 65 478M 65 312M 0 0 21.1M 0 0:00:22 0:00:14 0:00:08 23.8M\u000d 69 478M 69 334M 0 0 21.2M 0 0:00:22 0:00:15 0:00:07 23.9M\u000d 75 478M 75 359M 0 0 21.4M 0 0:00:22 0:00:16 0:00:06 23.9M\u000d 79 478M 79 382M 0 0 21.6M 0 0:00:22 0:00:17 0:00:05 24.2M\u000d 84 478M 84 406M 0 0 21.7M 0 0:00:22 0:00:18 0:00:04 23.9M\u000d 90 478M 90 431M 0 0 21.8M 0 0:00:21 0:00:19 0:00:02 23.9M\u000d 94 478M 94 453M 0 0 21.9M 0 0:00:21 0:00:20 0:00:01 23.9M\u000d 99 478M 99 478M 0 0 22.0M 0 0:00:21 0:00:21 --:--:-- 23.9M\u000d100 478M 100 478M 0 0 22.0M 0 0:00:21 0:00:21 --:--:-- 23.6M Mar 17 17:52:39.882399 chronyd[1711]: Selected source PHC0 Mar 17 17:52:49.434166 setup-nvidia[1705]: Downloading NVIDIA 535.230.02 Driver Mar 17 17:52:49.438779 setup-nvidia[2004]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 17 17:52:49.439016 setup-nvidia[2004]: Dload Upload Total Spent Left Speed Mar 17 17:52:52.130029 setup-nvidia[2004]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 1271 0 0 3591 0 26:26:51 --:--:-- 26:26:51 3590\u000d 34 326M 34 113M 0 0 98.4M 0 0:00:03 0:00:01 0:00:02 98.3M\u000d 79 326M 79 260M 0 0 120M 0 0:00:02 0:00:02 --:--:-- 120M\u000d100 326M 100 326M 0 0 121M 0 0:00:02 0:00:02 --:--:-- 121M Mar 17 17:52:52.188573 setup-nvidia[1705]: Extract the NVIDIA Driver Installer 535.230.02 Mar 17 17:52:52.188792 setup-nvidia[1705]: /opt/nvidia/workdir/nvidia-workdir / Mar 17 17:52:52.207198 setup-nvidia[2007]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Mar 17 17:52:52.531177 setup-nvidia[2007]: Verifying archive integrity... OK Mar 17 17:52:53.361852 setup-nvidia[2007]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Mar 17 17:52:55.243124 setup-nvidia[2033]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Mar 17 17:52:55.321886 setup-nvidia[1705]: / Mar 17 17:52:55.322100 setup-nvidia[1705]: Spawn system-nspawn container to install the NVIDIA drivers Mar 17 17:52:55.351753 waagent[1853]: 2025-03-17T17:52:55.351697Z INFO Daemon Daemon Provisioning complete Mar 17 17:52:55.360844 waagent[1853]: 2025-03-17T17:52:55.360796Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 17:52:55.362476 waagent[1853]: 2025-03-17T17:52:55.362426Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 17:52:55.363163 waagent[1853]: 2025-03-17T17:52:55.363120Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Mar 17 17:52:55.504850 waagent[2041]: 2025-03-17T17:52:55.504699Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Mar 17 17:52:55.505170 waagent[2041]: 2025-03-17T17:52:55.504908Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.0 Mar 17 17:52:55.505170 waagent[2041]: 2025-03-17T17:52:55.504995Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 17 17:52:55.590834 waagent[2041]: 2025-03-17T17:52:55.590735Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 17:52:55.591943 waagent[2041]: 2025-03-17T17:52:55.591022Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:55.591943 waagent[2041]: 2025-03-17T17:52:55.591140Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:55.592142 sudo[2040]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Mar 17 17:52:55.593866 sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 17 17:52:55.599720 waagent[2041]: 2025-03-17T17:52:55.599656Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:52:55.603076 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 17 17:52:55.611904 waagent[2041]: 2025-03-17T17:52:55.611852Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.166 Mar 17 17:52:55.612381 waagent[2041]: 2025-03-17T17:52:55.612320Z INFO ExtHandler Mar 17 17:52:55.612478 waagent[2041]: 2025-03-17T17:52:55.612439Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 288bae0a-a806-4037-80e9-36a5358ce3cb eTag: 6985950761349445385 source: Fabric] Mar 17 17:52:55.612811 waagent[2041]: 2025-03-17T17:52:55.612769Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 17:52:55.613517 waagent[2041]: 2025-03-17T17:52:55.613449Z INFO ExtHandler Mar 17 17:52:55.613569 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 17 17:52:55.613819 waagent[2041]: 2025-03-17T17:52:55.613537Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:52:55.617754 waagent[2041]: 2025-03-17T17:52:55.617718Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 17:52:55.623974 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 17 17:52:55.626346 systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 17 17:52:55.633124 (systemd)[2048]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 17 17:52:55.635668 systemd-logind[1714]: New session c3 of user root. Mar 17 17:52:55.681755 waagent[2041]: 2025-03-17T17:52:55.681685Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:52:55.682287 waagent[2041]: 2025-03-17T17:52:55.682238Z INFO ExtHandler Fetch goal state completed Mar 17 17:52:55.692798 waagent[2041]: 2025-03-17T17:52:55.692749Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2041 Mar 17 17:52:55.692952 waagent[2041]: 2025-03-17T17:52:55.692911Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 17 17:52:55.694763 waagent[2041]: 2025-03-17T17:52:55.694718Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 17:52:55.695148 waagent[2041]: 2025-03-17T17:52:55.695104Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 17:52:55.757036 waagent[2041]: 2025-03-17T17:52:55.756930Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 17:52:55.757196 waagent[2041]: 2025-03-17T17:52:55.757141Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 17:52:55.763700 waagent[2041]: 2025-03-17T17:52:55.763656Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 17:52:55.770953 systemd[1]: Reload requested from client PID 2062 ('systemctl') (unit waagent.service)... Mar 17 17:52:55.770970 systemd[1]: Reloading... Mar 17 17:52:55.813926 systemd[2048]: Queued start job for default target default.target. Mar 17 17:52:55.830520 systemd[2048]: Created slice app.slice - User Application Slice. Mar 17 17:52:55.830553 systemd[2048]: Reached target paths.target - Paths. Mar 17 17:52:55.830599 systemd[2048]: Reached target timers.target - Timers. Mar 17 17:52:55.831859 systemd[2048]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:52:55.845069 systemd[2048]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:52:55.845766 systemd[2048]: Reached target sockets.target - Sockets. Mar 17 17:52:55.845817 systemd[2048]: Reached target basic.target - Basic System. Mar 17 17:52:55.845865 systemd[2048]: Reached target default.target - Main User Target. Mar 17 17:52:55.845900 systemd[2048]: Startup finished in 204ms. Mar 17 17:52:55.852374 zram_generator::config[2107]: No configuration found. Mar 17 17:52:55.987645 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. Mar 17 17:52:56.136297 systemd[1]: Reloading finished in 364 ms. Mar 17 17:52:56.153075 waagent[2041]: 2025-03-17T17:52:56.152979Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Mar 17 17:52:56.154271 systemd[1]: Started user@0.service - User Manager for UID 0. Mar 17 17:52:56.163718 systemd[1]: Reload requested from client PID 2164 ('systemctl') (unit waagent.service)... Mar 17 17:52:56.163734 systemd[1]: Reloading... Mar 17 17:52:56.238378 zram_generator::config[2209]: No configuration found. Mar 17 17:52:56.367782 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. Mar 17 17:52:56.509292 systemd[1]: Reloading finished in 345 ms. Mar 17 17:52:56.525335 waagent[2041]: 2025-03-17T17:52:56.525232Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 17 17:52:56.525680 waagent[2041]: 2025-03-17T17:52:56.525437Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 17 17:52:56.541474 systemd[1]: Started session-c2.scope - Session c2 of User root. Mar 17 17:52:56.635370 kernel: loop6: detected capacity change from 0 to 12644352 Mar 17 17:52:56.642376 kernel: loop6: p9 Mar 17 17:52:56.739253 setup-nvidia[2270]: ░ Spawning container flatcardevelopercontainer-4230.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin. Mar 17 17:52:56.751371 kernel: EXT4-fs (loop6p9): mounted filesystem a6481100-8bf3-468a-a5db-d879944440da ro without journal. Quota mode: none. Mar 17 17:52:57.082257 waagent[2041]: 2025-03-17T17:52:57.082157Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 17:52:57.082892 waagent[2041]: 2025-03-17T17:52:57.082832Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Mar 17 17:52:57.083715 waagent[2041]: 2025-03-17T17:52:57.083662Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 17:52:57.084090 waagent[2041]: 2025-03-17T17:52:57.084035Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:57.084282 waagent[2041]: 2025-03-17T17:52:57.084231Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 17:52:57.084495 waagent[2041]: 2025-03-17T17:52:57.084448Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:57.085031 waagent[2041]: 2025-03-17T17:52:57.084969Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 17:52:57.085131 waagent[2041]: 2025-03-17T17:52:57.085063Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 17:52:57.085194 waagent[2041]: 2025-03-17T17:52:57.085142Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:57.085267 waagent[2041]: 2025-03-17T17:52:57.085212Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 17:52:57.085774 waagent[2041]: 2025-03-17T17:52:57.085727Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:57.086135 waagent[2041]: 2025-03-17T17:52:57.086071Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 17:52:57.086318 waagent[2041]: 2025-03-17T17:52:57.086264Z INFO EnvHandler ExtHandler Configure routes Mar 17 17:52:57.086378 waagent[2041]: 2025-03-17T17:52:57.086320Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 17:52:57.086434 waagent[2041]: 2025-03-17T17:52:57.086381Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 17:52:57.086434 waagent[2041]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 17:52:57.086434 waagent[2041]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 17:52:57.086434 waagent[2041]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 17:52:57.086434 waagent[2041]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:57.086434 waagent[2041]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:57.086434 waagent[2041]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:57.086831 waagent[2041]: 2025-03-17T17:52:57.086787Z INFO EnvHandler ExtHandler Gateway:None Mar 17 17:52:57.086916 waagent[2041]: 2025-03-17T17:52:57.086868Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 17:52:57.087172 waagent[2041]: 2025-03-17T17:52:57.087131Z INFO EnvHandler ExtHandler Routes:None Mar 17 17:52:57.092597 waagent[2041]: 2025-03-17T17:52:57.092545Z INFO ExtHandler ExtHandler Mar 17 17:52:57.092929 waagent[2041]: 2025-03-17T17:52:57.092884Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 67a65cdb-e087-4b21-bb36-073069b69fcf correlation 39840207-accd-4648-9e7f-e5e82d97260a created: 2025-03-17T17:51:07.231026Z] Mar 17 17:52:57.093826 waagent[2041]: 2025-03-17T17:52:57.093777Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 17:52:57.096085 waagent[2041]: 2025-03-17T17:52:57.096034Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 17 17:52:57.135953 waagent[2041]: 2025-03-17T17:52:57.135893Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: ECA69803-F304-49CD-AD9E-5CAC74B8AEB5;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Mar 17 17:52:57.192085 waagent[2041]: 2025-03-17T17:52:57.192027Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 17:52:57.192085 waagent[2041]: Executing ['ip', '-a', '-o', 'link']: Mar 17 17:52:57.192085 waagent[2041]: 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 Mar 17 17:52:57.192085 waagent[2041]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1d:b4:9b brd ff:ff:ff:ff:ff:ff Mar 17 17:52:57.192085 waagent[2041]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 17:52:57.192085 waagent[2041]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 17:52:57.192085 waagent[2041]: 2: eth0 inet 10.200.4.11/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 17:52:57.192085 waagent[2041]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 17:52:57.192085 waagent[2041]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 17 17:52:57.192085 waagent[2041]: 2: eth0 inet6 fe80::7e1e:52ff:fe1d:b49b/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 17 17:52:57.218737 waagent[2041]: 2025-03-17T17:52:57.218670Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Mar 17 17:52:57.218737 waagent[2041]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.218737 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.218737 waagent[2041]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.218737 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.218737 waagent[2041]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.218737 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.218737 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:52:57.218737 waagent[2041]: 10 1102 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:52:57.218737 waagent[2041]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:52:57.222481 waagent[2041]: 2025-03-17T17:52:57.222415Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 17:52:57.222481 waagent[2041]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.222481 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.222481 waagent[2041]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.222481 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.222481 waagent[2041]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:57.222481 waagent[2041]: pkts bytes target prot opt in out source destination Mar 17 17:52:57.222481 waagent[2041]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:52:57.222481 waagent[2041]: 16 1621 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:52:57.222481 waagent[2041]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:52:57.223179 waagent[2041]: 2025-03-17T17:52:57.223136Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Mar 17 17:52:57.223422 waagent[2041]: 2025-03-17T17:52:57.223365Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 17:52:59.220755 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Mar 17 17:53:01.304448 update_engine[1716]: I20250317 17:53:01.304371 1716 update_attempter.cc:509] Updating boot flags... Mar 17 17:53:01.386379 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (2420) Mar 17 17:53:01.440381 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (2419) Mar 17 17:53:01.493380 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (2419) Mar 17 17:53:26.170867 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 17 17:53:26.186317 systemd[1]: Started sshd@0-10.200.4.11:22-218.92.0.249:51561.service - OpenSSH per-connection server daemon (218.92.0.249:51561). Mar 17 17:53:28.585436 sshd[8392]: Received disconnect from 218.92.0.249 port 51561:11: [preauth] Mar 17 17:53:28.585436 sshd[8392]: Disconnected from authenticating user root 218.92.0.249 port 51561 [preauth] Mar 17 17:53:28.586765 systemd[1]: sshd@0-10.200.4.11:22-218.92.0.249:51561.service: Deactivated successfully. Mar 17 17:53:44.896718 systemd[1]: Started sshd@1-10.200.4.11:22-10.200.16.10:57008.service - OpenSSH per-connection server daemon (10.200.16.10:57008). Mar 17 17:53:45.498508 sshd[11868]: Accepted publickey for core from 10.200.16.10 port 57008 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:45.500109 sshd-session[11868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:45.508492 systemd-logind[1714]: New session 3 of user core. Mar 17 17:53:45.518540 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 17 17:53:46.033337 systemd[1]: Started sshd@2-10.200.4.11:22-10.200.16.10:57012.service - OpenSSH per-connection server daemon (10.200.16.10:57012). Mar 17 17:53:46.427388 kernel: hv_netvsc 7c1e521d-b49b-7c1e-521d-b49b7c1e521d eth0: nvsp_rndis_pkt_complete error status: 2 Mar 17 17:53:46.433369 kernel: hv_netvsc 7c1e521d-b49b-7c1e-521d-b49b7c1e521d eth0: nvsp_rndis_pkt_complete error status: 2 Mar 17 17:53:46.441369 kernel: hv_netvsc 7c1e521d-b49b-7c1e-521d-b49b7c1e521d eth0: nvsp_rndis_pkt_complete error status: 2 Mar 17 17:53:46.644669 sshd[11891]: Accepted publickey for core from 10.200.16.10 port 57012 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:46.649014 sshd-session[11891]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:46.654041 systemd-logind[1714]: New session 4 of user core. Mar 17 17:53:46.664918 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 17 17:53:47.086314 sshd[11912]: Connection closed by 10.200.16.10 port 57012 Mar 17 17:53:47.088182 sshd-session[11891]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:47.094880 systemd[1]: sshd@2-10.200.4.11:22-10.200.16.10:57012.service: Deactivated successfully. Mar 17 17:53:47.097003 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 17:53:47.098181 systemd-logind[1714]: Session 4 logged out. Waiting for processes to exit. Mar 17 17:53:47.099275 systemd-logind[1714]: Removed session 4. Mar 17 17:53:47.209660 systemd[1]: Started sshd@3-10.200.4.11:22-10.200.16.10:57024.service - OpenSSH per-connection server daemon (10.200.16.10:57024). Mar 17 17:53:47.816302 sshd[11925]: Accepted publickey for core from 10.200.16.10 port 57024 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:47.817957 sshd-session[11925]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:47.825442 systemd-logind[1714]: New session 5 of user core. Mar 17 17:53:47.832531 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 17 17:53:48.251461 sshd[11945]: Connection closed by 10.200.16.10 port 57024 Mar 17 17:53:48.252129 sshd-session[11925]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:48.255292 systemd-logind[1714]: Session 5 logged out. Waiting for processes to exit. Mar 17 17:53:48.255894 systemd[1]: sshd@3-10.200.4.11:22-10.200.16.10:57024.service: Deactivated successfully. Mar 17 17:53:48.257722 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 17:53:48.261750 systemd-logind[1714]: Removed session 5. Mar 17 17:53:58.365644 systemd[1]: Started sshd@4-10.200.4.11:22-10.200.16.10:60526.service - OpenSSH per-connection server daemon (10.200.16.10:60526). Mar 17 17:53:58.981955 sshd[12268]: Accepted publickey for core from 10.200.16.10 port 60526 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:58.983507 sshd-session[12268]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:58.988339 systemd-logind[1714]: New session 6 of user core. Mar 17 17:53:58.998866 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 17 17:53:59.414564 sshd[12288]: Connection closed by 10.200.16.10 port 60526 Mar 17 17:53:59.415020 sshd-session[12268]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:59.419189 systemd-logind[1714]: Session 6 logged out. Waiting for processes to exit. Mar 17 17:53:59.420887 systemd[1]: sshd@4-10.200.4.11:22-10.200.16.10:60526.service: Deactivated successfully. Mar 17 17:53:59.424473 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 17:53:59.425894 systemd-logind[1714]: Removed session 6. Mar 17 17:53:59.557837 systemd[1]: Started sshd@5-10.200.4.11:22-10.200.16.10:45024.service - OpenSSH per-connection server daemon (10.200.16.10:45024). Mar 17 17:54:00.173322 sshd[12307]: Accepted publickey for core from 10.200.16.10 port 45024 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:00.173928 sshd-session[12307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:00.179779 systemd-logind[1714]: New session 7 of user core. Mar 17 17:54:00.187745 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 17 17:54:00.610525 sshd[12325]: Connection closed by 10.200.16.10 port 45024 Mar 17 17:54:00.612176 sshd-session[12307]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:00.615798 systemd[1]: sshd@5-10.200.4.11:22-10.200.16.10:45024.service: Deactivated successfully. Mar 17 17:54:00.618615 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 17:54:00.619530 systemd-logind[1714]: Session 7 logged out. Waiting for processes to exit. Mar 17 17:54:00.625309 systemd-logind[1714]: Removed session 7. Mar 17 17:54:10.722363 systemd[1]: Started sshd@6-10.200.4.11:22-10.200.16.10:42970.service - OpenSSH per-connection server daemon (10.200.16.10:42970). Mar 17 17:54:11.340044 sshd[12633]: Accepted publickey for core from 10.200.16.10 port 42970 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:11.343986 sshd-session[12633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:11.350762 systemd-logind[1714]: New session 8 of user core. Mar 17 17:54:11.357157 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 17 17:54:11.778385 sshd[12678]: Connection closed by 10.200.16.10 port 42970 Mar 17 17:54:11.778941 sshd-session[12633]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:11.782471 systemd[1]: sshd@6-10.200.4.11:22-10.200.16.10:42970.service: Deactivated successfully. Mar 17 17:54:11.784213 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 17:54:11.784854 systemd-logind[1714]: Session 8 logged out. Waiting for processes to exit. Mar 17 17:54:11.785614 systemd-logind[1714]: Removed session 8. Mar 17 17:54:11.886161 systemd[1]: Started sshd@7-10.200.4.11:22-10.200.16.10:42972.service - OpenSSH per-connection server daemon (10.200.16.10:42972). Mar 17 17:54:12.487869 sshd[12703]: Accepted publickey for core from 10.200.16.10 port 42972 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:12.489384 sshd-session[12703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:12.493688 systemd-logind[1714]: New session 9 of user core. Mar 17 17:54:12.505515 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 17 17:54:12.919333 sshd[12705]: Connection closed by 10.200.16.10 port 42972 Mar 17 17:54:12.919801 sshd-session[12703]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:12.923267 systemd[1]: sshd@7-10.200.4.11:22-10.200.16.10:42972.service: Deactivated successfully. Mar 17 17:54:12.924999 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 17:54:12.925652 systemd-logind[1714]: Session 9 logged out. Waiting for processes to exit. Mar 17 17:54:12.926419 systemd-logind[1714]: Removed session 9. Mar 17 17:54:20.772825 setup-nvidia[2270]: \u000d Mar 17 17:54:20.772825 setup-nvidia[2270]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Mar 17 17:54:20.772825 setup-nvidia[2270]: frequently when this kernel module was built against the wrong or Mar 17 17:54:20.772825 setup-nvidia[2270]: improperly configured kernel sources, with a version of gcc that differs Mar 17 17:54:20.772825 setup-nvidia[2270]: from the one used to build the target kernel, or if another driver, such Mar 17 17:54:20.772825 setup-nvidia[2270]: as nouveau, is present and prevents the NVIDIA kernel module from Mar 17 17:54:20.772825 setup-nvidia[2270]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Mar 17 17:54:20.772825 setup-nvidia[2270]: installed in this system is supported by this NVIDIA Linux graphics Mar 17 17:54:20.772825 setup-nvidia[2270]: driver release. Mar 17 17:54:20.772825 setup-nvidia[2270]: \u000d Mar 17 17:54:20.772825 setup-nvidia[2270]: Please see the log entries 'Kernel module load error' and 'Kernel Mar 17 17:54:20.772825 setup-nvidia[2270]: messages' at the end of the file Mar 17 17:54:20.772825 setup-nvidia[2270]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Mar 17 17:54:20.772825 setup-nvidia[2270]: information. Mar 17 17:54:20.772825 setup-nvidia[2270]: \u000d Mar 17 17:54:20.788390 setup-nvidia[2270]: dmesg: read kernel buffer failed: Operation not permitted Mar 17 17:54:20.809827 setup-nvidia[2270]: \u000d Mar 17 17:54:20.809827 setup-nvidia[2270]: ERROR: Installation has failed. Please see the file Mar 17 17:54:20.809827 setup-nvidia[2270]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Mar 17 17:54:20.809827 setup-nvidia[2270]: details. You may find suggestions on fixing installation problems in Mar 17 17:54:20.809827 setup-nvidia[2270]: the README available on the Linux driver download page at Mar 17 17:54:20.809827 setup-nvidia[2270]: www.nvidia.com. Mar 17 17:54:20.809827 setup-nvidia[2270]: \u000d Mar 17 17:54:20.810257 setup-nvidia[2270]: Last 50 lines of nvidia-installer.log: Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Mar 17 17:54:20.811112 setup-nvidia[2270]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Mar 17 17:54:20.811112 setup-nvidia[2270]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Mar 17 17:54:20.811112 setup-nvidia[2270]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Mar 17 17:54:20.811112 setup-nvidia[2270]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Mar 17 17:54:20.811112 setup-nvidia[2270]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Mar 17 17:54:20.811112 setup-nvidia[2270]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Mar 17 17:54:20.811112 setup-nvidia[2270]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Mar 17 17:54:20.811112 setup-nvidia[2270]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Mar 17 17:54:20.811112 setup-nvidia[2270]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Mar 17 17:54:20.811112 setup-nvidia[2270]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Mar 17 17:54:20.811112 setup-nvidia[2270]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 17 17:54:20.811112 setup-nvidia[2270]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Mar 17 17:54:20.811112 setup-nvidia[2270]: make[2]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/build' Mar 17 17:54:20.811112 setup-nvidia[2270]: make[1]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/source' Mar 17 17:54:20.811112 setup-nvidia[2270]: -> done. Mar 17 17:54:20.811112 setup-nvidia[2270]: -> Kernel module compilation complete. Mar 17 17:54:20.811112 setup-nvidia[2270]: -> Unable to determine if Secure Boot is enabled: No such file or directory Mar 17 17:54:20.811112 setup-nvidia[2270]: 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. Mar 17 17:54:20.811112 setup-nvidia[2270]: \u000d Mar 17 17:54:20.812220 setup-nvidia[2270]: 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. Mar 17 17:54:20.812220 setup-nvidia[2270]: -> Kernel module load error: Operation not permitted Mar 17 17:54:20.812220 setup-nvidia[2270]: -> Kernel messages: Mar 17 17:54:20.812220 setup-nvidia[2270]: 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. Mar 17 17:54:23.031159 systemd[1]: Started sshd@8-10.200.4.11:22-10.200.16.10:40480.service - OpenSSH per-connection server daemon (10.200.16.10:40480). Mar 17 17:54:23.644652 sshd[12920]: Accepted publickey for core from 10.200.16.10 port 40480 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:23.646113 sshd-session[12920]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:23.650284 systemd-logind[1714]: New session 10 of user core. Mar 17 17:54:23.662475 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 17 17:54:24.080876 sshd[12922]: Connection closed by 10.200.16.10 port 40480 Mar 17 17:54:24.081257 sshd-session[12920]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:24.084575 systemd[1]: sshd@8-10.200.4.11:22-10.200.16.10:40480.service: Deactivated successfully. Mar 17 17:54:24.086382 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 17:54:24.087124 systemd-logind[1714]: Session 10 logged out. Waiting for processes to exit. Mar 17 17:54:24.087960 systemd-logind[1714]: Removed session 10. Mar 17 17:54:24.187857 systemd[1]: Started sshd@9-10.200.4.11:22-10.200.16.10:40492.service - OpenSSH per-connection server daemon (10.200.16.10:40492). Mar 17 17:54:24.788343 sshd[12928]: Accepted publickey for core from 10.200.16.10 port 40492 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:24.789777 sshd-session[12928]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:24.794028 systemd-logind[1714]: New session 11 of user core. Mar 17 17:54:24.803517 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 17 17:54:25.217091 sshd[12930]: Connection closed by 10.200.16.10 port 40492 Mar 17 17:54:25.217529 sshd-session[12928]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:25.220808 systemd[1]: sshd@9-10.200.4.11:22-10.200.16.10:40492.service: Deactivated successfully. Mar 17 17:54:25.222501 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 17:54:25.223196 systemd-logind[1714]: Session 11 logged out. Waiting for processes to exit. Mar 17 17:54:25.224030 systemd-logind[1714]: Removed session 11. Mar 17 17:54:28.388220 waagent[2041]: 2025-03-17T17:54:28.388152Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 17 17:54:28.395318 waagent[2041]: 2025-03-17T17:54:28.395268Z INFO ExtHandler Mar 17 17:54:28.395425 waagent[2041]: 2025-03-17T17:54:28.395386Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 17 17:54:28.451035 waagent[2041]: 2025-03-17T17:54:28.450995Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 17:54:28.504506 waagent[2041]: 2025-03-17T17:54:28.504448Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:54:28.504978 waagent[2041]: 2025-03-17T17:54:28.504932Z INFO ExtHandler Fetch goal state completed Mar 17 17:54:28.505357 waagent[2041]: 2025-03-17T17:54:28.505308Z INFO ExtHandler ExtHandler Mar 17 17:54:28.505452 waagent[2041]: 2025-03-17T17:54:28.505414Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 32aa318c-c8b2-4fce-ace3-c4a671e6c1d3 correlation 39840207-accd-4648-9e7f-e5e82d97260a created: 2025-03-17T17:54:23.935730Z] Mar 17 17:54:28.505779 waagent[2041]: 2025-03-17T17:54:28.505737Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 17:54:28.506292 waagent[2041]: 2025-03-17T17:54:28.506253Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Mar 17 17:54:34.528852 waagent[2041]: 2025-03-17T17:54:34.528775Z INFO ExtHandler Mar 17 17:54:34.529390 waagent[2041]: 2025-03-17T17:54:34.528933Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: fb075395-8f9e-49a4-9e63-0cee32471f44 eTag: 3552644111994216331 source: Fabric] Mar 17 17:54:34.529829 waagent[2041]: 2025-03-17T17:54:34.529777Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 17:54:35.324870 systemd[1]: Started sshd@10-10.200.4.11:22-10.200.16.10:52950.service - OpenSSH per-connection server daemon (10.200.16.10:52950). Mar 17 17:54:35.925590 sshd[12942]: Accepted publickey for core from 10.200.16.10 port 52950 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:35.927092 sshd-session[12942]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:35.931271 systemd-logind[1714]: New session 12 of user core. Mar 17 17:54:35.943479 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 17 17:54:36.357511 sshd[12944]: Connection closed by 10.200.16.10 port 52950 Mar 17 17:54:36.464776 systemd[1]: Started sshd@11-10.200.4.11:22-10.200.16.10:52962.service - OpenSSH per-connection server daemon (10.200.16.10:52962). Mar 17 17:54:38.381287 sshd-session[12942]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:38.381669 sshd[12947]: Accepted publickey for core from 10.200.16.10 port 52962 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:38.381600 sshd-session[12947]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:38.385273 systemd[1]: sshd@10-10.200.4.11:22-10.200.16.10:52950.service: Deactivated successfully. Mar 17 17:54:38.387200 systemd[1]: session-12.scope: Deactivated successfully. Mar 17 17:54:38.389723 systemd-logind[1714]: New session 13 of user core. Mar 17 17:54:38.401559 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 17 17:54:38.404816 systemd-logind[1714]: Session 12 logged out. Waiting for processes to exit. Mar 17 17:54:38.405624 systemd-logind[1714]: Removed session 12. Mar 17 17:54:38.552371 kernel: EXT4-fs (loop6p9): unmounting filesystem a6481100-8bf3-468a-a5db-d879944440da. Mar 17 17:54:38.552529 setup-nvidia[2270]: Container flatcardevelopercontainer-4230.1.0.bin exited successfully. Mar 17 17:54:38.554840 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.1.0.bin.mount: Deactivated successfully. Mar 17 17:54:38.612222 sudo[2040]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:38.613995 systemd[1]: session-c2.scope: Deactivated successfully. Mar 17 17:54:38.614277 systemd[1]: session-c2.scope: Consumed 5min 22.811s CPU time, 882.6M memory peak. Mar 17 17:54:38.747326 sshd[12952]: Connection closed by 10.200.16.10 port 52962 Mar 17 17:54:38.747697 sshd-session[12947]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:38.751103 systemd[1]: sshd@11-10.200.4.11:22-10.200.16.10:52962.service: Deactivated successfully. Mar 17 17:54:38.753107 systemd[1]: session-13.scope: Deactivated successfully. Mar 17 17:54:38.753775 systemd-logind[1714]: Session 13 logged out. Waiting for processes to exit. Mar 17 17:54:38.754587 systemd-logind[1714]: Removed session 13. Mar 17 17:54:39.054799 setup-nvidia[1705]: /opt/nvidia / Mar 17 17:54:39.055826 setup-nvidia[1705]: / Mar 17 17:54:39.077156 setup-nvidia[12974]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:54:39.108379 kernel: IPMI message handler: version 39.2 Mar 17 17:54:39.111368 kernel: ipmi device interface Mar 17 17:54:39.112829 setup-nvidia[1705]: /opt/nvidia/current/usr/lib/modules/6.6.83-flatcar/video / Mar 17 17:54:39.176588 kernel: nvidia: loading out-of-tree module taints kernel. Mar 17 17:54:39.176738 kernel: nvidia: module license 'NVIDIA' taints kernel. Mar 17 17:54:39.176760 kernel: Disabling lock debugging due to kernel taint Mar 17 17:54:39.183513 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Mar 17 17:54:39.183542 kernel: nvidia: module license taints kernel. Mar 17 17:54:39.570377 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Mar 17 17:54:40.087959 kernel: Mar 17 17:54:40.088115 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Mar 17 17:54:40.145930 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Mar 17 17:54:40.172371 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Mar 17 17:54:40.194393 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Mar 17 17:54:40.236372 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Mar 17 17:54:40.236561 setup-nvidia[1705]: / Mar 17 17:54:41.772366 setup-nvidia[13004]: Mon Mar 17 17:54:41 2025 Mar 17 17:54:41.772366 setup-nvidia[13004]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:41.772366 setup-nvidia[13004]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Mar 17 17:54:41.772366 setup-nvidia[13004]: |-----------------------------------------+----------------------+----------------------+ Mar 17 17:54:41.772366 setup-nvidia[13004]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Mar 17 17:54:41.772366 setup-nvidia[13004]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Mar 17 17:54:41.772366 setup-nvidia[13004]: | | | MIG M. | Mar 17 17:54:41.772366 setup-nvidia[13004]: |=========================================+======================+======================| Mar 17 17:54:41.779792 setup-nvidia[13004]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Mar 17 17:54:41.779792 setup-nvidia[13004]: | N/A 29C P0 36W / 250W | 0MiB / 16384MiB | 1% Default | Mar 17 17:54:41.779792 setup-nvidia[13004]: | | | N/A | Mar 17 17:54:41.779792 setup-nvidia[13004]: +-----------------------------------------+----------------------+----------------------+ Mar 17 17:54:41.779905 setup-nvidia[13004]: Mar 17 17:54:41.779905 setup-nvidia[13004]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:41.779905 setup-nvidia[13004]: | Processes: | Mar 17 17:54:41.779905 setup-nvidia[13004]: | GPU GI CI PID Type Process name GPU Memory | Mar 17 17:54:41.779905 setup-nvidia[13004]: | ID ID Usage | Mar 17 17:54:41.779905 setup-nvidia[13004]: |=======================================================================================| Mar 17 17:54:41.780036 setup-nvidia[13004]: | No running processes found | Mar 17 17:54:41.780036 setup-nvidia[13004]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:42.162909 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 17 17:54:42.167021 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 17 17:54:42.168692 systemd[1]: Startup finished in 755ms (firmware) + 26.861s (loader) + 1.989s (kernel) + 10.847s (initrd) + 2min 37.032s (userspace) = 3min 17.485s. Mar 17 17:54:48.622806 systemd[1]: Stopping user@0.service - User Manager for UID 0... Mar 17 17:54:48.623636 systemd[2048]: Activating special unit exit.target... Mar 17 17:54:48.623731 systemd[2048]: Stopped target default.target - Main User Target. Mar 17 17:54:48.623773 systemd[2048]: Stopped target basic.target - Basic System. Mar 17 17:54:48.623803 systemd[2048]: Stopped target paths.target - Paths. Mar 17 17:54:48.623829 systemd[2048]: Stopped target sockets.target - Sockets. Mar 17 17:54:48.623857 systemd[2048]: Stopped target timers.target - Timers. Mar 17 17:54:48.624233 systemd[2048]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:54:48.624473 systemd[2048]: Removed slice app.slice - User Application Slice. Mar 17 17:54:48.624507 systemd[2048]: Reached target shutdown.target - Shutdown. Mar 17 17:54:48.624665 systemd[2048]: Finished systemd-exit.service - Exit the Session. Mar 17 17:54:48.624704 systemd[2048]: Reached target exit.target - Exit the Session. Mar 17 17:54:48.639693 (sd-pam)[2049]: pam_unix(systemd-user:session): session closed for user root Mar 17 17:54:48.641282 systemd[1]: user@0.service: Deactivated successfully. Mar 17 17:54:48.641671 systemd[1]: Stopped user@0.service - User Manager for UID 0. Mar 17 17:54:48.655512 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 17 17:54:48.664333 systemd[1]: run-user-0.mount: Deactivated successfully. Mar 17 17:54:48.665232 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Mar 17 17:54:48.665429 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 17 17:54:48.667114 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Mar 17 17:54:48.667205 systemd[1]: user-0.slice: Consumed 5min 23.125s CPU time, 891.7M memory peak. Mar 17 17:54:48.667594 systemd-logind[1714]: Removed session c3. Mar 17 17:54:48.854011 systemd[1]: Started sshd@12-10.200.4.11:22-10.200.16.10:35126.service - OpenSSH per-connection server daemon (10.200.16.10:35126). Mar 17 17:54:49.453309 sshd[13015]: Accepted publickey for core from 10.200.16.10 port 35126 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:49.454739 sshd-session[13015]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:49.459173 systemd-logind[1714]: New session 14 of user core. Mar 17 17:54:49.469482 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 17 17:54:49.882076 sshd[13017]: Connection closed by 10.200.16.10 port 35126 Mar 17 17:54:49.882562 sshd-session[13015]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:49.886057 systemd[1]: sshd@12-10.200.4.11:22-10.200.16.10:35126.service: Deactivated successfully. Mar 17 17:54:49.887903 systemd[1]: session-14.scope: Deactivated successfully. Mar 17 17:54:49.888563 systemd-logind[1714]: Session 14 logged out. Waiting for processes to exit. Mar 17 17:54:49.889345 systemd-logind[1714]: Removed session 14. Mar 17 17:54:49.990240 systemd[1]: Started sshd@13-10.200.4.11:22-10.200.16.10:35134.service - OpenSSH per-connection server daemon (10.200.16.10:35134). Mar 17 17:54:50.594595 sshd[13023]: Accepted publickey for core from 10.200.16.10 port 35134 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:50.596033 sshd-session[13023]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:50.600416 systemd-logind[1714]: New session 15 of user core. Mar 17 17:54:50.615470 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 17 17:54:51.023251 sshd[13025]: Connection closed by 10.200.16.10 port 35134 Mar 17 17:54:51.023704 sshd-session[13023]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:51.027057 systemd[1]: sshd@13-10.200.4.11:22-10.200.16.10:35134.service: Deactivated successfully. Mar 17 17:54:51.028873 systemd[1]: session-15.scope: Deactivated successfully. Mar 17 17:54:51.029522 systemd-logind[1714]: Session 15 logged out. Waiting for processes to exit. Mar 17 17:54:51.030267 systemd-logind[1714]: Removed session 15. Mar 17 17:54:51.130499 systemd[1]: Started sshd@14-10.200.4.11:22-10.200.16.10:35150.service - OpenSSH per-connection server daemon (10.200.16.10:35150). Mar 17 17:54:51.729860 sshd[13031]: Accepted publickey for core from 10.200.16.10 port 35150 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:51.731418 sshd-session[13031]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:51.735752 systemd-logind[1714]: New session 16 of user core. Mar 17 17:54:51.748475 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 17 17:54:52.162584 sshd[13033]: Connection closed by 10.200.16.10 port 35150 Mar 17 17:54:52.163005 sshd-session[13031]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:52.166249 systemd[1]: sshd@14-10.200.4.11:22-10.200.16.10:35150.service: Deactivated successfully. Mar 17 17:54:52.168043 systemd[1]: session-16.scope: Deactivated successfully. Mar 17 17:54:52.168713 systemd-logind[1714]: Session 16 logged out. Waiting for processes to exit. Mar 17 17:54:52.169467 systemd-logind[1714]: Removed session 16. Mar 17 17:54:52.268905 systemd[1]: Started sshd@15-10.200.4.11:22-10.200.16.10:35164.service - OpenSSH per-connection server daemon (10.200.16.10:35164). Mar 17 17:54:52.867636 sshd[13039]: Accepted publickey for core from 10.200.16.10 port 35164 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:52.869111 sshd-session[13039]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:52.873495 systemd-logind[1714]: New session 17 of user core. Mar 17 17:54:52.884176 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 17 17:54:53.199651 sudo[13042]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 17 17:54:53.199968 sudo[13042]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:54:53.213727 sudo[13042]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:53.316038 sshd[13041]: Connection closed by 10.200.16.10 port 35164 Mar 17 17:54:53.316508 sshd-session[13039]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:53.320093 systemd[1]: sshd@15-10.200.4.11:22-10.200.16.10:35164.service: Deactivated successfully. Mar 17 17:54:53.321913 systemd[1]: session-17.scope: Deactivated successfully. Mar 17 17:54:53.322586 systemd-logind[1714]: Session 17 logged out. Waiting for processes to exit. Mar 17 17:54:53.323399 systemd-logind[1714]: Removed session 17. Mar 17 17:54:53.421760 systemd[1]: Started sshd@16-10.200.4.11:22-10.200.16.10:35174.service - OpenSSH per-connection server daemon (10.200.16.10:35174). Mar 17 17:54:54.020589 sshd[13048]: Accepted publickey for core from 10.200.16.10 port 35174 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:54.022070 sshd-session[13048]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:54.026406 systemd-logind[1714]: New session 18 of user core. Mar 17 17:54:54.033481 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 17 17:54:54.352635 sudo[13052]: 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 Mar 17 17:54:54.352945 sudo[13052]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:54:54.356166 sudo[13052]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:54.361566 sudo[13051]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Mar 17 17:54:54.361858 sudo[13051]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:54:54.381683 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:54:55.843369 augenrules[13074]: No rules Mar 17 17:54:55.844827 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:54:55.845100 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:54:55.846063 sudo[13051]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:55.945254 sshd[13050]: Connection closed by 10.200.16.10 port 35174 Mar 17 17:54:55.945722 sshd-session[13048]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:55.949550 systemd[1]: sshd@16-10.200.4.11:22-10.200.16.10:35174.service: Deactivated successfully. Mar 17 17:54:55.951377 systemd[1]: session-18.scope: Deactivated successfully. Mar 17 17:54:55.952021 systemd-logind[1714]: Session 18 logged out. Waiting for processes to exit. Mar 17 17:54:55.952842 systemd-logind[1714]: Removed session 18. Mar 17 17:54:55.985476 systemd[1]: Started sshd@17-10.200.4.11:22-218.92.0.249:25753.service - OpenSSH per-connection server daemon (218.92.0.249:25753). Mar 17 17:54:56.053511 systemd[1]: Started sshd@18-10.200.4.11:22-10.200.16.10:35180.service - OpenSSH per-connection server daemon (10.200.16.10:35180). Mar 17 17:54:56.666774 sshd[13086]: Accepted publickey for core from 10.200.16.10 port 35180 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:56.668277 sshd-session[13086]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:56.674221 systemd-logind[1714]: New session 19 of user core. Mar 17 17:54:56.683483 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 17 17:54:57.000871 sudo[13089]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Mar 17 17:54:57.001184 sudo[13089]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:54:57.004397 sudo[13089]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:57.109462 sshd[13088]: Connection closed by 10.200.16.10 port 35180 Mar 17 17:54:57.109883 sshd-session[13086]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:57.113290 systemd[1]: sshd@18-10.200.4.11:22-10.200.16.10:35180.service: Deactivated successfully. Mar 17 17:54:57.115154 systemd[1]: session-19.scope: Deactivated successfully. Mar 17 17:54:57.115851 systemd-logind[1714]: Session 19 logged out. Waiting for processes to exit. Mar 17 17:54:57.116629 systemd-logind[1714]: Removed session 19. Mar 17 17:54:57.216980 systemd[1]: Started sshd@19-10.200.4.11:22-10.200.16.10:35188.service - OpenSSH per-connection server daemon (10.200.16.10:35188). Mar 17 17:54:57.823102 sshd[13095]: Accepted publickey for core from 10.200.16.10 port 35188 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:57.824513 sshd-session[13095]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:57.828715 systemd-logind[1714]: New session 20 of user core. Mar 17 17:54:57.841469 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 17 17:54:58.156851 sudo[13098]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Mar 17 17:54:58.157161 sudo[13098]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:54:58.171473 sshd[13083]: Received disconnect from 218.92.0.249 port 25753:11: [preauth] Mar 17 17:54:58.171473 sshd[13083]: Disconnected from authenticating user root 218.92.0.249 port 25753 [preauth] Mar 17 17:54:58.173551 systemd[1]: sshd@17-10.200.4.11:22-218.92.0.249:25753.service: Deactivated successfully. Mar 17 17:55:01.490577 sudo[13098]: pam_unix(sudo:session): session closed for user root Mar 17 17:55:01.591538 sshd[13097]: Connection closed by 10.200.16.10 port 35188 Mar 17 17:55:01.591775 sshd-session[13095]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:01.595149 systemd[1]: sshd@19-10.200.4.11:22-10.200.16.10:35188.service: Deactivated successfully. Mar 17 17:55:01.597041 systemd[1]: session-20.scope: Deactivated successfully. Mar 17 17:55:01.598326 systemd-logind[1714]: Session 20 logged out. Waiting for processes to exit. Mar 17 17:55:01.599100 systemd-logind[1714]: Removed session 20. Mar 17 17:55:01.698685 systemd[1]: Started sshd@20-10.200.4.11:22-10.200.16.10:35172.service - OpenSSH per-connection server daemon (10.200.16.10:35172). Mar 17 17:55:02.303786 sshd[13106]: Accepted publickey for core from 10.200.16.10 port 35172 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:55:02.305294 sshd-session[13106]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:55:02.309727 systemd-logind[1714]: New session 21 of user core. Mar 17 17:55:02.319479 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 17 17:55:02.820718 sshd[13108]: Connection closed by 10.200.16.10 port 35172 Mar 17 17:55:02.821131 sshd-session[13106]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:02.824367 systemd[1]: sshd@20-10.200.4.11:22-10.200.16.10:35172.service: Deactivated successfully. Mar 17 17:55:02.826099 systemd[1]: session-21.scope: Deactivated successfully. Mar 17 17:55:02.826758 systemd-logind[1714]: Session 21 logged out. Waiting for processes to exit. Mar 17 17:55:02.827594 systemd-logind[1714]: Removed session 21. Mar 17 17:55:02.926718 systemd[1]: Started sshd@21-10.200.4.11:22-10.200.16.10:35176.service - OpenSSH per-connection server daemon (10.200.16.10:35176). Mar 17 17:55:03.526246 sshd[13123]: Accepted publickey for core from 10.200.16.10 port 35176 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:55:03.527697 sshd-session[13123]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:55:03.532131 systemd-logind[1714]: New session 22 of user core. Mar 17 17:55:03.545477 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 17 17:55:04.365586 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 17 17:55:04.365741 (dockerd)[13146]: 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 Mar 17 17:55:05.763692 dockerd[13146]: time="2025-03-17T17:55:05.763617139Z" level=info msg="Starting up" Mar 17 17:55:06.324365 dockerd[13146]: time="2025-03-17T17:55:06.324319867Z" level=info msg="Loading containers: start." Mar 17 17:55:06.543371 kernel: Initializing XFRM netlink socket Mar 17 17:55:06.604785 systemd-networkd[1417]: docker0: Link UP Mar 17 17:55:06.651675 dockerd[13146]: time="2025-03-17T17:55:06.651630540Z" level=info msg="Loading containers: done." Mar 17 17:55:06.666514 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2996781651-merged.mount: Deactivated successfully. Mar 17 17:55:06.674607 dockerd[13146]: time="2025-03-17T17:55:06.674557043Z" 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 Mar 17 17:55:06.674691 dockerd[13146]: time="2025-03-17T17:55:06.674660539Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Mar 17 17:55:06.674794 dockerd[13146]: time="2025-03-17T17:55:06.674770034Z" level=info msg="Daemon has completed initialization" Mar 17 17:55:06.727405 dockerd[13146]: time="2025-03-17T17:55:06.727336049Z" level=info msg="API listen on /run/docker.sock" Mar 17 17:55:06.727516 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 17 17:55:08.532892 systemd[1]: var-lib-docker-overlay2-31d78fa0b388083928f722a5d5137315b17b8d07428461c951322c031c05b37a\x2dinit-merged.mount: Deactivated successfully. Mar 17 17:55:08.601500 kernel: docker0: port 1(veth7776efc) entered blocking state Mar 17 17:55:08.601604 kernel: docker0: port 1(veth7776efc) entered disabled state Mar 17 17:55:08.601627 kernel: veth7776efc: entered allmulticast mode Mar 17 17:55:08.606377 kernel: veth7776efc: entered promiscuous mode Mar 17 17:55:08.606957 systemd-networkd[1417]: veth7776efc: Link UP Mar 17 17:55:09.228018 containerd[1725]: time="2025-03-17T17:55:09.227872459Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 17:55:09.228018 containerd[1725]: time="2025-03-17T17:55:09.227923056Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 17:55:09.228018 containerd[1725]: time="2025-03-17T17:55:09.227933956Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:55:09.228487 containerd[1725]: time="2025-03-17T17:55:09.228009153Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:55:09.258491 systemd[1]: Started docker-09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e.scope - libcontainer container 09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e. Mar 17 17:55:09.312376 kernel: eth0: renamed from vethe74c2c5 Mar 17 17:55:09.326607 kernel: docker0: port 1(veth7776efc) entered blocking state Mar 17 17:55:09.326665 kernel: docker0: port 1(veth7776efc) entered forwarding state Mar 17 17:55:09.327025 systemd-networkd[1417]: veth7776efc: Gained carrier Mar 17 17:55:09.327290 systemd-networkd[1417]: docker0: Gained carrier Mar 17 17:55:10.941503 systemd-networkd[1417]: docker0: Gained IPv6LL Mar 17 17:55:11.197460 systemd-networkd[1417]: veth7776efc: Gained IPv6LL Mar 17 17:55:12.618177 systemd[1]: docker-09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e.scope: Deactivated successfully. Mar 17 17:55:12.632285 containerd[1725]: time="2025-03-17T17:55:12.632210606Z" level=info msg="shim disconnected" id=09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e namespace=moby Mar 17 17:55:12.632285 containerd[1725]: time="2025-03-17T17:55:12.632268604Z" level=warning msg="cleaning up after shim disconnected" id=09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e namespace=moby Mar 17 17:55:12.632285 containerd[1725]: time="2025-03-17T17:55:12.632279003Z" level=info msg="cleaning up dead shim" namespace=moby Mar 17 17:55:12.632819 dockerd[13146]: time="2025-03-17T17:55:12.632765582Z" level=info msg="ignoring event" container=09273b756a3f328e0a31e2c774a05ddfa3e31d6dd368b340fa78f87fe65ba13e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Mar 17 17:55:12.665379 kernel: vethe74c2c5: renamed from eth0 Mar 17 17:55:12.677987 systemd-networkd[1417]: veth7776efc: Lost carrier Mar 17 17:55:12.678367 kernel: docker0: port 1(veth7776efc) entered disabled state Mar 17 17:55:12.693305 systemd-networkd[1417]: veth7776efc: Link DOWN Mar 17 17:55:12.697378 kernel: docker0: port 1(veth7776efc) entered disabled state Mar 17 17:55:12.697431 kernel: veth7776efc (unregistering): left allmulticast mode Mar 17 17:55:12.703534 kernel: veth7776efc (unregistering): left promiscuous mode Mar 17 17:55:12.706881 kernel: docker0: port 1(veth7776efc) entered disabled state Mar 17 17:55:12.734945 systemd[1]: run-docker-netns-3f4bb3b0e3e7.mount: Deactivated successfully. Mar 17 17:55:12.739439 systemd[1]: var-lib-docker-overlay2-31d78fa0b388083928f722a5d5137315b17b8d07428461c951322c031c05b37a-merged.mount: Deactivated successfully. Mar 17 17:55:12.866032 sshd[13125]: Connection closed by 10.200.16.10 port 35176 Mar 17 17:55:12.866473 sshd-session[13123]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:12.869929 systemd[1]: sshd@21-10.200.4.11:22-10.200.16.10:35176.service: Deactivated successfully. Mar 17 17:55:12.871719 systemd[1]: session-22.scope: Deactivated successfully. Mar 17 17:55:12.872343 systemd-logind[1714]: Session 22 logged out. Waiting for processes to exit. Mar 17 17:55:12.873101 systemd-logind[1714]: Removed session 22. Mar 17 17:55:12.976957 systemd[1]: Started sshd@22-10.200.4.11:22-10.200.16.10:43116.service - OpenSSH per-connection server daemon (10.200.16.10:43116). Mar 17 17:55:13.580788 sshd[13421]: Accepted publickey for core from 10.200.16.10 port 43116 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:55:13.582240 sshd-session[13421]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:55:13.586533 systemd-logind[1714]: New session 23 of user core. Mar 17 17:55:13.600473 systemd[1]: Started session-23.scope - Session 23 of User core. Mar 17 17:55:13.693572 systemd-networkd[1417]: docker0: Lost carrier Mar 17 17:55:13.967475 systemd[1]: var-lib-docker-overlay2-223971e3b7a1174ea0e2d22e2b9952689add484149e2355c08f4571d5e5be688\x2dinit-merged.mount: Deactivated successfully. Mar 17 17:55:14.027117 kernel: docker0: port 1(veth229bc96) entered blocking state Mar 17 17:55:14.027227 kernel: docker0: port 1(veth229bc96) entered disabled state Mar 17 17:55:14.029927 kernel: veth229bc96: entered allmulticast mode Mar 17 17:55:14.032780 kernel: veth229bc96: entered promiscuous mode Mar 17 17:55:14.033594 systemd-networkd[1417]: veth229bc96: Link UP Mar 17 17:55:14.036131 kernel: docker0: port 1(veth229bc96) entered blocking state Mar 17 17:55:14.039642 kernel: docker0: port 1(veth229bc96) entered forwarding state Mar 17 17:55:14.040391 kernel: docker0: port 1(veth229bc96) entered disabled state Mar 17 17:55:14.120367 containerd[1725]: time="2025-03-17T17:55:14.119773962Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 17:55:14.120367 containerd[1725]: time="2025-03-17T17:55:14.120334138Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 17:55:14.120367 containerd[1725]: time="2025-03-17T17:55:14.120361437Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:55:14.120852 containerd[1725]: time="2025-03-17T17:55:14.120448533Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:55:14.141529 systemd[1]: Started docker-d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d.scope - libcontainer container d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d. Mar 17 17:55:14.198376 kernel: eth0: renamed from veth7fdc6c4 Mar 17 17:55:14.213211 kernel: docker0: port 1(veth229bc96) entered blocking state Mar 17 17:55:14.213259 kernel: docker0: port 1(veth229bc96) entered forwarding state Mar 17 17:55:14.213593 systemd-networkd[1417]: veth229bc96: Gained carrier Mar 17 17:55:14.213846 systemd-networkd[1417]: docker0: Gained carrier Mar 17 17:55:14.251537 systemd[1]: docker-d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d.scope: Deactivated successfully. Mar 17 17:55:14.273393 containerd[1725]: time="2025-03-17T17:55:14.273316951Z" level=info msg="shim disconnected" id=d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d namespace=moby Mar 17 17:55:14.273393 containerd[1725]: time="2025-03-17T17:55:14.273389048Z" level=warning msg="cleaning up after shim disconnected" id=d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d namespace=moby Mar 17 17:55:14.273393 containerd[1725]: time="2025-03-17T17:55:14.273399848Z" level=info msg="cleaning up dead shim" namespace=moby Mar 17 17:55:14.273557 dockerd[13146]: time="2025-03-17T17:55:14.273480744Z" level=info msg="ignoring event" container=d695928bcbcdf26891f8f5641a2165f2830035df61a490b7ecb0b54e4560032d module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Mar 17 17:55:14.307822 systemd-networkd[1417]: veth229bc96: Lost carrier Mar 17 17:55:14.308433 kernel: docker0: port 1(veth229bc96) entered disabled state Mar 17 17:55:14.308579 kernel: veth7fdc6c4: renamed from eth0 Mar 17 17:55:14.333903 systemd-networkd[1417]: veth229bc96: Link DOWN Mar 17 17:55:14.337414 kernel: docker0: port 1(veth229bc96) entered disabled state Mar 17 17:55:14.343748 kernel: veth229bc96 (unregistering): left allmulticast mode Mar 17 17:55:14.343800 kernel: veth229bc96 (unregistering): left promiscuous mode Mar 17 17:55:14.346943 kernel: docker0: port 1(veth229bc96) entered disabled state Mar 17 17:55:14.497639 sshd[13423]: Connection closed by 10.200.16.10 port 43116 Mar 17 17:55:14.498059 sshd-session[13421]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:14.501371 systemd[1]: sshd@22-10.200.4.11:22-10.200.16.10:43116.service: Deactivated successfully. Mar 17 17:55:14.503170 systemd[1]: session-23.scope: Deactivated successfully. Mar 17 17:55:14.503808 systemd-logind[1714]: Session 23 logged out. Waiting for processes to exit. Mar 17 17:55:14.504595 systemd-logind[1714]: Removed session 23. Mar 17 17:55:14.603814 systemd[1]: Started sshd@23-10.200.4.11:22-10.200.16.10:43124.service - OpenSSH per-connection server daemon (10.200.16.10:43124). Mar 17 17:55:14.701552 systemd-networkd[1417]: docker0: Lost carrier Mar 17 17:55:14.964767 systemd[1]: var-lib-docker-overlay2-223971e3b7a1174ea0e2d22e2b9952689add484149e2355c08f4571d5e5be688-merged.mount: Deactivated successfully. Mar 17 17:55:15.202837 sshd[13531]: Accepted publickey for core from 10.200.16.10 port 43124 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:55:15.204299 sshd-session[13531]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:55:15.208641 systemd-logind[1714]: New session 24 of user core. Mar 17 17:55:15.220480 systemd[1]: Started session-24.scope - Session 24 of User core. Mar 17 17:55:15.586925 ntpdate[13543]: ntpdate 4.2.8p17@1.4004-o Mon Mar 17 15:34:21 UTC 2025 (1) Mar 17 17:55:22.318722 ntpdate[13543]: adjust time server 195.171.43.10 offset +0.001092 sec Mar 17 17:55:22.422041 sshd[13533]: Connection closed by 10.200.16.10 port 43124 Mar 17 17:55:22.422535 sshd-session[13531]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:22.426058 systemd[1]: sshd@23-10.200.4.11:22-10.200.16.10:43124.service: Deactivated successfully. Mar 17 17:55:22.427891 systemd[1]: session-24.scope: Deactivated successfully. Mar 17 17:55:22.428652 systemd-logind[1714]: Session 24 logged out. Waiting for processes to exit. Mar 17 17:55:22.429429 systemd-logind[1714]: Removed session 24.