Mar 19 11:41:00.217803 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Wed Mar 19 10:13:43 -00 2025 Mar 19 11:41:00.217831 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=08c32ef14ad6302a92b1d281c48443f5b56d59f0d37d38df628e5b6f012967bc Mar 19 11:41:00.217841 kernel: BIOS-provided physical RAM map: Mar 19 11:41:00.217848 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 19 11:41:00.217855 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 19 11:41:00.217861 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 19 11:41:00.217869 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 19 11:41:00.217878 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 19 11:41:00.217885 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 19 11:41:00.217892 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 19 11:41:00.217898 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Mar 19 11:41:00.217905 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Mar 19 11:41:00.217912 kernel: printk: bootconsole [earlyser0] enabled Mar 19 11:41:00.217919 kernel: NX (Execute Disable) protection: active Mar 19 11:41:00.217930 kernel: APIC: Static calls initialized Mar 19 11:41:00.217937 kernel: efi: EFI v2.7 by Microsoft Mar 19 11:41:00.217945 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Mar 19 11:41:00.217952 kernel: random: crng init done Mar 19 11:41:00.217960 kernel: secureboot: Secure boot disabled Mar 19 11:41:00.217967 kernel: SMBIOS 3.1.0 present. Mar 19 11:41:00.217975 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 19 11:41:00.217983 kernel: Hypervisor detected: Microsoft Hyper-V Mar 19 11:41:00.217990 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Mar 19 11:41:00.217998 kernel: Hyper-V: Host Build 10.0.20348.1799-1-0 Mar 19 11:41:00.218007 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 19 11:41:00.218015 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 19 11:41:00.218023 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 19 11:41:00.218030 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 19 11:41:00.218038 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 19 11:41:00.218046 kernel: tsc: Detected 2593.990 MHz processor Mar 19 11:41:00.218054 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 19 11:41:00.218062 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 19 11:41:00.218069 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Mar 19 11:41:00.218079 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Mar 19 11:41:00.218087 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 19 11:41:00.218094 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 19 11:41:00.218102 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Mar 19 11:41:00.218109 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 19 11:41:00.218117 kernel: Using GB pages for direct mapping Mar 19 11:41:00.218124 kernel: ACPI: Early table checksum verification disabled Mar 19 11:41:00.218136 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 19 11:41:00.218146 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218154 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218162 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 19 11:41:00.218170 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 19 11:41:00.218178 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218186 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218197 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218205 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218213 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218221 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218229 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 19 11:41:00.218237 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 19 11:41:00.218245 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 19 11:41:00.218253 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 19 11:41:00.218263 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 19 11:41:00.218271 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 19 11:41:00.218279 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 19 11:41:00.218287 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Mar 19 11:41:00.218295 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Mar 19 11:41:00.218303 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 19 11:41:00.218311 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 19 11:41:00.218318 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 19 11:41:00.218326 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 19 11:41:00.218337 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Mar 19 11:41:00.218345 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Mar 19 11:41:00.218353 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Mar 19 11:41:00.218360 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Mar 19 11:41:00.218368 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 19 11:41:00.218376 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Mar 19 11:41:00.218384 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Mar 19 11:41:00.218392 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Mar 19 11:41:00.218400 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Mar 19 11:41:00.218410 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Mar 19 11:41:00.218418 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Mar 19 11:41:00.218426 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Mar 19 11:41:00.218434 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Mar 19 11:41:00.218443 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Mar 19 11:41:00.218450 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Mar 19 11:41:00.218459 kernel: Zone ranges: Mar 19 11:41:00.218467 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 19 11:41:00.218474 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 19 11:41:00.218485 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Mar 19 11:41:00.218493 kernel: Movable zone start for each node Mar 19 11:41:00.218501 kernel: Early memory node ranges Mar 19 11:41:00.218509 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 19 11:41:00.218517 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 19 11:41:00.218524 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 19 11:41:00.218532 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Mar 19 11:41:00.218540 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Mar 19 11:41:00.218548 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Mar 19 11:41:00.218559 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 19 11:41:00.218567 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 19 11:41:00.218575 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 19 11:41:00.218583 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 19 11:41:00.218591 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 19 11:41:00.218599 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Mar 19 11:41:00.218607 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 19 11:41:00.218615 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 19 11:41:00.218623 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 19 11:41:00.218633 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Mar 19 11:41:00.218641 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 19 11:41:00.218653 kernel: Booting paravirtualized kernel on Hyper-V Mar 19 11:41:00.218661 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 19 11:41:00.218669 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Mar 19 11:41:00.218677 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Mar 19 11:41:00.218685 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Mar 19 11:41:00.218693 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Mar 19 11:41:00.218701 kernel: Hyper-V: PV spinlocks enabled Mar 19 11:41:00.218739 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 19 11:41:00.218748 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=08c32ef14ad6302a92b1d281c48443f5b56d59f0d37d38df628e5b6f012967bc Mar 19 11:41:00.218757 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 19 11:41:00.218765 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Mar 19 11:41:00.218773 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Mar 19 11:41:00.218781 kernel: Fallback order for Node 0: 0 Mar 19 11:41:00.218789 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Mar 19 11:41:00.218797 kernel: Policy zone: Normal Mar 19 11:41:00.218808 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 19 11:41:00.218824 kernel: software IO TLB: area num 8. Mar 19 11:41:00.218833 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2303K rwdata, 22860K rodata, 43480K init, 1592K bss, 2103120K reserved, 0K cma-reserved) Mar 19 11:41:00.218844 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 19 11:41:00.218852 kernel: Kernel/User page tables isolation: enabled Mar 19 11:41:00.218860 kernel: ftrace: allocating 37910 entries in 149 pages Mar 19 11:41:00.218869 kernel: ftrace: allocated 149 pages with 4 groups Mar 19 11:41:00.218877 kernel: Dynamic Preempt: voluntary Mar 19 11:41:00.218885 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 19 11:41:00.218895 kernel: rcu: RCU event tracing is enabled. Mar 19 11:41:00.218903 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Mar 19 11:41:00.218912 kernel: Trampoline variant of Tasks RCU enabled. Mar 19 11:41:00.218923 kernel: Rude variant of Tasks RCU enabled. Mar 19 11:41:00.218932 kernel: Tracing variant of Tasks RCU enabled. Mar 19 11:41:00.218940 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 19 11:41:00.218948 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Mar 19 11:41:00.218959 kernel: Using NULL legacy PIC Mar 19 11:41:00.218968 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Mar 19 11:41:00.218976 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 19 11:41:00.218985 kernel: Console: colour dummy device 80x25 Mar 19 11:41:00.218993 kernel: printk: console [tty1] enabled Mar 19 11:41:00.219001 kernel: printk: console [ttyS0] enabled Mar 19 11:41:00.219010 kernel: printk: bootconsole [earlyser0] disabled Mar 19 11:41:00.219018 kernel: ACPI: Core revision 20230628 Mar 19 11:41:00.219026 kernel: Failed to register legacy timer interrupt Mar 19 11:41:00.219035 kernel: APIC: Switch to symmetric I/O mode setup Mar 19 11:41:00.219046 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 19 11:41:00.219054 kernel: Hyper-V: Using IPI hypercalls Mar 19 11:41:00.219063 kernel: APIC: send_IPI() replaced with hv_send_ipi() Mar 19 11:41:00.219071 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Mar 19 11:41:00.219080 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Mar 19 11:41:00.219088 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Mar 19 11:41:00.219097 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Mar 19 11:41:00.219105 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Mar 19 11:41:00.219114 kernel: Hyper-V: Using enlightened APIC (xapic mode) Mar 19 11:41:00.219124 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Mar 19 11:41:00.219133 kernel: APIC: read() replaced with hv_apic_read() Mar 19 11:41:00.219141 kernel: APIC: write() replaced with hv_apic_write() Mar 19 11:41:00.219149 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Mar 19 11:41:00.219158 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Mar 19 11:41:00.219166 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Mar 19 11:41:00.219175 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Mar 19 11:41:00.219183 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 19 11:41:00.219192 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 19 11:41:00.219202 kernel: Spectre V2 : Mitigation: Retpolines Mar 19 11:41:00.219211 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 19 11:41:00.219219 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 19 11:41:00.219227 kernel: Speculative Store Bypass: Vulnerable Mar 19 11:41:00.219235 kernel: MDS: Mitigation: Clear CPU buffers Mar 19 11:41:00.219243 kernel: TAA: Mitigation: Clear CPU buffers Mar 19 11:41:00.219251 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 19 11:41:00.219260 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 19 11:41:00.219268 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 19 11:41:00.219276 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 19 11:41:00.219284 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 19 11:41:00.219295 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Mar 19 11:41:00.219304 kernel: Freeing SMP alternatives memory: 32K Mar 19 11:41:00.219312 kernel: pid_max: default: 32768 minimum: 301 Mar 19 11:41:00.219320 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 19 11:41:00.219328 kernel: landlock: Up and running. Mar 19 11:41:00.219336 kernel: SELinux: Initializing. Mar 19 11:41:00.219344 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 19 11:41:00.219353 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 19 11:41:00.219361 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Mar 19 11:41:00.219370 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 19 11:41:00.219378 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 19 11:41:00.219389 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 19 11:41:00.219398 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Mar 19 11:41:00.219406 kernel: signal: max sigframe size: 1776 Mar 19 11:41:00.219415 kernel: rcu: Hierarchical SRCU implementation. Mar 19 11:41:00.219423 kernel: rcu: Max phase no-delay instances is 400. Mar 19 11:41:00.219431 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 19 11:41:00.219440 kernel: smp: Bringing up secondary CPUs ... Mar 19 11:41:00.219448 kernel: smpboot: x86: Booting SMP configuration: Mar 19 11:41:00.219456 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Mar 19 11:41:00.219467 kernel: smp: Brought up 1 node, 6 CPUs Mar 19 11:41:00.219476 kernel: smpboot: Max logical packages: 1 Mar 19 11:41:00.219484 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Mar 19 11:41:00.219492 kernel: devtmpfs: initialized Mar 19 11:41:00.219501 kernel: x86/mm: Memory block size: 1024MB Mar 19 11:41:00.219509 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 19 11:41:00.219518 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 19 11:41:00.219526 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Mar 19 11:41:00.219535 kernel: pinctrl core: initialized pinctrl subsystem Mar 19 11:41:00.219546 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 19 11:41:00.219554 kernel: audit: initializing netlink subsys (disabled) Mar 19 11:41:00.219563 kernel: audit: type=2000 audit(1742384458.028:1): state=initialized audit_enabled=0 res=1 Mar 19 11:41:00.219571 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 19 11:41:00.219579 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 19 11:41:00.219588 kernel: cpuidle: using governor menu Mar 19 11:41:00.219596 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 19 11:41:00.219604 kernel: dca service started, version 1.12.1 Mar 19 11:41:00.219613 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Mar 19 11:41:00.219624 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 19 11:41:00.219632 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 19 11:41:00.219640 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 19 11:41:00.219649 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 19 11:41:00.219657 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 19 11:41:00.219665 kernel: ACPI: Added _OSI(Module Device) Mar 19 11:41:00.219674 kernel: ACPI: Added _OSI(Processor Device) Mar 19 11:41:00.219682 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 19 11:41:00.219691 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 19 11:41:00.219701 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 19 11:41:00.219719 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 19 11:41:00.219727 kernel: ACPI: Interpreter enabled Mar 19 11:41:00.219736 kernel: ACPI: PM: (supports S0 S5) Mar 19 11:41:00.219744 kernel: ACPI: Using IOAPIC for interrupt routing Mar 19 11:41:00.219753 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 19 11:41:00.219761 kernel: PCI: Ignoring E820 reservations for host bridge windows Mar 19 11:41:00.219769 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 19 11:41:00.219778 kernel: iommu: Default domain type: Translated Mar 19 11:41:00.219789 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 19 11:41:00.219797 kernel: efivars: Registered efivars operations Mar 19 11:41:00.219806 kernel: PCI: Using ACPI for IRQ routing Mar 19 11:41:00.219814 kernel: PCI: System does not support PCI Mar 19 11:41:00.219823 kernel: vgaarb: loaded Mar 19 11:41:00.219831 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 19 11:41:00.219839 kernel: VFS: Disk quotas dquot_6.6.0 Mar 19 11:41:00.219848 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 19 11:41:00.219856 kernel: pnp: PnP ACPI init Mar 19 11:41:00.219867 kernel: pnp: PnP ACPI: found 3 devices Mar 19 11:41:00.219876 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 19 11:41:00.219884 kernel: NET: Registered PF_INET protocol family Mar 19 11:41:00.219892 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 19 11:41:00.219901 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 19 11:41:00.219909 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 19 11:41:00.219918 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 19 11:41:00.219926 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 19 11:41:00.219937 kernel: TCP: Hash tables configured (established 524288 bind 65536) Mar 19 11:41:00.219946 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 19 11:41:00.219954 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 19 11:41:00.219962 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 19 11:41:00.219971 kernel: NET: Registered PF_XDP protocol family Mar 19 11:41:00.219979 kernel: PCI: CLS 0 bytes, default 64 Mar 19 11:41:00.219987 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 19 11:41:00.219996 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Mar 19 11:41:00.220004 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 19 11:41:00.220015 kernel: Initialise system trusted keyrings Mar 19 11:41:00.220024 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Mar 19 11:41:00.220032 kernel: Key type asymmetric registered Mar 19 11:41:00.220040 kernel: Asymmetric key parser 'x509' registered Mar 19 11:41:00.220048 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 19 11:41:00.220057 kernel: io scheduler mq-deadline registered Mar 19 11:41:00.220065 kernel: io scheduler kyber registered Mar 19 11:41:00.220073 kernel: io scheduler bfq registered Mar 19 11:41:00.220081 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 19 11:41:00.220090 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 19 11:41:00.220101 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 19 11:41:00.220109 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 19 11:41:00.220118 kernel: i8042: PNP: No PS/2 controller found. Mar 19 11:41:00.220267 kernel: rtc_cmos 00:02: registered as rtc0 Mar 19 11:41:00.220377 kernel: rtc_cmos 00:02: setting system clock to 2025-03-19T11:40:59 UTC (1742384459) Mar 19 11:41:00.220477 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 19 11:41:00.220489 kernel: intel_pstate: CPU model not supported Mar 19 11:41:00.220503 kernel: efifb: probing for efifb Mar 19 11:41:00.220511 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 19 11:41:00.220520 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 19 11:41:00.220528 kernel: efifb: scrolling: redraw Mar 19 11:41:00.220537 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 19 11:41:00.220545 kernel: Console: switching to colour frame buffer device 128x48 Mar 19 11:41:00.220554 kernel: fb0: EFI VGA frame buffer device Mar 19 11:41:00.220562 kernel: pstore: Using crash dump compression: deflate Mar 19 11:41:00.220571 kernel: pstore: Registered efi_pstore as persistent store backend Mar 19 11:41:00.220582 kernel: NET: Registered PF_INET6 protocol family Mar 19 11:41:00.220590 kernel: Segment Routing with IPv6 Mar 19 11:41:00.220598 kernel: In-situ OAM (IOAM) with IPv6 Mar 19 11:41:00.220607 kernel: NET: Registered PF_PACKET protocol family Mar 19 11:41:00.220615 kernel: Key type dns_resolver registered Mar 19 11:41:00.220624 kernel: IPI shorthand broadcast: enabled Mar 19 11:41:00.220632 kernel: sched_clock: Marking stable (1623004900, 102321600)->(2064776600, -339450100) Mar 19 11:41:00.220640 kernel: registered taskstats version 1 Mar 19 11:41:00.220649 kernel: Loading compiled-in X.509 certificates Mar 19 11:41:00.220660 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: ea8d6696bd19c98b32173a761210456cdad6b56b' Mar 19 11:41:00.220668 kernel: Key type .fscrypt registered Mar 19 11:41:00.220676 kernel: Key type fscrypt-provisioning registered Mar 19 11:41:00.220685 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 19 11:41:00.220693 kernel: ima: Allocated hash algorithm: sha1 Mar 19 11:41:00.220702 kernel: ima: No architecture policies found Mar 19 11:41:00.220723 kernel: clk: Disabling unused clocks Mar 19 11:41:00.220731 kernel: Freeing unused kernel image (initmem) memory: 43480K Mar 19 11:41:00.220740 kernel: Write protecting the kernel read-only data: 38912k Mar 19 11:41:00.220751 kernel: Freeing unused kernel image (rodata/data gap) memory: 1716K Mar 19 11:41:00.220760 kernel: Run /init as init process Mar 19 11:41:00.220768 kernel: with arguments: Mar 19 11:41:00.220776 kernel: /init Mar 19 11:41:00.220785 kernel: with environment: Mar 19 11:41:00.220793 kernel: HOME=/ Mar 19 11:41:00.220801 kernel: TERM=linux Mar 19 11:41:00.220809 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 19 11:41:00.220819 systemd[1]: Successfully made /usr/ read-only. Mar 19 11:41:00.220833 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 19 11:41:00.220843 systemd[1]: Detected virtualization microsoft. Mar 19 11:41:00.220851 systemd[1]: Detected architecture x86-64. Mar 19 11:41:00.220860 systemd[1]: Running in initrd. Mar 19 11:41:00.220868 systemd[1]: No hostname configured, using default hostname. Mar 19 11:41:00.220877 systemd[1]: Hostname set to . Mar 19 11:41:00.220886 systemd[1]: Initializing machine ID from random generator. Mar 19 11:41:00.220898 systemd[1]: Queued start job for default target initrd.target. Mar 19 11:41:00.220907 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 19 11:41:00.220916 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 19 11:41:00.220925 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 19 11:41:00.220934 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 19 11:41:00.220943 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 19 11:41:00.220953 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 19 11:41:00.220966 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 19 11:41:00.220975 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 19 11:41:00.220984 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 19 11:41:00.220993 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 19 11:41:00.221002 systemd[1]: Reached target paths.target - Path Units. Mar 19 11:41:00.221011 systemd[1]: Reached target slices.target - Slice Units. Mar 19 11:41:00.221020 systemd[1]: Reached target swap.target - Swaps. Mar 19 11:41:00.221029 systemd[1]: Reached target timers.target - Timer Units. Mar 19 11:41:00.221040 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 19 11:41:00.221049 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 19 11:41:00.221058 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 19 11:41:00.221067 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 19 11:41:00.221076 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 19 11:41:00.221085 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 19 11:41:00.221094 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 19 11:41:00.221103 systemd[1]: Reached target sockets.target - Socket Units. Mar 19 11:41:00.221112 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 19 11:41:00.221124 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 19 11:41:00.221133 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 19 11:41:00.221141 systemd[1]: Starting systemd-fsck-usr.service... Mar 19 11:41:00.221150 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 19 11:41:00.221159 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 19 11:41:00.221188 systemd-journald[205]: Collecting audit messages is disabled. Mar 19 11:41:00.221214 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:00.221224 systemd-journald[205]: Journal started Mar 19 11:41:00.221244 systemd-journald[205]: Runtime Journal (/run/log/journal/aca9c031b5244d8b8f68928fde5a774a) is 8M, max 2.2G, 2.1G free. Mar 19 11:41:00.229335 systemd[1]: Started systemd-journald.service - Journal Service. Mar 19 11:41:00.230417 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 19 11:41:00.230962 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 19 11:41:00.231682 systemd[1]: Finished systemd-fsck-usr.service. Mar 19 11:41:00.257764 systemd-modules-load[207]: Inserted module 'overlay' Mar 19 11:41:00.259474 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 19 11:41:00.260593 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 19 11:41:00.265728 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 19 11:41:00.274888 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:00.286399 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 19 11:41:00.295012 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 19 11:41:00.310561 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 19 11:41:00.333375 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 19 11:41:00.339481 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 19 11:41:00.350592 systemd-modules-load[207]: Inserted module 'br_netfilter' Mar 19 11:41:00.352057 kernel: Bridge firewalling registered Mar 19 11:41:00.351515 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 19 11:41:00.372861 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 19 11:41:00.374514 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 19 11:41:00.393292 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 19 11:41:00.414375 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 19 11:41:00.417161 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 19 11:41:00.431051 dracut-cmdline[240]: dracut-dracut-053 Mar 19 11:41:00.431051 dracut-cmdline[240]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=08c32ef14ad6302a92b1d281c48443f5b56d59f0d37d38df628e5b6f012967bc Mar 19 11:41:00.482384 systemd-resolved[250]: Positive Trust Anchors: Mar 19 11:41:00.482401 systemd-resolved[250]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 19 11:41:00.482432 systemd-resolved[250]: 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 19 11:41:00.485137 systemd-resolved[250]: Defaulting to hostname 'linux'. Mar 19 11:41:00.486253 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 19 11:41:00.487775 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 19 11:41:00.575729 kernel: SCSI subsystem initialized Mar 19 11:41:00.586727 kernel: Loading iSCSI transport class v2.0-870. Mar 19 11:41:00.600749 kernel: iscsi: registered transport (tcp) Mar 19 11:41:00.623672 kernel: iscsi: registered transport (qla4xxx) Mar 19 11:41:00.623701 kernel: QLogic iSCSI HBA Driver Mar 19 11:41:00.665210 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 19 11:41:00.687825 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 19 11:41:00.717799 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 19 11:41:00.717841 kernel: device-mapper: uevent: version 1.0.3 Mar 19 11:41:00.722491 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 19 11:41:00.764726 kernel: raid6: avx2x4 gen() 30859 MB/s Mar 19 11:41:00.784727 kernel: raid6: avx2x2 gen() 31333 MB/s Mar 19 11:41:00.805991 kernel: raid6: avx2x1 gen() 28032 MB/s Mar 19 11:41:00.806018 kernel: raid6: using algorithm avx2x2 gen() 31333 MB/s Mar 19 11:41:00.827929 kernel: raid6: .... xor() 18425 MB/s, rmw enabled Mar 19 11:41:00.827956 kernel: raid6: using avx2x2 recovery algorithm Mar 19 11:41:00.851725 kernel: xor: automatically using best checksumming function avx Mar 19 11:41:01.009734 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 19 11:41:01.020798 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 19 11:41:01.038894 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 19 11:41:01.060996 systemd-udevd[426]: Using default interface naming scheme 'v255'. Mar 19 11:41:01.066541 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 19 11:41:01.092860 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 19 11:41:01.108794 dracut-pre-trigger[438]: rd.md=0: removing MD RAID activation Mar 19 11:41:01.135882 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 19 11:41:01.146900 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 19 11:41:01.205283 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 19 11:41:01.222919 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 19 11:41:01.235745 kernel: cryptd: max_cpu_qlen set to 1000 Mar 19 11:41:01.238329 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 19 11:41:01.247504 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 19 11:41:01.263837 kernel: AVX2 version of gcm_enc/dec engaged. Mar 19 11:41:01.263861 kernel: AES CTR mode by8 optimization enabled Mar 19 11:41:01.263735 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 19 11:41:01.265367 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 19 11:41:01.283859 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 19 11:41:01.294348 kernel: hv_vmbus: Vmbus version:4.0 Mar 19 11:41:01.294693 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 19 11:41:01.294886 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 19 11:41:01.304233 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 19 11:41:01.313293 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 19 11:41:01.336813 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 19 11:41:01.336834 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 19 11:41:01.313955 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:01.351610 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 19 11:41:01.351630 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 19 11:41:01.326448 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:01.362720 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 19 11:41:01.362751 kernel: hv_vmbus: registering driver hv_netvsc Mar 19 11:41:01.366970 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:01.378972 kernel: hv_vmbus: registering driver hid_hyperv Mar 19 11:41:01.372205 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 19 11:41:01.394243 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 19 11:41:01.394272 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 19 11:41:01.380482 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 19 11:41:01.400023 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 19 11:41:01.400132 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:01.414119 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 19 11:41:01.424654 kernel: PTP clock support registered Mar 19 11:41:01.427722 kernel: hv_vmbus: registering driver hv_pci Mar 19 11:41:01.430726 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Mar 19 11:41:01.522323 kernel: hv_vmbus: registering driver hv_storvsc Mar 19 11:41:01.522342 kernel: hv_utils: Registering HyperV Utility Driver Mar 19 11:41:01.522355 kernel: hv_vmbus: registering driver hv_utils Mar 19 11:41:01.522367 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Mar 19 11:41:01.522525 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Mar 19 11:41:01.522680 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Mar 19 11:41:01.522837 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Mar 19 11:41:01.522961 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Mar 19 11:41:01.523108 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Mar 19 11:41:01.523248 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 19 11:41:01.523381 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 19 11:41:01.523516 kernel: pci 0001:00:00.0: Enabling HDA controller Mar 19 11:41:01.523650 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Mar 19 11:41:01.523799 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 19 11:41:01.523936 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 19 11:41:01.524065 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Mar 19 11:41:01.434911 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:01.475230 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:01.487188 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 19 11:41:01.510277 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 19 11:41:01.747946 kernel: hv_utils: Heartbeat IC version 3.0 Mar 19 11:41:01.748010 kernel: hv_utils: Shutdown IC version 3.2 Mar 19 11:41:01.750519 kernel: hv_utils: TimeSync IC version 4.0 Mar 19 11:41:02.852464 systemd-resolved[250]: Clock change detected. Flushing caches. Mar 19 11:41:02.873246 kernel: scsi host0: storvsc_host_t Mar 19 11:41:02.873444 kernel: scsi host1: storvsc_host_t Mar 19 11:41:02.873619 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 19 11:41:02.873648 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 19 11:41:02.873667 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 19 11:41:02.888410 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 19 11:41:02.889575 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 19 11:41:02.889593 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 19 11:41:02.902897 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Mar 19 11:41:02.934804 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 19 11:41:02.934979 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Mar 19 11:41:02.935143 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 19 11:41:02.935286 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 19 11:41:02.935431 kernel: sd 0:0:0:1: [sdb] Write Protect is off Mar 19 11:41:02.935590 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 19 11:41:02.935734 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 19 11:41:02.935898 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Mar 19 11:41:02.936056 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 19 11:41:02.936070 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 19 11:41:02.936211 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 19 11:41:02.936354 kernel: sdb: sdb1 Mar 19 11:41:02.936368 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Mar 19 11:41:03.485554 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (497) Mar 19 11:41:03.506550 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 19 11:41:03.523249 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 19 11:41:03.539675 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 19 11:41:03.694525 kernel: BTRFS: device fsid 8d57424d-5abc-4888-810f-658d040a58e4 devid 1 transid 36 /dev/sda3 scanned by (udev-worker) (491) Mar 19 11:41:03.714788 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 19 11:41:03.716307 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 19 11:41:03.744628 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 19 11:41:03.764506 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 19 11:41:03.772511 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 19 11:41:04.778991 disk-uuid[646]: The operation has completed successfully. Mar 19 11:41:04.782852 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 19 11:41:05.096423 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 19 11:41:05.096551 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 19 11:41:05.132625 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 19 11:41:05.141733 sh[735]: Success Mar 19 11:41:05.172515 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 19 11:41:05.418828 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 19 11:41:05.449922 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 19 11:41:05.451589 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 19 11:41:05.476170 kernel: BTRFS info (device dm-0): first mount of filesystem 8d57424d-5abc-4888-810f-658d040a58e4 Mar 19 11:41:05.476205 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 19 11:41:05.481059 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 19 11:41:05.485742 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 19 11:41:05.488843 kernel: BTRFS info (device dm-0): using free space tree Mar 19 11:41:06.010416 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 19 11:41:06.018047 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 19 11:41:06.032622 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 19 11:41:06.037418 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 19 11:41:06.064531 kernel: BTRFS info (device sda6): first mount of filesystem 3c2c2d54-a06e-4f36-8d13-ab30a5d0eab5 Mar 19 11:41:06.064581 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 19 11:41:06.070821 kernel: BTRFS info (device sda6): using free space tree Mar 19 11:41:06.118520 kernel: BTRFS info (device sda6): auto enabling async discard Mar 19 11:41:06.125589 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 19 11:41:06.131907 kernel: BTRFS info (device sda6): last unmount of filesystem 3c2c2d54-a06e-4f36-8d13-ab30a5d0eab5 Mar 19 11:41:06.131244 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 19 11:41:06.153624 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 19 11:41:06.165338 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 19 11:41:06.167743 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 19 11:41:06.186447 systemd-networkd[917]: lo: Link UP Mar 19 11:41:06.186458 systemd-networkd[917]: lo: Gained carrier Mar 19 11:41:06.187666 systemd-networkd[917]: Enumeration completed Mar 19 11:41:06.187760 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 19 11:41:06.188039 systemd-networkd[917]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 19 11:41:06.188044 systemd-networkd[917]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 19 11:41:06.188820 systemd-networkd[917]: eth0: Link UP Mar 19 11:41:06.188987 systemd-networkd[917]: eth0: Gained carrier Mar 19 11:41:06.188996 systemd-networkd[917]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 19 11:41:06.190883 systemd[1]: Reached target network.target - Network. Mar 19 11:41:06.254530 systemd-networkd[917]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 19 11:41:07.391617 systemd-networkd[917]: eth0: Gained IPv6LL Mar 19 11:41:07.644200 ignition[921]: Ignition 2.20.0 Mar 19 11:41:07.644214 ignition[921]: Stage: fetch-offline Mar 19 11:41:07.644255 ignition[921]: no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:07.644266 ignition[921]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:07.644379 ignition[921]: parsed url from cmdline: "" Mar 19 11:41:07.644383 ignition[921]: no config URL provided Mar 19 11:41:07.644389 ignition[921]: reading system config file "/usr/lib/ignition/user.ign" Mar 19 11:41:07.644399 ignition[921]: no config at "/usr/lib/ignition/user.ign" Mar 19 11:41:07.644405 ignition[921]: failed to fetch config: resource requires networking Mar 19 11:41:07.644603 ignition[921]: Ignition finished successfully Mar 19 11:41:07.675337 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 19 11:41:07.697688 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 19 11:41:07.709948 ignition[932]: Ignition 2.20.0 Mar 19 11:41:07.709960 ignition[932]: Stage: fetch Mar 19 11:41:07.710113 ignition[932]: no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:07.710124 ignition[932]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:07.710223 ignition[932]: parsed url from cmdline: "" Mar 19 11:41:07.710229 ignition[932]: no config URL provided Mar 19 11:41:07.710235 ignition[932]: reading system config file "/usr/lib/ignition/user.ign" Mar 19 11:41:07.710247 ignition[932]: no config at "/usr/lib/ignition/user.ign" Mar 19 11:41:07.710272 ignition[932]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 19 11:41:07.789171 ignition[932]: GET result: OK Mar 19 11:41:07.789193 ignition[932]: failed to retrieve userdata from IMDS, falling back to custom data: not a config (empty) Mar 19 11:41:07.825930 ignition[932]: opening config device: "/dev/sr0" Mar 19 11:41:07.826344 ignition[932]: getting drive status for "/dev/sr0" Mar 19 11:41:07.826417 ignition[932]: drive status: OK Mar 19 11:41:07.826448 ignition[932]: mounting config device Mar 19 11:41:07.826458 ignition[932]: op(1): [started] mounting "/dev/sr0" at "/tmp/ignition-azure2993955671" Mar 19 11:41:07.844515 kernel: UDF-fs: INFO Mounting volume 'UDF Volume', timestamp 2025/03/20 00:00 (1000) Mar 19 11:41:07.844659 ignition[932]: op(1): [finished] mounting "/dev/sr0" at "/tmp/ignition-azure2993955671" Mar 19 11:41:07.844670 ignition[932]: checking for config drive Mar 19 11:41:07.846175 systemd[1]: tmp-ignition\x2dazure2993955671.mount: Deactivated successfully. Mar 19 11:41:07.845037 ignition[932]: reading config Mar 19 11:41:07.845387 ignition[932]: op(2): [started] unmounting "/dev/sr0" at "/tmp/ignition-azure2993955671" Mar 19 11:41:07.850272 ignition[932]: op(2): [finished] unmounting "/dev/sr0" at "/tmp/ignition-azure2993955671" Mar 19 11:41:07.856224 unknown[932]: fetched base config from "system" Mar 19 11:41:07.850288 ignition[932]: config has been read from custom data Mar 19 11:41:07.856232 unknown[932]: fetched base config from "system" Mar 19 11:41:07.850300 ignition[932]: parsing config with SHA512: 4c31a54c69624e8de7c9d5102e6e6ed217a510b9982d71edd446dc0a6b8a0e02183edd624f29e1c9ca969899f22b92e15c740fb605f97c024bdb4f9f8a0a14b4 Mar 19 11:41:07.856238 unknown[932]: fetched user config from "azure" Mar 19 11:41:07.856431 ignition[932]: fetch: fetch complete Mar 19 11:41:07.858589 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 19 11:41:07.856437 ignition[932]: fetch: fetch passed Mar 19 11:41:07.882621 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 19 11:41:07.856483 ignition[932]: Ignition finished successfully Mar 19 11:41:07.913415 ignition[943]: Ignition 2.20.0 Mar 19 11:41:07.913429 ignition[943]: Stage: kargs Mar 19 11:41:07.913657 ignition[943]: no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:07.913670 ignition[943]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:07.921929 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 19 11:41:07.914287 ignition[943]: kargs: kargs passed Mar 19 11:41:07.914331 ignition[943]: Ignition finished successfully Mar 19 11:41:07.943668 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 19 11:41:07.962398 ignition[952]: Ignition 2.20.0 Mar 19 11:41:07.962410 ignition[952]: Stage: disks Mar 19 11:41:07.962595 ignition[952]: no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:07.962609 ignition[952]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:07.967990 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 19 11:41:07.963139 ignition[952]: disks: disks passed Mar 19 11:41:07.974436 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 19 11:41:07.963184 ignition[952]: Ignition finished successfully Mar 19 11:41:07.983026 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 19 11:41:07.991531 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 19 11:41:07.997842 systemd[1]: Reached target sysinit.target - System Initialization. Mar 19 11:41:08.006237 systemd[1]: Reached target basic.target - Basic System. Mar 19 11:41:08.026833 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 19 11:41:08.095456 systemd-fsck[965]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 19 11:41:08.102259 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 19 11:41:08.127648 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 19 11:41:08.220506 kernel: EXT4-fs (sda9): mounted filesystem 303a73dd-e104-408b-9302-bf91b04ba1ca r/w with ordered data mode. Quota mode: none. Mar 19 11:41:08.221078 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 19 11:41:08.223116 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 19 11:41:08.266571 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 19 11:41:08.278209 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 19 11:41:08.291689 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (976) Mar 19 11:41:08.291718 kernel: BTRFS info (device sda6): first mount of filesystem 3c2c2d54-a06e-4f36-8d13-ab30a5d0eab5 Mar 19 11:41:08.291732 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 19 11:41:08.291743 kernel: BTRFS info (device sda6): using free space tree Mar 19 11:41:08.292536 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 19 11:41:08.297219 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 19 11:41:08.297252 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 19 11:41:08.308004 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 19 11:41:08.320948 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 19 11:41:08.341390 kernel: BTRFS info (device sda6): auto enabling async discard Mar 19 11:41:08.342745 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 19 11:41:09.112042 coreos-metadata[979]: Mar 19 11:41:09.112 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 19 11:41:09.118138 coreos-metadata[979]: Mar 19 11:41:09.114 INFO Fetch successful Mar 19 11:41:09.118138 coreos-metadata[979]: Mar 19 11:41:09.114 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 19 11:41:09.130001 coreos-metadata[979]: Mar 19 11:41:09.121 INFO Fetch successful Mar 19 11:41:09.138962 coreos-metadata[979]: Mar 19 11:41:09.138 INFO wrote hostname ci-4230.1.0-a-e4c146a22e to /sysroot/etc/hostname Mar 19 11:41:09.145946 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 19 11:41:09.389784 initrd-setup-root[1006]: cut: /sysroot/etc/passwd: No such file or directory Mar 19 11:41:09.467697 initrd-setup-root[1013]: cut: /sysroot/etc/group: No such file or directory Mar 19 11:41:09.507558 initrd-setup-root[1020]: cut: /sysroot/etc/shadow: No such file or directory Mar 19 11:41:09.513735 initrd-setup-root[1027]: cut: /sysroot/etc/gshadow: No such file or directory Mar 19 11:41:10.496275 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 19 11:41:10.514576 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 19 11:41:10.516041 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 19 11:41:10.532400 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 19 11:41:10.542321 kernel: BTRFS info (device sda6): last unmount of filesystem 3c2c2d54-a06e-4f36-8d13-ab30a5d0eab5 Mar 19 11:41:10.560356 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 19 11:41:10.570678 ignition[1098]: INFO : Ignition 2.20.0 Mar 19 11:41:10.570678 ignition[1098]: INFO : Stage: mount Mar 19 11:41:10.580575 ignition[1098]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:10.580575 ignition[1098]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:10.580575 ignition[1098]: INFO : mount: mount passed Mar 19 11:41:10.580575 ignition[1098]: INFO : Ignition finished successfully Mar 19 11:41:10.572404 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 19 11:41:10.612244 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1109) Mar 19 11:41:10.576219 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 19 11:41:10.627816 kernel: BTRFS info (device sda6): first mount of filesystem 3c2c2d54-a06e-4f36-8d13-ab30a5d0eab5 Mar 19 11:41:10.627836 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 19 11:41:10.627849 kernel: BTRFS info (device sda6): using free space tree Mar 19 11:41:10.587955 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 19 11:41:10.636506 kernel: BTRFS info (device sda6): auto enabling async discard Mar 19 11:41:10.637482 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 19 11:41:10.675270 ignition[1126]: INFO : Ignition 2.20.0 Mar 19 11:41:10.675270 ignition[1126]: INFO : Stage: files Mar 19 11:41:10.680953 ignition[1126]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:10.680953 ignition[1126]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:10.680953 ignition[1126]: DEBUG : files: compiled without relabeling support, skipping Mar 19 11:41:10.768226 ignition[1126]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 19 11:41:10.768226 ignition[1126]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 19 11:41:10.867228 ignition[1126]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 19 11:41:10.872787 ignition[1126]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 19 11:41:10.878101 unknown[1126]: wrote ssh authorized keys file for user: core Mar 19 11:41:10.882039 ignition[1126]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 19 11:41:10.894743 ignition[1126]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 19 11:41:10.901592 ignition[1126]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 19 11:41:10.901592 ignition[1126]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 19 11:41:10.901592 ignition[1126]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 19 11:41:10.901592 ignition[1126]: INFO : files: files passed Mar 19 11:41:10.901592 ignition[1126]: INFO : Ignition finished successfully Mar 19 11:41:10.910029 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 19 11:41:10.938630 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 19 11:41:10.940066 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 19 11:41:10.952076 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 19 11:41:10.952165 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 19 11:41:10.979569 initrd-setup-root-after-ignition[1158]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 19 11:41:10.986433 initrd-setup-root-after-ignition[1158]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 19 11:41:10.992955 initrd-setup-root-after-ignition[1162]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 19 11:41:10.999778 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 19 11:41:11.003229 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 19 11:41:11.023680 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 19 11:41:11.047619 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 19 11:41:11.047744 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 19 11:41:11.056659 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 19 11:41:11.065810 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 19 11:41:11.072712 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 19 11:41:11.095676 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 19 11:41:11.108961 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 19 11:41:11.111709 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 19 11:41:11.127673 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 19 11:41:11.129236 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 19 11:41:11.138270 systemd[1]: Stopped target timers.target - Timer Units. Mar 19 11:41:11.147834 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 19 11:41:11.147958 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 19 11:41:11.162051 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 19 11:41:11.170939 systemd[1]: Stopped target basic.target - Basic System. Mar 19 11:41:11.172475 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 19 11:41:11.179415 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 19 11:41:11.191456 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 19 11:41:11.199957 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 19 11:41:11.208417 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 19 11:41:11.218151 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 19 11:41:11.225951 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 19 11:41:11.234347 systemd[1]: Stopped target swap.target - Swaps. Mar 19 11:41:11.242024 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 19 11:41:11.242176 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 19 11:41:11.252997 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 19 11:41:11.261898 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 19 11:41:11.269530 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 19 11:41:11.278296 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 19 11:41:11.287031 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 19 11:41:11.287156 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 19 11:41:11.300918 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 19 11:41:11.301082 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 19 11:41:11.309966 systemd[1]: ignition-files.service: Deactivated successfully. Mar 19 11:41:11.310082 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 19 11:41:11.318288 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 19 11:41:11.318409 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 19 11:41:11.341644 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 19 11:41:11.345409 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 19 11:41:11.345556 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 19 11:41:11.358646 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 19 11:41:11.375539 ignition[1182]: INFO : Ignition 2.20.0 Mar 19 11:41:11.375539 ignition[1182]: INFO : Stage: umount Mar 19 11:41:11.375539 ignition[1182]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 19 11:41:11.375539 ignition[1182]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 19 11:41:11.375539 ignition[1182]: INFO : umount: umount passed Mar 19 11:41:11.375539 ignition[1182]: INFO : Ignition finished successfully Mar 19 11:41:11.364838 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 19 11:41:11.364981 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 19 11:41:11.374162 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 19 11:41:11.374275 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 19 11:41:11.385727 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 19 11:41:11.385825 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 19 11:41:11.391166 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 19 11:41:11.391327 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 19 11:41:11.397682 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 19 11:41:11.397733 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 19 11:41:11.406278 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 19 11:41:11.406327 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 19 11:41:11.412449 systemd[1]: Stopped target network.target - Network. Mar 19 11:41:11.422374 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 19 11:41:11.422428 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 19 11:41:11.435533 systemd[1]: Stopped target paths.target - Path Units. Mar 19 11:41:11.493117 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 19 11:41:11.498584 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 19 11:41:11.500068 systemd[1]: Stopped target slices.target - Slice Units. Mar 19 11:41:11.514769 systemd[1]: Stopped target sockets.target - Socket Units. Mar 19 11:41:11.521722 systemd[1]: iscsid.socket: Deactivated successfully. Mar 19 11:41:11.521775 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 19 11:41:11.528759 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 19 11:41:11.528801 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 19 11:41:11.535754 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 19 11:41:11.535813 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 19 11:41:11.543383 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 19 11:41:11.543439 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 19 11:41:11.550665 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 19 11:41:11.558549 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 19 11:41:11.569772 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 19 11:41:11.570473 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 19 11:41:11.570590 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 19 11:41:11.581488 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 19 11:41:11.581828 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 19 11:41:11.581943 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 19 11:41:11.594377 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 19 11:41:11.594656 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 19 11:41:11.594749 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 19 11:41:11.602669 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 19 11:41:11.602757 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 19 11:41:11.612115 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 19 11:41:11.612185 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 19 11:41:11.614609 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 19 11:41:11.614675 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 19 11:41:11.648690 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 19 11:41:11.650301 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 19 11:41:11.650360 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 19 11:41:11.658456 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 19 11:41:11.658517 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 19 11:41:11.673476 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 19 11:41:11.673538 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 19 11:41:11.681931 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 19 11:41:11.681979 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 19 11:41:11.695466 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 19 11:41:11.705039 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 19 11:41:11.705104 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 19 11:41:11.744357 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 19 11:41:11.744550 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 19 11:41:11.754954 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 19 11:41:11.755044 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 19 11:41:11.764813 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 19 11:41:11.764909 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 19 11:41:11.770370 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 19 11:41:11.770418 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 19 11:41:11.778319 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 19 11:41:11.778377 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 19 11:41:11.795034 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 19 11:41:11.795086 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 19 11:41:11.806452 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 19 11:41:11.806518 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 19 11:41:11.833620 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 19 11:41:11.835275 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 19 11:41:11.835332 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 19 11:41:11.850919 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 19 11:41:11.850971 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:11.860111 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 19 11:41:11.860171 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 19 11:41:11.860611 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 19 11:41:11.860701 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 19 11:41:11.866322 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 19 11:41:11.882421 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 19 11:41:11.901689 systemd[1]: Switching root. Mar 19 11:41:11.975936 systemd-journald[205]: Journal stopped Mar 19 11:41:18.448282 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Mar 19 11:41:18.448315 kernel: SELinux: policy capability network_peer_controls=1 Mar 19 11:41:18.448329 kernel: SELinux: policy capability open_perms=1 Mar 19 11:41:18.448339 kernel: SELinux: policy capability extended_socket_class=1 Mar 19 11:41:18.448348 kernel: SELinux: policy capability always_check_network=0 Mar 19 11:41:18.448357 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 19 11:41:18.448367 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 19 11:41:18.448379 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 19 11:41:18.448389 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 19 11:41:18.448398 kernel: audit: type=1403 audit(1742384473.103:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 19 11:41:18.448408 systemd[1]: Successfully loaded SELinux policy in 229.881ms. Mar 19 11:41:18.448419 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.483ms. Mar 19 11:41:18.448431 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 19 11:41:18.448441 systemd[1]: Detected virtualization microsoft. Mar 19 11:41:18.448455 systemd[1]: Detected architecture x86-64. Mar 19 11:41:18.448465 systemd[1]: Detected first boot. Mar 19 11:41:18.448476 systemd[1]: Hostname set to . Mar 19 11:41:18.448500 systemd[1]: Initializing machine ID from random generator. Mar 19 11:41:18.448514 zram_generator::config[1228]: No configuration found. Mar 19 11:41:18.448529 kernel: Guest personality initialized and is inactive Mar 19 11:41:18.448539 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Mar 19 11:41:18.448549 kernel: Initialized host personality Mar 19 11:41:18.448559 kernel: NET: Registered PF_VSOCK protocol family Mar 19 11:41:18.448569 systemd[1]: Populated /etc with preset unit settings. Mar 19 11:41:18.448580 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 19 11:41:18.448591 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 19 11:41:18.448601 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 19 11:41:18.448614 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 19 11:41:18.448626 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 19 11:41:18.448637 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 19 11:41:18.448647 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 19 11:41:18.448658 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 19 11:41:18.448669 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 19 11:41:18.448679 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 19 11:41:18.448693 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 19 11:41:18.448704 systemd[1]: Created slice user.slice - User and Session Slice. Mar 19 11:41:18.448714 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 19 11:41:18.448725 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 19 11:41:18.448740 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 19 11:41:18.448751 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 19 11:41:18.448766 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 19 11:41:18.448777 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 19 11:41:18.448791 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 19 11:41:18.448802 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 19 11:41:18.448813 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 19 11:41:18.448824 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 19 11:41:18.448835 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 19 11:41:18.448846 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 19 11:41:18.448857 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 19 11:41:18.448868 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 19 11:41:18.448882 systemd[1]: Reached target slices.target - Slice Units. Mar 19 11:41:18.448893 systemd[1]: Reached target swap.target - Swaps. Mar 19 11:41:18.448904 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 19 11:41:18.448915 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 19 11:41:18.448926 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 19 11:41:18.448940 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 19 11:41:18.448951 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 19 11:41:18.448962 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 19 11:41:18.448973 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 19 11:41:18.448988 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 19 11:41:18.448999 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 19 11:41:18.449010 systemd[1]: Mounting media.mount - External Media Directory... Mar 19 11:41:18.449021 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 19 11:41:18.449035 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 19 11:41:18.449047 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 19 11:41:18.449058 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 19 11:41:18.449070 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 19 11:41:18.449081 systemd[1]: Reached target machines.target - Containers. Mar 19 11:41:18.449092 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 19 11:41:18.449104 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 19 11:41:18.449115 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 19 11:41:18.449129 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 19 11:41:18.449140 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 19 11:41:18.449151 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 19 11:41:18.449162 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 19 11:41:18.449173 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 19 11:41:18.449184 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 19 11:41:18.449196 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 19 11:41:18.449207 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 19 11:41:18.449221 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 19 11:41:18.449235 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 19 11:41:18.449246 systemd[1]: Stopped systemd-fsck-usr.service. Mar 19 11:41:18.449258 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 19 11:41:18.449269 kernel: fuse: init (API version 7.39) Mar 19 11:41:18.449280 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 19 11:41:18.449291 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 19 11:41:18.449303 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 19 11:41:18.449317 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 19 11:41:18.449328 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 19 11:41:18.449359 systemd-journald[1335]: Collecting audit messages is disabled. Mar 19 11:41:18.449383 systemd-journald[1335]: Journal started Mar 19 11:41:18.449408 systemd-journald[1335]: Runtime Journal (/run/log/journal/268aa59024414cab9bbc754fe4cde2f5) is 8M, max 2.2G, 2.1G free. Mar 19 11:41:17.724667 systemd[1]: Queued start job for default target multi-user.target. Mar 19 11:41:17.736896 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 19 11:41:17.737364 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 19 11:41:18.455058 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 19 11:41:18.463510 systemd[1]: verity-setup.service: Deactivated successfully. Mar 19 11:41:18.463546 systemd[1]: Stopped verity-setup.service. Mar 19 11:41:18.478516 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 19 11:41:18.482506 kernel: loop: module loaded Mar 19 11:41:18.482565 systemd[1]: Started systemd-journald.service - Journal Service. Mar 19 11:41:18.491098 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 19 11:41:18.496724 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 19 11:41:18.502710 systemd[1]: Mounted media.mount - External Media Directory. Mar 19 11:41:18.507113 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 19 11:41:18.511811 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 19 11:41:18.516520 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 19 11:41:18.520836 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 19 11:41:18.525863 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 19 11:41:18.531225 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 19 11:41:18.531426 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 19 11:41:18.537508 kernel: ACPI: bus type drm_connector registered Mar 19 11:41:18.539333 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 19 11:41:18.539539 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 19 11:41:18.544506 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 19 11:41:18.544697 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 19 11:41:18.549202 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 19 11:41:18.549397 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 19 11:41:18.554795 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 19 11:41:18.554988 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 19 11:41:18.559714 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 19 11:41:18.559904 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 19 11:41:18.564715 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 19 11:41:18.569673 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 19 11:41:18.575742 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 19 11:41:18.581177 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 19 11:41:18.586868 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 19 11:41:18.601725 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 19 11:41:18.620603 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 19 11:41:18.625983 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 19 11:41:18.630423 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 19 11:41:18.630460 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 19 11:41:18.636100 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 19 11:41:18.642282 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 19 11:41:18.647735 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 19 11:41:18.651780 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 19 11:41:18.719631 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 19 11:41:18.725035 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 19 11:41:18.729877 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 19 11:41:18.730940 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 19 11:41:18.735226 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 19 11:41:18.739967 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 19 11:41:18.745376 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 19 11:41:18.751164 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 19 11:41:18.756075 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 19 11:41:18.762716 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 19 11:41:18.767925 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 19 11:41:18.774396 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 19 11:41:18.780288 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 19 11:41:18.789934 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 19 11:41:18.806444 systemd-journald[1335]: Time spent on flushing to /var/log/journal/268aa59024414cab9bbc754fe4cde2f5 is 14.540ms for 960 entries. Mar 19 11:41:18.806444 systemd-journald[1335]: System Journal (/var/log/journal/268aa59024414cab9bbc754fe4cde2f5) is 8M, max 2.6G, 2.6G free. Mar 19 11:41:18.900443 systemd-journald[1335]: Received client request to flush runtime journal. Mar 19 11:41:18.815007 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 19 11:41:18.819994 udevadm[1375]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 19 11:41:18.902141 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 19 11:41:18.921537 kernel: loop0: detected capacity change from 0 to 138176 Mar 19 11:41:18.929105 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 19 11:41:18.964437 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 19 11:41:18.965209 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 19 11:41:19.000169 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 19 11:41:19.017756 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 19 11:41:19.079720 systemd-tmpfiles[1388]: ACLs are not supported, ignoring. Mar 19 11:41:19.079742 systemd-tmpfiles[1388]: ACLs are not supported, ignoring. Mar 19 11:41:19.085400 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 19 11:41:19.687520 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 19 11:41:19.791527 kernel: loop1: detected capacity change from 0 to 147912 Mar 19 11:41:20.365055 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 19 11:41:20.388703 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 19 11:41:20.413596 systemd-udevd[1395]: Using default interface naming scheme 'v255'. Mar 19 11:41:20.579534 kernel: loop2: detected capacity change from 0 to 28272 Mar 19 11:41:20.626081 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 19 11:41:20.641756 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 19 11:41:20.659401 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 19 11:41:20.724522 kernel: hv_vmbus: registering driver hv_balloon Mar 19 11:41:20.728509 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 19 11:41:20.730630 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 19 11:41:20.745204 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:20.756522 kernel: mousedev: PS/2 mouse device common for all mice Mar 19 11:41:20.765564 kernel: hv_vmbus: registering driver hyperv_fb Mar 19 11:41:20.774273 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 19 11:41:20.774323 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 19 11:41:20.778310 kernel: Console: switching to colour dummy device 80x25 Mar 19 11:41:20.779514 kernel: Console: switching to colour frame buffer device 128x48 Mar 19 11:41:20.785767 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 19 11:41:20.785974 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:20.791789 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 19 11:41:20.800649 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 19 11:41:20.807356 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 19 11:41:20.821511 kernel: EDAC MC: Ver: 3.0.0 Mar 19 11:41:20.957344 systemd-networkd[1410]: lo: Link UP Mar 19 11:41:20.957353 systemd-networkd[1410]: lo: Gained carrier Mar 19 11:41:20.959022 systemd-networkd[1410]: Enumeration completed Mar 19 11:41:20.959159 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 19 11:41:20.959420 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 19 11:41:20.959431 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 19 11:41:20.960224 systemd-networkd[1410]: eth0: Link UP Mar 19 11:41:20.960233 systemd-networkd[1410]: eth0: Gained carrier Mar 19 11:41:20.960246 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 19 11:41:20.971606 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1419) Mar 19 11:41:20.972736 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 19 11:41:20.975357 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 19 11:41:20.994702 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 19 11:41:21.026329 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 19 11:41:21.028347 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 19 11:41:21.029048 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 19 11:41:21.052089 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 19 11:41:21.054629 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 19 11:41:21.117679 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 19 11:41:21.140448 lvm[1527]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 19 11:41:21.185267 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 19 11:41:21.187166 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 19 11:41:21.208766 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 19 11:41:21.212944 lvm[1534]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 19 11:41:21.233512 kernel: loop3: detected capacity change from 0 to 138176 Mar 19 11:41:21.247071 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 19 11:41:21.247508 kernel: loop4: detected capacity change from 0 to 147912 Mar 19 11:41:21.258999 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 19 11:41:21.262503 kernel: loop5: detected capacity change from 0 to 28272 Mar 19 11:41:21.267310 (sd-merge)[1535]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 19 11:41:21.267842 (sd-merge)[1535]: Merged extensions into '/usr'. Mar 19 11:41:21.271139 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 19 11:41:21.286667 systemd[1]: Starting ensure-sysext.service... Mar 19 11:41:21.292025 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 19 11:41:21.299353 systemd[1]: Reload requested from client PID 1540 ('systemctl') (unit ensure-sysext.service)... Mar 19 11:41:21.299370 systemd[1]: Reloading... Mar 19 11:41:21.349528 zram_generator::config[1572]: No configuration found. Mar 19 11:41:21.361111 systemd-tmpfiles[1541]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 19 11:41:21.361412 systemd-tmpfiles[1541]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 19 11:41:21.362310 systemd-tmpfiles[1541]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 19 11:41:21.362696 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Mar 19 11:41:21.362766 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Mar 19 11:41:21.382380 systemd-tmpfiles[1541]: Detected autofs mount point /boot during canonicalization of boot. Mar 19 11:41:21.382396 systemd-tmpfiles[1541]: Skipping /boot Mar 19 11:41:21.396157 systemd-tmpfiles[1541]: Detected autofs mount point /boot during canonicalization of boot. Mar 19 11:41:21.396171 systemd-tmpfiles[1541]: Skipping /boot Mar 19 11:41:21.500611 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 19 11:41:21.655284 systemd[1]: Reloading finished in 355 ms. Mar 19 11:41:21.695250 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 19 11:41:21.707892 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 19 11:41:21.768739 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 19 11:41:21.774676 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 19 11:41:21.781269 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 19 11:41:21.786530 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 19 11:41:21.801109 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 19 11:41:21.813567 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 19 11:41:21.813924 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 19 11:41:21.824751 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 19 11:41:21.829945 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 19 11:41:21.835102 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 19 11:41:21.841659 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 19 11:41:21.857891 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 19 11:41:21.858024 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 19 11:41:21.858213 systemd[1]: Reached target time-set.target - System Time Set. Mar 19 11:41:21.862485 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 19 11:41:21.864357 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 19 11:41:21.870130 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 19 11:41:21.870338 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 19 11:41:21.875432 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 19 11:41:21.875635 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 19 11:41:21.880272 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 19 11:41:21.880452 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 19 11:41:21.885810 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 19 11:41:21.886006 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 19 11:41:21.891461 augenrules[1663]: No rules Mar 19 11:41:21.892325 systemd[1]: audit-rules.service: Deactivated successfully. Mar 19 11:41:21.892572 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 19 11:41:21.899609 systemd[1]: Finished ensure-sysext.service. Mar 19 11:41:21.906300 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 19 11:41:21.906360 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 19 11:41:22.058138 systemd-resolved[1642]: Positive Trust Anchors: Mar 19 11:41:22.058156 systemd-resolved[1642]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 19 11:41:22.058187 systemd-resolved[1642]: 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 19 11:41:22.093359 systemd-resolved[1642]: Using system hostname 'ci-4230.1.0-a-e4c146a22e'. Mar 19 11:41:22.095404 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 19 11:41:22.100132 systemd[1]: Reached target network.target - Network. Mar 19 11:41:22.103647 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 19 11:41:22.111590 systemd-networkd[1410]: eth0: Gained IPv6LL Mar 19 11:41:22.113961 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 19 11:41:22.119316 systemd[1]: Reached target network-online.target - Network is Online. Mar 19 11:41:23.010048 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 19 11:41:23.015549 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 19 11:41:27.468794 ldconfig[1367]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 19 11:41:27.485731 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 19 11:41:27.500722 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 19 11:41:27.513406 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 19 11:41:27.518284 systemd[1]: Reached target sysinit.target - System Initialization. Mar 19 11:41:27.522902 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 19 11:41:27.528236 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 19 11:41:27.533713 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 19 11:41:27.538174 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 19 11:41:27.543352 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 19 11:41:27.548721 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 19 11:41:27.548774 systemd[1]: Reached target paths.target - Path Units. Mar 19 11:41:27.552464 systemd[1]: Reached target timers.target - Timer Units. Mar 19 11:41:27.557265 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 19 11:41:27.563138 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 19 11:41:27.569949 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 19 11:41:27.575297 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 19 11:41:27.580416 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 19 11:41:27.586595 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 19 11:41:27.591674 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 19 11:41:27.596975 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 19 11:41:27.601280 systemd[1]: Reached target sockets.target - Socket Units. Mar 19 11:41:27.605298 systemd[1]: Reached target basic.target - Basic System. Mar 19 11:41:27.609089 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 19 11:41:27.609124 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 19 11:41:27.634578 systemd[1]: Starting chronyd.service - NTP client/server... Mar 19 11:41:27.639079 systemd[1]: Starting containerd.service - containerd container runtime... Mar 19 11:41:27.644599 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 19 11:41:27.649663 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 19 11:41:27.654531 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 19 11:41:27.660089 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 19 11:41:27.664292 jq[1689]: false Mar 19 11:41:27.664381 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 19 11:41:27.664428 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 19 11:41:27.665569 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 19 11:41:27.670368 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 19 11:41:27.672040 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 19 11:41:27.673661 KVP[1691]: KVP starting; pid is:1691 Mar 19 11:41:27.676535 KVP[1691]: KVP LIC Version: 3.1 Mar 19 11:41:27.678519 kernel: hv_utils: KVP IC version 4.0 Mar 19 11:41:27.679076 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 19 11:41:27.684401 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 19 11:41:27.684926 (chronyd)[1682]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 19 11:41:27.690093 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 19 11:41:27.690466 chronyd[1700]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 19 11:41:27.696636 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 19 11:41:27.702200 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 19 11:41:27.702766 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 19 11:41:27.703355 systemd[1]: Starting update-engine.service - Update Engine... Mar 19 11:41:27.708836 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 19 11:41:27.715619 chronyd[1700]: Timezone right/UTC failed leap second check, ignoring Mar 19 11:41:27.715782 chronyd[1700]: Loaded seccomp filter (level 2) Mar 19 11:41:27.716243 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 19 11:41:27.716513 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 19 11:41:27.716796 jq[1707]: true Mar 19 11:41:27.716852 systemd[1]: Started chronyd.service - NTP client/server. Mar 19 11:41:27.720933 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 19 11:41:27.721143 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 19 11:41:27.732385 (ntainerd)[1713]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 19 11:41:27.735748 jq[1712]: true Mar 19 11:41:27.737041 systemd[1]: motdgen.service: Deactivated successfully. Mar 19 11:41:27.737252 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 19 11:41:27.772006 extend-filesystems[1690]: Found loop3 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found loop4 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found loop5 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda1 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda2 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda3 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found usr Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda4 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda6 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda7 Mar 19 11:41:27.774962 extend-filesystems[1690]: Found sda9 Mar 19 11:41:27.774962 extend-filesystems[1690]: Checking size of /dev/sda9 Mar 19 11:41:27.809052 extend-filesystems[1690]: Old size kept for /dev/sda9 Mar 19 11:41:27.810627 extend-filesystems[1690]: Found sdb Mar 19 11:41:27.810627 extend-filesystems[1690]: Found sdb1 Mar 19 11:41:27.810627 extend-filesystems[1690]: Found sr0 Mar 19 11:41:27.810242 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 19 11:41:27.810479 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 19 11:41:27.828232 systemd-logind[1702]: New seat seat0. Mar 19 11:41:27.830674 systemd-logind[1702]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 19 11:41:27.830867 systemd[1]: Started systemd-logind.service - User Login Management. Mar 19 11:41:27.844420 dbus-daemon[1685]: [system] SELinux support is enabled Mar 19 11:41:27.845119 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 19 11:41:27.846246 update_engine[1705]: I20250319 11:41:27.846170 1705 main.cc:92] Flatcar Update Engine starting Mar 19 11:41:27.851449 update_engine[1705]: I20250319 11:41:27.851405 1705 update_check_scheduler.cc:74] Next update check in 7m16s Mar 19 11:41:27.853444 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 19 11:41:27.854268 dbus-daemon[1685]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 19 11:41:27.853479 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 19 11:41:27.858040 bash[1741]: Updated "/home/core/.ssh/authorized_keys" Mar 19 11:41:27.858704 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 19 11:41:27.858734 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 19 11:41:27.864445 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 19 11:41:27.870447 systemd[1]: Started update-engine.service - Update Engine. Mar 19 11:41:27.875700 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 19 11:41:27.880515 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1748) Mar 19 11:41:27.884506 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 19 11:41:27.928300 coreos-metadata[1684]: Mar 19 11:41:27.928 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 19 11:41:27.930949 coreos-metadata[1684]: Mar 19 11:41:27.930 INFO Fetch successful Mar 19 11:41:27.931081 coreos-metadata[1684]: Mar 19 11:41:27.931 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 19 11:41:27.935094 coreos-metadata[1684]: Mar 19 11:41:27.935 INFO Fetch successful Mar 19 11:41:27.935439 coreos-metadata[1684]: Mar 19 11:41:27.935 INFO Fetching http://168.63.129.16/machine/28e26d90-f0b4-43ca-818e-e0840a2d172e/541158cd%2D52a9%2D4e54%2Db005%2D4b7d4af4358c.%5Fci%2D4230.1.0%2Da%2De4c146a22e?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 19 11:41:27.936895 coreos-metadata[1684]: Mar 19 11:41:27.936 INFO Fetch successful Mar 19 11:41:27.937059 coreos-metadata[1684]: Mar 19 11:41:27.937 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 19 11:41:27.944005 coreos-metadata[1684]: Mar 19 11:41:27.943 INFO Fetch successful Mar 19 11:41:27.953401 sshd_keygen[1706]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 19 11:41:27.966377 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 19 11:41:27.971426 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 19 11:41:27.973994 setup-nvidia[1694]: Downloading Flatcar Container Linux Developer Container for version: 4230.1.0 Mar 19 11:41:27.974545 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 19 11:41:27.980699 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 19 11:41:27.985334 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 19 11:41:27.990567 systemd[1]: issuegen.service: Deactivated successfully. Mar 19 11:41:27.990783 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 19 11:41:27.997206 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 19 11:41:28.011097 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 19 11:41:28.034641 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 19 11:41:28.041933 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 19 11:41:28.047126 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 19 11:41:28.052036 systemd[1]: Reached target getty.target - Login Prompts. Mar 19 11:41:28.121393 locksmithd[1798]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 19 11:41:28.618770 setup-nvidia[1849]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 19 11:41:28.619136 setup-nvidia[1849]: Dload Upload Total Spent Left Speed Mar 19 11:41:29.107101 containerd[1713]: time="2025-03-19T11:41:29.107020700Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Mar 19 11:41:29.126870 containerd[1713]: time="2025-03-19T11:41:29.126828900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128477 containerd[1713]: time="2025-03-19T11:41:29.128442800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.83-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128477 containerd[1713]: time="2025-03-19T11:41:29.128473600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 19 11:41:29.128566 containerd[1713]: time="2025-03-19T11:41:29.128489600Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 19 11:41:29.128671 containerd[1713]: time="2025-03-19T11:41:29.128648900Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 19 11:41:29.128704 containerd[1713]: time="2025-03-19T11:41:29.128672600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128756 containerd[1713]: time="2025-03-19T11:41:29.128736100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128756 containerd[1713]: time="2025-03-19T11:41:29.128754100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128961 containerd[1713]: time="2025-03-19T11:41:29.128937900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 19 11:41:29.128961 containerd[1713]: time="2025-03-19T11:41:29.128958600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129012 containerd[1713]: time="2025-03-19T11:41:29.128972100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129012 containerd[1713]: time="2025-03-19T11:41:29.128981500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129081 containerd[1713]: time="2025-03-19T11:41:29.129061800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129367 containerd[1713]: time="2025-03-19T11:41:29.129337000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129497 containerd[1713]: time="2025-03-19T11:41:29.129474500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 19 11:41:29.129536 containerd[1713]: time="2025-03-19T11:41:29.129508200Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 19 11:41:29.129655 containerd[1713]: time="2025-03-19T11:41:29.129624300Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 19 11:41:29.129726 containerd[1713]: time="2025-03-19T11:41:29.129688900Z" level=info msg="metadata content store policy set" policy=shared Mar 19 11:41:29.150252 containerd[1713]: time="2025-03-19T11:41:29.150211300Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 19 11:41:29.150313 containerd[1713]: time="2025-03-19T11:41:29.150257500Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 19 11:41:29.150313 containerd[1713]: time="2025-03-19T11:41:29.150276500Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 19 11:41:29.150313 containerd[1713]: time="2025-03-19T11:41:29.150305200Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 19 11:41:29.150379 containerd[1713]: time="2025-03-19T11:41:29.150322300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 19 11:41:29.150481 containerd[1713]: time="2025-03-19T11:41:29.150457700Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 19 11:41:29.150733 containerd[1713]: time="2025-03-19T11:41:29.150704100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 19 11:41:29.150864 containerd[1713]: time="2025-03-19T11:41:29.150840700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 19 11:41:29.150895 containerd[1713]: time="2025-03-19T11:41:29.150865800Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 19 11:41:29.150895 containerd[1713]: time="2025-03-19T11:41:29.150881100Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 19 11:41:29.150936 containerd[1713]: time="2025-03-19T11:41:29.150895100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.150936 containerd[1713]: time="2025-03-19T11:41:29.150908400Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.150936 containerd[1713]: time="2025-03-19T11:41:29.150920000Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.150936 containerd[1713]: time="2025-03-19T11:41:29.150932900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.151066 containerd[1713]: time="2025-03-19T11:41:29.150946100Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.151066 containerd[1713]: time="2025-03-19T11:41:29.150958000Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.151066 containerd[1713]: time="2025-03-19T11:41:29.150968900Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.151066 containerd[1713]: time="2025-03-19T11:41:29.150980900Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 19 11:41:29.151066 containerd[1713]: time="2025-03-19T11:41:29.151000500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151067200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151085600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151099300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151111900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151124900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151138000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151150600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151163600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151185 containerd[1713]: time="2025-03-19T11:41:29.151178100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151196300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151209100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151221100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151234700Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151254500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151269000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151279000Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151328100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151345900Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151356600Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151369400Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 19 11:41:29.151375 containerd[1713]: time="2025-03-19T11:41:29.151380000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151636 containerd[1713]: time="2025-03-19T11:41:29.151391200Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 19 11:41:29.151636 containerd[1713]: time="2025-03-19T11:41:29.151401000Z" level=info msg="NRI interface is disabled by configuration." Mar 19 11:41:29.151636 containerd[1713]: time="2025-03-19T11:41:29.151410900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 19 11:41:29.151734 containerd[1713]: time="2025-03-19T11:41:29.151681900Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 19 11:41:29.151734 containerd[1713]: time="2025-03-19T11:41:29.151734600Z" level=info msg="Connect containerd service" Mar 19 11:41:29.151897 containerd[1713]: time="2025-03-19T11:41:29.151777600Z" level=info msg="using legacy CRI server" Mar 19 11:41:29.151897 containerd[1713]: time="2025-03-19T11:41:29.151791600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 19 11:41:29.151897 containerd[1713]: time="2025-03-19T11:41:29.151884000Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 19 11:41:29.152528 containerd[1713]: time="2025-03-19T11:41:29.152481800Z" 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 19 11:41:29.152690 containerd[1713]: time="2025-03-19T11:41:29.152648000Z" level=info msg="Start subscribing containerd event" Mar 19 11:41:29.152718 containerd[1713]: time="2025-03-19T11:41:29.152705100Z" level=info msg="Start recovering state" Mar 19 11:41:29.152782 containerd[1713]: time="2025-03-19T11:41:29.152764400Z" level=info msg="Start event monitor" Mar 19 11:41:29.152813 containerd[1713]: time="2025-03-19T11:41:29.152788700Z" level=info msg="Start snapshots syncer" Mar 19 11:41:29.152813 containerd[1713]: time="2025-03-19T11:41:29.152798000Z" level=info msg="Start cni network conf syncer for default" Mar 19 11:41:29.152858 containerd[1713]: time="2025-03-19T11:41:29.152806600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 19 11:41:29.152882 containerd[1713]: time="2025-03-19T11:41:29.152807000Z" level=info msg="Start streaming server" Mar 19 11:41:29.152882 containerd[1713]: time="2025-03-19T11:41:29.152868500Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 19 11:41:29.153387 containerd[1713]: time="2025-03-19T11:41:29.153117300Z" level=info msg="containerd successfully booted in 0.047044s" Mar 19 11:41:29.153189 systemd[1]: Started containerd.service - containerd container runtime. Mar 19 11:41:30.714734 waagent[1841]: 2025-03-19T11:41:30.714613Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Mar 19 11:41:30.719113 waagent[1841]: 2025-03-19T11:41:30.719056Z INFO Daemon Daemon OS: flatcar 4230.1.0 Mar 19 11:41:30.722575 waagent[1841]: 2025-03-19T11:41:30.722523Z INFO Daemon Daemon Python: 3.11.11 Mar 19 11:41:30.725866 waagent[1841]: 2025-03-19T11:41:30.725813Z INFO Daemon Daemon Run daemon Mar 19 11:41:30.728947 waagent[1841]: 2025-03-19T11:41:30.728904Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.0' Mar 19 11:41:30.735735 waagent[1841]: 2025-03-19T11:41:30.735678Z INFO Daemon Daemon Using waagent for provisioning Mar 19 11:41:30.739780 waagent[1841]: 2025-03-19T11:41:30.739735Z INFO Daemon Daemon Activate resource disk Mar 19 11:41:30.743306 waagent[1841]: 2025-03-19T11:41:30.743258Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 19 11:41:30.750878 waagent[1841]: 2025-03-19T11:41:30.750826Z INFO Daemon Daemon Found device: sdb Mar 19 11:41:30.760146 waagent[1841]: 2025-03-19T11:41:30.760085Z INFO Daemon Daemon Examining partition table Mar 19 11:41:30.804697 waagent[1841]: 2025-03-19T11:41:30.804633Z INFO Daemon Daemon GPT not detected, determining filesystem Mar 19 11:41:30.839582 waagent[1841]: 2025-03-19T11:41:30.839514Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Mar 19 11:41:30.844192 waagent[1841]: 2025-03-19T11:41:30.844138Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Mar 19 11:41:33.870518 kernel: sdb: sdb1 Mar 19 11:41:33.873517 kernel: sdb: sdb1 Mar 19 11:41:34.830223 login[1844]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying Mar 19 11:41:34.830562 login[1843]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 19 11:41:34.912438 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 19 11:41:34.924721 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 19 11:41:34.930219 systemd-logind[1702]: New session 1 of user core. Mar 19 11:41:34.934974 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 19 11:41:34.937461 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 19 11:41:34.945157 (systemd)[1920]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 19 11:41:34.947325 systemd-logind[1702]: New session c1 of user core. Mar 19 11:41:35.526679 systemd[1920]: Queued start job for default target default.target. Mar 19 11:41:35.539551 systemd[1920]: Created slice app.slice - User Application Slice. Mar 19 11:41:35.539582 systemd[1920]: Reached target paths.target - Paths. Mar 19 11:41:35.539631 systemd[1920]: Reached target timers.target - Timers. Mar 19 11:41:35.540975 systemd[1920]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 19 11:41:35.551656 systemd[1920]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 19 11:41:35.551776 systemd[1920]: Reached target sockets.target - Sockets. Mar 19 11:41:35.551862 systemd[1920]: Reached target basic.target - Basic System. Mar 19 11:41:35.551912 systemd[1920]: Reached target default.target - Main User Target. Mar 19 11:41:35.551943 systemd[1920]: Startup finished in 598ms. Mar 19 11:41:35.551981 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 19 11:41:35.553408 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 19 11:41:35.833600 login[1844]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 19 11:41:35.837690 systemd-logind[1702]: New session 2 of user core. Mar 19 11:41:35.855618 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 19 11:41:40.454326 waagent[1841]: 2025-03-19T11:41:40.454234Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Mar 19 11:41:40.466156 waagent[1841]: 2025-03-19T11:41:40.466097Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Mar 19 11:41:40.466512 kernel: sdb: sdb1 Mar 19 11:41:46.127753 waagent[1841]: 2025-03-19T11:41:46.127651Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Mar 19 11:41:46.147470 waagent[1841]: 2025-03-19T11:41:46.147384Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Mar 19 11:41:46.147643 kernel: EXT4-fs (sdb1): mounted filesystem 819c397d-f71f-4a5b-bc80-f69febca7fed r/w with ordered data mode. Quota mode: none. Mar 19 11:41:46.149946 waagent[1841]: 2025-03-19T11:41:46.149879Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 19 11:41:46.150656 waagent[1841]: 2025-03-19T11:41:46.150142Z INFO Daemon Daemon Running default provisioning handler Mar 19 11:41:46.158766 waagent[1841]: 2025-03-19T11:41:46.157635Z 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 19 11:41:46.172422 waagent[1841]: 2025-03-19T11:41:46.172364Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 19 11:41:46.173403 waagent[1841]: 2025-03-19T11:41:46.173359Z INFO Daemon Daemon cloud-init is enabled: False Mar 19 11:41:46.174919 waagent[1841]: 2025-03-19T11:41:46.174870Z INFO Daemon Daemon Copying ovf-env.xml Mar 19 11:41:46.187545 waagent[1841]: 2025-03-19T11:41:46.187470Z INFO Daemon Daemon Successfully mounted dvd Mar 19 11:41:46.201561 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 19 11:41:46.204949 waagent[1841]: 2025-03-19T11:41:46.204887Z INFO Daemon Daemon Detect protocol endpoint Mar 19 11:41:46.206655 waagent[1841]: 2025-03-19T11:41:46.206604Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 19 11:41:46.207461 waagent[1841]: 2025-03-19T11:41:46.207416Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 19 11:41:46.208853 waagent[1841]: 2025-03-19T11:41:46.208811Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 19 11:41:46.210347 waagent[1841]: 2025-03-19T11:41:46.210304Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 19 11:41:46.211675 waagent[1841]: 2025-03-19T11:41:46.211627Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 19 11:41:46.250293 waagent[1841]: 2025-03-19T11:41:46.250238Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 19 11:41:46.252166 waagent[1841]: 2025-03-19T11:41:46.252127Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 19 11:41:46.253517 waagent[1841]: 2025-03-19T11:41:46.253465Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 19 11:41:46.371536 waagent[1841]: 2025-03-19T11:41:46.371418Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 19 11:41:46.373292 waagent[1841]: 2025-03-19T11:41:46.373239Z INFO Daemon Daemon Forcing an update of the goal state. Mar 19 11:41:46.377924 waagent[1841]: 2025-03-19T11:41:46.377828Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 19 11:41:46.420014 waagent[1841]: 2025-03-19T11:41:46.419949Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 Mar 19 11:41:46.422117 waagent[1841]: 2025-03-19T11:41:46.422067Z INFO Daemon Mar 19 11:41:46.422393 waagent[1841]: 2025-03-19T11:41:46.422350Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ff47136f-742c-4ff7-b321-86bf9576a617 eTag: 10203739866040479077 source: Fabric] Mar 19 11:41:46.424124 waagent[1841]: 2025-03-19T11:41:46.424076Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 19 11:41:46.425912 waagent[1841]: 2025-03-19T11:41:46.425865Z INFO Daemon Mar 19 11:41:46.426095 waagent[1841]: 2025-03-19T11:41:46.426055Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 19 11:41:46.431263 waagent[1841]: 2025-03-19T11:41:46.431219Z INFO Daemon Daemon Downloading artifacts profile blob Mar 19 11:41:46.577534 waagent[1841]: 2025-03-19T11:41:46.577408Z INFO Daemon Downloaded certificate {'thumbprint': 'C51E35E32F91015377A4C186BE86C91093BD3271', 'hasPrivateKey': True} Mar 19 11:41:46.579540 waagent[1841]: 2025-03-19T11:41:46.579472Z INFO Daemon Downloaded certificate {'thumbprint': '07B3D3B738799B042F77DD0E41BE8E2893EF3231', 'hasPrivateKey': False} Mar 19 11:41:46.580321 waagent[1841]: 2025-03-19T11:41:46.580273Z INFO Daemon Fetch goal state completed Mar 19 11:41:46.618306 waagent[1841]: 2025-03-19T11:41:46.618246Z INFO Daemon Daemon Starting provisioning Mar 19 11:41:46.620017 waagent[1841]: 2025-03-19T11:41:46.619955Z INFO Daemon Daemon Handle ovf-env.xml. Mar 19 11:41:46.621445 waagent[1841]: 2025-03-19T11:41:46.621388Z INFO Daemon Daemon Set hostname [ci-4230.1.0-a-e4c146a22e] Mar 19 11:41:46.630566 waagent[1841]: 2025-03-19T11:41:46.630477Z INFO Daemon Daemon Publish hostname [ci-4230.1.0-a-e4c146a22e] Mar 19 11:41:46.632411 waagent[1841]: 2025-03-19T11:41:46.632321Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 19 11:41:46.634016 waagent[1841]: 2025-03-19T11:41:46.633954Z INFO Daemon Daemon Primary interface is [eth0] Mar 19 11:41:46.644109 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 19 11:41:46.644119 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 19 11:41:46.644180 systemd-networkd[1410]: eth0: DHCP lease lost Mar 19 11:41:46.644774 waagent[1841]: 2025-03-19T11:41:46.644612Z INFO Daemon Daemon Create user account if not exists Mar 19 11:41:46.649184 waagent[1841]: 2025-03-19T11:41:46.649100Z INFO Daemon Daemon User core already exists, skip useradd Mar 19 11:41:46.650882 waagent[1841]: 2025-03-19T11:41:46.650830Z INFO Daemon Daemon Configure sudoer Mar 19 11:41:46.654487 waagent[1841]: 2025-03-19T11:41:46.652760Z INFO Daemon Daemon Configure sshd Mar 19 11:41:46.654935 waagent[1841]: 2025-03-19T11:41:46.654858Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 19 11:41:46.656341 waagent[1841]: 2025-03-19T11:41:46.656292Z INFO Daemon Daemon Deploy ssh public key. Mar 19 11:41:46.708015 waagent[1841]: 2025-03-19T11:41:46.707937Z INFO Daemon Daemon Decode custom data Mar 19 11:41:46.711821 waagent[1841]: 2025-03-19T11:41:46.711764Z INFO Daemon Daemon Save custom data Mar 19 11:41:46.747554 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 19 11:41:51.495542 chronyd[1700]: Selected source PHC0 Mar 19 11:41:54.117904 setup-nvidia[1849]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 478M 0 5921 0 0 9885 0 14:06:44 --:--:-- 14:06:44 9884\u000d 1 478M 1 6880k 0 0 4311k 0 0:01:53 0:00:01 0:01:52 4310k\u000d 5 478M 5 26.0M 0 0 9623k 0 0:00:50 0:00:02 0:00:48 9623k\u000d 9 478M 9 47.2M 0 0 13.0M 0 0:00:36 0:00:03 0:00:33 13.0M\u000d 14 478M 14 70.6M 0 0 15.3M 0 0:00:31 0:00:04 0:00:27 15.3M\u000d 19 478M 19 92.2M 0 0 16.5M 0 0:00:29 0:00:05 0:00:24 18.4M\u000d 23 478M 23 114M 0 0 17.4M 0 0:00:27 0:00:06 0:00:21 21.6M\u000d 29 478M 29 141M 0 0 18.6M 0 0:00:25 0:00:07 0:00:18 24.0M\u000d 34 478M 34 166M 0 0 19.1M 0 0:00:25 0:00:08 0:00:17 23.4M\u000d 39 478M 39 190M 0 0 19.6M 0 0:00:24 0:00:09 0:00:15 23.5M\u000d 41 478M 41 197M 0 0 16.7M 0 0:00:28 0:00:11 0:00:17 16.8M\u000d 43 478M 43 208M 0 0 16.4M 0 0:00:29 0:00:12 0:00:17 15.3M\u000d 48 478M 48 233M 0 0 17.1M 0 0:00:27 0:00:13 0:00:14 15.1M\u000d 54 478M 54 258M 0 0 17.6M 0 0:00:27 0:00:14 0:00:13 15.5M\u000d 59 478M 59 283M 0 0 18.1M 0 0:00:26 0:00:15 0:00:11 15.6M\u000d 63 478M 63 305M 0 0 18.3M 0 0:00:26 0:00:16 0:00:10 22.5M\u000d 68 478M 68 326M 0 0 18.4M 0 0:00:25 0:00:17 0:00:08 23.5M\u000d 70 478M 70 335M 0 0 18.0M 0 0:00:26 0:00:18 0:00:08 20.6M\u000d 70 478M 70 339M 0 0 17.3M 0 0:00:27 0:00:19 0:00:08 16.2M\u000d 75 478M 75 362M 0 0 17.5M 0 0:00:27 0:00:20 0:00:07 15.6M\u000d 80 478M 80 385M 0 0 17.7M 0 0:00:26 0:00:21 0:00:05 15.8M\u000d 85 478M 85 409M 0 0 18.0M 0 0:00:26 0:00:22 0:00:04 16.7M\u000d 90 478M 90 435M 0 0 18.4M 0 0:00:26 0:00:23 0:00:03 19.6M\u000d 95 478M 95 458M 0 0 18.5M 0 0:00:25 0:00:24 0:00:01 23.6M\u000d100 478M 100 478M 0 0 18.7M 0 0:00:25 0:00:25 --:--:-- 24.2M Mar 19 11:42:05.298512 setup-nvidia[1694]: Downloading NVIDIA 535.230.02 Driver Mar 19 11:42:05.302783 setup-nvidia[2017]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 19 11:42:05.303009 setup-nvidia[2017]: Dload Upload Total Spent Left Speed Mar 19 11:42:06.691247 setup-nvidia[2017]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 59 326M 59 192M 0 0 217M 0 0:00:01 --:--:-- 0:00:01 217M\u000d100 326M 100 326M 0 0 234M 0 0:00:01 0:00:01 --:--:-- 234M Mar 19 11:42:06.713693 setup-nvidia[1694]: Extract the NVIDIA Driver Installer 535.230.02 Mar 19 11:42:06.713912 setup-nvidia[1694]: /opt/nvidia/workdir/nvidia-workdir / Mar 19 11:42:06.730991 setup-nvidia[2020]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Mar 19 11:42:06.989226 setup-nvidia[2020]: Verifying archive integrity... OK Mar 19 11:42:07.732383 setup-nvidia[2020]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Mar 19 11:42:08.888554 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Mar 19 11:42:09.550402 setup-nvidia[2046]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Mar 19 11:42:09.690714 setup-nvidia[1694]: / Mar 19 11:42:09.690976 setup-nvidia[1694]: Spawn system-nspawn container to install the NVIDIA drivers Mar 19 11:42:09.834877 sudo[2053]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Mar 19 11:42:09.836551 sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 19 11:42:09.845816 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 19 11:42:09.857698 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 19 11:42:09.867592 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 19 11:42:09.869636 systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 19 11:42:09.875541 (systemd)[2056]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 19 11:42:09.877615 systemd-logind[1702]: New session c3 of user root. Mar 19 11:42:10.064625 systemd[2056]: Queued start job for default target default.target. Mar 19 11:42:10.074725 systemd[2056]: Created slice app.slice - User Application Slice. Mar 19 11:42:10.074757 systemd[2056]: Reached target paths.target - Paths. Mar 19 11:42:10.074805 systemd[2056]: Reached target timers.target - Timers. Mar 19 11:42:10.078093 systemd[2056]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 19 11:42:10.088182 systemd[2056]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 19 11:42:10.088243 systemd[2056]: Reached target sockets.target - Sockets. Mar 19 11:42:10.088326 systemd[2056]: Reached target basic.target - Basic System. Mar 19 11:42:10.088378 systemd[2056]: Reached target default.target - Main User Target. Mar 19 11:42:10.088409 systemd[2056]: Startup finished in 194ms. Mar 19 11:42:10.088470 systemd[1]: Started user@0.service - User Manager for UID 0. Mar 19 11:42:10.089912 systemd[1]: Started session-c2.scope - Session c2 of User root. Mar 19 11:42:10.184512 kernel: loop6: detected capacity change from 0 to 12644352 Mar 19 11:42:10.245511 kernel: loop6: p9 Mar 19 11:42:10.498155 setup-nvidia[2065]: ░ Spawning container flatcardevelopercontainer-4230.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin. Mar 19 11:42:10.542515 kernel: EXT4-fs (loop6p9): mounted filesystem abca95e3-c627-4304-9eb1-ed9b39d44da4 ro without journal. Quota mode: none. Mar 19 11:42:13.071650 update_engine[1705]: I20250319 11:42:13.071563 1705 update_attempter.cc:509] Updating boot flags... Mar 19 11:42:13.132523 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2095) Mar 19 11:42:13.223557 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2095) Mar 19 11:42:13.332522 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2095) Mar 19 11:42:16.756714 waagent[1841]: 2025-03-19T11:42:16.756643Z INFO Daemon Daemon Provisioning complete Mar 19 11:42:16.767412 waagent[1841]: 2025-03-19T11:42:16.767363Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 19 11:42:16.768882 waagent[1841]: 2025-03-19T11:42:16.768824Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 19 11:42:16.770340 waagent[1841]: 2025-03-19T11:42:16.770291Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Mar 19 11:42:16.923190 waagent[2291]: 2025-03-19T11:42:16.923096Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Mar 19 11:42:16.923544 waagent[2291]: 2025-03-19T11:42:16.923251Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.0 Mar 19 11:42:16.923544 waagent[2291]: 2025-03-19T11:42:16.923336Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 19 11:42:17.013742 waagent[2291]: 2025-03-19T11:42:17.013598Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 19 11:42:17.013906 waagent[2291]: 2025-03-19T11:42:17.013858Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 19 11:42:17.013996 waagent[2291]: 2025-03-19T11:42:17.013955Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 19 11:42:17.021488 waagent[2291]: 2025-03-19T11:42:17.021419Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 19 11:42:17.030168 waagent[2291]: 2025-03-19T11:42:17.030117Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 19 11:42:17.030642 waagent[2291]: 2025-03-19T11:42:17.030595Z INFO ExtHandler Mar 19 11:42:17.030733 waagent[2291]: 2025-03-19T11:42:17.030694Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: cca073c1-68a2-4816-8104-cd0647234828 eTag: 10203739866040479077 source: Fabric] Mar 19 11:42:17.031068 waagent[2291]: 2025-03-19T11:42:17.031023Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 19 11:42:17.031742 waagent[2291]: 2025-03-19T11:42:17.031690Z INFO ExtHandler Mar 19 11:42:17.031822 waagent[2291]: 2025-03-19T11:42:17.031785Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 19 11:42:17.035252 waagent[2291]: 2025-03-19T11:42:17.035211Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 19 11:42:17.101931 waagent[2291]: 2025-03-19T11:42:17.101835Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C51E35E32F91015377A4C186BE86C91093BD3271', 'hasPrivateKey': True} Mar 19 11:42:17.102300 waagent[2291]: 2025-03-19T11:42:17.102251Z INFO ExtHandler Downloaded certificate {'thumbprint': '07B3D3B738799B042F77DD0E41BE8E2893EF3231', 'hasPrivateKey': False} Mar 19 11:42:17.102768 waagent[2291]: 2025-03-19T11:42:17.102721Z INFO ExtHandler Fetch goal state completed Mar 19 11:42:17.113513 waagent[2291]: 2025-03-19T11:42:17.113454Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2291 Mar 19 11:42:17.113668 waagent[2291]: 2025-03-19T11:42:17.113624Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 19 11:42:17.115425 waagent[2291]: 2025-03-19T11:42:17.115378Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 19 11:42:17.115845 waagent[2291]: 2025-03-19T11:42:17.115800Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 19 11:42:17.185976 waagent[2291]: 2025-03-19T11:42:17.185923Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 19 11:42:17.186171 waagent[2291]: 2025-03-19T11:42:17.186127Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 19 11:42:17.192748 waagent[2291]: 2025-03-19T11:42:17.192709Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 19 11:42:17.200165 systemd[1]: Reload requested from client PID 2306 ('systemctl') (unit waagent.service)... Mar 19 11:42:17.200181 systemd[1]: Reloading... Mar 19 11:42:17.263518 zram_generator::config[2349]: No configuration found. Mar 19 11:42:17.396890 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 19 11:42:17.550252 systemd[1]: Reloading finished in 349 ms. Mar 19 11:42:17.564266 waagent[2291]: 2025-03-19T11:42:17.564184Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Mar 19 11:42:17.570402 systemd[1]: Reload requested from client PID 2406 ('systemctl') (unit waagent.service)... Mar 19 11:42:17.570419 systemd[1]: Reloading... Mar 19 11:42:17.652580 zram_generator::config[2449]: No configuration found. Mar 19 11:42:17.777919 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 19 11:42:17.925171 systemd[1]: Reloading finished in 354 ms. Mar 19 11:42:17.938155 waagent[2291]: 2025-03-19T11:42:17.938064Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 19 11:42:17.938557 waagent[2291]: 2025-03-19T11:42:17.938227Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 19 11:42:19.384303 waagent[2291]: 2025-03-19T11:42:19.384199Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 19 11:42:19.384906 waagent[2291]: 2025-03-19T11:42:19.384844Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Mar 19 11:42:19.385723 waagent[2291]: 2025-03-19T11:42:19.385671Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 19 11:42:19.386288 waagent[2291]: 2025-03-19T11:42:19.386239Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 19 11:42:19.386394 waagent[2291]: 2025-03-19T11:42:19.386337Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 19 11:42:19.386735 waagent[2291]: 2025-03-19T11:42:19.386675Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 19 11:42:19.387015 waagent[2291]: 2025-03-19T11:42:19.386968Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 19 11:42:19.387308 waagent[2291]: 2025-03-19T11:42:19.387257Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 19 11:42:19.387662 waagent[2291]: 2025-03-19T11:42:19.387589Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 19 11:42:19.387777 waagent[2291]: 2025-03-19T11:42:19.387709Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 19 11:42:19.387832 waagent[2291]: 2025-03-19T11:42:19.387781Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 19 11:42:19.387986 waagent[2291]: 2025-03-19T11:42:19.387941Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 19 11:42:19.388309 waagent[2291]: 2025-03-19T11:42:19.388248Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 19 11:42:19.389013 waagent[2291]: 2025-03-19T11:42:19.388958Z INFO EnvHandler ExtHandler Configure routes Mar 19 11:42:19.389074 waagent[2291]: 2025-03-19T11:42:19.389027Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 19 11:42:19.389743 waagent[2291]: 2025-03-19T11:42:19.389701Z INFO EnvHandler ExtHandler Gateway:None Mar 19 11:42:19.390104 waagent[2291]: 2025-03-19T11:42:19.390064Z INFO EnvHandler ExtHandler Routes:None Mar 19 11:42:19.390250 waagent[2291]: 2025-03-19T11:42:19.390197Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 19 11:42:19.390250 waagent[2291]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 19 11:42:19.390250 waagent[2291]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Mar 19 11:42:19.390250 waagent[2291]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 19 11:42:19.390250 waagent[2291]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 19 11:42:19.390250 waagent[2291]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 19 11:42:19.390250 waagent[2291]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 19 11:42:19.395637 waagent[2291]: 2025-03-19T11:42:19.395580Z INFO ExtHandler ExtHandler Mar 19 11:42:19.395926 waagent[2291]: 2025-03-19T11:42:19.395876Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 9c93e4fe-6fbb-49f1-b739-549c3a7e9217 correlation a43b0f5b-b473-4ca1-b923-28afae2846ff created: 2025-03-19T11:40:06.253479Z] Mar 19 11:42:19.397149 waagent[2291]: 2025-03-19T11:42:19.397091Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 19 11:42:19.400294 waagent[2291]: 2025-03-19T11:42:19.400238Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Mar 19 11:42:19.488456 waagent[2291]: 2025-03-19T11:42:19.488393Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 3AF7813D-B806-48FE-A7EF-C467C52DA746;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Mar 19 11:42:19.522627 waagent[2291]: 2025-03-19T11:42:19.522555Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Mar 19 11:42:19.522627 waagent[2291]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.522627 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.522627 waagent[2291]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.522627 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.522627 waagent[2291]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.522627 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.522627 waagent[2291]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 19 11:42:19.522627 waagent[2291]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 19 11:42:19.522627 waagent[2291]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 19 11:42:19.526216 waagent[2291]: 2025-03-19T11:42:19.526163Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 19 11:42:19.526216 waagent[2291]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.526216 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.526216 waagent[2291]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.526216 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.526216 waagent[2291]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 19 11:42:19.526216 waagent[2291]: pkts bytes target prot opt in out source destination Mar 19 11:42:19.526216 waagent[2291]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 19 11:42:19.526216 waagent[2291]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 19 11:42:19.526216 waagent[2291]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 19 11:42:19.526475 waagent[2291]: 2025-03-19T11:42:19.526442Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Mar 19 11:42:19.526671 waagent[2291]: 2025-03-19T11:42:19.526629Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 19 11:42:19.532837 waagent[2291]: 2025-03-19T11:42:19.532778Z INFO MonitorHandler ExtHandler Network interfaces: Mar 19 11:42:19.532837 waagent[2291]: Executing ['ip', '-a', '-o', 'link']: Mar 19 11:42:19.532837 waagent[2291]: 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 19 11:42:19.532837 waagent[2291]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:20:d1:bb brd ff:ff:ff:ff:ff:ff Mar 19 11:42:19.532837 waagent[2291]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 19 11:42:19.532837 waagent[2291]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 19 11:42:19.532837 waagent[2291]: 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 19 11:42:19.532837 waagent[2291]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 19 11:42:19.532837 waagent[2291]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 19 11:42:19.532837 waagent[2291]: 2: eth0 inet6 fe80::7e1e:52ff:fe20:d1bb/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 19 11:42:23.744411 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 19 11:42:23.760729 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:33514.service - OpenSSH per-connection server daemon (10.200.16.10:33514). Mar 19 11:42:24.590901 sshd[3502]: Accepted publickey for core from 10.200.16.10 port 33514 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:24.592254 sshd-session[3502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:24.596351 systemd-logind[1702]: New session 3 of user core. Mar 19 11:42:24.607628 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 19 11:42:25.164958 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:33526.service - OpenSSH per-connection server daemon (10.200.16.10:33526). Mar 19 11:42:25.568504 kernel: hv_netvsc 7c1e5220-d1bb-7c1e-5220-d1bb7c1e5220 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 19 11:42:25.568864 kernel: hv_netvsc 7c1e5220-d1bb-7c1e-5220-d1bb7c1e5220 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 19 11:42:25.579370 kernel: hv_netvsc 7c1e5220-d1bb-7c1e-5220-d1bb7c1e5220 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 19 11:42:25.818973 sshd[3507]: Accepted publickey for core from 10.200.16.10 port 33526 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:25.820262 sshd-session[3507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:25.824331 systemd-logind[1702]: New session 4 of user core. Mar 19 11:42:25.845622 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 19 11:42:26.277572 sshd[3509]: Connection closed by 10.200.16.10 port 33526 Mar 19 11:42:26.278034 sshd-session[3507]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:26.281307 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:33526.service: Deactivated successfully. Mar 19 11:42:26.282965 systemd[1]: session-4.scope: Deactivated successfully. Mar 19 11:42:26.283644 systemd-logind[1702]: Session 4 logged out. Waiting for processes to exit. Mar 19 11:42:26.284718 systemd-logind[1702]: Removed session 4. Mar 19 11:42:26.395892 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:33542.service - OpenSSH per-connection server daemon (10.200.16.10:33542). Mar 19 11:42:27.046668 sshd[3515]: Accepted publickey for core from 10.200.16.10 port 33542 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:27.047974 sshd-session[3515]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:27.052074 systemd-logind[1702]: New session 5 of user core. Mar 19 11:42:27.064653 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 19 11:42:27.507170 sshd[3561]: Connection closed by 10.200.16.10 port 33542 Mar 19 11:42:27.507556 sshd-session[3515]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:27.510664 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:33542.service: Deactivated successfully. Mar 19 11:42:27.512234 systemd[1]: session-5.scope: Deactivated successfully. Mar 19 11:42:27.512867 systemd-logind[1702]: Session 5 logged out. Waiting for processes to exit. Mar 19 11:42:27.513619 systemd-logind[1702]: Removed session 5. Mar 19 11:42:37.629057 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:41386.service - OpenSSH per-connection server daemon (10.200.16.10:41386). Mar 19 11:42:38.280777 sshd[4567]: Accepted publickey for core from 10.200.16.10 port 41386 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:38.282086 sshd-session[4567]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:38.286958 systemd-logind[1702]: New session 6 of user core. Mar 19 11:42:38.292681 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 19 11:42:38.742710 sshd[4958]: Connection closed by 10.200.16.10 port 41386 Mar 19 11:42:38.743090 sshd-session[4567]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:38.746419 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:41386.service: Deactivated successfully. Mar 19 11:42:38.748001 systemd[1]: session-6.scope: Deactivated successfully. Mar 19 11:42:38.748729 systemd-logind[1702]: Session 6 logged out. Waiting for processes to exit. Mar 19 11:42:38.749593 systemd-logind[1702]: Removed session 6. Mar 19 11:42:38.862354 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:42672.service - OpenSSH per-connection server daemon (10.200.16.10:42672). Mar 19 11:42:39.514761 sshd[5180]: Accepted publickey for core from 10.200.16.10 port 42672 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:39.516074 sshd-session[5180]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:39.520446 systemd-logind[1702]: New session 7 of user core. Mar 19 11:42:39.532625 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 19 11:42:39.973182 sshd[5508]: Connection closed by 10.200.16.10 port 42672 Mar 19 11:42:39.973569 sshd-session[5180]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:39.977073 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:42672.service: Deactivated successfully. Mar 19 11:42:39.978662 systemd[1]: session-7.scope: Deactivated successfully. Mar 19 11:42:39.979298 systemd-logind[1702]: Session 7 logged out. Waiting for processes to exit. Mar 19 11:42:39.980075 systemd-logind[1702]: Removed session 7. Mar 19 11:42:50.100156 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:45404.service - OpenSSH per-connection server daemon (10.200.16.10:45404). Mar 19 11:42:54.040903 sshd[6108]: Accepted publickey for core from 10.200.16.10 port 45404 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:50.764016 sshd-session[6108]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:50.768094 systemd-logind[1702]: New session 8 of user core. Mar 19 11:42:54.041624 sshd[6112]: Connection closed by 10.200.16.10 port 45404 Mar 19 11:42:54.041717 sshd[6118]: Accepted publickey for core from 10.200.16.10 port 45418 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:42:51.226146 sshd-session[6108]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:50.776657 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 19 11:42:54.042166 sshd[6120]: Connection closed by 10.200.16.10 port 45418 Mar 19 11:42:51.995239 sshd-session[6118]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:42:51.229302 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:45404.service: Deactivated successfully. Mar 19 11:42:52.452790 sshd-session[6118]: pam_unix(sshd:session): session closed for user core Mar 19 11:42:51.230929 systemd[1]: session-8.scope: Deactivated successfully. Mar 19 11:42:51.231583 systemd-logind[1702]: Session 8 logged out. Waiting for processes to exit. Mar 19 11:42:51.232392 systemd-logind[1702]: Removed session 8. Mar 19 11:42:51.339739 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:45418.service - OpenSSH per-connection server daemon (10.200.16.10:45418). Mar 19 11:42:51.999634 systemd-logind[1702]: New session 9 of user core. Mar 19 11:42:52.010617 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 19 11:42:52.455263 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:45418.service: Deactivated successfully. Mar 19 11:42:52.456957 systemd[1]: session-9.scope: Deactivated successfully. Mar 19 11:42:52.457796 systemd-logind[1702]: Session 9 logged out. Waiting for processes to exit. Mar 19 11:42:52.458570 systemd-logind[1702]: Removed session 9. Mar 19 11:43:02.594816 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:42678.service - OpenSSH per-connection server daemon (10.200.16.10:42678). Mar 19 11:43:03.245459 sshd[7953]: Accepted publickey for core from 10.200.16.10 port 42678 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:03.251181 sshd-session[7953]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:03.259670 systemd-logind[1702]: New session 10 of user core. Mar 19 11:43:03.266709 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 19 11:43:03.704673 sshd[8051]: Connection closed by 10.200.16.10 port 42678 Mar 19 11:43:03.705483 sshd-session[7953]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:03.708783 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:42678.service: Deactivated successfully. Mar 19 11:43:03.709221 systemd-logind[1702]: Session 10 logged out. Waiting for processes to exit. Mar 19 11:43:03.711830 systemd[1]: session-10.scope: Deactivated successfully. Mar 19 11:43:03.714107 systemd-logind[1702]: Removed session 10. Mar 19 11:43:03.829777 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:42688.service - OpenSSH per-connection server daemon (10.200.16.10:42688). Mar 19 11:43:04.485138 sshd[8161]: Accepted publickey for core from 10.200.16.10 port 42688 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:04.487051 sshd-session[8161]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:04.493587 systemd-logind[1702]: New session 11 of user core. Mar 19 11:43:04.498810 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 19 11:43:05.352153 sshd[8342]: Connection closed by 10.200.16.10 port 42688 Mar 19 11:43:13.138183 sshd-session[8161]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:13.141713 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:42688.service: Deactivated successfully. Mar 19 11:43:13.143330 systemd[1]: session-11.scope: Deactivated successfully. Mar 19 11:43:13.144107 systemd-logind[1702]: Session 11 logged out. Waiting for processes to exit. Mar 19 11:43:13.145073 systemd-logind[1702]: Removed session 11. Mar 19 11:43:15.076781 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:57744.service - OpenSSH per-connection server daemon (10.200.16.10:57744). Mar 19 11:43:15.723094 sshd[9322]: Accepted publickey for core from 10.200.16.10 port 57744 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:15.724477 sshd-session[9322]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:15.729388 systemd-logind[1702]: New session 12 of user core. Mar 19 11:43:15.735652 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 19 11:43:16.185219 sshd[9508]: Connection closed by 10.200.16.10 port 57744 Mar 19 11:43:16.186870 sshd-session[9322]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:16.190095 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:57744.service: Deactivated successfully. Mar 19 11:43:16.192184 systemd[1]: session-12.scope: Deactivated successfully. Mar 19 11:43:16.193810 systemd-logind[1702]: Session 12 logged out. Waiting for processes to exit. Mar 19 11:43:16.194708 systemd-logind[1702]: Removed session 12. Mar 19 11:43:16.305284 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:57758.service - OpenSSH per-connection server daemon (10.200.16.10:57758). Mar 19 11:43:16.956194 sshd[9610]: Accepted publickey for core from 10.200.16.10 port 57758 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:16.957600 sshd-session[9610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:16.962652 systemd-logind[1702]: New session 13 of user core. Mar 19 11:43:16.968640 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 19 11:43:17.419332 sshd[9788]: Connection closed by 10.200.16.10 port 57758 Mar 19 11:43:17.419775 sshd-session[9610]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:17.427854 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:57758.service: Deactivated successfully. Mar 19 11:43:17.429778 systemd[1]: session-13.scope: Deactivated successfully. Mar 19 11:43:17.431313 systemd-logind[1702]: Session 13 logged out. Waiting for processes to exit. Mar 19 11:43:17.432513 systemd-logind[1702]: Removed session 13. Mar 19 11:43:27.538810 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:48568.service - OpenSSH per-connection server daemon (10.200.16.10:48568). Mar 19 11:43:28.209869 sshd[11765]: Accepted publickey for core from 10.200.16.10 port 48568 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:28.211372 sshd-session[11765]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:28.216929 systemd-logind[1702]: New session 14 of user core. Mar 19 11:43:28.228701 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 19 11:43:28.673534 sshd[11863]: Connection closed by 10.200.16.10 port 48568 Mar 19 11:43:28.674035 sshd-session[11765]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:28.676920 systemd-logind[1702]: Session 14 logged out. Waiting for processes to exit. Mar 19 11:43:28.678669 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:48568.service: Deactivated successfully. Mar 19 11:43:28.682251 systemd[1]: session-14.scope: Deactivated successfully. Mar 19 11:43:28.683783 systemd-logind[1702]: Removed session 14. Mar 19 11:43:28.802608 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:59636.service - OpenSSH per-connection server daemon (10.200.16.10:59636). Mar 19 11:43:29.454226 sshd[11887]: Accepted publickey for core from 10.200.16.10 port 59636 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:29.456045 sshd-session[11887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:29.461544 systemd-logind[1702]: New session 15 of user core. Mar 19 11:43:29.468649 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 19 11:43:29.918564 sshd[11915]: Connection closed by 10.200.16.10 port 59636 Mar 19 11:43:29.919349 sshd-session[11887]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:29.922439 systemd-logind[1702]: Session 15 logged out. Waiting for processes to exit. Mar 19 11:43:29.924171 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:59636.service: Deactivated successfully. Mar 19 11:43:29.926338 systemd[1]: session-15.scope: Deactivated successfully. Mar 19 11:43:29.927857 systemd-logind[1702]: Removed session 15. Mar 19 11:43:31.217520 waagent[2291]: 2025-03-19T11:43:31.213704Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 19 11:43:31.225896 waagent[2291]: 2025-03-19T11:43:31.225833Z INFO ExtHandler Mar 19 11:43:31.225991 waagent[2291]: 2025-03-19T11:43:31.225962Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a7e1b48e-8522-4169-8e65-b3f7e82a8d5a eTag: 4445668326411665564 source: Fabric] Mar 19 11:43:31.226380 waagent[2291]: 2025-03-19T11:43:31.226326Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 19 11:43:31.227089 waagent[2291]: 2025-03-19T11:43:31.227034Z INFO ExtHandler Mar 19 11:43:31.227172 waagent[2291]: 2025-03-19T11:43:31.227133Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 19 11:43:31.285712 waagent[2291]: 2025-03-19T11:43:31.285609Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.383250Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C51E35E32F91015377A4C186BE86C91093BD3271', 'hasPrivateKey': True} Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.383785Z INFO ExtHandler Downloaded certificate {'thumbprint': '07B3D3B738799B042F77DD0E41BE8E2893EF3231', 'hasPrivateKey': False} Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.384232Z INFO ExtHandler Fetch goal state completed Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.384666Z INFO ExtHandler ExtHandler Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.384749Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 8a5af3aa-ae00-455a-9715-29fca24a6447 correlation a43b0f5b-b473-4ca1-b923-28afae2846ff created: 2025-03-19T11:43:23.457367Z] Mar 19 11:43:31.385517 waagent[2291]: 2025-03-19T11:43:31.385063Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 19 11:43:31.389621 waagent[2291]: 2025-03-19T11:43:31.388744Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 4 ms] Mar 19 11:43:40.098029 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:59446.service - OpenSSH per-connection server daemon (10.200.16.10:59446). Mar 19 11:43:40.904746 sshd[12229]: Accepted publickey for core from 10.200.16.10 port 59446 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:40.906288 sshd-session[12229]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:40.911522 systemd-logind[1702]: New session 16 of user core. Mar 19 11:43:40.924683 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 19 11:43:41.368825 sshd[12264]: Connection closed by 10.200.16.10 port 59446 Mar 19 11:43:41.369144 sshd-session[12229]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:41.372486 systemd-logind[1702]: Session 16 logged out. Waiting for processes to exit. Mar 19 11:43:41.373183 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:59446.service: Deactivated successfully. Mar 19 11:43:41.374996 systemd[1]: session-16.scope: Deactivated successfully. Mar 19 11:43:41.378340 systemd-logind[1702]: Removed session 16. Mar 19 11:43:41.499777 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:59462.service - OpenSSH per-connection server daemon (10.200.16.10:59462). Mar 19 11:43:42.243961 sshd[12294]: Accepted publickey for core from 10.200.16.10 port 59462 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:42.245434 sshd-session[12294]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:42.250090 systemd-logind[1702]: New session 17 of user core. Mar 19 11:43:42.265989 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 19 11:43:42.709525 sshd[12314]: Connection closed by 10.200.16.10 port 59462 Mar 19 11:43:42.711135 sshd-session[12294]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:42.719147 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:59462.service: Deactivated successfully. Mar 19 11:43:42.721865 systemd[1]: session-17.scope: Deactivated successfully. Mar 19 11:43:42.725195 systemd-logind[1702]: Session 17 logged out. Waiting for processes to exit. Mar 19 11:43:42.726918 systemd-logind[1702]: Removed session 17. Mar 19 11:43:52.830750 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:51812.service - OpenSSH per-connection server daemon (10.200.16.10:51812). Mar 19 11:43:53.500629 sshd[12620]: Accepted publickey for core from 10.200.16.10 port 51812 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:53.501626 sshd-session[12620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:53.518229 systemd-logind[1702]: New session 18 of user core. Mar 19 11:43:53.524922 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 19 11:43:53.962798 sshd[12646]: Connection closed by 10.200.16.10 port 51812 Mar 19 11:43:53.963644 sshd-session[12620]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:53.969739 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:51812.service: Deactivated successfully. Mar 19 11:43:53.970167 systemd-logind[1702]: Session 18 logged out. Waiting for processes to exit. Mar 19 11:43:53.972487 systemd[1]: session-18.scope: Deactivated successfully. Mar 19 11:43:53.974106 systemd-logind[1702]: Removed session 18. Mar 19 11:43:54.092616 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:51826.service - OpenSSH per-connection server daemon (10.200.16.10:51826). Mar 19 11:43:54.745740 sshd[12670]: Accepted publickey for core from 10.200.16.10 port 51826 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:43:54.747360 sshd-session[12670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:43:54.753963 systemd-logind[1702]: New session 19 of user core. Mar 19 11:43:54.763126 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 19 11:43:55.206362 sshd[12702]: Connection closed by 10.200.16.10 port 51826 Mar 19 11:43:55.206799 sshd-session[12670]: pam_unix(sshd:session): session closed for user core Mar 19 11:43:55.210252 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:51826.service: Deactivated successfully. Mar 19 11:43:55.212151 systemd[1]: session-19.scope: Deactivated successfully. Mar 19 11:43:55.212976 systemd-logind[1702]: Session 19 logged out. Waiting for processes to exit. Mar 19 11:43:55.214041 systemd-logind[1702]: Removed session 19. Mar 19 11:44:04.529368 setup-nvidia[2065]: \u000d Mar 19 11:44:04.529368 setup-nvidia[2065]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Mar 19 11:44:04.529368 setup-nvidia[2065]: frequently when this kernel module was built against the wrong or Mar 19 11:44:04.529368 setup-nvidia[2065]: improperly configured kernel sources, with a version of gcc that differs Mar 19 11:44:04.529368 setup-nvidia[2065]: from the one used to build the target kernel, or if another driver, such Mar 19 11:44:04.529368 setup-nvidia[2065]: as nouveau, is present and prevents the NVIDIA kernel module from Mar 19 11:44:04.529368 setup-nvidia[2065]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Mar 19 11:44:04.529368 setup-nvidia[2065]: installed in this system is supported by this NVIDIA Linux graphics Mar 19 11:44:04.529368 setup-nvidia[2065]: driver release. Mar 19 11:44:04.529368 setup-nvidia[2065]: \u000d Mar 19 11:44:04.529368 setup-nvidia[2065]: Please see the log entries 'Kernel module load error' and 'Kernel Mar 19 11:44:04.529368 setup-nvidia[2065]: messages' at the end of the file Mar 19 11:44:04.529368 setup-nvidia[2065]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Mar 19 11:44:04.529368 setup-nvidia[2065]: information. Mar 19 11:44:04.529368 setup-nvidia[2065]: \u000d Mar 19 11:44:04.543811 setup-nvidia[2065]: dmesg: read kernel buffer failed: Operation not permitted Mar 19 11:44:04.564736 setup-nvidia[2065]: \u000d Mar 19 11:44:04.564736 setup-nvidia[2065]: ERROR: Installation has failed. Please see the file Mar 19 11:44:04.564736 setup-nvidia[2065]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Mar 19 11:44:04.564736 setup-nvidia[2065]: details. You may find suggestions on fixing installation problems in Mar 19 11:44:04.564736 setup-nvidia[2065]: the README available on the Linux driver download page at Mar 19 11:44:04.564736 setup-nvidia[2065]: www.nvidia.com. Mar 19 11:44:04.564736 setup-nvidia[2065]: \u000d Mar 19 11:44:04.565148 setup-nvidia[2065]: Last 50 lines of nvidia-installer.log: Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Mar 19 11:44:04.565955 setup-nvidia[2065]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Mar 19 11:44:04.565955 setup-nvidia[2065]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Mar 19 11:44:04.565955 setup-nvidia[2065]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Mar 19 11:44:04.565955 setup-nvidia[2065]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Mar 19 11:44:04.565955 setup-nvidia[2065]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Mar 19 11:44:04.565955 setup-nvidia[2065]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Mar 19 11:44:04.565955 setup-nvidia[2065]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Mar 19 11:44:04.565955 setup-nvidia[2065]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Mar 19 11:44:04.565955 setup-nvidia[2065]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Mar 19 11:44:04.565955 setup-nvidia[2065]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Mar 19 11:44:04.565955 setup-nvidia[2065]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 19 11:44:04.565955 setup-nvidia[2065]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Mar 19 11:44:04.565955 setup-nvidia[2065]: make[2]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/build' Mar 19 11:44:04.565955 setup-nvidia[2065]: make[1]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/source' Mar 19 11:44:04.565955 setup-nvidia[2065]: -> done. Mar 19 11:44:04.565955 setup-nvidia[2065]: -> Kernel module compilation complete. Mar 19 11:44:04.565955 setup-nvidia[2065]: -> Unable to determine if Secure Boot is enabled: No such file or directory Mar 19 11:44:04.565955 setup-nvidia[2065]: 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 19 11:44:04.565955 setup-nvidia[2065]: \u000d Mar 19 11:44:04.567163 setup-nvidia[2065]: 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 19 11:44:04.567163 setup-nvidia[2065]: -> Kernel module load error: Operation not permitted Mar 19 11:44:04.567163 setup-nvidia[2065]: -> Kernel messages: Mar 19 11:44:04.567163 setup-nvidia[2065]: 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 19 11:44:05.321966 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:52694.service - OpenSSH per-connection server daemon (10.200.16.10:52694). Mar 19 11:44:05.975158 sshd[13016]: Accepted publickey for core from 10.200.16.10 port 52694 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:05.976442 sshd-session[13016]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:05.980732 systemd-logind[1702]: New session 20 of user core. Mar 19 11:44:05.993612 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 19 11:44:06.432449 sshd[13018]: Connection closed by 10.200.16.10 port 52694 Mar 19 11:44:06.432793 sshd-session[13016]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:06.435857 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:52694.service: Deactivated successfully. Mar 19 11:44:06.437467 systemd[1]: session-20.scope: Deactivated successfully. Mar 19 11:44:06.438106 systemd-logind[1702]: Session 20 logged out. Waiting for processes to exit. Mar 19 11:44:06.438881 systemd-logind[1702]: Removed session 20. Mar 19 11:44:06.550826 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:52710.service - OpenSSH per-connection server daemon (10.200.16.10:52710). Mar 19 11:44:07.203818 sshd[13024]: Accepted publickey for core from 10.200.16.10 port 52710 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:07.205198 sshd-session[13024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:07.209400 systemd-logind[1702]: New session 21 of user core. Mar 19 11:44:07.222624 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 19 11:44:07.665640 sshd[13026]: Connection closed by 10.200.16.10 port 52710 Mar 19 11:44:07.665996 sshd-session[13024]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:07.668979 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:52710.service: Deactivated successfully. Mar 19 11:44:07.670616 systemd[1]: session-21.scope: Deactivated successfully. Mar 19 11:44:07.671255 systemd-logind[1702]: Session 21 logged out. Waiting for processes to exit. Mar 19 11:44:07.672007 systemd-logind[1702]: Removed session 21. Mar 19 11:44:17.783211 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:53178.service - OpenSSH per-connection server daemon (10.200.16.10:53178). Mar 19 11:44:18.437837 sshd[13032]: Accepted publickey for core from 10.200.16.10 port 53178 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:18.439448 sshd-session[13032]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:18.444341 systemd-logind[1702]: New session 22 of user core. Mar 19 11:44:18.456617 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 19 11:44:18.897055 sshd[13035]: Connection closed by 10.200.16.10 port 53178 Mar 19 11:44:18.897474 sshd-session[13032]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:18.900739 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:53178.service: Deactivated successfully. Mar 19 11:44:18.902335 systemd[1]: session-22.scope: Deactivated successfully. Mar 19 11:44:18.903014 systemd-logind[1702]: Session 22 logged out. Waiting for processes to exit. Mar 19 11:44:18.903885 systemd-logind[1702]: Removed session 22. Mar 19 11:44:19.018336 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:42710.service - OpenSSH per-connection server daemon (10.200.16.10:42710). Mar 19 11:44:19.668504 sshd[13041]: Accepted publickey for core from 10.200.16.10 port 42710 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:19.669840 sshd-session[13041]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:19.674046 systemd-logind[1702]: New session 23 of user core. Mar 19 11:44:19.691749 systemd[1]: Started session-23.scope - Session 23 of User core. Mar 19 11:44:20.128777 sshd[13043]: Connection closed by 10.200.16.10 port 42710 Mar 19 11:44:20.129278 sshd-session[13041]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:20.132909 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:42710.service: Deactivated successfully. Mar 19 11:44:20.134652 systemd[1]: session-23.scope: Deactivated successfully. Mar 19 11:44:20.135311 systemd-logind[1702]: Session 23 logged out. Waiting for processes to exit. Mar 19 11:44:20.136127 systemd-logind[1702]: Removed session 23. Mar 19 11:44:22.263540 kernel: EXT4-fs (loop6p9): unmounting filesystem abca95e3-c627-4304-9eb1-ed9b39d44da4. Mar 19 11:44:22.264125 setup-nvidia[2065]: Container flatcardevelopercontainer-4230.1.0.bin exited successfully. Mar 19 11:44:22.266675 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.1.0.bin.mount: Deactivated successfully. Mar 19 11:44:22.332456 sudo[2053]: pam_unix(sudo:session): session closed for user root Mar 19 11:44:22.334316 systemd[1]: session-c2.scope: Deactivated successfully. Mar 19 11:44:22.334596 systemd[1]: session-c2.scope: Consumed 5min 12.069s CPU time, 882.4M memory peak. Mar 19 11:44:22.750996 setup-nvidia[1694]: /opt/nvidia / Mar 19 11:44:22.751962 setup-nvidia[1694]: / Mar 19 11:44:22.768044 setup-nvidia[13065]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 19 11:44:22.792516 kernel: IPMI message handler: version 39.2 Mar 19 11:44:22.796518 kernel: ipmi device interface Mar 19 11:44:22.797223 setup-nvidia[1694]: /opt/nvidia/current/usr/lib/modules/6.6.83-flatcar/video / Mar 19 11:44:22.853809 kernel: nvidia: loading out-of-tree module taints kernel. Mar 19 11:44:22.853945 kernel: nvidia: module license 'NVIDIA' taints kernel. Mar 19 11:44:22.853987 kernel: Disabling lock debugging due to kernel taint Mar 19 11:44:22.860970 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Mar 19 11:44:22.861022 kernel: nvidia: module license taints kernel. Mar 19 11:44:23.249527 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Mar 19 11:44:23.651849 kernel: Mar 19 11:44:23.651971 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Mar 19 11:44:23.854055 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Mar 19 11:44:23.888522 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Mar 19 11:44:23.910531 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Mar 19 11:44:23.952518 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Mar 19 11:44:23.952858 setup-nvidia[1694]: / Mar 19 11:44:26.648925 setup-nvidia[13095]: Wed Mar 19 11:44:26 2025 Mar 19 11:44:26.648925 setup-nvidia[13095]: +---------------------------------------------------------------------------------------+ Mar 19 11:44:26.648925 setup-nvidia[13095]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Mar 19 11:44:26.648925 setup-nvidia[13095]: |-----------------------------------------+----------------------+----------------------+ Mar 19 11:44:26.648925 setup-nvidia[13095]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Mar 19 11:44:26.648925 setup-nvidia[13095]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Mar 19 11:44:26.648925 setup-nvidia[13095]: | | | MIG M. | Mar 19 11:44:26.648925 setup-nvidia[13095]: |=========================================+======================+======================| Mar 19 11:44:26.656358 setup-nvidia[13095]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Mar 19 11:44:26.656358 setup-nvidia[13095]: | N/A 25C P0 34W / 250W | 0MiB / 16384MiB | 0% Default | Mar 19 11:44:26.656358 setup-nvidia[13095]: | | | N/A | Mar 19 11:44:26.656358 setup-nvidia[13095]: +-----------------------------------------+----------------------+----------------------+ Mar 19 11:44:26.656476 setup-nvidia[13095]: Mar 19 11:44:26.656476 setup-nvidia[13095]: +---------------------------------------------------------------------------------------+ Mar 19 11:44:26.656476 setup-nvidia[13095]: | Processes: | Mar 19 11:44:26.656476 setup-nvidia[13095]: | GPU GI CI PID Type Process name GPU Memory | Mar 19 11:44:26.656476 setup-nvidia[13095]: | ID ID Usage | Mar 19 11:44:26.656476 setup-nvidia[13095]: |=======================================================================================| Mar 19 11:44:26.656632 setup-nvidia[13095]: | No running processes found | Mar 19 11:44:26.656632 setup-nvidia[13095]: +---------------------------------------------------------------------------------------+ Mar 19 11:44:27.029560 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 19 11:44:27.031676 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 19 11:44:27.031988 systemd[1]: Startup finished in 1.614s (firmware) + 31.853s (loader) + 1.804s (kernel) + 12.001s (initrd) + 3min 14.157s (userspace) = 4min 1.430s. Mar 19 11:44:30.243080 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:54552.service - OpenSSH per-connection server daemon (10.200.16.10:54552). Mar 19 11:44:30.896250 sshd[13102]: Accepted publickey for core from 10.200.16.10 port 54552 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:30.897631 sshd-session[13102]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:30.902965 systemd-logind[1702]: New session 24 of user core. Mar 19 11:44:30.914625 systemd[1]: Started session-24.scope - Session 24 of User core. Mar 19 11:44:31.356818 sshd[13104]: Connection closed by 10.200.16.10 port 54552 Mar 19 11:44:31.357130 sshd-session[13102]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:31.360330 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:54552.service: Deactivated successfully. Mar 19 11:44:31.361971 systemd[1]: session-24.scope: Deactivated successfully. Mar 19 11:44:31.362604 systemd-logind[1702]: Session 24 logged out. Waiting for processes to exit. Mar 19 11:44:31.363369 systemd-logind[1702]: Removed session 24. Mar 19 11:44:31.471054 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:54560.service - OpenSSH per-connection server daemon (10.200.16.10:54560). Mar 19 11:44:32.125510 sshd[13110]: Accepted publickey for core from 10.200.16.10 port 54560 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:32.126831 sshd-session[13110]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:32.131262 systemd-logind[1702]: New session 25 of user core. Mar 19 11:44:32.153618 systemd[1]: Started session-25.scope - Session 25 of User core. Mar 19 11:44:32.378562 systemd[1]: Stopping user@0.service - User Manager for UID 0... Mar 19 11:44:32.379322 systemd[2056]: Activating special unit exit.target... Mar 19 11:44:32.379410 systemd[2056]: Stopped target default.target - Main User Target. Mar 19 11:44:32.379451 systemd[2056]: Stopped target basic.target - Basic System. Mar 19 11:44:32.379480 systemd[2056]: Stopped target paths.target - Paths. Mar 19 11:44:32.379528 systemd[2056]: Stopped target sockets.target - Sockets. Mar 19 11:44:32.379558 systemd[2056]: Stopped target timers.target - Timers. Mar 19 11:44:32.379910 systemd[2056]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 19 11:44:32.380120 systemd[2056]: Removed slice app.slice - User Application Slice. Mar 19 11:44:32.380152 systemd[2056]: Reached target shutdown.target - Shutdown. Mar 19 11:44:32.380305 systemd[2056]: Finished systemd-exit.service - Exit the Session. Mar 19 11:44:32.380344 systemd[2056]: Reached target exit.target - Exit the Session. Mar 19 11:44:32.392649 (sd-pam)[2057]: pam_unix(systemd-user:session): session closed for user root Mar 19 11:44:32.394102 systemd[1]: user@0.service: Deactivated successfully. Mar 19 11:44:32.394463 systemd[1]: Stopped user@0.service - User Manager for UID 0. Mar 19 11:44:32.407657 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 19 11:44:32.418310 systemd[1]: run-user-0.mount: Deactivated successfully. Mar 19 11:44:32.419239 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Mar 19 11:44:32.419447 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 19 11:44:32.421292 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Mar 19 11:44:32.421380 systemd[1]: user-0.slice: Consumed 5min 12.378s CPU time, 891.8M memory peak. Mar 19 11:44:32.421801 systemd-logind[1702]: Removed session c3. Mar 19 11:44:32.583088 sshd[13112]: Connection closed by 10.200.16.10 port 54560 Mar 19 11:44:32.583468 sshd-session[13110]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:32.586674 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:54560.service: Deactivated successfully. Mar 19 11:44:32.588310 systemd[1]: session-25.scope: Deactivated successfully. Mar 19 11:44:32.589024 systemd-logind[1702]: Session 25 logged out. Waiting for processes to exit. Mar 19 11:44:32.590036 systemd-logind[1702]: Removed session 25. Mar 19 11:44:32.704019 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:54564.service - OpenSSH per-connection server daemon (10.200.16.10:54564). Mar 19 11:44:33.355122 sshd[13122]: Accepted publickey for core from 10.200.16.10 port 54564 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:33.356427 sshd-session[13122]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:33.360483 systemd-logind[1702]: New session 26 of user core. Mar 19 11:44:33.376615 systemd[1]: Started session-26.scope - Session 26 of User core. Mar 19 11:44:33.813732 sshd[13124]: Connection closed by 10.200.16.10 port 54564 Mar 19 11:44:33.814091 sshd-session[13122]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:33.817045 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:54564.service: Deactivated successfully. Mar 19 11:44:33.818696 systemd[1]: session-26.scope: Deactivated successfully. Mar 19 11:44:33.819318 systemd-logind[1702]: Session 26 logged out. Waiting for processes to exit. Mar 19 11:44:33.820074 systemd-logind[1702]: Removed session 26. Mar 19 11:44:33.928664 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:54580.service - OpenSSH per-connection server daemon (10.200.16.10:54580). Mar 19 11:44:34.576408 sshd[13130]: Accepted publickey for core from 10.200.16.10 port 54580 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:34.577763 sshd-session[13130]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:34.581823 systemd-logind[1702]: New session 27 of user core. Mar 19 11:44:34.592630 systemd[1]: Started session-27.scope - Session 27 of User core. Mar 19 11:44:35.038220 sshd[13132]: Connection closed by 10.200.16.10 port 54580 Mar 19 11:44:35.038599 sshd-session[13130]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:35.041648 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:54580.service: Deactivated successfully. Mar 19 11:44:35.043254 systemd[1]: session-27.scope: Deactivated successfully. Mar 19 11:44:35.043902 systemd-logind[1702]: Session 27 logged out. Waiting for processes to exit. Mar 19 11:44:35.044672 systemd-logind[1702]: Removed session 27. Mar 19 11:44:35.152856 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:54582.service - OpenSSH per-connection server daemon (10.200.16.10:54582). Mar 19 11:44:35.800485 sshd[13138]: Accepted publickey for core from 10.200.16.10 port 54582 ssh2: RSA SHA256:spf3OijCpLuZYElFk97aEjkfr8djrCt0njqv3sYaas8 Mar 19 11:44:35.801778 sshd-session[13138]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 19 11:44:35.805870 systemd-logind[1702]: New session 28 of user core. Mar 19 11:44:35.819628 systemd[1]: Started session-28.scope - Session 28 of User core. Mar 19 11:44:37.712081 sshd[13140]: Connection closed by 10.200.16.10 port 54582 Mar 19 11:44:37.712521 sshd-session[13138]: pam_unix(sshd:session): session closed for user core Mar 19 11:44:37.715057 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:54582.service: Deactivated successfully. Mar 19 11:44:37.716872 systemd[1]: session-28.scope: Deactivated successfully. Mar 19 11:44:37.717076 systemd[1]: session-28.scope: Consumed 1.399s CPU time, 14.1M memory peak. Mar 19 11:44:37.718194 systemd-logind[1702]: Session 28 logged out. Waiting for processes to exit. Mar 19 11:44:37.718998 systemd-logind[1702]: Removed session 28.