Mar 25 01:31:59.274128 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.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Mar 24 23:38:35 -00 2025 Mar 25 01:31:59.274159 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.274169 kernel: BIOS-provided physical RAM map: Mar 25 01:31:59.274177 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 25 01:31:59.274184 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 25 01:31:59.274191 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 25 01:31:59.274200 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 25 01:31:59.274211 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 25 01:31:59.274219 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 25 01:31:59.274226 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 25 01:31:59.274233 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Mar 25 01:31:59.274241 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Mar 25 01:31:59.274248 kernel: printk: bootconsole [earlyser0] enabled Mar 25 01:31:59.274255 kernel: NX (Execute Disable) protection: active Mar 25 01:31:59.274268 kernel: APIC: Static calls initialized Mar 25 01:31:59.274277 kernel: efi: EFI v2.7 by Microsoft Mar 25 01:31:59.274285 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Mar 25 01:31:59.274293 kernel: random: crng init done Mar 25 01:31:59.274301 kernel: secureboot: Secure boot disabled Mar 25 01:31:59.274309 kernel: SMBIOS 3.1.0 present. Mar 25 01:31:59.274317 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 25 01:31:59.274325 kernel: Hypervisor detected: Microsoft Hyper-V Mar 25 01:31:59.274333 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Mar 25 01:31:59.274341 kernel: Hyper-V: Host Build 10.0.20348.1799-1-0 Mar 25 01:31:59.274352 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 25 01:31:59.274360 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 25 01:31:59.274369 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 25 01:31:59.274386 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 25 01:31:59.274396 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 25 01:31:59.274404 kernel: tsc: Detected 2593.992 MHz processor Mar 25 01:31:59.274413 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 25 01:31:59.274421 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 25 01:31:59.274436 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Mar 25 01:31:59.274449 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Mar 25 01:31:59.274468 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 25 01:31:59.274483 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 25 01:31:59.274501 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Mar 25 01:31:59.274510 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 25 01:31:59.274519 kernel: Using GB pages for direct mapping Mar 25 01:31:59.274527 kernel: ACPI: Early table checksum verification disabled Mar 25 01:31:59.274541 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 25 01:31:59.274554 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274562 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274571 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 25 01:31:59.274580 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 25 01:31:59.274588 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274611 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274625 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274634 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274642 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274651 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274660 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.274669 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 25 01:31:59.274678 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 25 01:31:59.274686 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 25 01:31:59.274703 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 25 01:31:59.274712 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 25 01:31:59.274720 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 25 01:31:59.274729 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Mar 25 01:31:59.274737 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Mar 25 01:31:59.274746 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 25 01:31:59.274755 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 25 01:31:59.274763 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 25 01:31:59.274772 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 25 01:31:59.274784 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Mar 25 01:31:59.274793 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Mar 25 01:31:59.274801 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Mar 25 01:31:59.274810 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Mar 25 01:31:59.274818 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 25 01:31:59.274827 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Mar 25 01:31:59.274860 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Mar 25 01:31:59.274896 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Mar 25 01:31:59.274908 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Mar 25 01:31:59.274923 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Mar 25 01:31:59.274932 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Mar 25 01:31:59.274940 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Mar 25 01:31:59.274949 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Mar 25 01:31:59.274958 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Mar 25 01:31:59.274967 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Mar 25 01:31:59.274976 kernel: Zone ranges: Mar 25 01:31:59.274985 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 25 01:31:59.274993 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 25 01:31:59.275006 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Mar 25 01:31:59.275014 kernel: Movable zone start for each node Mar 25 01:31:59.275023 kernel: Early memory node ranges Mar 25 01:31:59.275032 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 25 01:31:59.275040 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 25 01:31:59.275049 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 25 01:31:59.275058 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Mar 25 01:31:59.275066 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Mar 25 01:31:59.275075 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Mar 25 01:31:59.275087 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 25 01:31:59.275096 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 25 01:31:59.275104 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 25 01:31:59.275113 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 25 01:31:59.275122 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 25 01:31:59.275130 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Mar 25 01:31:59.275139 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 25 01:31:59.275148 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 25 01:31:59.275156 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 25 01:31:59.275168 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Mar 25 01:31:59.275177 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 25 01:31:59.275186 kernel: Booting paravirtualized kernel on Hyper-V Mar 25 01:31:59.275195 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 25 01:31:59.275203 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Mar 25 01:31:59.275212 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Mar 25 01:31:59.275221 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Mar 25 01:31:59.275229 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Mar 25 01:31:59.275238 kernel: Hyper-V: PV spinlocks enabled Mar 25 01:31:59.275250 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 25 01:31:59.275260 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.275270 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 25 01:31:59.275278 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Mar 25 01:31:59.275287 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Mar 25 01:31:59.275296 kernel: Fallback order for Node 0: 0 Mar 25 01:31:59.275304 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Mar 25 01:31:59.275313 kernel: Policy zone: Normal Mar 25 01:31:59.275325 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 25 01:31:59.275342 kernel: software IO TLB: area num 8. Mar 25 01:31:59.275352 kernel: Memory: 115326400K/117439364K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43592K init, 1472K bss, 2112708K reserved, 0K cma-reserved) Mar 25 01:31:59.275365 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 25 01:31:59.275374 kernel: Kernel/User page tables isolation: enabled Mar 25 01:31:59.275390 kernel: ftrace: allocating 37985 entries in 149 pages Mar 25 01:31:59.275407 kernel: ftrace: allocated 149 pages with 4 groups Mar 25 01:31:59.275419 kernel: Dynamic Preempt: voluntary Mar 25 01:31:59.275435 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 25 01:31:59.275450 kernel: rcu: RCU event tracing is enabled. Mar 25 01:31:59.275465 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Mar 25 01:31:59.275482 kernel: Trampoline variant of Tasks RCU enabled. Mar 25 01:31:59.275503 kernel: Rude variant of Tasks RCU enabled. Mar 25 01:31:59.275517 kernel: Tracing variant of Tasks RCU enabled. Mar 25 01:31:59.275534 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 25 01:31:59.275548 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Mar 25 01:31:59.275564 kernel: Using NULL legacy PIC Mar 25 01:31:59.275574 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Mar 25 01:31:59.275583 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 25 01:31:59.275604 kernel: Console: colour dummy device 80x25 Mar 25 01:31:59.275614 kernel: printk: console [tty1] enabled Mar 25 01:31:59.275623 kernel: printk: console [ttyS0] enabled Mar 25 01:31:59.275633 kernel: printk: bootconsole [earlyser0] disabled Mar 25 01:31:59.275642 kernel: ACPI: Core revision 20230628 Mar 25 01:31:59.275651 kernel: Failed to register legacy timer interrupt Mar 25 01:31:59.275660 kernel: APIC: Switch to symmetric I/O mode setup Mar 25 01:31:59.275674 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 25 01:31:59.275683 kernel: Hyper-V: Using IPI hypercalls Mar 25 01:31:59.275697 kernel: APIC: send_IPI() replaced with hv_send_ipi() Mar 25 01:31:59.275706 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Mar 25 01:31:59.275715 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Mar 25 01:31:59.275724 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Mar 25 01:31:59.275742 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Mar 25 01:31:59.275757 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Mar 25 01:31:59.275767 kernel: Hyper-V: Using enlightened APIC (xapic mode) Mar 25 01:31:59.275781 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Mar 25 01:31:59.275790 kernel: APIC: read() replaced with hv_apic_read() Mar 25 01:31:59.275800 kernel: APIC: write() replaced with hv_apic_write() Mar 25 01:31:59.275809 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Mar 25 01:31:59.275818 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Mar 25 01:31:59.275827 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Mar 25 01:31:59.275837 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Mar 25 01:31:59.275846 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 25 01:31:59.275855 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 25 01:31:59.275868 kernel: Spectre V2 : Mitigation: Retpolines Mar 25 01:31:59.275877 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 25 01:31:59.275886 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 25 01:31:59.275895 kernel: Speculative Store Bypass: Vulnerable Mar 25 01:31:59.275904 kernel: MDS: Mitigation: Clear CPU buffers Mar 25 01:31:59.275913 kernel: TAA: Mitigation: Clear CPU buffers Mar 25 01:31:59.275922 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 25 01:31:59.275931 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 25 01:31:59.275940 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 25 01:31:59.275949 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 25 01:31:59.275958 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 25 01:31:59.275970 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Mar 25 01:31:59.275979 kernel: Freeing SMP alternatives memory: 32K Mar 25 01:31:59.275988 kernel: pid_max: default: 32768 minimum: 301 Mar 25 01:31:59.275997 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 25 01:31:59.276006 kernel: landlock: Up and running. Mar 25 01:31:59.276015 kernel: SELinux: Initializing. Mar 25 01:31:59.276024 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.276033 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.276042 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Mar 25 01:31:59.276051 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.276061 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.276073 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.276083 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Mar 25 01:31:59.276092 kernel: signal: max sigframe size: 1776 Mar 25 01:31:59.276101 kernel: rcu: Hierarchical SRCU implementation. Mar 25 01:31:59.276110 kernel: rcu: Max phase no-delay instances is 400. Mar 25 01:31:59.276120 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 25 01:31:59.276129 kernel: smp: Bringing up secondary CPUs ... Mar 25 01:31:59.276138 kernel: smpboot: x86: Booting SMP configuration: Mar 25 01:31:59.276147 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Mar 25 01:31:59.276159 kernel: smp: Brought up 1 node, 6 CPUs Mar 25 01:31:59.276169 kernel: smpboot: Max logical packages: 1 Mar 25 01:31:59.276178 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Mar 25 01:31:59.276187 kernel: devtmpfs: initialized Mar 25 01:31:59.276196 kernel: x86/mm: Memory block size: 1024MB Mar 25 01:31:59.276205 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 25 01:31:59.276214 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 25 01:31:59.276223 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Mar 25 01:31:59.276233 kernel: pinctrl core: initialized pinctrl subsystem Mar 25 01:31:59.276245 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 25 01:31:59.276254 kernel: audit: initializing netlink subsys (disabled) Mar 25 01:31:59.276264 kernel: audit: type=2000 audit(1742866317.028:1): state=initialized audit_enabled=0 res=1 Mar 25 01:31:59.276272 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 25 01:31:59.276282 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 25 01:31:59.276291 kernel: cpuidle: using governor menu Mar 25 01:31:59.276300 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 25 01:31:59.276309 kernel: dca service started, version 1.12.1 Mar 25 01:31:59.276318 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Mar 25 01:31:59.276330 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 25 01:31:59.276340 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 25 01:31:59.276349 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 25 01:31:59.276358 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 25 01:31:59.276367 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 25 01:31:59.276376 kernel: ACPI: Added _OSI(Module Device) Mar 25 01:31:59.276385 kernel: ACPI: Added _OSI(Processor Device) Mar 25 01:31:59.276394 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 25 01:31:59.276403 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 25 01:31:59.276416 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 25 01:31:59.276425 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 25 01:31:59.276434 kernel: ACPI: Interpreter enabled Mar 25 01:31:59.276443 kernel: ACPI: PM: (supports S0 S5) Mar 25 01:31:59.276452 kernel: ACPI: Using IOAPIC for interrupt routing Mar 25 01:31:59.276461 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 25 01:31:59.276470 kernel: PCI: Ignoring E820 reservations for host bridge windows Mar 25 01:31:59.276479 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 25 01:31:59.276488 kernel: iommu: Default domain type: Translated Mar 25 01:31:59.276501 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 25 01:31:59.276510 kernel: efivars: Registered efivars operations Mar 25 01:31:59.276519 kernel: PCI: Using ACPI for IRQ routing Mar 25 01:31:59.276528 kernel: PCI: System does not support PCI Mar 25 01:31:59.276537 kernel: vgaarb: loaded Mar 25 01:31:59.276546 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 25 01:31:59.276556 kernel: VFS: Disk quotas dquot_6.6.0 Mar 25 01:31:59.276565 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 25 01:31:59.276574 kernel: pnp: PnP ACPI init Mar 25 01:31:59.276586 kernel: pnp: PnP ACPI: found 3 devices Mar 25 01:31:59.276668 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 25 01:31:59.276678 kernel: NET: Registered PF_INET protocol family Mar 25 01:31:59.276688 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.276697 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.276706 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 25 01:31:59.276716 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 25 01:31:59.276725 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.276739 kernel: TCP: Hash tables configured (established 524288 bind 65536) Mar 25 01:31:59.276749 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.276758 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.276767 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 25 01:31:59.276776 kernel: NET: Registered PF_XDP protocol family Mar 25 01:31:59.276785 kernel: PCI: CLS 0 bytes, default 64 Mar 25 01:31:59.276801 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 25 01:31:59.276812 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Mar 25 01:31:59.276822 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 25 01:31:59.276836 kernel: Initialise system trusted keyrings Mar 25 01:31:59.276845 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Mar 25 01:31:59.276854 kernel: Key type asymmetric registered Mar 25 01:31:59.276863 kernel: Asymmetric key parser 'x509' registered Mar 25 01:31:59.276872 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 25 01:31:59.276881 kernel: io scheduler mq-deadline registered Mar 25 01:31:59.276891 kernel: io scheduler kyber registered Mar 25 01:31:59.276900 kernel: io scheduler bfq registered Mar 25 01:31:59.276909 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 25 01:31:59.276918 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 25 01:31:59.276931 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 25 01:31:59.276940 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 25 01:31:59.276949 kernel: i8042: PNP: No PS/2 controller found. Mar 25 01:31:59.277120 kernel: rtc_cmos 00:02: registered as rtc0 Mar 25 01:31:59.277241 kernel: rtc_cmos 00:02: setting system clock to 2025-03-25T01:31:58 UTC (1742866318) Mar 25 01:31:59.277353 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 25 01:31:59.277367 kernel: intel_pstate: CPU model not supported Mar 25 01:31:59.277382 kernel: efifb: probing for efifb Mar 25 01:31:59.277391 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 25 01:31:59.277400 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 25 01:31:59.277410 kernel: efifb: scrolling: redraw Mar 25 01:31:59.277419 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 25 01:31:59.277428 kernel: Console: switching to colour frame buffer device 128x48 Mar 25 01:31:59.277437 kernel: fb0: EFI VGA frame buffer device Mar 25 01:31:59.277446 kernel: pstore: Using crash dump compression: deflate Mar 25 01:31:59.277455 kernel: pstore: Registered efi_pstore as persistent store backend Mar 25 01:31:59.277468 kernel: NET: Registered PF_INET6 protocol family Mar 25 01:31:59.277477 kernel: Segment Routing with IPv6 Mar 25 01:31:59.277491 kernel: In-situ OAM (IOAM) with IPv6 Mar 25 01:31:59.277501 kernel: NET: Registered PF_PACKET protocol family Mar 25 01:31:59.277510 kernel: Key type dns_resolver registered Mar 25 01:31:59.277519 kernel: IPI shorthand broadcast: enabled Mar 25 01:31:59.277528 kernel: sched_clock: Marking stable (1676004600, 101321500)->(2131279000, -353952900) Mar 25 01:31:59.277537 kernel: registered taskstats version 1 Mar 25 01:31:59.277546 kernel: Loading compiled-in X.509 certificates Mar 25 01:31:59.277559 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: eff01054e94a599f8e404b9a9482f4e2220f5386' Mar 25 01:31:59.277569 kernel: Key type .fscrypt registered Mar 25 01:31:59.277577 kernel: Key type fscrypt-provisioning registered Mar 25 01:31:59.277587 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 25 01:31:59.277610 kernel: ima: Allocated hash algorithm: sha1 Mar 25 01:31:59.277619 kernel: ima: No architecture policies found Mar 25 01:31:59.277629 kernel: clk: Disabling unused clocks Mar 25 01:31:59.277638 kernel: Freeing unused kernel image (initmem) memory: 43592K Mar 25 01:31:59.277647 kernel: Write protecting the kernel read-only data: 40960k Mar 25 01:31:59.277661 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 25 01:31:59.277670 kernel: Run /init as init process Mar 25 01:31:59.277679 kernel: with arguments: Mar 25 01:31:59.277688 kernel: /init Mar 25 01:31:59.277697 kernel: with environment: Mar 25 01:31:59.277706 kernel: HOME=/ Mar 25 01:31:59.277714 kernel: TERM=linux Mar 25 01:31:59.277724 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 25 01:31:59.277734 systemd[1]: Successfully made /usr/ read-only. Mar 25 01:31:59.277750 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 25 01:31:59.277761 systemd[1]: Detected virtualization microsoft. Mar 25 01:31:59.277770 systemd[1]: Detected architecture x86-64. Mar 25 01:31:59.277779 systemd[1]: Running in initrd. Mar 25 01:31:59.277789 systemd[1]: No hostname configured, using default hostname. Mar 25 01:31:59.277799 systemd[1]: Hostname set to . Mar 25 01:31:59.277808 systemd[1]: Initializing machine ID from random generator. Mar 25 01:31:59.277822 systemd[1]: Queued start job for default target initrd.target. Mar 25 01:31:59.277832 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:31:59.277842 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:31:59.277852 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 25 01:31:59.277862 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:31:59.277872 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 25 01:31:59.277882 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 25 01:31:59.277896 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 25 01:31:59.277906 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 25 01:31:59.277916 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:31:59.277926 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:31:59.277936 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:31:59.277946 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:31:59.277956 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:31:59.277966 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:31:59.277979 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:31:59.277989 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:31:59.277999 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 25 01:31:59.278009 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 25 01:31:59.278018 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:31:59.278028 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:31:59.278038 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:31:59.278048 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:31:59.278057 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 25 01:31:59.278071 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:31:59.278081 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 25 01:31:59.278090 systemd[1]: Starting systemd-fsck-usr.service... Mar 25 01:31:59.278100 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:31:59.278110 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:31:59.278120 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:31:59.278153 systemd-journald[205]: Collecting audit messages is disabled. Mar 25 01:31:59.278181 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 25 01:31:59.278192 systemd-journald[205]: Journal started Mar 25 01:31:59.278215 systemd-journald[205]: Runtime Journal (/run/log/journal/9a9803195d5d4d41bda1761faaf9a46b) is 8M, max 2.2G, 2.1G free. Mar 25 01:31:59.275775 systemd-modules-load[207]: Inserted module 'overlay' Mar 25 01:31:59.286610 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:31:59.286679 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:31:59.304868 systemd[1]: Finished systemd-fsck-usr.service. Mar 25 01:31:59.306488 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:31:59.315033 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:31:59.322478 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:31:59.348165 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:31:59.355338 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 25 01:31:59.355809 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:31:59.357273 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:31:59.386328 systemd-modules-load[207]: Inserted module 'br_netfilter' Mar 25 01:31:59.387891 kernel: Bridge firewalling registered Mar 25 01:31:59.390207 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:31:59.395200 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:31:59.397333 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:31:59.399699 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:31:59.428537 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:31:59.430975 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 25 01:31:59.440420 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:31:59.464676 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:31:59.480681 dracut-cmdline[238]: dracut-dracut-053 Mar 25 01:31:59.486075 dracut-cmdline[238]: 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.498277 systemd-resolved[241]: Positive Trust Anchors: Mar 25 01:31:59.498288 systemd-resolved[241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:31:59.498317 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 25 01:31:59.500867 systemd-resolved[241]: Defaulting to hostname 'linux'. Mar 25 01:31:59.501962 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:31:59.510993 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:31:59.603618 kernel: SCSI subsystem initialized Mar 25 01:31:59.614614 kernel: Loading iSCSI transport class v2.0-870. Mar 25 01:31:59.626612 kernel: iscsi: registered transport (tcp) Mar 25 01:31:59.651490 kernel: iscsi: registered transport (qla4xxx) Mar 25 01:31:59.651546 kernel: QLogic iSCSI HBA Driver Mar 25 01:31:59.692371 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 25 01:31:59.695155 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 25 01:31:59.747021 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 25 01:31:59.747072 kernel: device-mapper: uevent: version 1.0.3 Mar 25 01:31:59.751557 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 25 01:31:59.798621 kernel: raid6: avx2x4 gen() 29865 MB/s Mar 25 01:31:59.818613 kernel: raid6: avx2x2 gen() 30766 MB/s Mar 25 01:31:59.839736 kernel: raid6: avx2x1 gen() 27826 MB/s Mar 25 01:31:59.839762 kernel: raid6: using algorithm avx2x2 gen() 30766 MB/s Mar 25 01:31:59.861785 kernel: raid6: .... xor() 18317 MB/s, rmw enabled Mar 25 01:31:59.861811 kernel: raid6: using avx2x2 recovery algorithm Mar 25 01:31:59.885612 kernel: xor: automatically using best checksumming function avx Mar 25 01:32:00.029622 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 25 01:32:00.040435 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:32:00.046039 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:00.081814 systemd-udevd[426]: Using default interface naming scheme 'v255'. Mar 25 01:32:00.087576 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:00.089766 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 25 01:32:00.126238 dracut-pre-trigger[434]: rd.md=0: removing MD RAID activation Mar 25 01:32:00.153181 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:32:00.155699 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:32:00.234519 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:00.237985 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 25 01:32:00.262610 kernel: cryptd: max_cpu_qlen set to 1000 Mar 25 01:32:00.275959 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 25 01:32:00.298895 kernel: AVX2 version of gcm_enc/dec engaged. Mar 25 01:32:00.298923 kernel: AES CTR mode by8 optimization enabled Mar 25 01:32:00.292352 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:32:00.307886 kernel: hv_vmbus: Vmbus version:4.0 Mar 25 01:32:00.298996 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:00.303896 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:32:00.315567 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 25 01:32:00.323492 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:32:00.323711 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:00.331417 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:32:00.332896 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:32:00.373283 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 25 01:32:00.373311 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 25 01:32:00.333070 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:00.353191 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:00.386611 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 25 01:32:00.386642 kernel: hv_vmbus: registering driver hv_netvsc Mar 25 01:32:00.386669 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:00.408808 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 25 01:32:00.408834 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 25 01:32:00.403890 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:00.415023 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:32:00.424871 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:00.468713 kernel: PTP clock support registered Mar 25 01:32:00.468739 kernel: hv_vmbus: registering driver hv_pci Mar 25 01:32:00.468753 kernel: hv_vmbus: registering driver hid_hyperv Mar 25 01:32:00.468766 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 25 01:32:00.468778 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Mar 25 01:32:00.716426 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 25 01:32:00.716586 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Mar 25 01:32:00.716769 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Mar 25 01:32:00.716948 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Mar 25 01:32:00.717079 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Mar 25 01:32:00.717207 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Mar 25 01:32:00.717369 kernel: hv_vmbus: registering driver hv_storvsc Mar 25 01:32:00.717384 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Mar 25 01:32:00.717525 kernel: hv_utils: Registering HyperV Utility Driver Mar 25 01:32:00.717538 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 25 01:32:00.717697 kernel: hv_vmbus: registering driver hv_utils Mar 25 01:32:00.717711 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 25 01:32:00.717868 kernel: pci 0001:00:00.0: Enabling HDA controller Mar 25 01:32:00.718028 kernel: hv_utils: Heartbeat IC version 3.0 Mar 25 01:32:00.718045 kernel: scsi host1: storvsc_host_t Mar 25 01:32:00.718188 kernel: scsi host0: storvsc_host_t Mar 25 01:32:00.718339 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 25 01:32:00.718488 kernel: hv_utils: Shutdown IC version 3.2 Mar 25 01:32:00.718502 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 25 01:32:00.718663 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 25 01:32:00.721300 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Mar 25 01:32:00.721461 kernel: hv_utils: TimeSync IC version 4.0 Mar 25 01:32:00.721476 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 25 01:32:00.721638 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 25 01:32:00.721883 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Mar 25 01:32:00.722046 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 25 01:32:00.722190 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 25 01:32:00.722203 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 25 01:32:00.438725 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:32:00.691298 systemd-resolved[241]: Clock change detected. Flushing caches. Mar 25 01:32:00.738159 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:00.759821 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 25 01:32:00.790737 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Mar 25 01:32:00.790963 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 25 01:32:00.791118 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Mar 25 01:32:00.791260 kernel: sd 0:0:0:1: [sdb] Write Protect is off Mar 25 01:32:00.791405 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 25 01:32:00.791551 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Mar 25 01:32:00.791694 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 25 01:32:00.791862 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 25 01:32:00.792003 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 25 01:32:00.792144 kernel: sdb: sdb1 Mar 25 01:32:00.792158 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:00.792169 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Mar 25 01:32:00.792303 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 25 01:32:01.412809 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by (udev-worker) (480) Mar 25 01:32:01.433961 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 25 01:32:01.473732 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 25 01:32:01.517865 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 25 01:32:01.533647 kernel: BTRFS: device fsid 6d9424cd-1432-492b-b006-b311869817e2 devid 1 transid 39 /dev/sda3 scanned by (udev-worker) (473) Mar 25 01:32:01.551658 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 25 01:32:01.553160 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 25 01:32:01.563599 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 25 01:32:01.598799 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:01.605800 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:02.612811 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:02.612875 disk-uuid[641]: The operation has completed successfully. Mar 25 01:32:04.471685 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 25 01:32:04.471812 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 25 01:32:04.480488 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 25 01:32:04.509538 sh[728]: Success Mar 25 01:32:04.535797 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 25 01:32:04.724367 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 25 01:32:04.734642 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 25 01:32:04.756309 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 25 01:32:04.775819 kernel: BTRFS info (device dm-0): first mount of filesystem 6d9424cd-1432-492b-b006-b311869817e2 Mar 25 01:32:04.775856 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:04.780677 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 25 01:32:04.784248 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 25 01:32:04.787321 kernel: BTRFS info (device dm-0): using free space tree Mar 25 01:32:05.226455 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 25 01:32:05.228645 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 25 01:32:05.229872 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 25 01:32:05.235232 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 25 01:32:05.291995 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:05.292042 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:05.295106 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:05.334801 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:05.342812 kernel: BTRFS info (device sda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:05.349625 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 25 01:32:05.354009 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:32:05.364580 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 25 01:32:05.388842 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:32:05.418866 systemd-networkd[909]: lo: Link UP Mar 25 01:32:05.418876 systemd-networkd[909]: lo: Gained carrier Mar 25 01:32:05.420004 systemd-networkd[909]: Enumeration completed Mar 25 01:32:05.420077 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:32:05.420381 systemd-networkd[909]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:05.420386 systemd-networkd[909]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:05.421428 systemd[1]: Reached target network.target - Network. Mar 25 01:32:05.421895 systemd-networkd[909]: eth0: Link UP Mar 25 01:32:05.422068 systemd-networkd[909]: eth0: Gained carrier Mar 25 01:32:05.422077 systemd-networkd[909]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:05.486816 systemd-networkd[909]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:06.732457 ignition[908]: Ignition 2.20.0 Mar 25 01:32:06.732470 ignition[908]: Stage: fetch-offline Mar 25 01:32:06.732505 ignition[908]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:06.732515 ignition[908]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:06.732628 ignition[908]: parsed url from cmdline: "" Mar 25 01:32:06.732633 ignition[908]: no config URL provided Mar 25 01:32:06.732639 ignition[908]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:32:06.732649 ignition[908]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:32:06.732658 ignition[908]: failed to fetch config: resource requires networking Mar 25 01:32:06.732848 ignition[908]: Ignition finished successfully Mar 25 01:32:06.759683 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:32:06.762738 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 25 01:32:06.795424 ignition[922]: Ignition 2.20.0 Mar 25 01:32:06.795438 ignition[922]: Stage: fetch Mar 25 01:32:06.795586 ignition[922]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:06.795600 ignition[922]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:06.795709 ignition[922]: parsed url from cmdline: "" Mar 25 01:32:06.795713 ignition[922]: no config URL provided Mar 25 01:32:06.795720 ignition[922]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:32:06.795732 ignition[922]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:32:06.795760 ignition[922]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 25 01:32:06.873222 ignition[922]: GET result: OK Mar 25 01:32:06.873241 ignition[922]: failed to retrieve userdata from IMDS, falling back to custom data: not a config (empty) Mar 25 01:32:06.911450 ignition[922]: opening config device: "/dev/sr0" Mar 25 01:32:06.911889 ignition[922]: getting drive status for "/dev/sr0" Mar 25 01:32:06.911966 ignition[922]: drive status: OK Mar 25 01:32:06.911997 ignition[922]: mounting config device Mar 25 01:32:06.912005 ignition[922]: op(1): [started] mounting "/dev/sr0" at "/tmp/ignition-azure617698951" Mar 25 01:32:06.932247 ignition[922]: op(1): [finished] mounting "/dev/sr0" at "/tmp/ignition-azure617698951" Mar 25 01:32:06.937006 kernel: UDF-fs: INFO Mounting volume 'UDF Volume', timestamp 2025/03/26 00:00 (1000) Mar 25 01:32:06.934458 systemd[1]: tmp-ignition\x2dazure617698951.mount: Deactivated successfully. Mar 25 01:32:06.932258 ignition[922]: checking for config drive Mar 25 01:32:06.941254 unknown[922]: fetched base config from "system" Mar 25 01:32:06.933722 ignition[922]: reading config Mar 25 01:32:06.941261 unknown[922]: fetched base config from "system" Mar 25 01:32:06.934079 ignition[922]: op(2): [started] unmounting "/dev/sr0" at "/tmp/ignition-azure617698951" Mar 25 01:32:06.941267 unknown[922]: fetched user config from "azure" Mar 25 01:32:06.937014 ignition[922]: op(2): [finished] unmounting "/dev/sr0" at "/tmp/ignition-azure617698951" Mar 25 01:32:06.954389 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 25 01:32:06.937029 ignition[922]: config has been read from custom data Mar 25 01:32:06.957884 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 25 01:32:06.937040 ignition[922]: parsing config with SHA512: d5ce31af04bccb35431af59862489f612c022796cabf891b46b24c4dae13e8ea30c1ec142740d9ff91a93ccb8e5da9dd6ee50a167502856a91a893c0c1d60e4d Mar 25 01:32:06.941480 ignition[922]: fetch: fetch complete Mar 25 01:32:06.941485 ignition[922]: fetch: fetch passed Mar 25 01:32:06.941529 ignition[922]: Ignition finished successfully Mar 25 01:32:06.999742 ignition[932]: Ignition 2.20.0 Mar 25 01:32:06.999753 ignition[932]: Stage: kargs Mar 25 01:32:06.999922 ignition[932]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:06.999936 ignition[932]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:07.000443 ignition[932]: kargs: kargs passed Mar 25 01:32:07.012258 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 25 01:32:07.000484 ignition[932]: Ignition finished successfully Mar 25 01:32:07.025455 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 25 01:32:07.059737 ignition[942]: Ignition 2.20.0 Mar 25 01:32:07.059750 ignition[942]: Stage: disks Mar 25 01:32:07.059937 ignition[942]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:07.059950 ignition[942]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:07.060524 ignition[942]: disks: disks passed Mar 25 01:32:07.060567 ignition[942]: Ignition finished successfully Mar 25 01:32:07.072591 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 25 01:32:07.078358 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 25 01:32:07.085478 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 25 01:32:07.093983 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:32:07.095369 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:32:07.108210 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:32:07.116958 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 25 01:32:07.227527 systemd-fsck[954]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 25 01:32:07.233434 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 25 01:32:07.235921 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 25 01:32:07.348799 kernel: EXT4-fs (sda9): mounted filesystem 4e6dca82-2e50-453c-be25-61f944b72008 r/w with ordered data mode. Quota mode: none. Mar 25 01:32:07.348984 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 25 01:32:07.351007 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 25 01:32:07.380241 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:32:07.382483 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 25 01:32:07.396339 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 25 01:32:07.420777 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (965) Mar 25 01:32:07.420818 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:07.420831 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:07.420843 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:07.397864 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 25 01:32:07.437211 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:07.397897 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:32:07.423566 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 25 01:32:07.439796 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:32:07.448651 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 25 01:32:07.452727 systemd-networkd[909]: eth0: Gained IPv6LL Mar 25 01:32:08.533356 coreos-metadata[967]: Mar 25 01:32:08.533 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 25 01:32:08.541113 coreos-metadata[967]: Mar 25 01:32:08.541 INFO Fetch successful Mar 25 01:32:08.544816 coreos-metadata[967]: Mar 25 01:32:08.541 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 25 01:32:08.552768 coreos-metadata[967]: Mar 25 01:32:08.548 INFO Fetch successful Mar 25 01:32:08.560367 coreos-metadata[967]: Mar 25 01:32:08.560 INFO wrote hostname ci-4284.0.0-a-5b60e4cce0 to /sysroot/etc/hostname Mar 25 01:32:08.567731 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 25 01:32:08.690452 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory Mar 25 01:32:08.746716 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory Mar 25 01:32:08.778759 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory Mar 25 01:32:08.785139 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory Mar 25 01:32:10.192255 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 25 01:32:10.195014 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 25 01:32:10.218324 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 25 01:32:10.229691 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 25 01:32:10.238414 kernel: BTRFS info (device sda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:10.262976 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 25 01:32:10.274566 ignition[1085]: INFO : Ignition 2.20.0 Mar 25 01:32:10.274566 ignition[1085]: INFO : Stage: mount Mar 25 01:32:10.280378 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:10.280378 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:10.280378 ignition[1085]: INFO : mount: mount passed Mar 25 01:32:10.280378 ignition[1085]: INFO : Ignition finished successfully Mar 25 01:32:10.281042 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 25 01:32:10.289497 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 25 01:32:10.307009 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:32:10.343773 kernel: BTRFS: device label OEM devid 1 transid 18 /dev/sda6 scanned by mount (1098) Mar 25 01:32:10.343817 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:10.347977 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:10.351005 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:10.357796 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:10.359119 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:32:10.386149 ignition[1115]: INFO : Ignition 2.20.0 Mar 25 01:32:10.386149 ignition[1115]: INFO : Stage: files Mar 25 01:32:10.391694 ignition[1115]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:10.391694 ignition[1115]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:10.391694 ignition[1115]: DEBUG : files: compiled without relabeling support, skipping Mar 25 01:32:10.437477 ignition[1115]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 25 01:32:10.437477 ignition[1115]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 25 01:32:10.525926 ignition[1115]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 25 01:32:10.531498 ignition[1115]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 25 01:32:10.536810 ignition[1115]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 25 01:32:10.531866 unknown[1115]: wrote ssh authorized keys file for user: core Mar 25 01:32:10.548139 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:32:10.555141 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:32:10.555141 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:32:10.555141 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:32:10.555141 ignition[1115]: INFO : files: files passed Mar 25 01:32:10.555141 ignition[1115]: INFO : Ignition finished successfully Mar 25 01:32:10.571317 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 25 01:32:10.574914 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 25 01:32:10.604496 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 25 01:32:10.615620 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 25 01:32:10.615739 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 25 01:32:10.636117 initrd-setup-root-after-ignition[1148]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.636117 initrd-setup-root-after-ignition[1148]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.648636 initrd-setup-root-after-ignition[1152]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.656073 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:32:10.657766 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 25 01:32:10.668964 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 25 01:32:10.717816 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 25 01:32:10.717943 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 25 01:32:10.726803 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 25 01:32:10.735406 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 25 01:32:10.743221 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 25 01:32:10.744276 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 25 01:32:10.785331 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:32:10.788434 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 25 01:32:10.823298 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:32:10.825081 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:10.833949 systemd[1]: Stopped target timers.target - Timer Units. Mar 25 01:32:10.851171 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 25 01:32:10.851306 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:32:10.865440 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 25 01:32:10.874422 systemd[1]: Stopped target basic.target - Basic System. Mar 25 01:32:10.877137 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 25 01:32:10.886917 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:32:10.894849 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 25 01:32:10.908541 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 25 01:32:10.916938 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:32:10.925586 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 25 01:32:10.934413 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 25 01:32:10.942394 systemd[1]: Stopped target swap.target - Swaps. Mar 25 01:32:10.949177 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 25 01:32:10.949307 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:32:10.960097 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:32:10.961545 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:32:10.974255 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 25 01:32:10.974381 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:32:10.983762 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 25 01:32:10.983900 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 25 01:32:11.000758 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 25 01:32:11.000904 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:32:11.003084 systemd[1]: ignition-files.service: Deactivated successfully. Mar 25 01:32:11.003194 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 25 01:32:11.010883 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 25 01:32:11.010998 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 25 01:32:11.019722 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 25 01:32:11.028095 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 25 01:32:11.028221 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:32:11.048322 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 25 01:32:11.084604 ignition[1172]: INFO : Ignition 2.20.0 Mar 25 01:32:11.084604 ignition[1172]: INFO : Stage: umount Mar 25 01:32:11.084604 ignition[1172]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:11.084604 ignition[1172]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:11.084604 ignition[1172]: INFO : umount: umount passed Mar 25 01:32:11.084604 ignition[1172]: INFO : Ignition finished successfully Mar 25 01:32:11.054723 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 25 01:32:11.054867 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:11.065087 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 25 01:32:11.065210 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:32:11.080588 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 25 01:32:11.083350 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 25 01:32:11.083447 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 25 01:32:11.092289 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 25 01:32:11.092431 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 25 01:32:11.098734 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 25 01:32:11.098826 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 25 01:32:11.104611 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 25 01:32:11.104665 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 25 01:32:11.106853 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 25 01:32:11.106902 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 25 01:32:11.117760 systemd[1]: Stopped target network.target - Network. Mar 25 01:32:11.127351 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 25 01:32:11.127408 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:32:11.135392 systemd[1]: Stopped target paths.target - Path Units. Mar 25 01:32:11.142666 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 25 01:32:11.147843 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:32:11.155548 systemd[1]: Stopped target slices.target - Slice Units. Mar 25 01:32:11.156939 systemd[1]: Stopped target sockets.target - Socket Units. Mar 25 01:32:11.164687 systemd[1]: iscsid.socket: Deactivated successfully. Mar 25 01:32:11.164732 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:32:11.171872 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 25 01:32:11.171910 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:32:11.178722 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 25 01:32:11.178770 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 25 01:32:11.191724 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 25 01:32:11.191791 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 25 01:32:11.203658 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 25 01:32:11.211213 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 25 01:32:11.222928 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 25 01:32:11.223095 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 25 01:32:11.235674 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 25 01:32:11.235894 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 25 01:32:11.236000 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 25 01:32:11.247482 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 25 01:32:11.247728 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 25 01:32:11.247835 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 25 01:32:11.252754 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 25 01:32:11.252829 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:32:11.259201 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 25 01:32:11.259254 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 25 01:32:11.268819 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 25 01:32:11.275589 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 25 01:32:11.275662 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:32:11.287135 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 25 01:32:11.287186 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:32:11.304058 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 25 01:32:11.304111 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 25 01:32:11.311743 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 25 01:32:11.311807 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:32:11.320993 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:11.323414 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 25 01:32:11.323480 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:11.360444 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 25 01:32:11.360592 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:11.363023 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 25 01:32:11.363070 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 25 01:32:11.374928 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 25 01:32:11.374964 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:32:11.383132 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 25 01:32:11.383185 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:32:11.399537 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 25 01:32:11.399591 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 25 01:32:11.412404 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:32:11.412459 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:11.425174 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 25 01:32:11.432918 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 25 01:32:11.594014 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Mar 25 01:32:11.432974 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:32:11.445979 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:32:11.446031 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:11.459347 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 25 01:32:11.459410 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:11.460024 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 25 01:32:11.460123 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 25 01:32:11.466310 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 25 01:32:11.466409 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 25 01:32:11.472269 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 25 01:32:11.482045 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 25 01:32:11.516068 systemd[1]: Switching root. Mar 25 01:32:11.639144 systemd-journald[205]: Journal stopped Mar 25 01:32:17.229358 kernel: SELinux: policy capability network_peer_controls=1 Mar 25 01:32:17.229393 kernel: SELinux: policy capability open_perms=1 Mar 25 01:32:17.229406 kernel: SELinux: policy capability extended_socket_class=1 Mar 25 01:32:17.229415 kernel: SELinux: policy capability always_check_network=0 Mar 25 01:32:17.229424 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 25 01:32:17.229433 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 25 01:32:17.229444 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 25 01:32:17.229456 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 25 01:32:17.229466 kernel: audit: type=1403 audit(1742866332.905:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 25 01:32:17.229482 systemd[1]: Successfully loaded SELinux policy in 216.769ms. Mar 25 01:32:17.229494 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.814ms. Mar 25 01:32:17.229505 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 25 01:32:17.229516 systemd[1]: Detected virtualization microsoft. Mar 25 01:32:17.229526 systemd[1]: Detected architecture x86-64. Mar 25 01:32:17.229540 systemd[1]: Detected first boot. Mar 25 01:32:17.229551 systemd[1]: Hostname set to . Mar 25 01:32:17.229562 systemd[1]: Initializing machine ID from random generator. Mar 25 01:32:17.229573 zram_generator::config[1222]: No configuration found. Mar 25 01:32:17.229585 kernel: Guest personality initialized and is inactive Mar 25 01:32:17.229597 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Mar 25 01:32:17.229607 kernel: Initialized host personality Mar 25 01:32:17.229617 kernel: NET: Registered PF_VSOCK protocol family Mar 25 01:32:17.229627 systemd[1]: Populated /etc with preset unit settings. Mar 25 01:32:17.229638 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 25 01:32:17.229649 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 25 01:32:17.229659 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 25 01:32:17.229670 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 25 01:32:17.229683 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 25 01:32:17.229695 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 25 01:32:17.229705 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 25 01:32:17.229719 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 25 01:32:17.229730 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 25 01:32:17.229741 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 25 01:32:17.229752 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 25 01:32:17.229765 systemd[1]: Created slice user.slice - User and Session Slice. Mar 25 01:32:17.229777 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:32:17.229840 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:32:17.229853 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 25 01:32:17.229864 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 25 01:32:17.229875 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 25 01:32:17.229892 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:32:17.229903 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 25 01:32:17.229914 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:32:17.229928 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 25 01:32:17.229939 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 25 01:32:17.229950 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 25 01:32:17.229961 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 25 01:32:17.229972 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:17.229983 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:32:17.229994 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:32:17.230008 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:32:17.230023 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 25 01:32:17.230035 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 25 01:32:17.230046 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 25 01:32:17.230058 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:32:17.230072 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:32:17.230083 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:32:17.230094 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 25 01:32:17.230105 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 25 01:32:17.230117 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 25 01:32:17.230128 systemd[1]: Mounting media.mount - External Media Directory... Mar 25 01:32:17.230139 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:17.230150 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 25 01:32:17.230164 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 25 01:32:17.230176 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 25 01:32:17.230187 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 25 01:32:17.230199 systemd[1]: Reached target machines.target - Containers. Mar 25 01:32:17.230210 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 25 01:32:17.230221 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:32:17.230232 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:32:17.230243 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 25 01:32:17.230257 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:32:17.230272 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:32:17.230283 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:32:17.230294 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 25 01:32:17.230306 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:32:17.230317 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 25 01:32:17.230328 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 25 01:32:17.230340 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 25 01:32:17.230351 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 25 01:32:17.230365 systemd[1]: Stopped systemd-fsck-usr.service. Mar 25 01:32:17.230377 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 25 01:32:17.230388 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:32:17.230399 kernel: fuse: init (API version 7.39) Mar 25 01:32:17.230410 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:32:17.230421 kernel: loop: module loaded Mar 25 01:32:17.230431 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 25 01:32:17.230468 systemd-journald[1329]: Collecting audit messages is disabled. Mar 25 01:32:17.230497 systemd-journald[1329]: Journal started Mar 25 01:32:17.230523 systemd-journald[1329]: Runtime Journal (/run/log/journal/226d45cc37c249f48f90baa6a5e5740c) is 8M, max 2.2G, 2.1G free. Mar 25 01:32:16.475132 systemd[1]: Queued start job for default target multi-user.target. Mar 25 01:32:16.486448 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 25 01:32:16.486986 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 25 01:32:17.240839 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 25 01:32:17.253435 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 25 01:32:17.264182 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:32:17.274059 systemd[1]: verity-setup.service: Deactivated successfully. Mar 25 01:32:17.274098 systemd[1]: Stopped verity-setup.service. Mar 25 01:32:17.286802 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:17.294266 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:32:17.297721 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 25 01:32:17.302975 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 25 01:32:17.307452 systemd[1]: Mounted media.mount - External Media Directory. Mar 25 01:32:17.311628 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 25 01:32:17.316028 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 25 01:32:17.320887 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 25 01:32:17.325009 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 25 01:32:17.329961 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:32:17.335714 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 25 01:32:17.335962 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 25 01:32:17.341413 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:32:17.341645 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:32:17.346717 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:32:17.346942 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:32:17.352794 kernel: ACPI: bus type drm_connector registered Mar 25 01:32:17.354580 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 25 01:32:17.354774 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 25 01:32:17.359398 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:32:17.359601 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:32:17.364066 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:32:17.364466 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:32:17.369259 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:32:17.374109 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 25 01:32:17.379506 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 25 01:32:17.385010 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 25 01:32:17.399980 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 25 01:32:17.405993 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 25 01:32:17.420260 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 25 01:32:17.424838 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 25 01:32:17.424881 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:32:17.430068 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 25 01:32:17.436041 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 25 01:32:17.457258 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 25 01:32:17.461266 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:32:17.462631 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 25 01:32:17.467757 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 25 01:32:17.472282 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:32:17.473222 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 25 01:32:17.477446 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:32:17.478418 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:32:17.483440 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 25 01:32:17.489301 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 25 01:32:17.495407 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:17.500347 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 25 01:32:17.505272 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 25 01:32:17.511828 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 25 01:32:17.520692 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 25 01:32:17.525417 systemd-journald[1329]: Time spent on flushing to /var/log/journal/226d45cc37c249f48f90baa6a5e5740c is 17.568ms for 953 entries. Mar 25 01:32:17.525417 systemd-journald[1329]: System Journal (/var/log/journal/226d45cc37c249f48f90baa6a5e5740c) is 8M, max 2.6G, 2.6G free. Mar 25 01:32:17.591619 systemd-journald[1329]: Received client request to flush runtime journal. Mar 25 01:32:17.548030 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 25 01:32:17.553423 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 25 01:32:17.560757 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 25 01:32:17.565654 udevadm[1373]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 25 01:32:17.592604 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 25 01:32:17.616798 kernel: loop0: detected capacity change from 0 to 109808 Mar 25 01:32:17.667038 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:32:17.684822 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 25 01:32:17.685501 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 25 01:32:17.776895 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 25 01:32:17.782990 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:32:17.865850 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Mar 25 01:32:17.865871 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Mar 25 01:32:17.871035 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:32:18.312807 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 25 01:32:18.359140 kernel: loop1: detected capacity change from 0 to 151640 Mar 25 01:32:18.796811 kernel: loop2: detected capacity change from 0 to 28424 Mar 25 01:32:19.231327 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 25 01:32:19.238451 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:19.288539 systemd-udevd[1389]: Using default interface naming scheme 'v255'. Mar 25 01:32:19.484387 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:19.493111 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:32:19.496839 kernel: loop3: detected capacity change from 0 to 109808 Mar 25 01:32:19.513327 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 25 01:32:19.515100 kernel: loop4: detected capacity change from 0 to 151640 Mar 25 01:32:19.535903 kernel: loop5: detected capacity change from 0 to 28424 Mar 25 01:32:19.545122 (sd-merge)[1403]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 25 01:32:19.545607 (sd-merge)[1403]: Merged extensions into '/usr'. Mar 25 01:32:19.550331 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 25 01:32:19.558149 systemd[1]: Starting ensure-sysext.service... Mar 25 01:32:19.561514 kernel: hv_vmbus: registering driver hyperv_fb Mar 25 01:32:19.566560 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 25 01:32:19.566656 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 25 01:32:19.567251 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:32:19.579765 kernel: Console: switching to colour dummy device 80x25 Mar 25 01:32:19.583809 kernel: Console: switching to colour frame buffer device 128x48 Mar 25 01:32:19.583858 kernel: mousedev: PS/2 mouse device common for all mice Mar 25 01:32:19.594429 kernel: hv_vmbus: registering driver hv_balloon Mar 25 01:32:19.594485 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 25 01:32:19.607588 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 25 01:32:19.640802 kernel: EDAC MC: Ver: 3.0.0 Mar 25 01:32:19.642232 systemd-tmpfiles[1438]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 25 01:32:19.642299 systemd[1]: Reload requested from client PID 1435 ('systemctl') (unit ensure-sysext.service)... Mar 25 01:32:19.642309 systemd[1]: Reloading... Mar 25 01:32:19.642538 systemd-tmpfiles[1438]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 25 01:32:19.643472 systemd-tmpfiles[1438]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 25 01:32:19.643766 systemd-tmpfiles[1438]: ACLs are not supported, ignoring. Mar 25 01:32:19.643894 systemd-tmpfiles[1438]: ACLs are not supported, ignoring. Mar 25 01:32:19.650484 systemd-tmpfiles[1438]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:32:19.650498 systemd-tmpfiles[1438]: Skipping /boot Mar 25 01:32:19.663987 systemd-tmpfiles[1438]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:32:19.664001 systemd-tmpfiles[1438]: Skipping /boot Mar 25 01:32:19.749151 zram_generator::config[1493]: No configuration found. Mar 25 01:32:19.758845 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1402) Mar 25 01:32:19.888297 systemd-networkd[1406]: lo: Link UP Mar 25 01:32:19.888309 systemd-networkd[1406]: lo: Gained carrier Mar 25 01:32:19.889668 systemd-networkd[1406]: Enumeration completed Mar 25 01:32:19.890111 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:19.890122 systemd-networkd[1406]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:19.890697 systemd-networkd[1406]: eth0: Link UP Mar 25 01:32:19.890706 systemd-networkd[1406]: eth0: Gained carrier Mar 25 01:32:19.890720 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:19.892149 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 25 01:32:19.923817 systemd-networkd[1406]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:20.025693 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 25 01:32:20.030954 systemd[1]: Reloading finished in 388 ms. Mar 25 01:32:20.058930 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 25 01:32:20.063912 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:32:20.091420 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:32:20.150106 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 25 01:32:20.155689 systemd[1]: Finished ensure-sysext.service. Mar 25 01:32:20.161821 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:20.163267 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:32:20.184921 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 25 01:32:20.189749 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:32:20.190829 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 25 01:32:20.196451 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:32:20.201501 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:32:20.206453 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:32:20.212903 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:32:20.217071 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:32:20.219009 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 25 01:32:20.223580 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 25 01:32:20.227311 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 25 01:32:20.233180 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 25 01:32:20.238763 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 25 01:32:20.245287 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:32:20.246766 systemd[1]: Reached target time-set.target - System Time Set. Mar 25 01:32:20.248409 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 25 01:32:20.249915 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:20.250375 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:20.251596 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:32:20.251777 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:32:20.252846 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:32:20.253018 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:32:20.253606 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:32:20.253793 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:32:20.254242 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:32:20.254405 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:32:20.260075 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:32:20.260258 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:32:20.261366 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 25 01:32:20.284743 lvm[1608]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:32:20.286461 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 25 01:32:20.319125 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 25 01:32:20.321760 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 25 01:32:20.323270 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:32:20.325291 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 25 01:32:20.349297 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 25 01:32:20.353164 lvm[1650]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:32:20.368878 augenrules[1654]: No rules Mar 25 01:32:20.370199 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:32:20.370449 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:32:20.392420 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 25 01:32:20.526386 systemd-resolved[1624]: Positive Trust Anchors: Mar 25 01:32:20.526409 systemd-resolved[1624]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:32:20.526440 systemd-resolved[1624]: 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 25 01:32:20.530640 systemd-resolved[1624]: Using system hostname 'ci-4284.0.0-a-5b60e4cce0'. Mar 25 01:32:20.532477 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:32:20.534140 systemd[1]: Reached target network.target - Network. Mar 25 01:32:20.534836 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:32:20.582759 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 25 01:32:20.584536 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 25 01:32:20.594743 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:21.500928 systemd-networkd[1406]: eth0: Gained IPv6LL Mar 25 01:32:21.503218 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 25 01:32:21.508611 systemd[1]: Reached target network-online.target - Network is Online. Mar 25 01:32:24.268846 ldconfig[1359]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:32:24.284809 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 25 01:32:24.291907 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 25 01:32:24.313809 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 25 01:32:24.318270 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:32:24.322844 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 25 01:32:24.327838 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 25 01:32:24.333058 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 25 01:32:24.337428 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 25 01:32:24.342506 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 25 01:32:24.347483 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 25 01:32:24.347518 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:32:24.351157 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:32:24.366089 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 25 01:32:24.371721 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 25 01:32:24.378227 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 25 01:32:24.383400 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 25 01:32:24.388618 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 25 01:32:24.394697 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 25 01:32:24.399396 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 25 01:32:24.404972 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 25 01:32:24.409374 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:32:24.413172 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:32:24.416942 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:32:24.416977 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:32:24.419383 systemd[1]: Starting chronyd.service - NTP client/server... Mar 25 01:32:24.423689 systemd[1]: Starting containerd.service - containerd container runtime... Mar 25 01:32:24.435659 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 25 01:32:24.440999 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 25 01:32:24.445516 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 25 01:32:24.450444 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 25 01:32:24.454333 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 25 01:32:24.454380 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 25 01:32:24.455373 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 25 01:32:24.459565 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 25 01:32:24.460589 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 25 01:32:24.461065 jq[1678]: false Mar 25 01:32:24.463525 KVP[1680]: KVP starting; pid is:1680 Mar 25 01:32:24.465438 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 25 01:32:24.467257 KVP[1680]: KVP LIC Version: 3.1 Mar 25 01:32:24.467797 kernel: hv_utils: KVP IC version 4.0 Mar 25 01:32:24.472133 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 25 01:32:24.477656 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 25 01:32:24.478167 (chronyd)[1671]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 25 01:32:24.484815 chronyd[1690]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 25 01:32:24.485558 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 25 01:32:24.487891 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 25 01:32:24.488311 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 25 01:32:24.488879 systemd[1]: Starting update-engine.service - Update Engine... Mar 25 01:32:24.495263 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 25 01:32:24.502865 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 25 01:32:24.503118 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 25 01:32:24.503436 systemd[1]: motdgen.service: Deactivated successfully. Mar 25 01:32:24.503637 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 25 01:32:24.506452 jq[1697]: true Mar 25 01:32:24.508119 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 25 01:32:24.508343 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 25 01:32:24.517700 chronyd[1690]: Timezone right/UTC failed leap second check, ignoring Mar 25 01:32:24.517943 chronyd[1690]: Loaded seccomp filter (level 2) Mar 25 01:32:24.519870 (ntainerd)[1705]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 25 01:32:24.520220 systemd[1]: Started chronyd.service - NTP client/server. Mar 25 01:32:24.521455 jq[1704]: true Mar 25 01:32:24.539501 extend-filesystems[1679]: Found loop3 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found loop4 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found loop5 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda1 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda2 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda3 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found usr Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda4 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda6 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda7 Mar 25 01:32:24.542342 extend-filesystems[1679]: Found sda9 Mar 25 01:32:24.542342 extend-filesystems[1679]: Checking size of /dev/sda9 Mar 25 01:32:24.574806 update_engine[1696]: I20250325 01:32:24.573096 1696 main.cc:92] Flatcar Update Engine starting Mar 25 01:32:24.600443 systemd-logind[1693]: New seat seat0. Mar 25 01:32:24.601610 systemd-logind[1693]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 25 01:32:24.601849 systemd[1]: Started systemd-logind.service - User Login Management. Mar 25 01:32:24.700878 dbus-daemon[1674]: [system] SELinux support is enabled Mar 25 01:32:24.701096 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 25 01:32:24.703890 update_engine[1696]: I20250325 01:32:24.703830 1696 update_check_scheduler.cc:74] Next update check in 2m5s Mar 25 01:32:24.706435 extend-filesystems[1679]: Old size kept for /dev/sda9 Mar 25 01:32:24.714333 extend-filesystems[1679]: Found sdb Mar 25 01:32:24.714333 extend-filesystems[1679]: Found sdb1 Mar 25 01:32:24.714333 extend-filesystems[1679]: Found sr0 Mar 25 01:32:24.743340 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1731) Mar 25 01:32:24.708003 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 25 01:32:24.743521 coreos-metadata[1673]: Mar 25 01:32:24.740 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 25 01:32:24.743759 bash[1730]: Updated "/home/core/.ssh/authorized_keys" Mar 25 01:32:24.716712 dbus-daemon[1674]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 25 01:32:24.708232 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 25 01:32:24.716620 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 25 01:32:24.716656 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 25 01:32:24.722343 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 25 01:32:24.722373 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 25 01:32:24.730388 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 25 01:32:24.749303 coreos-metadata[1673]: Mar 25 01:32:24.745 INFO Fetch successful Mar 25 01:32:24.749303 coreos-metadata[1673]: Mar 25 01:32:24.745 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 25 01:32:24.749303 coreos-metadata[1673]: Mar 25 01:32:24.749 INFO Fetch successful Mar 25 01:32:24.745354 systemd[1]: Started update-engine.service - Update Engine. Mar 25 01:32:24.749468 coreos-metadata[1673]: Mar 25 01:32:24.749 INFO Fetching http://168.63.129.16/machine/44eaf1ef-9764-442d-ac4b-3261e119f7b3/7bdafd12%2Ddafa%2D41fd%2D88df%2D5a669ec50bbf.%5Fci%2D4284.0.0%2Da%2D5b60e4cce0?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 25 01:32:24.751054 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 25 01:32:24.756012 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 25 01:32:24.761559 setup-nvidia[1683]: Downloading Flatcar Container Linux Developer Container for version: 4284.0.0 Mar 25 01:32:24.778531 coreos-metadata[1673]: Mar 25 01:32:24.778 INFO Fetch successful Mar 25 01:32:24.778650 coreos-metadata[1673]: Mar 25 01:32:24.778 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 25 01:32:24.786246 coreos-metadata[1673]: Mar 25 01:32:24.786 INFO Fetch successful Mar 25 01:32:24.819964 sshd_keygen[1703]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 25 01:32:24.821535 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 25 01:32:24.826375 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 25 01:32:24.842935 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 25 01:32:24.848776 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 25 01:32:24.865098 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 25 01:32:24.870704 systemd[1]: issuegen.service: Deactivated successfully. Mar 25 01:32:24.870948 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 25 01:32:24.876629 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 25 01:32:24.889703 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 25 01:32:24.895389 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 25 01:32:24.902235 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 25 01:32:24.907604 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 25 01:32:24.913209 systemd[1]: Reached target getty.target - Login Prompts. Mar 25 01:32:24.998348 locksmithd[1782]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 25 01:32:25.326873 setup-nvidia[1835]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 25 01:32:25.327192 setup-nvidia[1835]: Dload Upload Total Spent Left Speed Mar 25 01:32:25.894227 containerd[1705]: time="2025-03-25T01:32:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Mar 25 01:32:25.895371 containerd[1705]: time="2025-03-25T01:32:25.895334800Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 25 01:32:25.904438 containerd[1705]: time="2025-03-25T01:32:25.904391200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.4µs" Mar 25 01:32:25.904438 containerd[1705]: time="2025-03-25T01:32:25.904421100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 25 01:32:25.904529 containerd[1705]: time="2025-03-25T01:32:25.904442300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 25 01:32:25.904616 containerd[1705]: time="2025-03-25T01:32:25.904589700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 25 01:32:25.904656 containerd[1705]: time="2025-03-25T01:32:25.904614300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 25 01:32:25.904656 containerd[1705]: time="2025-03-25T01:32:25.904640900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904725 containerd[1705]: time="2025-03-25T01:32:25.904701500Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904725 containerd[1705]: time="2025-03-25T01:32:25.904721200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904963 containerd[1705]: time="2025-03-25T01:32:25.904933400Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904963 containerd[1705]: time="2025-03-25T01:32:25.904954400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905038 containerd[1705]: time="2025-03-25T01:32:25.904966500Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905038 containerd[1705]: time="2025-03-25T01:32:25.904975400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905098 containerd[1705]: time="2025-03-25T01:32:25.905074500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905345 containerd[1705]: time="2025-03-25T01:32:25.905322500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905383 containerd[1705]: time="2025-03-25T01:32:25.905360200Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.905383 containerd[1705]: time="2025-03-25T01:32:25.905372200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 25 01:32:25.905426 containerd[1705]: time="2025-03-25T01:32:25.905397600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 25 01:32:25.905668 containerd[1705]: time="2025-03-25T01:32:25.905636700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 25 01:32:25.905757 containerd[1705]: time="2025-03-25T01:32:25.905734500Z" level=info msg="metadata content store policy set" policy=shared Mar 25 01:32:25.925586 containerd[1705]: time="2025-03-25T01:32:25.925527400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 25 01:32:25.925662 containerd[1705]: time="2025-03-25T01:32:25.925590100Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 25 01:32:25.925662 containerd[1705]: time="2025-03-25T01:32:25.925607700Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 25 01:32:25.925662 containerd[1705]: time="2025-03-25T01:32:25.925621100Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 25 01:32:25.925662 containerd[1705]: time="2025-03-25T01:32:25.925634600Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 25 01:32:25.925662 containerd[1705]: time="2025-03-25T01:32:25.925647200Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925680900Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925699200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925710300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925722200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925731900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 25 01:32:25.925763 containerd[1705]: time="2025-03-25T01:32:25.925743200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 25 01:32:25.925906 containerd[1705]: time="2025-03-25T01:32:25.925876200Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 25 01:32:25.925906 containerd[1705]: time="2025-03-25T01:32:25.925898500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 25 01:32:25.925957 containerd[1705]: time="2025-03-25T01:32:25.925910800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 25 01:32:25.925957 containerd[1705]: time="2025-03-25T01:32:25.925921900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 25 01:32:25.925957 containerd[1705]: time="2025-03-25T01:32:25.925933100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 25 01:32:25.925957 containerd[1705]: time="2025-03-25T01:32:25.925944600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 25 01:32:25.925957 containerd[1705]: time="2025-03-25T01:32:25.925955400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 25 01:32:25.926063 containerd[1705]: time="2025-03-25T01:32:25.925967000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 25 01:32:25.926063 containerd[1705]: time="2025-03-25T01:32:25.925979500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 25 01:32:25.926063 containerd[1705]: time="2025-03-25T01:32:25.925990300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 25 01:32:25.926063 containerd[1705]: time="2025-03-25T01:32:25.926000100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 25 01:32:25.926151 containerd[1705]: time="2025-03-25T01:32:25.926062400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 25 01:32:25.926151 containerd[1705]: time="2025-03-25T01:32:25.926077300Z" level=info msg="Start snapshots syncer" Mar 25 01:32:25.926151 containerd[1705]: time="2025-03-25T01:32:25.926105300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 25 01:32:25.926362 containerd[1705]: time="2025-03-25T01:32:25.926321100Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Mar 25 01:32:25.926510 containerd[1705]: time="2025-03-25T01:32:25.926396800Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 25 01:32:25.926548 containerd[1705]: time="2025-03-25T01:32:25.926530400Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 25 01:32:25.926698 containerd[1705]: time="2025-03-25T01:32:25.926643400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 25 01:32:25.926734 containerd[1705]: time="2025-03-25T01:32:25.926703800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 25 01:32:25.926758 containerd[1705]: time="2025-03-25T01:32:25.926741200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 25 01:32:25.926801 containerd[1705]: time="2025-03-25T01:32:25.926764900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 25 01:32:25.926841 containerd[1705]: time="2025-03-25T01:32:25.926798600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 25 01:32:25.926841 containerd[1705]: time="2025-03-25T01:32:25.926813400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 25 01:32:25.926841 containerd[1705]: time="2025-03-25T01:32:25.926823700Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 25 01:32:25.926905 containerd[1705]: time="2025-03-25T01:32:25.926847400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 25 01:32:25.926905 containerd[1705]: time="2025-03-25T01:32:25.926866100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 25 01:32:25.926905 containerd[1705]: time="2025-03-25T01:32:25.926876200Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926911000Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926926500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926935900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926945700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926953900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 25 01:32:25.926970 containerd[1705]: time="2025-03-25T01:32:25.926965700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.926977200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.926994300Z" level=info msg="runtime interface created" Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.927000200Z" level=info msg="created NRI interface" Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.927008500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.927020600Z" level=info msg="Connect containerd service" Mar 25 01:32:25.927102 containerd[1705]: time="2025-03-25T01:32:25.927046200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 25 01:32:25.928212 containerd[1705]: time="2025-03-25T01:32:25.928179300Z" 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 25 01:32:26.914521 waagent[1827]: 2025-03-25T01:32:26.914438Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Mar 25 01:32:26.918733 waagent[1827]: 2025-03-25T01:32:26.918677Z INFO Daemon Daemon OS: flatcar 4284.0.0 Mar 25 01:32:26.922136 waagent[1827]: 2025-03-25T01:32:26.922090Z INFO Daemon Daemon Python: 3.11.11 Mar 25 01:32:26.925504 waagent[1827]: 2025-03-25T01:32:26.925456Z INFO Daemon Daemon Run daemon Mar 25 01:32:26.928541 waagent[1827]: 2025-03-25T01:32:26.928499Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4284.0.0' Mar 25 01:32:26.935511 waagent[1827]: 2025-03-25T01:32:26.935121Z INFO Daemon Daemon Using waagent for provisioning Mar 25 01:32:26.939717 waagent[1827]: 2025-03-25T01:32:26.939671Z INFO Daemon Daemon Activate resource disk Mar 25 01:32:26.943210 waagent[1827]: 2025-03-25T01:32:26.943160Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 25 01:32:26.950965 waagent[1827]: 2025-03-25T01:32:26.950916Z INFO Daemon Daemon Found device: sdb Mar 25 01:32:26.960071 waagent[1827]: 2025-03-25T01:32:26.960022Z INFO Daemon Daemon Examining partition table Mar 25 01:32:26.975089 waagent[1827]: 2025-03-25T01:32:26.975021Z INFO Daemon Daemon GPT not detected, determining filesystem Mar 25 01:32:27.057752 waagent[1827]: 2025-03-25T01:32:27.057665Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Mar 25 01:32:27.062507 waagent[1827]: 2025-03-25T01:32:27.062448Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Mar 25 01:32:27.163146 containerd[1705]: time="2025-03-25T01:32:27.163097600Z" level=info msg="Start subscribing containerd event" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163169900Z" level=info msg="Start recovering state" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163265600Z" level=info msg="Start event monitor" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163284300Z" level=info msg="Start cni network conf syncer for default" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163293100Z" level=info msg="Start streaming server" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163302400Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163310900Z" level=info msg="runtime interface starting up..." Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163317200Z" level=info msg="starting plugins..." Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163331900Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163352200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163408600Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 25 01:32:27.163486 containerd[1705]: time="2025-03-25T01:32:27.163473200Z" level=info msg="containerd successfully booted in 1.269809s" Mar 25 01:32:27.163572 systemd[1]: Started containerd.service - containerd container runtime. Mar 25 01:32:27.562388 kernel: sdb: sdb1 Mar 25 01:32:27.580527 waagent[1827]: 2025-03-25T01:32:27.580449Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Mar 25 01:32:27.595833 kernel: sdb: sdb1 Mar 25 01:32:27.596096 waagent[1827]: 2025-03-25T01:32:27.596045Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Mar 25 01:32:32.891254 login[1829]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Mar 25 01:32:32.988114 login[1830]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 25 01:32:32.999391 systemd-logind[1693]: New session 2 of user core. Mar 25 01:32:33.000436 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 25 01:32:33.001692 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 25 01:32:33.045084 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 25 01:32:33.047650 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 25 01:32:33.094208 (systemd)[1922]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 25 01:32:33.097357 systemd-logind[1693]: New session c1 of user core. Mar 25 01:32:33.102156 waagent[1827]: 2025-03-25T01:32:33.102087Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Mar 25 01:32:33.126524 waagent[1827]: 2025-03-25T01:32:33.126465Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Mar 25 01:32:33.166550 kernel: EXT4-fs (sdb1): mounted filesystem c900469e-2b8d-4d67-8925-5c50026af934 r/w with ordered data mode. Quota mode: none. Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.128656Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.129660Z INFO Daemon Daemon Running default provisioning handler Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.136957Z 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 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.138090Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.138411Z INFO Daemon Daemon cloud-init is enabled: False Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.139848Z INFO Daemon Daemon Copying ovf-env.xml Mar 25 01:32:33.166658 waagent[1827]: 2025-03-25T01:32:33.153969Z INFO Daemon Daemon Successfully mounted dvd Mar 25 01:32:33.177537 waagent[1827]: 2025-03-25T01:32:33.177493Z INFO Daemon Daemon Detect protocol endpoint Mar 25 01:32:33.177606 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 25 01:32:33.201157 waagent[1827]: 2025-03-25T01:32:33.179042Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 25 01:32:33.201157 waagent[1827]: 2025-03-25T01:32:33.180550Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 25 01:32:33.201157 waagent[1827]: 2025-03-25T01:32:33.181990Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 25 01:32:33.201157 waagent[1827]: 2025-03-25T01:32:33.183599Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 25 01:32:33.201157 waagent[1827]: 2025-03-25T01:32:33.184820Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 25 01:32:33.203816 waagent[1827]: 2025-03-25T01:32:33.203762Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 25 01:32:33.205557 waagent[1827]: 2025-03-25T01:32:33.205528Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 25 01:32:33.206932 waagent[1827]: 2025-03-25T01:32:33.206854Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 25 01:32:33.312627 systemd[1922]: Queued start job for default target default.target. Mar 25 01:32:33.331446 systemd[1922]: Created slice app.slice - User Application Slice. Mar 25 01:32:33.331478 systemd[1922]: Reached target paths.target - Paths. Mar 25 01:32:33.331526 systemd[1922]: Reached target timers.target - Timers. Mar 25 01:32:33.332825 systemd[1922]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:32:33.343652 systemd[1922]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:32:33.343795 systemd[1922]: Reached target sockets.target - Sockets. Mar 25 01:32:33.343853 systemd[1922]: Reached target basic.target - Basic System. Mar 25 01:32:33.343899 systemd[1922]: Reached target default.target - Main User Target. Mar 25 01:32:33.343970 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 25 01:32:33.344031 systemd[1922]: Startup finished in 238ms. Mar 25 01:32:33.345546 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 25 01:32:33.349498 waagent[1827]: 2025-03-25T01:32:33.349412Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 25 01:32:33.350768 waagent[1827]: 2025-03-25T01:32:33.350726Z INFO Daemon Daemon Forcing an update of the goal state. Mar 25 01:32:33.354828 waagent[1827]: 2025-03-25T01:32:33.354767Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 25 01:32:33.397940 waagent[1827]: 2025-03-25T01:32:33.397895Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 Mar 25 01:32:33.399985 waagent[1827]: 2025-03-25T01:32:33.399945Z INFO Daemon Mar 25 01:32:33.400274 waagent[1827]: 2025-03-25T01:32:33.400239Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 3fc09ecd-aebd-4146-9de7-6948886106b7 eTag: 14327303715691194227 source: Fabric] Mar 25 01:32:33.401868 waagent[1827]: 2025-03-25T01:32:33.401826Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 25 01:32:33.402571 waagent[1827]: 2025-03-25T01:32:33.402533Z INFO Daemon Mar 25 01:32:33.403785 waagent[1827]: 2025-03-25T01:32:33.403747Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 25 01:32:33.408192 waagent[1827]: 2025-03-25T01:32:33.408157Z INFO Daemon Daemon Downloading artifacts profile blob Mar 25 01:32:33.575841 waagent[1827]: 2025-03-25T01:32:33.575747Z INFO Daemon Downloaded certificate {'thumbprint': 'CD2A4C49F898BD527DFA65364CCC4D64CF23520D', 'hasPrivateKey': False} Mar 25 01:32:33.577913 waagent[1827]: 2025-03-25T01:32:33.577864Z INFO Daemon Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:32:33.578535 waagent[1827]: 2025-03-25T01:32:33.578494Z INFO Daemon Fetch goal state completed Mar 25 01:32:33.617888 waagent[1827]: 2025-03-25T01:32:33.617834Z INFO Daemon Daemon Starting provisioning Mar 25 01:32:33.619532 waagent[1827]: 2025-03-25T01:32:33.619484Z INFO Daemon Daemon Handle ovf-env.xml. Mar 25 01:32:33.621395 waagent[1827]: 2025-03-25T01:32:33.621354Z INFO Daemon Daemon Set hostname [ci-4284.0.0-a-5b60e4cce0] Mar 25 01:32:33.651696 waagent[1827]: 2025-03-25T01:32:33.651646Z INFO Daemon Daemon Publish hostname [ci-4284.0.0-a-5b60e4cce0] Mar 25 01:32:33.653485 waagent[1827]: 2025-03-25T01:32:33.653444Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 25 01:32:33.654898 waagent[1827]: 2025-03-25T01:32:33.654860Z INFO Daemon Daemon Primary interface is [eth0] Mar 25 01:32:33.671792 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:33.671803 systemd-networkd[1406]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:33.671828 systemd-networkd[1406]: eth0: DHCP lease lost Mar 25 01:32:33.672254 waagent[1827]: 2025-03-25T01:32:33.672202Z INFO Daemon Daemon Create user account if not exists Mar 25 01:32:33.673940 waagent[1827]: 2025-03-25T01:32:33.673898Z INFO Daemon Daemon User core already exists, skip useradd Mar 25 01:32:33.674631 waagent[1827]: 2025-03-25T01:32:33.674595Z INFO Daemon Daemon Configure sudoer Mar 25 01:32:33.676371 waagent[1827]: 2025-03-25T01:32:33.676331Z INFO Daemon Daemon Configure sshd Mar 25 01:32:33.677714 waagent[1827]: 2025-03-25T01:32:33.677670Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 25 01:32:33.678956 waagent[1827]: 2025-03-25T01:32:33.678919Z INFO Daemon Daemon Deploy ssh public key. Mar 25 01:32:33.729578 waagent[1827]: 2025-03-25T01:32:33.729519Z INFO Daemon Daemon Decode custom data Mar 25 01:32:33.731267 waagent[1827]: 2025-03-25T01:32:33.731221Z INFO Daemon Daemon Save custom data Mar 25 01:32:33.746818 systemd-networkd[1406]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:33.891707 login[1829]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 25 01:32:33.896266 systemd-logind[1693]: New session 1 of user core. Mar 25 01:32:33.914908 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 25 01:32:45.798127 setup-nvidia[1835]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 483M 0 192k 0 0 209k 0 0:39:22 --:--:-- 0:39:22 209k\u000d 2 483M 2 14.0M 0 0 7252k 0 0:01:08 0:00:01 0:01:07 7250k\u000d 8 483M 8 39.4M 0 0 13.2M 0 0:00:36 0:00:02 0:00:34 13.2M\u000d 13 483M 13 64.8M 0 0 16.3M 0 0:00:29 0:00:03 0:00:26 16.3M\u000d 18 483M 18 90.1M 0 0 18.1M 0 0:00:26 0:00:04 0:00:22 18.1M\u000d 23 483M 23 115M 0 0 19.4M 0 0:00:24 0:00:05 0:00:19 22.9M\u000d 29 483M 29 140M 0 0 20.2M 0 0:00:23 0:00:06 0:00:17 25.5M\u000d 34 483M 34 165M 0 0 20.7M 0 0:00:23 0:00:07 0:00:16 25.2M\u000d 39 483M 39 189M 0 0 21.1M 0 0:00:22 0:00:08 0:00:14 25.0M\u000d 44 483M 44 214M 0 0 21.6M 0 0:00:22 0:00:09 0:00:13 25.0M\u000d 49 483M 49 240M 0 0 21.9M 0 0:00:22 0:00:10 0:00:12 25.0M\u000d 54 483M 54 265M 0 0 22.2M 0 0:00:21 0:00:11 0:00:10 25.0M\u000d 60 483M 60 291M 0 0 22.5M 0 0:00:21 0:00:12 0:00:09 25.3M\u000d 65 483M 65 317M 0 0 22.8M 0 0:00:21 0:00:13 0:00:08 25.7M\u000d 70 483M 70 343M 0 0 22.9M 0 0:00:21 0:00:14 0:00:07 25.5M\u000d 76 483M 76 368M 0 0 23.1M 0 0:00:20 0:00:15 0:00:05 25.6M\u000d 81 483M 81 394M 0 0 23.2M 0 0:00:20 0:00:16 0:00:04 25.6M\u000d 86 483M 86 417M 0 0 23.3M 0 0:00:20 0:00:17 0:00:03 25.3M\u000d 91 483M 91 443M 0 0 23.4M 0 0:00:20 0:00:18 0:00:02 25.2M\u000d 97 483M 97 471M 0 0 23.6M 0 0:00:20 0:00:19 0:00:01 25.8M\u000d100 483M 100 483M 0 0 23.6M 0 0:00:20 0:00:20 --:--:-- 25.4M Mar 25 01:32:48.301703 chronyd[1690]: Selected source PHC0 Mar 25 01:32:57.251284 setup-nvidia[1683]: Downloading NVIDIA 535.230.02 Driver Mar 25 01:32:57.256187 setup-nvidia[1994]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 25 01:32:57.256187 setup-nvidia[1994]: Dload Upload Total Spent Left Speed Mar 25 01:32:59.277655 setup-nvidia[1994]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 33 326M 33 109M 0 0 111M 0 0:00:02 --:--:-- 0:00:02 111M\u000d 97 326M 97 317M 0 0 160M 0 0:00:02 0:00:01 0:00:01 160M\u000d100 326M 100 326M 0 0 161M 0 0:00:02 0:00:02 --:--:-- 161M Mar 25 01:32:59.328962 setup-nvidia[1683]: Extract the NVIDIA Driver Installer 535.230.02 Mar 25 01:32:59.328962 setup-nvidia[1683]: /opt/nvidia/workdir/nvidia-workdir / Mar 25 01:32:59.348937 setup-nvidia[1997]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Mar 25 01:32:59.711074 setup-nvidia[1997]: Verifying archive integrity... OK Mar 25 01:33:00.471039 setup-nvidia[1997]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Mar 25 01:33:02.449289 setup-nvidia[2023]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Mar 25 01:33:02.576643 setup-nvidia[1683]: / Mar 25 01:33:02.576643 setup-nvidia[1683]: Spawn system-nspawn container to install the NVIDIA drivers Mar 25 01:33:02.946088 sudo[2030]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4284.0.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 25 01:33:02.947911 sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 01:33:02.955393 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 25 01:33:02.956557 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 25 01:33:02.985845 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 25 01:33:02.988054 systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 25 01:33:03.003797 (systemd)[2033]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 25 01:33:03.005995 systemd-logind[1693]: New session c3 of user root. Mar 25 01:33:03.196084 systemd[2033]: Queued start job for default target default.target. Mar 25 01:33:03.204855 systemd[2033]: Created slice app.slice - User Application Slice. Mar 25 01:33:03.204886 systemd[2033]: Reached target paths.target - Paths. Mar 25 01:33:03.204963 systemd[2033]: Reached target timers.target - Timers. Mar 25 01:33:03.206150 systemd[2033]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:33:03.216585 systemd[2033]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:33:03.216644 systemd[2033]: Reached target sockets.target - Sockets. Mar 25 01:33:03.216688 systemd[2033]: Reached target basic.target - Basic System. Mar 25 01:33:03.216731 systemd[2033]: Reached target default.target - Main User Target. Mar 25 01:33:03.216761 systemd[2033]: Startup finished in 204ms. Mar 25 01:33:03.216905 systemd[1]: Started user@0.service - User Manager for UID 0. Mar 25 01:33:03.218319 systemd[1]: Started session-c2.scope - Session c2 of User root. Mar 25 01:33:03.322805 kernel: loop6: detected capacity change from 0 to 12644352 Mar 25 01:33:03.336804 kernel: loop6: p9 Mar 25 01:33:03.632262 setup-nvidia[2042]: ░ Spawning container flatcardevelopercontainer-4284.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4284.0.0.bin. Mar 25 01:33:03.646800 kernel: EXT4-fs (loop6p9): mounted filesystem 5775d2e2-188b-4921-88b6-682c3efa3461 ro without journal. Quota mode: none. Mar 25 01:33:03.780564 waagent[1827]: 2025-03-25T01:33:03.780498Z INFO Daemon Daemon Provisioning complete Mar 25 01:33:03.790524 waagent[1827]: 2025-03-25T01:33:03.790480Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 25 01:33:03.792161 waagent[1827]: 2025-03-25T01:33:03.792116Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 25 01:33:03.793485 waagent[1827]: 2025-03-25T01:33:03.793448Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Mar 25 01:33:03.938239 waagent[2046]: 2025-03-25T01:33:03.938112Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Mar 25 01:33:03.938532 waagent[2046]: 2025-03-25T01:33:03.938251Z INFO ExtHandler ExtHandler OS: flatcar 4284.0.0 Mar 25 01:33:03.938532 waagent[2046]: 2025-03-25T01:33:03.938323Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 25 01:33:03.938532 waagent[2046]: 2025-03-25T01:33:03.938395Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Mar 25 01:33:04.005303 waagent[2046]: 2025-03-25T01:33:04.005238Z INFO ExtHandler ExtHandler Distro: flatcar-4284.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 25 01:33:04.005487 waagent[2046]: 2025-03-25T01:33:04.005448Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:33:04.005568 waagent[2046]: 2025-03-25T01:33:04.005535Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:33:04.012491 waagent[2046]: 2025-03-25T01:33:04.012407Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 25 01:33:04.022177 waagent[2046]: 2025-03-25T01:33:04.022134Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 25 01:33:04.022618 waagent[2046]: 2025-03-25T01:33:04.022579Z INFO ExtHandler Mar 25 01:33:04.022701 waagent[2046]: 2025-03-25T01:33:04.022670Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 459553de-faaa-4807-9bb5-f1410b5f1799 eTag: 14327303715691194227 source: Fabric] Mar 25 01:33:04.023045 waagent[2046]: 2025-03-25T01:33:04.023006Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 25 01:33:04.023647 waagent[2046]: 2025-03-25T01:33:04.023608Z INFO ExtHandler Mar 25 01:33:04.023721 waagent[2046]: 2025-03-25T01:33:04.023691Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 25 01:33:04.027115 waagent[2046]: 2025-03-25T01:33:04.027077Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 25 01:33:04.102580 waagent[2046]: 2025-03-25T01:33:04.102521Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CD2A4C49F898BD527DFA65364CCC4D64CF23520D', 'hasPrivateKey': False} Mar 25 01:33:04.102967 waagent[2046]: 2025-03-25T01:33:04.102929Z INFO ExtHandler Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:33:04.103411 waagent[2046]: 2025-03-25T01:33:04.103372Z INFO ExtHandler Fetch goal state completed Mar 25 01:33:04.114312 waagent[2046]: 2025-03-25T01:33:04.114268Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Mar 25 01:33:04.119361 waagent[2046]: 2025-03-25T01:33:04.119314Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2046 Mar 25 01:33:04.119494 waagent[2046]: 2025-03-25T01:33:04.119458Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 25 01:33:04.119845 waagent[2046]: 2025-03-25T01:33:04.119806Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Mar 25 01:33:04.121897 waagent[2046]: 2025-03-25T01:33:04.121849Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 25 01:33:04.122329 waagent[2046]: 2025-03-25T01:33:04.122289Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 25 01:33:04.122467 waagent[2046]: 2025-03-25T01:33:04.122432Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 25 01:33:04.123195 waagent[2046]: 2025-03-25T01:33:04.123154Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 25 01:33:04.198857 waagent[2046]: 2025-03-25T01:33:04.198756Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 25 01:33:04.199013 waagent[2046]: 2025-03-25T01:33:04.198974Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 25 01:33:04.229995 waagent[2046]: 2025-03-25T01:33:04.229954Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 25 01:33:04.237368 systemd[1]: Reload requested from client PID 2065 ('systemctl') (unit waagent.service)... Mar 25 01:33:04.237383 systemd[1]: Reloading... Mar 25 01:33:04.302828 zram_generator::config[2111]: No configuration found. Mar 25 01:33:04.431413 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 25 01:33:04.580474 systemd[1]: Reloading finished in 342 ms. Mar 25 01:33:04.598365 waagent[2046]: 2025-03-25T01:33:04.598298Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 25 01:33:04.598477 waagent[2046]: 2025-03-25T01:33:04.598442Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 25 01:33:05.650684 waagent[2046]: 2025-03-25T01:33:05.650604Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 25 01:33:05.651057 waagent[2046]: 2025-03-25T01:33:05.650968Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Mar 25 01:33:05.652313 waagent[2046]: 2025-03-25T01:33:05.652266Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 25 01:33:05.652566 waagent[2046]: 2025-03-25T01:33:05.652520Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:33:05.652934 waagent[2046]: 2025-03-25T01:33:05.652893Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 25 01:33:05.653003 waagent[2046]: 2025-03-25T01:33:05.652964Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:33:05.653663 waagent[2046]: 2025-03-25T01:33:05.653612Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 25 01:33:05.653769 waagent[2046]: 2025-03-25T01:33:05.653708Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 25 01:33:05.654000 waagent[2046]: 2025-03-25T01:33:05.653809Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:33:05.654411 waagent[2046]: 2025-03-25T01:33:05.654362Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 25 01:33:05.654634 waagent[2046]: 2025-03-25T01:33:05.654590Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:33:05.654909 waagent[2046]: 2025-03-25T01:33:05.654855Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 25 01:33:05.655009 waagent[2046]: 2025-03-25T01:33:05.654966Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 25 01:33:05.655009 waagent[2046]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 25 01:33:05.655009 waagent[2046]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Mar 25 01:33:05.655009 waagent[2046]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 25 01:33:05.655009 waagent[2046]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:33:05.655009 waagent[2046]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:33:05.655009 waagent[2046]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:33:05.655166 waagent[2046]: 2025-03-25T01:33:05.655023Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 25 01:33:05.655550 waagent[2046]: 2025-03-25T01:33:05.655501Z INFO EnvHandler ExtHandler Configure routes Mar 25 01:33:05.655705 waagent[2046]: 2025-03-25T01:33:05.655667Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 25 01:33:05.656260 waagent[2046]: 2025-03-25T01:33:05.656222Z INFO EnvHandler ExtHandler Gateway:None Mar 25 01:33:05.656788 waagent[2046]: 2025-03-25T01:33:05.656747Z INFO EnvHandler ExtHandler Routes:None Mar 25 01:33:05.661846 waagent[2046]: 2025-03-25T01:33:05.661778Z INFO ExtHandler ExtHandler Mar 25 01:33:05.662176 waagent[2046]: 2025-03-25T01:33:05.662136Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 9686d00b-17aa-4731-800d-7a242949e837 correlation cf099670-49fd-4ebc-a890-7249a66afc01 created: 2025-03-25T01:31:10.489342Z] Mar 25 01:33:05.663274 waagent[2046]: 2025-03-25T01:33:05.663222Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 25 01:33:05.666022 waagent[2046]: 2025-03-25T01:33:05.665974Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Mar 25 01:33:05.734874 waagent[2046]: 2025-03-25T01:33:05.734820Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 8894AA9F-AF0F-412F-9539-83AEEB432F36;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Mar 25 01:33:05.784004 waagent[2046]: 2025-03-25T01:33:05.783941Z INFO MonitorHandler ExtHandler Network interfaces: Mar 25 01:33:05.784004 waagent[2046]: Executing ['ip', '-a', '-o', 'link']: Mar 25 01:33:05.784004 waagent[2046]: 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 25 01:33:05.784004 waagent[2046]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:77:fa:da brd ff:ff:ff:ff:ff:ff Mar 25 01:33:05.784004 waagent[2046]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 25 01:33:05.784004 waagent[2046]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 25 01:33:05.784004 waagent[2046]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 25 01:33:05.784004 waagent[2046]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 25 01:33:05.784004 waagent[2046]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 25 01:33:05.784004 waagent[2046]: 2: eth0 inet6 fe80::7e1e:52ff:fe77:fada/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 25 01:33:05.942871 waagent[2046]: 2025-03-25T01:33:05.942745Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 25 01:33:05.942871 waagent[2046]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.942871 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.942871 waagent[2046]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.942871 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.942871 waagent[2046]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.942871 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.942871 waagent[2046]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 25 01:33:05.942871 waagent[2046]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 25 01:33:05.942871 waagent[2046]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 25 01:33:05.946367 waagent[2046]: 2025-03-25T01:33:05.946323Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 25 01:33:05.946367 waagent[2046]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.946367 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.946367 waagent[2046]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.946367 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.946367 waagent[2046]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:33:05.946367 waagent[2046]: pkts bytes target prot opt in out source destination Mar 25 01:33:05.946367 waagent[2046]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 25 01:33:05.946367 waagent[2046]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 25 01:33:05.946367 waagent[2046]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 25 01:33:05.946624 waagent[2046]: 2025-03-25T01:33:05.946591Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Mar 25 01:33:05.946776 waagent[2046]: 2025-03-25T01:33:05.946745Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 25 01:33:07.685385 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Mar 25 01:33:10.103214 update_engine[1696]: I20250325 01:33:10.103120 1696 update_attempter.cc:509] Updating boot flags... Mar 25 01:33:10.174808 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2274) Mar 25 01:33:10.269811 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2264) Mar 25 01:33:27.867615 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 25 01:33:27.870457 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:59944.service - OpenSSH per-connection server daemon (10.200.16.10:59944). Mar 25 01:33:37.373407 sshd[5486]: Accepted publickey for core from 10.200.16.10 port 59944 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:37.375391 sshd-session[5486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:37.384300 systemd-logind[1693]: New session 3 of user core. Mar 25 01:33:37.399273 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 25 01:33:37.927006 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:37282.service - OpenSSH per-connection server daemon (10.200.16.10:37282). Mar 25 01:33:38.264814 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.327637 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.362868 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.425566 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.463806 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.527810 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.558862 sshd[5969]: Accepted publickey for core from 10.200.16.10 port 37282 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:38.560207 sshd-session[5969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:38.564907 systemd-logind[1693]: New session 4 of user core. Mar 25 01:33:38.574910 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 25 01:33:38.637933 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:38.638275 kernel: hv_netvsc 7c1e5277-fada-7c1e-5277-fada7c1e5277 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:39.007254 sshd[6143]: Connection closed by 10.200.16.10 port 37282 Mar 25 01:33:39.009007 sshd-session[5969]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:39.012479 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:37282.service: Deactivated successfully. Mar 25 01:33:39.014448 systemd[1]: session-4.scope: Deactivated successfully. Mar 25 01:33:39.015625 systemd-logind[1693]: Session 4 logged out. Waiting for processes to exit. Mar 25 01:33:39.016773 systemd-logind[1693]: Removed session 4. Mar 25 01:33:39.123900 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:56928.service - OpenSSH per-connection server daemon (10.200.16.10:56928). Mar 25 01:33:39.773295 sshd[6232]: Accepted publickey for core from 10.200.16.10 port 56928 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:39.775024 sshd-session[6232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:39.781360 systemd-logind[1693]: New session 5 of user core. Mar 25 01:33:39.789954 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 25 01:33:40.228377 sshd[6401]: Connection closed by 10.200.16.10 port 56928 Mar 25 01:33:40.228751 sshd-session[6232]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:40.232066 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:56928.service: Deactivated successfully. Mar 25 01:33:40.233668 systemd[1]: session-5.scope: Deactivated successfully. Mar 25 01:33:40.234302 systemd-logind[1693]: Session 5 logged out. Waiting for processes to exit. Mar 25 01:33:40.235109 systemd-logind[1693]: Removed session 5. Mar 25 01:33:50.341278 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:57734.service - OpenSSH per-connection server daemon (10.200.16.10:57734). Mar 25 01:33:50.979103 sshd[7940]: Accepted publickey for core from 10.200.16.10 port 57734 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:50.980612 sshd-session[7940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:50.985591 systemd-logind[1693]: New session 6 of user core. Mar 25 01:33:51.001948 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 25 01:33:52.228930 sshd[8132]: Connection closed by 10.200.16.10 port 57734 Mar 25 01:33:51.540568 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:57750.service - OpenSSH per-connection server daemon (10.200.16.10:57750). Mar 25 01:33:52.229485 sshd[8155]: Accepted publickey for core from 10.200.16.10 port 57750 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:52.229403 sshd-session[7940]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:52.230407 sshd-session[8155]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:52.232321 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:57734.service: Deactivated successfully. Mar 25 01:33:52.234076 systemd[1]: session-6.scope: Deactivated successfully. Mar 25 01:33:52.235609 systemd-logind[1693]: Session 6 logged out. Waiting for processes to exit. Mar 25 01:33:52.239206 systemd-logind[1693]: New session 7 of user core. Mar 25 01:33:52.254369 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 25 01:33:52.258843 systemd-logind[1693]: Removed session 6. Mar 25 01:33:52.621057 sshd[8194]: Connection closed by 10.200.16.10 port 57750 Mar 25 01:33:52.621337 sshd-session[8155]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:52.624522 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:57750.service: Deactivated successfully. Mar 25 01:33:52.626116 systemd[1]: session-7.scope: Deactivated successfully. Mar 25 01:33:52.626734 systemd-logind[1693]: Session 7 logged out. Waiting for processes to exit. Mar 25 01:33:52.627513 systemd-logind[1693]: Removed session 7. Mar 25 01:34:02.745028 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:60744.service - OpenSSH per-connection server daemon (10.200.16.10:60744). Mar 25 01:34:03.418336 sshd[11439]: Accepted publickey for core from 10.200.16.10 port 60744 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:03.420256 sshd-session[11439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:03.426667 systemd-logind[1693]: New session 8 of user core. Mar 25 01:34:03.437704 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 25 01:34:03.867986 sshd[11459]: Connection closed by 10.200.16.10 port 60744 Mar 25 01:34:03.868827 sshd-session[11439]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:03.871827 systemd-logind[1693]: Session 8 logged out. Waiting for processes to exit. Mar 25 01:34:03.873367 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:60744.service: Deactivated successfully. Mar 25 01:34:03.874999 systemd[1]: session-8.scope: Deactivated successfully. Mar 25 01:34:03.876716 systemd-logind[1693]: Removed session 8. Mar 25 01:34:03.987026 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:60758.service - OpenSSH per-connection server daemon (10.200.16.10:60758). Mar 25 01:34:04.632517 sshd[11483]: Accepted publickey for core from 10.200.16.10 port 60758 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:04.634153 sshd-session[11483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:04.643135 systemd-logind[1693]: New session 9 of user core. Mar 25 01:34:04.652949 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 25 01:34:05.078473 sshd[11515]: Connection closed by 10.200.16.10 port 60758 Mar 25 01:34:05.078867 sshd-session[11483]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:05.083179 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:60758.service: Deactivated successfully. Mar 25 01:34:05.085167 systemd[1]: session-9.scope: Deactivated successfully. Mar 25 01:34:05.086008 systemd-logind[1693]: Session 9 logged out. Waiting for processes to exit. Mar 25 01:34:05.087173 systemd-logind[1693]: Removed session 9. Mar 25 01:34:15.198157 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:47188.service - OpenSSH per-connection server daemon (10.200.16.10:47188). Mar 25 01:34:15.850234 sshd[11900]: Accepted publickey for core from 10.200.16.10 port 47188 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:15.852186 sshd-session[11900]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:15.862131 systemd-logind[1693]: New session 10 of user core. Mar 25 01:34:15.871555 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 25 01:34:16.296524 sshd[11925]: Connection closed by 10.200.16.10 port 47188 Mar 25 01:34:16.298503 sshd-session[11900]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:16.304465 systemd-logind[1693]: Session 10 logged out. Waiting for processes to exit. Mar 25 01:34:16.306559 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:47188.service: Deactivated successfully. Mar 25 01:34:16.309000 systemd[1]: session-10.scope: Deactivated successfully. Mar 25 01:34:16.310989 systemd-logind[1693]: Removed session 10. Mar 25 01:34:16.409347 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:47190.service - OpenSSH per-connection server daemon (10.200.16.10:47190). Mar 25 01:34:17.063986 sshd[11938]: Accepted publickey for core from 10.200.16.10 port 47190 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:17.065448 sshd-session[11938]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:17.072918 systemd-logind[1693]: New session 11 of user core. Mar 25 01:34:17.082991 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 25 01:34:17.516020 sshd[11963]: Connection closed by 10.200.16.10 port 47190 Mar 25 01:34:17.518975 sshd-session[11938]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:17.523015 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:47190.service: Deactivated successfully. Mar 25 01:34:17.525674 systemd[1]: session-11.scope: Deactivated successfully. Mar 25 01:34:17.526908 systemd-logind[1693]: Session 11 logged out. Waiting for processes to exit. Mar 25 01:34:17.528042 systemd-logind[1693]: Removed session 11. Mar 25 01:34:27.638128 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:49572.service - OpenSSH per-connection server daemon (10.200.16.10:49572). Mar 25 01:34:28.289640 sshd[12305]: Accepted publickey for core from 10.200.16.10 port 49572 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:28.291602 sshd-session[12305]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:28.326908 systemd-logind[1693]: New session 12 of user core. Mar 25 01:34:28.327602 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 25 01:34:28.739580 sshd[12315]: Connection closed by 10.200.16.10 port 49572 Mar 25 01:34:28.741559 sshd-session[12305]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:28.746512 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:49572.service: Deactivated successfully. Mar 25 01:34:28.748550 systemd[1]: session-12.scope: Deactivated successfully. Mar 25 01:34:28.750940 systemd-logind[1693]: Session 12 logged out. Waiting for processes to exit. Mar 25 01:34:28.752340 systemd-logind[1693]: Removed session 12. Mar 25 01:34:28.854020 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:49576.service - OpenSSH per-connection server daemon (10.200.16.10:49576). Mar 25 01:34:29.489553 sshd[12332]: Accepted publickey for core from 10.200.16.10 port 49576 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:29.491059 sshd-session[12332]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:29.499892 systemd-logind[1693]: New session 13 of user core. Mar 25 01:34:29.507554 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 25 01:34:29.936826 sshd[12351]: Connection closed by 10.200.16.10 port 49576 Mar 25 01:34:29.938423 sshd-session[12332]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:29.943236 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:49576.service: Deactivated successfully. Mar 25 01:34:29.945498 systemd[1]: session-13.scope: Deactivated successfully. Mar 25 01:34:29.946588 systemd-logind[1693]: Session 13 logged out. Waiting for processes to exit. Mar 25 01:34:29.948506 systemd-logind[1693]: Removed session 13. Mar 25 01:34:30.096939 update_engine[1696]: I20250325 01:34:30.096874 1696 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Mar 25 01:34:30.096939 update_engine[1696]: I20250325 01:34:30.096933 1696 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Mar 25 01:34:30.097350 update_engine[1696]: I20250325 01:34:30.097098 1696 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Mar 25 01:34:30.097848 update_engine[1696]: I20250325 01:34:30.097581 1696 omaha_request_params.cc:62] Current group set to alpha Mar 25 01:34:30.097848 update_engine[1696]: I20250325 01:34:30.097692 1696 update_attempter.cc:499] Already updated boot flags. Skipping. Mar 25 01:34:30.097848 update_engine[1696]: I20250325 01:34:30.097707 1696 update_attempter.cc:643] Scheduling an action processor start. Mar 25 01:34:30.097848 update_engine[1696]: I20250325 01:34:30.097723 1696 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Mar 25 01:34:30.097848 update_engine[1696]: I20250325 01:34:30.097766 1696 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Mar 25 01:34:30.097994 update_engine[1696]: I20250325 01:34:30.097872 1696 omaha_request_action.cc:271] Posting an Omaha request to disabled Mar 25 01:34:30.097994 update_engine[1696]: I20250325 01:34:30.097883 1696 omaha_request_action.cc:272] Request: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: Mar 25 01:34:30.097994 update_engine[1696]: I20250325 01:34:30.097891 1696 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 25 01:34:30.098227 locksmithd[1782]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Mar 25 01:34:30.099811 update_engine[1696]: I20250325 01:34:30.099123 1696 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 25 01:34:30.099811 update_engine[1696]: I20250325 01:34:30.099423 1696 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 25 01:34:30.122405 update_engine[1696]: E20250325 01:34:30.122253 1696 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 25 01:34:30.122405 update_engine[1696]: I20250325 01:34:30.122367 1696 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Mar 25 01:34:40.052358 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:34298.service - OpenSSH per-connection server daemon (10.200.16.10:34298). Mar 25 01:34:40.106869 update_engine[1696]: I20250325 01:34:40.106806 1696 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 25 01:34:40.107204 update_engine[1696]: I20250325 01:34:40.107020 1696 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 25 01:34:40.107239 update_engine[1696]: I20250325 01:34:40.107223 1696 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 25 01:34:40.128034 update_engine[1696]: E20250325 01:34:40.127981 1696 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 25 01:34:40.128128 update_engine[1696]: I20250325 01:34:40.128052 1696 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Mar 25 01:34:40.692816 sshd[12582]: Accepted publickey for core from 10.200.16.10 port 34298 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:40.694258 sshd-session[12582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:40.698592 systemd-logind[1693]: New session 14 of user core. Mar 25 01:34:40.710940 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 25 01:34:41.136827 sshd[12584]: Connection closed by 10.200.16.10 port 34298 Mar 25 01:34:41.137239 sshd-session[12582]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:41.140898 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:34298.service: Deactivated successfully. Mar 25 01:34:41.142671 systemd[1]: session-14.scope: Deactivated successfully. Mar 25 01:34:41.143444 systemd-logind[1693]: Session 14 logged out. Waiting for processes to exit. Mar 25 01:34:41.144312 systemd-logind[1693]: Removed session 14. Mar 25 01:34:41.249118 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:34314.service - OpenSSH per-connection server daemon (10.200.16.10:34314). Mar 25 01:34:41.903545 sshd[12590]: Accepted publickey for core from 10.200.16.10 port 34314 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:41.905887 sshd-session[12590]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:41.910317 systemd-logind[1693]: New session 15 of user core. Mar 25 01:34:41.927921 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 25 01:34:42.346929 sshd[12638]: Connection closed by 10.200.16.10 port 34314 Mar 25 01:34:42.347377 sshd-session[12590]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:42.350700 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:34314.service: Deactivated successfully. Mar 25 01:34:42.352796 systemd[1]: session-15.scope: Deactivated successfully. Mar 25 01:34:42.353464 systemd-logind[1693]: Session 15 logged out. Waiting for processes to exit. Mar 25 01:34:42.354272 systemd-logind[1693]: Removed session 15. Mar 25 01:34:43.847972 setup-nvidia[2042]: \u000d Mar 25 01:34:43.847972 setup-nvidia[2042]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Mar 25 01:34:43.847972 setup-nvidia[2042]: frequently when this kernel module was built against the wrong or Mar 25 01:34:43.847972 setup-nvidia[2042]: improperly configured kernel sources, with a version of gcc that differs Mar 25 01:34:43.847972 setup-nvidia[2042]: from the one used to build the target kernel, or if another driver, such Mar 25 01:34:43.847972 setup-nvidia[2042]: as nouveau, is present and prevents the NVIDIA kernel module from Mar 25 01:34:43.847972 setup-nvidia[2042]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Mar 25 01:34:43.847972 setup-nvidia[2042]: installed in this system is supported by this NVIDIA Linux graphics Mar 25 01:34:43.847972 setup-nvidia[2042]: driver release. Mar 25 01:34:43.847972 setup-nvidia[2042]: \u000d Mar 25 01:34:43.847972 setup-nvidia[2042]: Please see the log entries 'Kernel module load error' and 'Kernel Mar 25 01:34:43.847972 setup-nvidia[2042]: messages' at the end of the file Mar 25 01:34:43.847972 setup-nvidia[2042]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Mar 25 01:34:43.847972 setup-nvidia[2042]: information. Mar 25 01:34:43.847972 setup-nvidia[2042]: \u000d Mar 25 01:34:43.862563 setup-nvidia[2042]: dmesg: read kernel buffer failed: Operation not permitted Mar 25 01:34:43.881614 setup-nvidia[2042]: \u000d Mar 25 01:34:43.881614 setup-nvidia[2042]: ERROR: Installation has failed. Please see the file Mar 25 01:34:43.881614 setup-nvidia[2042]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Mar 25 01:34:43.881614 setup-nvidia[2042]: details. You may find suggestions on fixing installation problems in Mar 25 01:34:43.881614 setup-nvidia[2042]: the README available on the Linux driver download page at Mar 25 01:34:43.881614 setup-nvidia[2042]: www.nvidia.com. Mar 25 01:34:43.881614 setup-nvidia[2042]: \u000d Mar 25 01:34:43.882366 setup-nvidia[2042]: Last 50 lines of nvidia-installer.log: Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Mar 25 01:34:43.882921 setup-nvidia[2042]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Mar 25 01:34:43.882921 setup-nvidia[2042]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Mar 25 01:34:43.882921 setup-nvidia[2042]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Mar 25 01:34:43.882921 setup-nvidia[2042]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Mar 25 01:34:43.882921 setup-nvidia[2042]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Mar 25 01:34:43.882921 setup-nvidia[2042]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Mar 25 01:34:43.882921 setup-nvidia[2042]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Mar 25 01:34:43.882921 setup-nvidia[2042]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Mar 25 01:34:43.882921 setup-nvidia[2042]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Mar 25 01:34:43.882921 setup-nvidia[2042]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Mar 25 01:34:43.882921 setup-nvidia[2042]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 25 01:34:43.882921 setup-nvidia[2042]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Mar 25 01:34:43.882921 setup-nvidia[2042]: make[2]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/build' Mar 25 01:34:43.882921 setup-nvidia[2042]: make[1]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/source' Mar 25 01:34:43.882921 setup-nvidia[2042]: -> done. Mar 25 01:34:43.882921 setup-nvidia[2042]: -> Kernel module compilation complete. Mar 25 01:34:43.882921 setup-nvidia[2042]: -> Unable to determine if Secure Boot is enabled: No such file or directory Mar 25 01:34:43.882921 setup-nvidia[2042]: 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 25 01:34:43.882921 setup-nvidia[2042]: \u000d Mar 25 01:34:43.884060 setup-nvidia[2042]: 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 25 01:34:43.884060 setup-nvidia[2042]: -> Kernel module load error: Operation not permitted Mar 25 01:34:43.884060 setup-nvidia[2042]: -> Kernel messages: Mar 25 01:34:43.884060 setup-nvidia[2042]: 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 25 01:34:44.285023 waagent[2046]: 2025-03-25T01:34:44.284966Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 25 01:34:44.291695 waagent[2046]: 2025-03-25T01:34:44.291649Z INFO ExtHandler Mar 25 01:34:44.291795 waagent[2046]: 2025-03-25T01:34:44.291746Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a790d54b-d367-490a-ba06-8c8f3b807698 eTag: 11862506009147318564 source: Fabric] Mar 25 01:34:44.292133 waagent[2046]: 2025-03-25T01:34:44.292094Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 25 01:34:44.292774 waagent[2046]: 2025-03-25T01:34:44.292735Z INFO ExtHandler Mar 25 01:34:44.292867 waagent[2046]: 2025-03-25T01:34:44.292837Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 25 01:34:44.372884 waagent[2046]: 2025-03-25T01:34:44.372842Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 25 01:34:44.436990 waagent[2046]: 2025-03-25T01:34:44.436927Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CD2A4C49F898BD527DFA65364CCC4D64CF23520D', 'hasPrivateKey': False} Mar 25 01:34:44.437365 waagent[2046]: 2025-03-25T01:34:44.437325Z INFO ExtHandler Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:34:44.437795 waagent[2046]: 2025-03-25T01:34:44.437749Z INFO ExtHandler Fetch goal state completed Mar 25 01:34:44.438187 waagent[2046]: 2025-03-25T01:34:44.438149Z INFO ExtHandler ExtHandler Mar 25 01:34:44.438259 waagent[2046]: 2025-03-25T01:34:44.438229Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: e6e434c7-22ec-4c8e-931c-f381e575ed72 correlation cf099670-49fd-4ebc-a890-7249a66afc01 created: 2025-03-25T01:34:34.644989Z] Mar 25 01:34:44.438573 waagent[2046]: 2025-03-25T01:34:44.438538Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 25 01:34:44.439167 waagent[2046]: 2025-03-25T01:34:44.439131Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Mar 25 01:34:50.104668 update_engine[1696]: I20250325 01:34:50.104580 1696 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 25 01:34:50.105105 update_engine[1696]: I20250325 01:34:50.104841 1696 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 25 01:34:50.105105 update_engine[1696]: I20250325 01:34:50.105073 1696 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 25 01:34:50.237545 update_engine[1696]: E20250325 01:34:50.237493 1696 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 25 01:34:50.237648 update_engine[1696]: I20250325 01:34:50.237567 1696 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Mar 25 01:34:52.459356 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:32918.service - OpenSSH per-connection server daemon (10.200.16.10:32918). Mar 25 01:34:53.113175 sshd[12811]: Accepted publickey for core from 10.200.16.10 port 32918 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:53.114620 sshd-session[12811]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:53.118740 systemd-logind[1693]: New session 16 of user core. Mar 25 01:34:53.135078 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 25 01:34:53.557634 sshd[12813]: Connection closed by 10.200.16.10 port 32918 Mar 25 01:34:53.558003 sshd-session[12811]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:53.561429 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:32918.service: Deactivated successfully. Mar 25 01:34:53.563238 systemd[1]: session-16.scope: Deactivated successfully. Mar 25 01:34:53.563922 systemd-logind[1693]: Session 16 logged out. Waiting for processes to exit. Mar 25 01:34:53.564703 systemd-logind[1693]: Removed session 16. Mar 25 01:34:53.669250 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:32920.service - OpenSSH per-connection server daemon (10.200.16.10:32920). Mar 25 01:34:54.304022 sshd[12819]: Accepted publickey for core from 10.200.16.10 port 32920 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:54.305361 sshd-session[12819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:54.309538 systemd-logind[1693]: New session 17 of user core. Mar 25 01:34:54.318908 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 25 01:34:54.751796 sshd[12821]: Connection closed by 10.200.16.10 port 32920 Mar 25 01:34:54.752190 sshd-session[12819]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:54.755597 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:32920.service: Deactivated successfully. Mar 25 01:34:54.757714 systemd[1]: session-17.scope: Deactivated successfully. Mar 25 01:34:54.760290 systemd-logind[1693]: Session 17 logged out. Waiting for processes to exit. Mar 25 01:34:54.761516 systemd-logind[1693]: Removed session 17. Mar 25 01:34:56.051806 kernel: EXT4-fs (loop6p9): unmounting filesystem 5775d2e2-188b-4921-88b6-682c3efa3461. Mar 25 01:34:56.052379 setup-nvidia[2042]: Container flatcardevelopercontainer-4284.0.0.bin exited successfully. Mar 25 01:34:56.055299 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4284.0.0.bin.mount: Deactivated successfully. Mar 25 01:34:56.111572 sudo[2030]: pam_unix(sudo:session): session closed for user root Mar 25 01:34:56.113334 systemd[1]: session-c2.scope: Deactivated successfully. Mar 25 01:34:56.113585 systemd[1]: session-c2.scope: Consumed 5min 18.498s CPU time, 881.9M memory peak. Mar 25 01:34:56.517801 setup-nvidia[1683]: /opt/nvidia / Mar 25 01:34:56.518834 setup-nvidia[1683]: / Mar 25 01:34:56.534250 setup-nvidia[12843]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:34:56.579801 kernel: IPMI message handler: version 39.2 Mar 25 01:34:56.583798 kernel: ipmi device interface Mar 25 01:34:56.590492 setup-nvidia[1683]: /opt/nvidia/current/usr/lib/modules/6.6.83-flatcar/video / Mar 25 01:34:56.642767 kernel: nvidia: loading out-of-tree module taints kernel. Mar 25 01:34:56.642914 kernel: nvidia: module license 'NVIDIA' taints kernel. Mar 25 01:34:56.642938 kernel: Disabling lock debugging due to kernel taint Mar 25 01:34:56.650079 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Mar 25 01:34:56.650149 kernel: nvidia: module license taints kernel. Mar 25 01:34:57.049805 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Mar 25 01:34:57.449928 kernel: Mar 25 01:34:57.450069 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Mar 25 01:34:57.656322 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Mar 25 01:34:57.685808 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Mar 25 01:34:57.704814 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Mar 25 01:34:57.748808 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Mar 25 01:34:57.748961 setup-nvidia[1683]: / Mar 25 01:34:59.468163 setup-nvidia[12875]: Tue Mar 25 01:34:59 2025 Mar 25 01:34:59.468163 setup-nvidia[12875]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:59.468163 setup-nvidia[12875]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Mar 25 01:34:59.468163 setup-nvidia[12875]: |-----------------------------------------+----------------------+----------------------+ Mar 25 01:34:59.468163 setup-nvidia[12875]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Mar 25 01:34:59.468163 setup-nvidia[12875]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Mar 25 01:34:59.468163 setup-nvidia[12875]: | | | MIG M. | Mar 25 01:34:59.468163 setup-nvidia[12875]: |=========================================+======================+======================| Mar 25 01:34:59.472189 setup-nvidia[12875]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Mar 25 01:34:59.472189 setup-nvidia[12875]: | N/A 25C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Mar 25 01:34:59.472189 setup-nvidia[12875]: | | | N/A | Mar 25 01:34:59.472189 setup-nvidia[12875]: +-----------------------------------------+----------------------+----------------------+ Mar 25 01:34:59.472321 setup-nvidia[12875]: Mar 25 01:34:59.472321 setup-nvidia[12875]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:59.472321 setup-nvidia[12875]: | Processes: | Mar 25 01:34:59.472321 setup-nvidia[12875]: | GPU GI CI PID Type Process name GPU Memory | Mar 25 01:34:59.472321 setup-nvidia[12875]: | ID ID Usage | Mar 25 01:34:59.472321 setup-nvidia[12875]: |=======================================================================================| Mar 25 01:34:59.472461 setup-nvidia[12875]: | No running processes found | Mar 25 01:34:59.472461 setup-nvidia[12875]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:59.840660 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 25 01:34:59.845678 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 25 01:34:59.850309 systemd[1]: Startup finished in 1.224s (firmware) + 26.806s (loader) + 1.863s (kernel) + 13.729s (initrd) + 2min 47.160s (userspace) = 3min 30.784s. Mar 25 01:35:00.100976 update_engine[1696]: I20250325 01:35:00.100811 1696 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 25 01:35:00.101331 update_engine[1696]: I20250325 01:35:00.101073 1696 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 25 01:35:00.101331 update_engine[1696]: I20250325 01:35:00.101289 1696 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 25 01:35:00.121565 update_engine[1696]: E20250325 01:35:00.121528 1696 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 25 01:35:00.121634 update_engine[1696]: I20250325 01:35:00.121582 1696 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Mar 25 01:35:00.121634 update_engine[1696]: I20250325 01:35:00.121593 1696 omaha_request_action.cc:617] Omaha request response: Mar 25 01:35:00.121687 update_engine[1696]: E20250325 01:35:00.121665 1696 omaha_request_action.cc:636] Omaha request network transfer failed. Mar 25 01:35:00.121717 update_engine[1696]: I20250325 01:35:00.121688 1696 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Mar 25 01:35:00.121717 update_engine[1696]: I20250325 01:35:00.121695 1696 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 25 01:35:00.121717 update_engine[1696]: I20250325 01:35:00.121701 1696 update_attempter.cc:306] Processing Done. Mar 25 01:35:00.121814 update_engine[1696]: E20250325 01:35:00.121716 1696 update_attempter.cc:619] Update failed. Mar 25 01:35:00.121814 update_engine[1696]: I20250325 01:35:00.121722 1696 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Mar 25 01:35:00.121814 update_engine[1696]: I20250325 01:35:00.121728 1696 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Mar 25 01:35:00.121814 update_engine[1696]: I20250325 01:35:00.121735 1696 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Mar 25 01:35:00.121905 update_engine[1696]: I20250325 01:35:00.121821 1696 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Mar 25 01:35:00.121905 update_engine[1696]: I20250325 01:35:00.121850 1696 omaha_request_action.cc:271] Posting an Omaha request to disabled Mar 25 01:35:00.121905 update_engine[1696]: I20250325 01:35:00.121858 1696 omaha_request_action.cc:272] Request: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: Mar 25 01:35:00.121905 update_engine[1696]: I20250325 01:35:00.121865 1696 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Mar 25 01:35:00.122166 update_engine[1696]: I20250325 01:35:00.121994 1696 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Mar 25 01:35:00.122166 update_engine[1696]: I20250325 01:35:00.122148 1696 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Mar 25 01:35:00.122218 locksmithd[1782]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Mar 25 01:35:00.143197 update_engine[1696]: E20250325 01:35:00.143158 1696 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143206 1696 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143216 1696 omaha_request_action.cc:617] Omaha request response: Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143224 1696 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143229 1696 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143235 1696 update_attempter.cc:306] Processing Done. Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143241 1696 update_attempter.cc:310] Error event sent. Mar 25 01:35:00.143259 update_engine[1696]: I20250325 01:35:00.143250 1696 update_check_scheduler.cc:74] Next update check in 42m24s Mar 25 01:35:00.143482 locksmithd[1782]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Mar 25 01:35:04.864204 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:40346.service - OpenSSH per-connection server daemon (10.200.16.10:40346). Mar 25 01:35:05.562850 sshd[12882]: Accepted publickey for core from 10.200.16.10 port 40346 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:05.564328 sshd-session[12882]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:05.568697 systemd-logind[1693]: New session 18 of user core. Mar 25 01:35:05.581903 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 25 01:35:06.011141 sshd[12884]: Connection closed by 10.200.16.10 port 40346 Mar 25 01:35:06.011514 sshd-session[12882]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:06.014743 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:40346.service: Deactivated successfully. Mar 25 01:35:06.016419 systemd[1]: session-18.scope: Deactivated successfully. Mar 25 01:35:06.017221 systemd-logind[1693]: Session 18 logged out. Waiting for processes to exit. Mar 25 01:35:06.018044 systemd-logind[1693]: Removed session 18. Mar 25 01:35:06.123182 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:40348.service - OpenSSH per-connection server daemon (10.200.16.10:40348). Mar 25 01:35:06.124005 systemd[1]: Stopping user@0.service - User Manager for UID 0... Mar 25 01:35:06.124800 systemd[2033]: Activating special unit exit.target... Mar 25 01:35:06.124882 systemd[2033]: Stopped target default.target - Main User Target. Mar 25 01:35:06.124927 systemd[2033]: Stopped target basic.target - Basic System. Mar 25 01:35:06.124963 systemd[2033]: Stopped target paths.target - Paths. Mar 25 01:35:06.124995 systemd[2033]: Stopped target sockets.target - Sockets. Mar 25 01:35:06.125035 systemd[2033]: Stopped target timers.target - Timers. Mar 25 01:35:06.125424 systemd[2033]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:35:06.125659 systemd[2033]: Removed slice app.slice - User Application Slice. Mar 25 01:35:06.125694 systemd[2033]: Reached target shutdown.target - Shutdown. Mar 25 01:35:06.125921 systemd[2033]: Finished systemd-exit.service - Exit the Session. Mar 25 01:35:06.125973 systemd[2033]: Reached target exit.target - Exit the Session. Mar 25 01:35:06.144566 (sd-pam)[2034]: pam_unix(systemd-user:session): session closed for user root Mar 25 01:35:06.147031 systemd[1]: user@0.service: Deactivated successfully. Mar 25 01:35:06.147593 systemd[1]: Stopped user@0.service - User Manager for UID 0. Mar 25 01:35:06.149351 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 25 01:35:06.172796 systemd[1]: run-user-0.mount: Deactivated successfully. Mar 25 01:35:06.173761 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Mar 25 01:35:06.174018 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 25 01:35:06.176192 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Mar 25 01:35:06.176298 systemd[1]: user-0.slice: Consumed 5min 18.786s CPU time, 890.8M memory peak. Mar 25 01:35:06.176624 systemd-logind[1693]: Removed session c3. Mar 25 01:35:06.761671 sshd[12890]: Accepted publickey for core from 10.200.16.10 port 40348 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:06.764503 sshd-session[12890]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:06.768932 systemd-logind[1693]: New session 19 of user core. Mar 25 01:35:06.779918 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 25 01:35:07.214971 sshd[12896]: Connection closed by 10.200.16.10 port 40348 Mar 25 01:35:07.215279 sshd-session[12890]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:07.218713 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:40348.service: Deactivated successfully. Mar 25 01:35:07.220481 systemd[1]: session-19.scope: Deactivated successfully. Mar 25 01:35:07.221201 systemd-logind[1693]: Session 19 logged out. Waiting for processes to exit. Mar 25 01:35:07.222013 systemd-logind[1693]: Removed session 19. Mar 25 01:35:07.326334 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:40354.service - OpenSSH per-connection server daemon (10.200.16.10:40354). Mar 25 01:35:07.960635 sshd[12902]: Accepted publickey for core from 10.200.16.10 port 40354 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:07.962077 sshd-session[12902]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:07.966446 systemd-logind[1693]: New session 20 of user core. Mar 25 01:35:07.974913 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 25 01:35:08.404981 sshd[12904]: Connection closed by 10.200.16.10 port 40354 Mar 25 01:35:08.405364 sshd-session[12902]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:08.410731 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:40354.service: Deactivated successfully. Mar 25 01:35:08.412488 systemd[1]: session-20.scope: Deactivated successfully. Mar 25 01:35:08.413198 systemd-logind[1693]: Session 20 logged out. Waiting for processes to exit. Mar 25 01:35:08.414026 systemd-logind[1693]: Removed session 20. Mar 25 01:35:08.516371 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:40368.service - OpenSSH per-connection server daemon (10.200.16.10:40368). Mar 25 01:35:09.150394 sshd[12910]: Accepted publickey for core from 10.200.16.10 port 40368 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:09.152014 sshd-session[12910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:09.156231 systemd-logind[1693]: New session 21 of user core. Mar 25 01:35:09.165922 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 25 01:35:09.598966 sshd[12912]: Connection closed by 10.200.16.10 port 40368 Mar 25 01:35:09.599334 sshd-session[12910]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:09.602628 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:40368.service: Deactivated successfully. Mar 25 01:35:09.604352 systemd[1]: session-21.scope: Deactivated successfully. Mar 25 01:35:09.605058 systemd-logind[1693]: Session 21 logged out. Waiting for processes to exit. Mar 25 01:35:09.605906 systemd-logind[1693]: Removed session 21. Mar 25 01:35:09.730386 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:57364.service - OpenSSH per-connection server daemon (10.200.16.10:57364). Mar 25 01:35:10.374569 sshd[12918]: Accepted publickey for core from 10.200.16.10 port 57364 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:10.375998 sshd-session[12918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:10.380224 systemd-logind[1693]: New session 22 of user core. Mar 25 01:35:10.390930 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 25 01:35:12.305365 sshd[12920]: Connection closed by 10.200.16.10 port 57364 Mar 25 01:35:12.305778 sshd-session[12918]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:12.309093 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:57364.service: Deactivated successfully. Mar 25 01:35:12.310684 systemd[1]: session-22.scope: Deactivated successfully. Mar 25 01:35:12.310891 systemd[1]: session-22.scope: Consumed 1.401s CPU time, 14M memory peak. Mar 25 01:35:12.311322 systemd-logind[1693]: Session 22 logged out. Waiting for processes to exit. Mar 25 01:35:12.312105 systemd-logind[1693]: Removed session 22.