Mar 17 17:51:54.219600 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Mon Mar 17 16:09:25 -00 2025 Mar 17 17:51:54.219628 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.219638 kernel: BIOS-provided physical RAM map: Mar 17 17:51:54.219645 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 17 17:51:54.219652 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 17 17:51:54.219658 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 17 17:51:54.219666 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 17 17:51:54.219676 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 17 17:51:54.219683 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 17 17:51:54.219689 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 17 17:51:54.219696 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Mar 17 17:51:54.219703 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Mar 17 17:51:54.219710 kernel: printk: bootconsole [earlyser0] enabled Mar 17 17:51:54.219717 kernel: NX (Execute Disable) protection: active Mar 17 17:51:54.219728 kernel: APIC: Static calls initialized Mar 17 17:51:54.219735 kernel: efi: EFI v2.7 by Microsoft Mar 17 17:51:54.219743 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Mar 17 17:51:54.219751 kernel: random: crng init done Mar 17 17:51:54.219758 kernel: secureboot: Secure boot disabled Mar 17 17:51:54.219766 kernel: SMBIOS 3.1.0 present. Mar 17 17:51:54.219773 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 17 17:51:54.219781 kernel: Hypervisor detected: Microsoft Hyper-V Mar 17 17:51:54.219788 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Mar 17 17:51:54.219795 kernel: Hyper-V: Host Build 10.0.20348.1799-1-0 Mar 17 17:51:54.219805 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 17 17:51:54.219812 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 17 17:51:54.219820 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 17 17:51:54.219828 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 17 17:51:54.219836 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 17 17:51:54.219844 kernel: tsc: Detected 2593.994 MHz processor Mar 17 17:51:54.219852 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 17 17:51:54.219860 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 17 17:51:54.219867 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Mar 17 17:51:54.219878 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Mar 17 17:51:54.219885 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 17 17:51:54.219893 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 17 17:51:54.219900 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Mar 17 17:51:54.219908 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 17 17:51:54.219915 kernel: Using GB pages for direct mapping Mar 17 17:51:54.219923 kernel: ACPI: Early table checksum verification disabled Mar 17 17:51:54.219934 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 17 17:51:54.219945 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.219953 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.219961 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 17 17:51:54.219977 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 17 17:51:54.219986 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.219994 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220011 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220019 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220027 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220035 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220044 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:51:54.220052 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 17 17:51:54.220060 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 17 17:51:54.220068 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 17 17:51:54.220079 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 17 17:51:54.220087 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 17 17:51:54.220095 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 17 17:51:54.220103 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Mar 17 17:51:54.220111 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Mar 17 17:51:54.220119 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 17 17:51:54.220127 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 17 17:51:54.220135 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 17 17:51:54.220143 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 17 17:51:54.220154 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Mar 17 17:51:54.220162 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Mar 17 17:51:54.220170 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Mar 17 17:51:54.220177 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Mar 17 17:51:54.220185 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 17 17:51:54.220194 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Mar 17 17:51:54.220202 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Mar 17 17:51:54.220210 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Mar 17 17:51:54.220218 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Mar 17 17:51:54.220228 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Mar 17 17:51:54.220237 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Mar 17 17:51:54.220245 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Mar 17 17:51:54.220253 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Mar 17 17:51:54.220261 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Mar 17 17:51:54.220269 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Mar 17 17:51:54.220277 kernel: Zone ranges: Mar 17 17:51:54.220286 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 17 17:51:54.220294 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 17 17:51:54.220304 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Mar 17 17:51:54.220312 kernel: Movable zone start for each node Mar 17 17:51:54.220320 kernel: Early memory node ranges Mar 17 17:51:54.220328 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 17 17:51:54.220339 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 17 17:51:54.220347 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 17 17:51:54.220355 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Mar 17 17:51:54.220372 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Mar 17 17:51:54.220381 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Mar 17 17:51:54.220392 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 17 17:51:54.220400 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 17 17:51:54.220408 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 17 17:51:54.220416 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 17 17:51:54.220424 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 17 17:51:54.220432 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Mar 17 17:51:54.220440 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 17 17:51:54.220448 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 17 17:51:54.220456 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 17 17:51:54.220467 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Mar 17 17:51:54.220475 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 17 17:51:54.220483 kernel: Booting paravirtualized kernel on Hyper-V Mar 17 17:51:54.220491 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 17 17:51:54.220500 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Mar 17 17:51:54.220508 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Mar 17 17:51:54.220516 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Mar 17 17:51:54.220524 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Mar 17 17:51:54.220532 kernel: Hyper-V: PV spinlocks enabled Mar 17 17:51:54.220543 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 17 17:51:54.220552 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.220560 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 17:51:54.220568 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Mar 17 17:51:54.220577 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Mar 17 17:51:54.220584 kernel: Fallback order for Node 0: 0 Mar 17 17:51:54.220592 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Mar 17 17:51:54.220600 kernel: Policy zone: Normal Mar 17 17:51:54.220611 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 17:51:54.220627 kernel: software IO TLB: area num 8. Mar 17 17:51:54.220636 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2303K rwdata, 22860K rodata, 43476K init, 1596K bss, 2103120K reserved, 0K cma-reserved) Mar 17 17:51:54.220648 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 17 17:51:54.220656 kernel: Kernel/User page tables isolation: enabled Mar 17 17:51:54.220665 kernel: ftrace: allocating 37910 entries in 149 pages Mar 17 17:51:54.220673 kernel: ftrace: allocated 149 pages with 4 groups Mar 17 17:51:54.220681 kernel: Dynamic Preempt: voluntary Mar 17 17:51:54.220690 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 17:51:54.220702 kernel: rcu: RCU event tracing is enabled. Mar 17 17:51:54.220711 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Mar 17 17:51:54.220720 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 17:51:54.220731 kernel: Rude variant of Tasks RCU enabled. Mar 17 17:51:54.220740 kernel: Tracing variant of Tasks RCU enabled. Mar 17 17:51:54.220749 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 17:51:54.220757 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Mar 17 17:51:54.220768 kernel: Using NULL legacy PIC Mar 17 17:51:54.220777 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Mar 17 17:51:54.220785 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 17 17:51:54.220794 kernel: Console: colour dummy device 80x25 Mar 17 17:51:54.220802 kernel: printk: console [tty1] enabled Mar 17 17:51:54.220810 kernel: printk: console [ttyS0] enabled Mar 17 17:51:54.220819 kernel: printk: bootconsole [earlyser0] disabled Mar 17 17:51:54.220827 kernel: ACPI: Core revision 20230628 Mar 17 17:51:54.220836 kernel: Failed to register legacy timer interrupt Mar 17 17:51:54.220844 kernel: APIC: Switch to symmetric I/O mode setup Mar 17 17:51:54.220855 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 17 17:51:54.220864 kernel: Hyper-V: Using IPI hypercalls Mar 17 17:51:54.220872 kernel: APIC: send_IPI() replaced with hv_send_ipi() Mar 17 17:51:54.220881 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Mar 17 17:51:54.220889 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Mar 17 17:51:54.220901 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Mar 17 17:51:54.220914 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Mar 17 17:51:54.220925 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Mar 17 17:51:54.220938 kernel: Hyper-V: Using enlightened APIC (xapic mode) Mar 17 17:51:54.220954 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Mar 17 17:51:54.220965 kernel: APIC: read() replaced with hv_apic_read() Mar 17 17:51:54.220978 kernel: APIC: write() replaced with hv_apic_write() Mar 17 17:51:54.220989 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Mar 17 17:51:54.221002 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Mar 17 17:51:54.221013 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593994) Mar 17 17:51:54.221026 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Mar 17 17:51:54.221039 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 17 17:51:54.221052 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 17 17:51:54.221067 kernel: Spectre V2 : Mitigation: Retpolines Mar 17 17:51:54.221078 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 17 17:51:54.221090 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 17 17:51:54.221101 kernel: Speculative Store Bypass: Vulnerable Mar 17 17:51:54.221110 kernel: MDS: Mitigation: Clear CPU buffers Mar 17 17:51:54.221118 kernel: TAA: Mitigation: Clear CPU buffers Mar 17 17:51:54.221126 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 17 17:51:54.221135 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 17 17:51:54.221143 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 17 17:51:54.221151 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 17 17:51:54.221160 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 17 17:51:54.221171 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Mar 17 17:51:54.221179 kernel: Freeing SMP alternatives memory: 32K Mar 17 17:51:54.221187 kernel: pid_max: default: 32768 minimum: 301 Mar 17 17:51:54.221196 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 17 17:51:54.221204 kernel: landlock: Up and running. Mar 17 17:51:54.221213 kernel: SELinux: Initializing. Mar 17 17:51:54.221221 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.221229 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.221238 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Mar 17 17:51:54.221247 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.221262 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.221274 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 17 17:51:54.221283 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Mar 17 17:51:54.221292 kernel: signal: max sigframe size: 1776 Mar 17 17:51:54.221303 kernel: rcu: Hierarchical SRCU implementation. Mar 17 17:51:54.221312 kernel: rcu: Max phase no-delay instances is 400. Mar 17 17:51:54.221321 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 17 17:51:54.221329 kernel: smp: Bringing up secondary CPUs ... Mar 17 17:51:54.221338 kernel: smpboot: x86: Booting SMP configuration: Mar 17 17:51:54.221346 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Mar 17 17:51:54.221358 kernel: smp: Brought up 1 node, 6 CPUs Mar 17 17:51:54.221393 kernel: smpboot: Max logical packages: 1 Mar 17 17:51:54.221402 kernel: smpboot: Total of 6 processors activated (31127.92 BogoMIPS) Mar 17 17:51:54.221410 kernel: devtmpfs: initialized Mar 17 17:51:54.221419 kernel: x86/mm: Memory block size: 1024MB Mar 17 17:51:54.221428 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 17 17:51:54.221436 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 17:51:54.221445 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Mar 17 17:51:54.221454 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 17:51:54.221465 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 17:51:54.221474 kernel: audit: initializing netlink subsys (disabled) Mar 17 17:51:54.221482 kernel: audit: type=2000 audit(1742233912.029:1): state=initialized audit_enabled=0 res=1 Mar 17 17:51:54.221491 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 17:51:54.221499 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 17 17:51:54.221508 kernel: cpuidle: using governor menu Mar 17 17:51:54.221516 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 17:51:54.221525 kernel: dca service started, version 1.12.1 Mar 17 17:51:54.221533 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Mar 17 17:51:54.221544 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 17 17:51:54.221553 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 17:51:54.221561 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 17 17:51:54.221570 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 17:51:54.221578 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 17 17:51:54.221587 kernel: ACPI: Added _OSI(Module Device) Mar 17 17:51:54.221595 kernel: ACPI: Added _OSI(Processor Device) Mar 17 17:51:54.221604 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 17:51:54.221612 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 17:51:54.221623 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 17:51:54.221632 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 17 17:51:54.221640 kernel: ACPI: Interpreter enabled Mar 17 17:51:54.221649 kernel: ACPI: PM: (supports S0 S5) Mar 17 17:51:54.221657 kernel: ACPI: Using IOAPIC for interrupt routing Mar 17 17:51:54.221666 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 17 17:51:54.221674 kernel: PCI: Ignoring E820 reservations for host bridge windows Mar 17 17:51:54.221683 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 17 17:51:54.221692 kernel: iommu: Default domain type: Translated Mar 17 17:51:54.221702 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 17 17:51:54.221711 kernel: efivars: Registered efivars operations Mar 17 17:51:54.221719 kernel: PCI: Using ACPI for IRQ routing Mar 17 17:51:54.221728 kernel: PCI: System does not support PCI Mar 17 17:51:54.221737 kernel: vgaarb: loaded Mar 17 17:51:54.221746 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 17 17:51:54.221754 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 17:51:54.221763 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 17:51:54.221771 kernel: pnp: PnP ACPI init Mar 17 17:51:54.221782 kernel: pnp: PnP ACPI: found 3 devices Mar 17 17:51:54.221791 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 17 17:51:54.221799 kernel: NET: Registered PF_INET protocol family Mar 17 17:51:54.221808 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.221817 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 17 17:51:54.221825 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 17:51:54.221834 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 17:51:54.221842 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.221854 kernel: TCP: Hash tables configured (established 524288 bind 65536) Mar 17 17:51:54.221862 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.221871 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 17 17:51:54.221879 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 17:51:54.221888 kernel: NET: Registered PF_XDP protocol family Mar 17 17:51:54.221896 kernel: PCI: CLS 0 bytes, default 64 Mar 17 17:51:54.221905 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 17 17:51:54.221914 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Mar 17 17:51:54.221922 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 17 17:51:54.221933 kernel: Initialise system trusted keyrings Mar 17 17:51:54.221942 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Mar 17 17:51:54.221950 kernel: Key type asymmetric registered Mar 17 17:51:54.221958 kernel: Asymmetric key parser 'x509' registered Mar 17 17:51:54.221967 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 17 17:51:54.221975 kernel: io scheduler mq-deadline registered Mar 17 17:51:54.221984 kernel: io scheduler kyber registered Mar 17 17:51:54.221992 kernel: io scheduler bfq registered Mar 17 17:51:54.222001 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 17 17:51:54.222009 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 17:51:54.222020 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 17 17:51:54.222029 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 17 17:51:54.222037 kernel: i8042: PNP: No PS/2 controller found. Mar 17 17:51:54.222190 kernel: rtc_cmos 00:02: registered as rtc0 Mar 17 17:51:54.222296 kernel: rtc_cmos 00:02: setting system clock to 2025-03-17T17:51:53 UTC (1742233913) Mar 17 17:51:54.222426 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 17 17:51:54.222440 kernel: intel_pstate: CPU model not supported Mar 17 17:51:54.222453 kernel: efifb: probing for efifb Mar 17 17:51:54.222462 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 17:51:54.222471 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 17:51:54.222479 kernel: efifb: scrolling: redraw Mar 17 17:51:54.222488 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 17:51:54.222496 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:51:54.222505 kernel: fb0: EFI VGA frame buffer device Mar 17 17:51:54.222513 kernel: pstore: Using crash dump compression: deflate Mar 17 17:51:54.222522 kernel: pstore: Registered efi_pstore as persistent store backend Mar 17 17:51:54.222533 kernel: NET: Registered PF_INET6 protocol family Mar 17 17:51:54.222542 kernel: Segment Routing with IPv6 Mar 17 17:51:54.222550 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 17:51:54.222559 kernel: NET: Registered PF_PACKET protocol family Mar 17 17:51:54.222567 kernel: Key type dns_resolver registered Mar 17 17:51:54.222575 kernel: IPI shorthand broadcast: enabled Mar 17 17:51:54.222584 kernel: sched_clock: Marking stable (1658004400, 101770600)->(2115606600, -355831600) Mar 17 17:51:54.222592 kernel: registered taskstats version 1 Mar 17 17:51:54.222601 kernel: Loading compiled-in X.509 certificates Mar 17 17:51:54.222612 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 2d438fc13e28f87f3f580874887bade2e2b0c7dd' Mar 17 17:51:54.222621 kernel: Key type .fscrypt registered Mar 17 17:51:54.222629 kernel: Key type fscrypt-provisioning registered Mar 17 17:51:54.222637 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 17:51:54.222646 kernel: ima: Allocated hash algorithm: sha1 Mar 17 17:51:54.222655 kernel: ima: No architecture policies found Mar 17 17:51:54.222663 kernel: clk: Disabling unused clocks Mar 17 17:51:54.222672 kernel: Freeing unused kernel image (initmem) memory: 43476K Mar 17 17:51:54.222680 kernel: Write protecting the kernel read-only data: 38912k Mar 17 17:51:54.222691 kernel: Freeing unused kernel image (rodata/data gap) memory: 1716K Mar 17 17:51:54.222700 kernel: Run /init as init process Mar 17 17:51:54.222708 kernel: with arguments: Mar 17 17:51:54.222717 kernel: /init Mar 17 17:51:54.222725 kernel: with environment: Mar 17 17:51:54.222733 kernel: HOME=/ Mar 17 17:51:54.222742 kernel: TERM=linux Mar 17 17:51:54.222750 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 17:51:54.222760 systemd[1]: Successfully made /usr/ read-only. Mar 17 17:51:54.222774 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 17 17:51:54.222994 systemd[1]: Detected virtualization microsoft. Mar 17 17:51:54.223007 systemd[1]: Detected architecture x86-64. Mar 17 17:51:54.223015 systemd[1]: Running in initrd. Mar 17 17:51:54.223133 systemd[1]: No hostname configured, using default hostname. Mar 17 17:51:54.223143 systemd[1]: Hostname set to . Mar 17 17:51:54.223151 systemd[1]: Initializing machine ID from random generator. Mar 17 17:51:54.223166 systemd[1]: Queued start job for default target initrd.target. Mar 17 17:51:54.223175 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:51:54.223185 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:51:54.223195 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 17 17:51:54.223204 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:51:54.223213 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 17 17:51:54.223223 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 17 17:51:54.223236 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 17 17:51:54.223245 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 17 17:51:54.223254 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:51:54.223263 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:51:54.223273 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:51:54.223282 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:51:54.223291 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:51:54.223300 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:51:54.223312 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:51:54.223321 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:51:54.223330 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 17 17:51:54.223339 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 17 17:51:54.223348 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:51:54.223357 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:51:54.223413 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:51:54.223424 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:51:54.223433 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 17 17:51:54.223445 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:51:54.223454 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 17 17:51:54.223464 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 17:51:54.223473 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:51:54.223482 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:51:54.223491 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:54.223525 systemd-journald[205]: Collecting audit messages is disabled. Mar 17 17:51:54.223551 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 17 17:51:54.223561 systemd-journald[205]: Journal started Mar 17 17:51:54.223587 systemd-journald[205]: Runtime Journal (/run/log/journal/30100b95e59c4d529a47dbd1aa819e15) is 8M, max 2.2G, 2.1G free. Mar 17 17:51:54.225102 systemd-modules-load[207]: Inserted module 'overlay' Mar 17 17:51:54.240248 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:51:54.240567 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:51:54.242219 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 17:51:54.243637 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:54.278383 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 17:51:54.281866 systemd-modules-load[207]: Inserted module 'br_netfilter' Mar 17 17:51:54.285355 kernel: Bridge firewalling registered Mar 17 17:51:54.285536 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:54.295972 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:51:54.298072 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:51:54.298460 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:51:54.300111 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:51:54.341468 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:51:54.342596 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:51:54.345303 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:51:54.347917 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:51:54.350205 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:51:54.358064 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:54.376122 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 17 17:51:54.381036 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:51:54.405004 dracut-cmdline[244]: dracut-dracut-053 Mar 17 17:51:54.410222 dracut-cmdline[244]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=2a4a0f64c0160ed10b339be09fdc9d7e265b13f78aefc87616e79bf13c00bb1c Mar 17 17:51:54.419054 systemd-resolved[240]: Positive Trust Anchors: Mar 17 17:51:54.419062 systemd-resolved[240]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:51:54.419092 systemd-resolved[240]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Mar 17 17:51:54.421623 systemd-resolved[240]: Defaulting to hostname 'linux'. Mar 17 17:51:54.422720 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:51:54.436702 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:51:54.531392 kernel: SCSI subsystem initialized Mar 17 17:51:54.541387 kernel: Loading iSCSI transport class v2.0-870. Mar 17 17:51:54.553387 kernel: iscsi: registered transport (tcp) Mar 17 17:51:54.576197 kernel: iscsi: registered transport (qla4xxx) Mar 17 17:51:54.576230 kernel: QLogic iSCSI HBA Driver Mar 17 17:51:54.616067 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 17 17:51:54.638488 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 17 17:51:54.668458 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 17:51:54.668509 kernel: device-mapper: uevent: version 1.0.3 Mar 17 17:51:54.673015 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 17 17:51:54.715381 kernel: raid6: avx2x4 gen() 31187 MB/s Mar 17 17:51:54.734377 kernel: raid6: avx2x2 gen() 30559 MB/s Mar 17 17:51:54.755390 kernel: raid6: avx2x1 gen() 27971 MB/s Mar 17 17:51:54.755417 kernel: raid6: using algorithm avx2x4 gen() 31187 MB/s Mar 17 17:51:54.778454 kernel: raid6: .... xor() 10209 MB/s, rmw enabled Mar 17 17:51:54.778481 kernel: raid6: using avx2x2 recovery algorithm Mar 17 17:51:54.801384 kernel: xor: automatically using best checksumming function avx Mar 17 17:51:54.956397 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 17 17:51:54.967006 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:51:54.988495 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:51:55.010639 systemd-udevd[428]: Using default interface naming scheme 'v255'. Mar 17 17:51:55.016200 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:51:55.034506 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 17 17:51:55.050307 dracut-pre-trigger[438]: rd.md=0: removing MD RAID activation Mar 17 17:51:55.081162 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:51:55.102120 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:51:55.157237 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:51:55.173564 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 17 17:51:55.200413 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 17:51:55.183808 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 17 17:51:55.190951 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:51:55.196084 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:51:55.208396 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:51:55.230565 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 17 17:51:55.248480 kernel: AVX2 version of gcm_enc/dec engaged. Mar 17 17:51:55.248508 kernel: hv_vmbus: Vmbus version:4.0 Mar 17 17:51:55.248521 kernel: AES CTR mode by8 optimization enabled Mar 17 17:51:55.240911 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:51:55.246984 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:51:55.247231 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:55.284452 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 17:51:55.256015 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:55.257441 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:51:55.257625 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:55.308670 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Mar 17 17:51:55.272642 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:55.277827 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:55.298684 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:51:55.333410 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 17:51:55.333432 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 17:51:55.298798 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:55.337684 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:51:55.345667 kernel: PTP clock support registered Mar 17 17:51:55.349387 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 17:51:55.349417 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 17:51:55.351379 kernel: scsi host0: storvsc_host_t Mar 17 17:51:55.353382 kernel: scsi host1: storvsc_host_t Mar 17 17:51:55.363407 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 17:51:55.363580 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:51:55.379707 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 17:51:55.380555 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 17:51:55.380716 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 17:51:55.380731 kernel: hv_vmbus: registering driver hv_pci Mar 17 17:51:55.380742 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 17:51:55.387038 kernel: hv_vmbus: registering driver hv_utils Mar 17 17:51:55.387070 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Mar 17 17:51:55.875640 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 17:51:55.875661 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 17:51:55.875675 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 17:51:55.875687 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 17:51:55.875698 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 17 17:51:55.875710 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Mar 17 17:51:55.875873 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 17:51:55.876011 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Mar 17 17:51:55.876165 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Mar 17 17:51:55.876316 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 17:51:55.876457 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Mar 17 17:51:55.876641 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Mar 17 17:51:55.876785 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 17 17:51:55.876930 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 17 17:51:55.877070 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 17:51:55.877238 kernel: pci 0001:00:00.0: Enabling HDA controller Mar 17 17:51:55.877380 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 17:51:55.877394 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 17:51:55.877548 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Mar 17 17:51:55.913309 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 17:51:55.925476 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 17:51:55.925641 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 17 17:51:55.925790 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 17 17:51:55.925924 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Mar 17 17:51:55.926057 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Mar 17 17:51:55.926194 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Mar 17 17:51:55.926328 kernel: sd 0:0:0:1: [sda] Write Protect is off Mar 17 17:51:55.926463 kernel: sd 0:0:0:0: [sdb] Write Protect is off Mar 17 17:51:55.926639 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Mar 17 17:51:55.926774 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Mar 17 17:51:55.926908 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 17:51:55.927042 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 17:51:55.927174 kernel: sda: sda1 Mar 17 17:51:55.927188 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Mar 17 17:51:55.927199 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Mar 17 17:51:55.927337 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Mar 17 17:51:55.804448 systemd-resolved[240]: Clock change detected. Flushing caches. Mar 17 17:51:55.824212 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:51:55.882143 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:51:55.954347 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:51:56.447981 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 17 17:51:56.479537 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sdb6 scanned by (udev-worker) (503) Mar 17 17:51:56.501321 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:51:56.517536 kernel: BTRFS: device fsid 16b3954e-2e86-4c7f-a948-d3d3817b1bdc devid 1 transid 42 /dev/sdb3 scanned by (udev-worker) (493) Mar 17 17:51:56.525592 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 17 17:51:56.544097 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 17 17:51:56.545564 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 17 17:51:56.575654 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 17 17:51:56.595529 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Mar 17 17:51:56.602530 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Mar 17 17:51:57.609058 disk-uuid[642]: The operation has completed successfully. Mar 17 17:51:57.615818 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Mar 17 17:51:58.381554 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 17:51:58.381661 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 17 17:51:58.411669 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 17 17:51:58.422423 sh[730]: Success Mar 17 17:51:58.445530 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 17 17:51:58.653452 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 17 17:51:58.670912 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 17 17:51:58.673185 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 17 17:51:58.697322 kernel: BTRFS info (device dm-0): first mount of filesystem 16b3954e-2e86-4c7f-a948-d3d3817b1bdc Mar 17 17:51:58.697355 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:51:58.702493 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 17 17:51:58.706275 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 17 17:51:58.709433 kernel: BTRFS info (device dm-0): using free space tree Mar 17 17:51:59.062301 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 17 17:51:59.064583 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 17 17:51:59.082702 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 17 17:51:59.085095 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 17 17:51:59.110498 kernel: BTRFS info (device sdb6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:51:59.110537 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:51:59.110551 kernel: BTRFS info (device sdb6): using free space tree Mar 17 17:51:59.132538 kernel: BTRFS info (device sdb6): auto enabling async discard Mar 17 17:51:59.140200 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 17:51:59.149028 kernel: BTRFS info (device sdb6): last unmount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:51:59.153612 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 17 17:51:59.168678 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 17 17:51:59.205854 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:51:59.231695 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:51:59.261027 systemd-networkd[915]: lo: Link UP Mar 17 17:51:59.261038 systemd-networkd[915]: lo: Gained carrier Mar 17 17:51:59.262160 systemd-networkd[915]: Enumeration completed Mar 17 17:51:59.262229 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:51:59.262512 systemd-networkd[915]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:51:59.262527 systemd-networkd[915]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:51:59.263270 systemd-networkd[915]: eth0: Link UP Mar 17 17:51:59.263432 systemd-networkd[915]: eth0: Gained carrier Mar 17 17:51:59.263441 systemd-networkd[915]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:51:59.267996 systemd[1]: Reached target network.target - Network. Mar 17 17:51:59.330558 systemd-networkd[915]: eth0: DHCPv4 address 10.200.4.10/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:00.292544 ignition[852]: Ignition 2.20.0 Mar 17 17:52:00.292555 ignition[852]: Stage: fetch-offline Mar 17 17:52:00.292598 ignition[852]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.292609 ignition[852]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.292711 ignition[852]: parsed url from cmdline: "" Mar 17 17:52:00.292715 ignition[852]: no config URL provided Mar 17 17:52:00.292720 ignition[852]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:52:00.292730 ignition[852]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:52:00.292736 ignition[852]: failed to fetch config: resource requires networking Mar 17 17:52:00.292963 ignition[852]: Ignition finished successfully Mar 17 17:52:00.320449 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:52:00.339736 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 17 17:52:00.352165 ignition[931]: Ignition 2.20.0 Mar 17 17:52:00.352176 ignition[931]: Stage: fetch Mar 17 17:52:00.352336 ignition[931]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.352347 ignition[931]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.352443 ignition[931]: parsed url from cmdline: "" Mar 17 17:52:00.352449 ignition[931]: no config URL provided Mar 17 17:52:00.352455 ignition[931]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:52:00.352467 ignition[931]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:52:00.352491 ignition[931]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 17:52:00.429834 ignition[931]: GET result: OK Mar 17 17:52:00.429856 ignition[931]: failed to retrieve userdata from IMDS, falling back to custom data: not a config (empty) Mar 17 17:52:00.468984 ignition[931]: opening config device: "/dev/sr0" Mar 17 17:52:00.469389 ignition[931]: getting drive status for "/dev/sr0" Mar 17 17:52:00.469465 ignition[931]: drive status: OK Mar 17 17:52:00.469495 ignition[931]: mounting config device Mar 17 17:52:00.469506 ignition[931]: op(1): [started] mounting "/dev/sr0" at "/tmp/ignition-azure2024507413" Mar 17 17:52:00.486539 kernel: UDF-fs: INFO Mounting volume 'UDF Volume', timestamp 2025/03/18 00:00 (1000) Mar 17 17:52:00.486700 ignition[931]: op(1): [finished] mounting "/dev/sr0" at "/tmp/ignition-azure2024507413" Mar 17 17:52:00.486711 ignition[931]: checking for config drive Mar 17 17:52:00.488262 systemd[1]: tmp-ignition\x2dazure2024507413.mount: Deactivated successfully. Mar 17 17:52:00.487048 ignition[931]: reading config Mar 17 17:52:00.487375 ignition[931]: op(2): [started] unmounting "/dev/sr0" at "/tmp/ignition-azure2024507413" Mar 17 17:52:00.491594 ignition[931]: op(2): [finished] unmounting "/dev/sr0" at "/tmp/ignition-azure2024507413" Mar 17 17:52:00.496871 unknown[931]: fetched base config from "system" Mar 17 17:52:00.491610 ignition[931]: config has been read from custom data Mar 17 17:52:00.496884 unknown[931]: fetched base config from "system" Mar 17 17:52:00.491623 ignition[931]: parsing config with SHA512: df3c0660de8792ffb66ea05c3804787c4867f4215f5886293640bb892bac5ae0ad6f01e720ba3c305ef19c4347a233f6d8c5dcb3118a2af6220b111ab96d33b3 Mar 17 17:52:00.496890 unknown[931]: fetched user config from "azure" Mar 17 17:52:00.497157 ignition[931]: fetch: fetch complete Mar 17 17:52:00.502621 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 17 17:52:00.497167 ignition[931]: fetch: fetch passed Mar 17 17:52:00.519698 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 17 17:52:00.497213 ignition[931]: Ignition finished successfully Mar 17 17:52:00.553578 ignition[942]: Ignition 2.20.0 Mar 17 17:52:00.553590 ignition[942]: Stage: kargs Mar 17 17:52:00.553762 ignition[942]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.553775 ignition[942]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.554397 ignition[942]: kargs: kargs passed Mar 17 17:52:00.554444 ignition[942]: Ignition finished successfully Mar 17 17:52:00.568604 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 17 17:52:00.586688 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 17 17:52:00.598411 ignition[952]: Ignition 2.20.0 Mar 17 17:52:00.598422 ignition[952]: Stage: disks Mar 17 17:52:00.598620 ignition[952]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:00.598633 ignition[952]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:00.603934 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 17 17:52:00.599172 ignition[952]: disks: disks passed Mar 17 17:52:00.610218 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 17 17:52:00.599215 ignition[952]: Ignition finished successfully Mar 17 17:52:00.612223 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 17 17:52:00.623574 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:52:00.630630 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:52:00.638811 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:52:00.663641 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 17 17:52:00.722598 systemd-fsck[964]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 17 17:52:00.728112 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 17 17:52:00.744638 systemd-networkd[915]: eth0: Gained IPv6LL Mar 17 17:52:00.749625 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 17 17:52:00.848539 kernel: EXT4-fs (sdb9): mounted filesystem 21764504-a65e-45eb-84e1-376b55b62aba r/w with ordered data mode. Quota mode: none. Mar 17 17:52:00.849229 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 17 17:52:00.851200 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 17 17:52:01.046597 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:52:01.048190 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 17 17:52:01.078135 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sdb6 scanned by mount (975) Mar 17 17:52:01.078165 kernel: BTRFS info (device sdb6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:01.078180 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:52:01.078194 kernel: BTRFS info (device sdb6): using free space tree Mar 17 17:52:01.078207 kernel: BTRFS info (device sdb6): auto enabling async discard Mar 17 17:52:01.059918 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 17 17:52:01.079561 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 17:52:01.079595 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:52:01.090236 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:52:01.102027 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 17 17:52:01.111422 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 17 17:52:01.845702 coreos-metadata[977]: Mar 17 17:52:01.845 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:52:01.851700 coreos-metadata[977]: Mar 17 17:52:01.848 INFO Fetch successful Mar 17 17:52:01.851700 coreos-metadata[977]: Mar 17 17:52:01.848 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:52:01.863785 coreos-metadata[977]: Mar 17 17:52:01.855 INFO Fetch successful Mar 17 17:52:01.870183 coreos-metadata[977]: Mar 17 17:52:01.870 INFO wrote hostname ci-4230.1.0-a-303ed89b81 to /sysroot/etc/hostname Mar 17 17:52:01.872085 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:52:01.963976 initrd-setup-root[1005]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 17:52:02.025882 initrd-setup-root[1012]: cut: /sysroot/etc/group: No such file or directory Mar 17 17:52:02.033156 initrd-setup-root[1019]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 17:52:02.040417 initrd-setup-root[1026]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 17:52:03.059573 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 17 17:52:03.077633 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 17 17:52:03.079111 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 17 17:52:03.089391 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 17 17:52:03.100378 kernel: BTRFS info (device sdb6): last unmount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:03.110403 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 17 17:52:03.128987 ignition[1097]: INFO : Ignition 2.20.0 Mar 17 17:52:03.128987 ignition[1097]: INFO : Stage: mount Mar 17 17:52:03.134743 ignition[1097]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.134743 ignition[1097]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.134743 ignition[1097]: INFO : mount: mount passed Mar 17 17:52:03.134743 ignition[1097]: INFO : Ignition finished successfully Mar 17 17:52:03.135928 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 17 17:52:03.161629 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 17 17:52:03.169266 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:52:03.188534 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sdb6 scanned by mount (1108) Mar 17 17:52:03.188574 kernel: BTRFS info (device sdb6): first mount of filesystem e64ce651-fa93-44de-893d-ff1e0bc9061f Mar 17 17:52:03.196909 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:52:03.199880 kernel: BTRFS info (device sdb6): using free space tree Mar 17 17:52:03.205531 kernel: BTRFS info (device sdb6): auto enabling async discard Mar 17 17:52:03.207369 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:52:03.250731 ignition[1125]: INFO : Ignition 2.20.0 Mar 17 17:52:03.250731 ignition[1125]: INFO : Stage: files Mar 17 17:52:03.256357 ignition[1125]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.256357 ignition[1125]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.256357 ignition[1125]: DEBUG : files: compiled without relabeling support, skipping Mar 17 17:52:03.290254 ignition[1125]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 17:52:03.290254 ignition[1125]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 17:52:03.383299 ignition[1125]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 17:52:03.388752 ignition[1125]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 17:52:03.388752 ignition[1125]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 17:52:03.384095 unknown[1125]: wrote ssh authorized keys file for user: core Mar 17 17:52:03.417301 ignition[1125]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:52:03.424280 ignition[1125]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:52:03.424280 ignition[1125]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:52:03.424280 ignition[1125]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:52:03.424280 ignition[1125]: INFO : files: files passed Mar 17 17:52:03.424280 ignition[1125]: INFO : Ignition finished successfully Mar 17 17:52:03.439097 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 17 17:52:03.467651 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 17 17:52:03.469231 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 17 17:52:03.477931 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 17:52:03.478033 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 17 17:52:03.499053 initrd-setup-root-after-ignition[1157]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.499053 initrd-setup-root-after-ignition[1157]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.511879 initrd-setup-root-after-ignition[1161]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:52:03.519360 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:52:03.521043 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 17 17:52:03.551651 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 17 17:52:03.574170 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 17:52:03.574273 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 17 17:52:03.583087 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 17 17:52:03.584876 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 17 17:52:03.603110 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 17 17:52:03.617657 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 17 17:52:03.632026 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:52:03.634678 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 17 17:52:03.650321 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:52:03.651895 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:52:03.660687 systemd[1]: Stopped target timers.target - Timer Units. Mar 17 17:52:03.669544 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 17:52:03.669666 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:52:03.683569 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 17 17:52:03.692215 systemd[1]: Stopped target basic.target - Basic System. Mar 17 17:52:03.694372 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 17 17:52:03.703546 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:52:03.715957 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 17 17:52:03.724811 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 17 17:52:03.726919 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:52:03.738017 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 17 17:52:03.747660 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 17 17:52:03.755639 systemd[1]: Stopped target swap.target - Swaps. Mar 17 17:52:03.762579 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 17:52:03.762722 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:52:03.775762 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:52:03.783984 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:52:03.792762 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 17 17:52:03.792891 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:52:03.802176 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 17:52:03.802305 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 17 17:52:03.815389 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 17:52:03.815558 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:52:03.824075 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 17:52:03.824191 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 17 17:52:03.831960 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 17:52:03.832075 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:52:03.850659 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 17 17:52:03.856708 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 17:52:03.856840 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:52:03.896310 ignition[1181]: INFO : Ignition 2.20.0 Mar 17 17:52:03.896310 ignition[1181]: INFO : Stage: umount Mar 17 17:52:03.896310 ignition[1181]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:52:03.896310 ignition[1181]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:52:03.896310 ignition[1181]: INFO : umount: umount passed Mar 17 17:52:03.896310 ignition[1181]: INFO : Ignition finished successfully Mar 17 17:52:03.866494 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 17 17:52:03.874058 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 17:52:03.874187 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:52:03.882490 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 17:52:03.882634 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:52:03.897969 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 17:52:03.898079 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 17 17:52:03.908182 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 17:52:03.908274 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 17 17:52:03.911491 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 17:52:03.911567 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 17 17:52:03.920522 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 17:52:03.920575 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 17 17:52:03.933115 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 17:52:03.933163 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 17 17:52:03.940717 systemd[1]: Stopped target network.target - Network. Mar 17 17:52:03.942913 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 17:52:03.942975 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:52:03.954413 systemd[1]: Stopped target paths.target - Path Units. Mar 17 17:52:03.962981 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 17:52:03.972562 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:52:03.974462 systemd[1]: Stopped target slices.target - Slice Units. Mar 17 17:52:03.983308 systemd[1]: Stopped target sockets.target - Socket Units. Mar 17 17:52:03.990791 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 17:52:03.990841 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:52:03.999112 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 17:52:03.999154 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:52:04.006444 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 17:52:04.006497 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 17 17:52:04.014008 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 17 17:52:04.014072 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 17 17:52:04.021587 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 17 17:52:04.029430 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 17 17:52:04.038340 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 17:52:04.038999 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 17:52:04.039111 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 17 17:52:04.051620 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 17:52:04.051744 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 17 17:52:04.064925 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 17 17:52:04.064987 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 17 17:52:04.065222 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 17:52:04.065312 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 17 17:52:04.069982 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 17:52:04.070031 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:52:04.076283 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 17:52:04.076335 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 17 17:52:04.106616 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 17 17:52:04.113245 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 17:52:04.113316 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:52:04.115486 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 17:52:04.115547 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:52:04.133233 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 17:52:04.133288 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 17 17:52:04.141416 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 17 17:52:04.141463 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:52:04.154229 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:52:04.163135 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 17:52:04.163205 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:52:04.197741 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 17:52:04.197922 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:52:04.206601 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 17:52:04.206698 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 17 17:52:04.214177 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 17:52:04.214232 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 17 17:52:04.221763 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 17:52:04.221800 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:52:04.229621 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 17:52:04.229672 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:52:04.239644 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 17:52:04.239691 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 17 17:52:04.251839 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:52:04.251893 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:52:04.295653 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 17 17:52:04.299243 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 17:52:04.299298 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:52:04.311942 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:52:04.311991 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:04.320733 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 17 17:52:04.320796 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:52:04.321364 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 17:52:04.321453 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 17 17:52:04.324835 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 17 17:52:04.332998 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 17 17:52:04.384671 systemd[1]: Switching root. Mar 17 17:52:04.501496 systemd-journald[205]: Journal stopped Mar 17 17:52:09.131216 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Mar 17 17:52:09.131248 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 17:52:09.131261 kernel: SELinux: policy capability open_perms=1 Mar 17 17:52:09.131271 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 17:52:09.131280 kernel: SELinux: policy capability always_check_network=0 Mar 17 17:52:09.131289 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 17:52:09.131299 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 17:52:09.131311 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 17:52:09.131321 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 17:52:09.131331 kernel: audit: type=1403 audit(1742233925.307:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 17:52:09.131341 systemd[1]: Successfully loaded SELinux policy in 198.282ms. Mar 17 17:52:09.131353 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.913ms. Mar 17 17:52:09.131364 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 17 17:52:09.131375 systemd[1]: Detected virtualization microsoft. Mar 17 17:52:09.131389 systemd[1]: Detected architecture x86-64. Mar 17 17:52:09.131399 systemd[1]: Detected first boot. Mar 17 17:52:09.131411 systemd[1]: Hostname set to . Mar 17 17:52:09.131421 systemd[1]: Initializing machine ID from random generator. Mar 17 17:52:09.131432 zram_generator::config[1227]: No configuration found. Mar 17 17:52:09.131490 kernel: Guest personality initialized and is inactive Mar 17 17:52:09.131504 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Mar 17 17:52:09.131524 kernel: Initialized host personality Mar 17 17:52:09.131536 kernel: NET: Registered PF_VSOCK protocol family Mar 17 17:52:09.131548 systemd[1]: Populated /etc with preset unit settings. Mar 17 17:52:09.131560 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 17 17:52:09.131570 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 17:52:09.131581 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 17 17:52:09.131597 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 17:52:09.131609 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 17 17:52:09.131620 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 17 17:52:09.131631 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 17 17:52:09.131642 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 17 17:52:09.131653 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 17 17:52:09.131664 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 17 17:52:09.131678 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 17 17:52:09.131689 systemd[1]: Created slice user.slice - User and Session Slice. Mar 17 17:52:09.131700 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:52:09.131711 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:52:09.131723 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 17 17:52:09.131735 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 17 17:52:09.131750 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 17 17:52:09.131762 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:52:09.131776 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 17 17:52:09.131788 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:52:09.131799 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 17 17:52:09.131810 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 17 17:52:09.131821 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 17 17:52:09.131832 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 17 17:52:09.131843 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:52:09.131854 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:52:09.131868 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:52:09.131880 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:52:09.131891 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 17 17:52:09.131902 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 17 17:52:09.131913 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 17 17:52:09.131927 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:52:09.131939 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:52:09.131950 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:52:09.131962 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 17 17:52:09.131973 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 17 17:52:09.131985 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 17 17:52:09.131996 systemd[1]: Mounting media.mount - External Media Directory... Mar 17 17:52:09.132008 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:09.132023 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 17 17:52:09.132035 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 17 17:52:09.132046 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 17 17:52:09.132058 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 17 17:52:09.132071 systemd[1]: Reached target machines.target - Containers. Mar 17 17:52:09.132086 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 17 17:52:09.132098 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:52:09.132109 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:52:09.132124 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 17 17:52:09.132135 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:52:09.132147 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:52:09.132158 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:52:09.132169 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 17 17:52:09.132181 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:52:09.132192 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 17:52:09.132204 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 17:52:09.132218 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 17 17:52:09.132230 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 17:52:09.132241 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 17:52:09.132253 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 17 17:52:09.132264 kernel: fuse: init (API version 7.39) Mar 17 17:52:09.132275 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:52:09.132287 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:52:09.132298 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 17 17:52:09.132312 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 17 17:52:09.132324 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 17 17:52:09.132338 kernel: loop: module loaded Mar 17 17:52:09.132349 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:52:09.132360 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 17:52:09.132372 systemd[1]: Stopped verity-setup.service. Mar 17 17:52:09.132383 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:09.132394 kernel: ACPI: bus type drm_connector registered Mar 17 17:52:09.132408 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 17 17:52:09.132420 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 17 17:52:09.132431 systemd[1]: Mounted media.mount - External Media Directory. Mar 17 17:52:09.132442 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 17 17:52:09.132454 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 17 17:52:09.132487 systemd-journald[1334]: Collecting audit messages is disabled. Mar 17 17:52:09.132529 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 17 17:52:09.132543 systemd-journald[1334]: Journal started Mar 17 17:52:09.132566 systemd-journald[1334]: Runtime Journal (/run/log/journal/4c0c4f7ac39141c1b0e671a35777eafd) is 8M, max 2.2G, 2.1G free. Mar 17 17:52:08.350306 systemd[1]: Queued start job for default target multi-user.target. Mar 17 17:52:08.366021 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Mar 17 17:52:08.366486 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 17:52:09.140026 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:52:09.143473 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 17 17:52:09.148468 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:52:09.153839 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 17:52:09.154064 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 17 17:52:09.159043 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:52:09.159256 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:52:09.164127 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:52:09.164341 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:52:09.168887 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:52:09.169089 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:52:09.174227 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 17:52:09.174437 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 17 17:52:09.178946 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:52:09.179127 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:52:09.183642 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:52:09.188264 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 17 17:52:09.193396 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 17 17:52:09.198538 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 17 17:52:09.203754 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:52:09.218048 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 17 17:52:09.235581 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 17 17:52:09.240744 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 17 17:52:09.245011 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 17:52:09.245046 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:52:09.250095 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 17 17:52:09.255864 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 17 17:52:09.261353 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 17 17:52:09.265426 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:52:09.266667 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 17 17:52:09.271889 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 17 17:52:09.276375 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:52:09.277296 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 17 17:52:09.281830 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:52:09.282885 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:52:09.287852 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 17 17:52:09.293611 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 17 17:52:09.298486 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 17 17:52:09.305720 systemd-journald[1334]: Time spent on flushing to /var/log/journal/4c0c4f7ac39141c1b0e671a35777eafd is 14.187ms for 954 entries. Mar 17 17:52:09.305720 systemd-journald[1334]: System Journal (/var/log/journal/4c0c4f7ac39141c1b0e671a35777eafd) is 8M, max 2.6G, 2.6G free. Mar 17 17:52:09.365291 systemd-journald[1334]: Received client request to flush runtime journal. Mar 17 17:52:09.365356 kernel: loop0: detected capacity change from 0 to 138176 Mar 17 17:52:09.306033 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 17 17:52:09.313669 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 17 17:52:09.314456 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 17 17:52:09.328070 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 17 17:52:09.333248 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 17 17:52:09.339207 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 17 17:52:09.350435 udevadm[1373]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 17:52:09.366451 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 17 17:52:09.380068 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:52:09.426460 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 17:52:09.427197 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 17 17:52:09.683041 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 17 17:52:09.696672 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:52:09.772813 systemd-tmpfiles[1387]: ACLs are not supported, ignoring. Mar 17 17:52:09.772835 systemd-tmpfiles[1387]: ACLs are not supported, ignoring. Mar 17 17:52:09.778173 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:52:10.194542 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 17:52:10.262545 kernel: loop1: detected capacity change from 0 to 147912 Mar 17 17:52:10.609508 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 17 17:52:10.624709 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:52:10.652506 systemd-udevd[1393]: Using default interface naming scheme 'v255'. Mar 17 17:52:10.767542 kernel: loop2: detected capacity change from 0 to 28272 Mar 17 17:52:10.886603 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:52:10.897693 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:52:10.921918 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 17 17:52:10.974623 kernel: hv_vmbus: registering driver hv_balloon Mar 17 17:52:10.974927 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 17:52:10.999537 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 17:52:10.999608 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 17:52:11.006574 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 17:52:11.011328 kernel: Console: switching to colour dummy device 80x25 Mar 17 17:52:11.016267 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:52:11.029660 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:52:11.039546 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 17:52:11.040735 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:52:11.040957 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:11.047093 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:52:11.049930 kernel: EDAC MC: Ver: 3.0.0 Mar 17 17:52:11.122545 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 42 scanned by (udev-worker) (1408) Mar 17 17:52:11.123735 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 17 17:52:11.163269 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 17 17:52:11.168739 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 17 17:52:11.178092 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:52:11.188865 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 17 17:52:11.195163 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 17 17:52:11.260812 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 17 17:52:11.299537 kernel: loop3: detected capacity change from 0 to 138176 Mar 17 17:52:11.308165 systemd-networkd[1399]: lo: Link UP Mar 17 17:52:11.308175 systemd-networkd[1399]: lo: Gained carrier Mar 17 17:52:11.309647 systemd-networkd[1399]: Enumeration completed Mar 17 17:52:11.309761 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:52:11.310075 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:11.310086 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:52:11.310693 systemd-networkd[1399]: eth0: Link UP Mar 17 17:52:11.310703 systemd-networkd[1399]: eth0: Gained carrier Mar 17 17:52:11.310717 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:11.312534 kernel: loop4: detected capacity change from 0 to 147912 Mar 17 17:52:11.327659 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 17 17:52:11.331545 kernel: loop5: detected capacity change from 0 to 28272 Mar 17 17:52:11.333429 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 17 17:52:11.346142 (sd-merge)[1526]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 17 17:52:11.346655 (sd-merge)[1526]: Merged extensions into '/usr'. Mar 17 17:52:11.348562 systemd-networkd[1399]: eth0: DHCPv4 address 10.200.4.10/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:11.350011 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 17 17:52:11.356099 lvm[1520]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:52:11.366640 systemd[1]: Starting ensure-sysext.service... Mar 17 17:52:11.369612 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:52:11.373363 systemd[1]: Reload requested from client PID 1530 ('systemctl') (unit ensure-sysext.service)... Mar 17 17:52:11.373374 systemd[1]: Reloading... Mar 17 17:52:11.422551 zram_generator::config[1565]: No configuration found. Mar 17 17:52:11.444031 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 17:52:11.444329 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 17 17:52:11.445257 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 17:52:11.445576 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Mar 17 17:52:11.445646 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Mar 17 17:52:11.462616 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:52:11.462630 systemd-tmpfiles[1531]: Skipping /boot Mar 17 17:52:11.475368 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:52:11.475384 systemd-tmpfiles[1531]: Skipping /boot Mar 17 17:52:11.563329 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 17:52:11.698938 systemd[1]: Reloading finished in 325 ms. Mar 17 17:52:11.724030 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:52:11.729379 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 17 17:52:11.734598 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 17 17:52:11.756777 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:52:11.769557 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:52:11.788736 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:52:11.867785 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 17 17:52:11.873588 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 17 17:52:11.879803 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 17 17:52:11.883355 lvm[1639]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:52:11.886063 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:52:11.888626 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 17 17:52:11.906040 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 17 17:52:11.920182 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:11.920451 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:52:11.932022 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:52:11.937221 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:52:11.944224 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:52:11.951169 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:52:11.955241 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:52:11.955370 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 17 17:52:11.955677 systemd[1]: Reached target time-set.target - System Time Set. Mar 17 17:52:11.960241 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:52:11.962059 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 17 17:52:11.967508 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:52:11.967705 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:52:11.973031 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:52:11.973227 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:52:11.977930 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:52:11.978112 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:52:11.983352 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:52:11.983537 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:52:11.991423 systemd[1]: Finished ensure-sysext.service. Mar 17 17:52:11.998045 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:52:11.998104 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:52:12.050117 augenrules[1668]: No rules Mar 17 17:52:12.051809 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:52:12.052047 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:52:12.099565 systemd-resolved[1641]: Positive Trust Anchors: Mar 17 17:52:12.099583 systemd-resolved[1641]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:52:12.099613 systemd-resolved[1641]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Mar 17 17:52:12.099667 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 17 17:52:12.103540 systemd-resolved[1641]: Using system hostname 'ci-4230.1.0-a-303ed89b81'. Mar 17 17:52:12.105324 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:52:12.109734 systemd[1]: Reached target network.target - Network. Mar 17 17:52:12.113279 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:52:12.711393 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 17 17:52:12.716691 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 17:52:13.032647 systemd-networkd[1399]: eth0: Gained IPv6LL Mar 17 17:52:13.035110 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 17 17:52:13.040552 systemd[1]: Reached target network-online.target - Network is Online. Mar 17 17:52:15.870249 ldconfig[1365]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:52:15.880702 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 17 17:52:15.895772 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 17 17:52:15.904659 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 17 17:52:15.909680 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:52:15.914342 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 17 17:52:15.919501 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 17 17:52:15.925114 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 17 17:52:15.929816 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 17 17:52:15.935133 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 17 17:52:15.940453 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 17:52:15.940565 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:52:15.944377 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:52:15.949182 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 17 17:52:15.955246 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 17 17:52:15.962764 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 17 17:52:15.968138 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 17 17:52:15.973124 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 17 17:52:15.979740 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 17 17:52:15.984157 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 17 17:52:15.989427 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 17 17:52:15.993756 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:52:15.997543 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:52:16.001165 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:52:16.001197 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:52:16.020450 systemd[1]: Starting chronyd.service - NTP client/server... Mar 17 17:52:16.024897 systemd[1]: Starting containerd.service - containerd container runtime... Mar 17 17:52:16.030342 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 17 17:52:16.035353 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 17 17:52:16.039772 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 17 17:52:16.044732 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 17 17:52:16.048639 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Mar 17 17:52:16.048697 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 17 17:52:16.049724 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 17 17:52:16.056474 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 17 17:52:16.057891 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 17 17:52:16.062203 KVP[1692]: KVP starting; pid is:1692 Mar 17 17:52:16.062927 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 17 17:52:16.063189 jq[1690]: false Mar 17 17:52:16.066336 KVP[1692]: KVP LIC Version: 3.1 Mar 17 17:52:16.066530 kernel: hv_utils: KVP IC version 4.0 Mar 17 17:52:16.069780 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 17 17:52:16.075721 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 17 17:52:16.080642 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 17 17:52:16.083024 (chronyd)[1683]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 17 17:52:16.087774 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 17:52:16.088289 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 17:52:16.089287 systemd[1]: Starting update-engine.service - Update Engine... Mar 17 17:52:16.091815 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 17 17:52:16.098562 chronyd[1710]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 17 17:52:16.100211 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 17:52:16.100462 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 17 17:52:16.100865 jq[1709]: true Mar 17 17:52:16.100889 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 17:52:16.101129 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 17 17:52:16.105802 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 17:52:16.106042 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 17 17:52:16.111359 extend-filesystems[1691]: Found loop3 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found loop4 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found loop5 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sda Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sda1 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb1 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb2 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb3 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found usr Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb4 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb6 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb7 Mar 17 17:52:16.111359 extend-filesystems[1691]: Found sdb9 Mar 17 17:52:16.111359 extend-filesystems[1691]: Checking size of /dev/sdb9 Mar 17 17:52:16.130899 chronyd[1710]: Timezone right/UTC failed leap second check, ignoring Mar 17 17:52:16.169068 extend-filesystems[1691]: Old size kept for /dev/sdb9 Mar 17 17:52:16.169068 extend-filesystems[1691]: Found sr0 Mar 17 17:52:16.127797 (ntainerd)[1715]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 17 17:52:16.131081 chronyd[1710]: Loaded seccomp filter (level 2) Mar 17 17:52:16.132018 systemd[1]: Started chronyd.service - NTP client/server. Mar 17 17:52:16.175638 jq[1714]: true Mar 17 17:52:16.156788 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 17:52:16.157026 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 17 17:52:16.182750 update_engine[1703]: I20250317 17:52:16.182677 1703 main.cc:92] Flatcar Update Engine starting Mar 17 17:52:16.198114 dbus-daemon[1686]: [system] SELinux support is enabled Mar 17 17:52:16.198893 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 17 17:52:16.204916 update_engine[1703]: I20250317 17:52:16.204735 1703 update_check_scheduler.cc:74] Next update check in 5m54s Mar 17 17:52:16.207538 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 42 scanned by (udev-worker) (1757) Mar 17 17:52:16.210580 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Mar 17 17:52:16.210617 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 17 17:52:16.215695 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 17 17:52:16.215725 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 17 17:52:16.220648 systemd[1]: Started update-engine.service - Update Engine. Mar 17 17:52:16.239771 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 17 17:52:16.241277 systemd-logind[1700]: New seat seat0. Mar 17 17:52:16.244167 systemd-logind[1700]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 17:52:16.248955 systemd[1]: Started systemd-logind.service - User Login Management. Mar 17 17:52:16.264421 coreos-metadata[1685]: Mar 17 17:52:16.264 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:52:16.267956 coreos-metadata[1685]: Mar 17 17:52:16.267 INFO Fetch successful Mar 17 17:52:16.267956 coreos-metadata[1685]: Mar 17 17:52:16.267 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 17 17:52:16.271743 coreos-metadata[1685]: Mar 17 17:52:16.271 INFO Fetch successful Mar 17 17:52:16.272057 coreos-metadata[1685]: Mar 17 17:52:16.272 INFO Fetching http://168.63.129.16/machine/8d6c3163-13d5-4dd7-8f54-001bcf7f62e1/b5e1159f%2Dbc6f%2D4083%2Dbc58%2D70c35a6640f3.%5Fci%2D4230.1.0%2Da%2D303ed89b81?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 17 17:52:16.301195 coreos-metadata[1685]: Mar 17 17:52:16.301 INFO Fetch successful Mar 17 17:52:16.301459 coreos-metadata[1685]: Mar 17 17:52:16.301 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:52:16.309157 coreos-metadata[1685]: Mar 17 17:52:16.308 INFO Fetch successful Mar 17 17:52:16.327169 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 17 17:52:16.331893 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 17 17:52:16.371812 setup-nvidia[1695]: Downloading Flatcar Container Linux Developer Container for version: 4230.1.0 Mar 17 17:52:16.373206 bash[1746]: Updated "/home/core/.ssh/authorized_keys" Mar 17 17:52:16.373390 sshd_keygen[1716]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 17:52:16.374591 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 17 17:52:16.380489 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 17 17:52:16.395688 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 17 17:52:16.410754 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 17 17:52:16.415189 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 17 17:52:16.419775 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 17:52:16.419974 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 17 17:52:16.426364 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 17 17:52:16.441041 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 17 17:52:16.445722 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 17 17:52:16.452876 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 17 17:52:16.457664 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 17 17:52:16.462351 systemd[1]: Reached target getty.target - Login Prompts. Mar 17 17:52:16.469535 locksmithd[1787]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 17:52:17.275502 containerd[1715]: time="2025-03-17T17:52:17.275415700Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Mar 17 17:52:17.295983 containerd[1715]: time="2025-03-17T17:52:17.295944500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.297524 containerd[1715]: time="2025-03-17T17:52:17.297492000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.83-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.297583 containerd[1715]: time="2025-03-17T17:52:17.297531100Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 17:52:17.297583 containerd[1715]: time="2025-03-17T17:52:17.297547800Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.297714 containerd[1715]: time="2025-03-17T17:52:17.297694100Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 17 17:52:17.297742 containerd[1715]: time="2025-03-17T17:52:17.297716500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.297800 containerd[1715]: time="2025-03-17T17:52:17.297780700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.297835 containerd[1715]: time="2025-03-17T17:52:17.297798900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298008 containerd[1715]: time="2025-03-17T17:52:17.297985900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298035 containerd[1715]: time="2025-03-17T17:52:17.298006800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298035 containerd[1715]: time="2025-03-17T17:52:17.298020700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298035 containerd[1715]: time="2025-03-17T17:52:17.298029600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298228 containerd[1715]: time="2025-03-17T17:52:17.298206100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298455 containerd[1715]: time="2025-03-17T17:52:17.298435100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298600 containerd[1715]: time="2025-03-17T17:52:17.298577600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:52:17.298600 containerd[1715]: time="2025-03-17T17:52:17.298597300Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 17:52:17.298700 containerd[1715]: time="2025-03-17T17:52:17.298680500Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 17:52:17.298761 containerd[1715]: time="2025-03-17T17:52:17.298741700Z" level=info msg="metadata content store policy set" policy=shared Mar 17 17:52:17.316509 containerd[1715]: time="2025-03-17T17:52:17.316477000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 17:52:17.316565 containerd[1715]: time="2025-03-17T17:52:17.316531900Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 17:52:17.316606 containerd[1715]: time="2025-03-17T17:52:17.316564400Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 17 17:52:17.316606 containerd[1715]: time="2025-03-17T17:52:17.316584900Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 17 17:52:17.316606 containerd[1715]: time="2025-03-17T17:52:17.316599500Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 17:52:17.316743 containerd[1715]: time="2025-03-17T17:52:17.316720800Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 17:52:17.316981 containerd[1715]: time="2025-03-17T17:52:17.316952600Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 17:52:17.317115 containerd[1715]: time="2025-03-17T17:52:17.317092700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 17 17:52:17.317142 containerd[1715]: time="2025-03-17T17:52:17.317117500Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 17 17:52:17.317142 containerd[1715]: time="2025-03-17T17:52:17.317133800Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 17 17:52:17.317183 containerd[1715]: time="2025-03-17T17:52:17.317153600Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317183 containerd[1715]: time="2025-03-17T17:52:17.317167300Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317232 containerd[1715]: time="2025-03-17T17:52:17.317180300Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317232 containerd[1715]: time="2025-03-17T17:52:17.317194000Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317232 containerd[1715]: time="2025-03-17T17:52:17.317208200Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317232 containerd[1715]: time="2025-03-17T17:52:17.317221500Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317233200Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317244600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317263300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317276000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317288100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317311 containerd[1715]: time="2025-03-17T17:52:17.317301600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317314200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317327000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317338000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317350200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317363000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317377600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317389700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317400700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317412200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317425600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 17 17:52:17.317450 containerd[1715]: time="2025-03-17T17:52:17.317444600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317459100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317469600Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317527800Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317547600Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317558400Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317571200Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317581400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317593300Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317602500Z" level=info msg="NRI interface is disabled by configuration." Mar 17 17:52:17.317705 containerd[1715]: time="2025-03-17T17:52:17.317612100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 17:52:17.317923 containerd[1715]: time="2025-03-17T17:52:17.317877300Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 17 17:52:17.317923 containerd[1715]: time="2025-03-17T17:52:17.317926000Z" level=info msg="Connect containerd service" Mar 17 17:52:17.318114 containerd[1715]: time="2025-03-17T17:52:17.318008700Z" level=info msg="using legacy CRI server" Mar 17 17:52:17.318114 containerd[1715]: time="2025-03-17T17:52:17.318024800Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 17 17:52:17.318157 containerd[1715]: time="2025-03-17T17:52:17.318127200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 17:52:17.319504 containerd[1715]: time="2025-03-17T17:52:17.319318200Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Mar 17 17:52:17.319655 containerd[1715]: time="2025-03-17T17:52:17.319613100Z" level=info msg="Start subscribing containerd event" Mar 17 17:52:17.319699 containerd[1715]: time="2025-03-17T17:52:17.319676400Z" level=info msg="Start recovering state" Mar 17 17:52:17.319756 containerd[1715]: time="2025-03-17T17:52:17.319737500Z" level=info msg="Start event monitor" Mar 17 17:52:17.319791 containerd[1715]: time="2025-03-17T17:52:17.319760300Z" level=info msg="Start snapshots syncer" Mar 17 17:52:17.319791 containerd[1715]: time="2025-03-17T17:52:17.319771400Z" level=info msg="Start cni network conf syncer for default" Mar 17 17:52:17.319791 containerd[1715]: time="2025-03-17T17:52:17.319778600Z" level=info msg="Start streaming server" Mar 17 17:52:17.319862 containerd[1715]: time="2025-03-17T17:52:17.319845300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 17:52:17.319920 containerd[1715]: time="2025-03-17T17:52:17.319899900Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 17:52:17.319991 containerd[1715]: time="2025-03-17T17:52:17.319970400Z" level=info msg="containerd successfully booted in 0.045518s" Mar 17 17:52:17.320057 systemd[1]: Started containerd.service - containerd container runtime. Mar 17 17:52:17.456878 setup-nvidia[1865]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 17 17:52:17.457203 setup-nvidia[1865]: Dload Upload Total Spent Left Speed Mar 17 17:52:18.147541 waagent[1846]: 2025-03-17T17:52:18.147418Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Mar 17 17:52:18.151702 waagent[1846]: 2025-03-17T17:52:18.151637Z INFO Daemon Daemon OS: flatcar 4230.1.0 Mar 17 17:52:18.155049 waagent[1846]: 2025-03-17T17:52:18.154969Z INFO Daemon Daemon Python: 3.11.11 Mar 17 17:52:18.158399 waagent[1846]: 2025-03-17T17:52:18.158336Z INFO Daemon Daemon Run daemon Mar 17 17:52:18.161323 waagent[1846]: 2025-03-17T17:52:18.161268Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.0' Mar 17 17:52:18.167872 waagent[1846]: 2025-03-17T17:52:18.167807Z INFO Daemon Daemon Using waagent for provisioning Mar 17 17:52:18.173707 waagent[1846]: 2025-03-17T17:52:18.173649Z INFO Daemon Daemon Activate resource disk Mar 17 17:52:18.177122 waagent[1846]: 2025-03-17T17:52:18.177060Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 17:52:18.184411 waagent[1846]: 2025-03-17T17:52:18.184352Z INFO Daemon Daemon Found device: sda Mar 17 17:52:18.195327 waagent[1846]: 2025-03-17T17:52:18.195272Z INFO Daemon Daemon Examining partition table Mar 17 17:52:18.211740 waagent[1846]: 2025-03-17T17:52:18.211662Z INFO Daemon Daemon GPT not detected, determining filesystem Mar 17 17:52:18.346382 waagent[1846]: 2025-03-17T17:52:18.346282Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Mar 17 17:52:18.350873 waagent[1846]: 2025-03-17T17:52:18.350807Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Mar 17 17:52:18.707556 kernel: sda: sda1 Mar 17 17:52:18.715808 waagent[1846]: 2025-03-17T17:52:18.715697Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Mar 17 17:52:18.733540 kernel: sda: sda1 Mar 17 17:52:18.733617 waagent[1846]: 2025-03-17T17:52:18.733568Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Mar 17 17:52:24.369127 login[1848]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Mar 17 17:52:24.369560 waagent[1846]: 2025-03-17T17:52:24.369103Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Mar 17 17:52:24.371128 login[1849]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 17:52:24.383124 systemd-logind[1700]: New session 1 of user core. Mar 17 17:52:24.384197 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 17 17:52:24.388543 kernel: EXT4-fs (sda1): mounted filesystem 92c7429e-5701-4ff6-8073-c5f734239cc2 r/w with ordered data mode. Quota mode: none. Mar 17 17:52:24.388681 waagent[1846]: 2025-03-17T17:52:24.388606Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Mar 17 17:52:24.390882 waagent[1846]: 2025-03-17T17:52:24.390830Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:52:24.391122 waagent[1846]: 2025-03-17T17:52:24.391080Z INFO Daemon Daemon Running default provisioning handler Mar 17 17:52:24.403794 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 17 17:52:24.416137 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 17 17:52:24.419071 waagent[1846]: 2025-03-17T17:52:24.417907Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Mar 17 17:52:24.419768 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 17 17:52:24.421304 waagent[1846]: 2025-03-17T17:52:24.421250Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 17:52:24.422332 waagent[1846]: 2025-03-17T17:52:24.422287Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 17:52:24.423790 waagent[1846]: 2025-03-17T17:52:24.423747Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 17:52:24.428250 (systemd)[1940]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 17:52:24.435585 waagent[1846]: 2025-03-17T17:52:24.435509Z INFO Daemon Daemon Successfully mounted dvd Mar 17 17:52:24.446692 systemd-logind[1700]: New session c1 of user core. Mar 17 17:52:24.495628 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 17:52:24.495857 waagent[1846]: 2025-03-17T17:52:24.495685Z INFO Daemon Daemon Detect protocol endpoint Mar 17 17:52:24.498054 waagent[1846]: 2025-03-17T17:52:24.497990Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:52:24.499526 waagent[1846]: 2025-03-17T17:52:24.499467Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 17:52:24.500226 waagent[1846]: 2025-03-17T17:52:24.500185Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 17:52:24.501087 waagent[1846]: 2025-03-17T17:52:24.501043Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 17:52:24.502393 waagent[1846]: 2025-03-17T17:52:24.502353Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 17:52:24.581604 waagent[1846]: 2025-03-17T17:52:24.581536Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 17:52:24.583386 waagent[1846]: 2025-03-17T17:52:24.583344Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 17:52:24.584618 waagent[1846]: 2025-03-17T17:52:24.584567Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 17:52:24.680940 systemd[1940]: Queued start job for default target default.target. Mar 17 17:52:24.699620 systemd[1940]: Created slice app.slice - User Application Slice. Mar 17 17:52:24.699651 systemd[1940]: Reached target paths.target - Paths. Mar 17 17:52:24.699699 systemd[1940]: Reached target timers.target - Timers. Mar 17 17:52:24.700997 systemd[1940]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:52:24.709579 waagent[1846]: 2025-03-17T17:52:24.709481Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 17:52:24.712787 systemd[1940]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:52:24.712914 systemd[1940]: Reached target sockets.target - Sockets. Mar 17 17:52:24.713006 systemd[1940]: Reached target basic.target - Basic System. Mar 17 17:52:24.713059 systemd[1940]: Reached target default.target - Main User Target. Mar 17 17:52:24.713093 systemd[1940]: Startup finished in 260ms. Mar 17 17:52:24.713113 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 17 17:52:24.714593 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 17 17:52:24.714688 waagent[1846]: 2025-03-17T17:52:24.714597Z INFO Daemon Daemon Forcing an update of the goal state. Mar 17 17:52:24.726445 waagent[1846]: 2025-03-17T17:52:24.726387Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:52:24.740166 waagent[1846]: 2025-03-17T17:52:24.740119Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.166 Mar 17 17:52:24.742111 waagent[1846]: 2025-03-17T17:52:24.742063Z INFO Daemon Mar 17 17:52:24.742466 waagent[1846]: 2025-03-17T17:52:24.742424Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ab5e8588-9451-4709-a903-5be78e669b57 eTag: 9471761050266859410 source: Fabric] Mar 17 17:52:24.744162 waagent[1846]: 2025-03-17T17:52:24.744113Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 17 17:52:24.745886 waagent[1846]: 2025-03-17T17:52:24.745839Z INFO Daemon Mar 17 17:52:24.746036 waagent[1846]: 2025-03-17T17:52:24.745996Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:52:24.750712 waagent[1846]: 2025-03-17T17:52:24.750672Z INFO Daemon Daemon Downloading artifacts profile blob Mar 17 17:52:24.861472 waagent[1846]: 2025-03-17T17:52:24.861400Z INFO Daemon Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:52:24.863613 waagent[1846]: 2025-03-17T17:52:24.863564Z INFO Daemon Fetch goal state completed Mar 17 17:52:24.900758 waagent[1846]: 2025-03-17T17:52:24.900700Z INFO Daemon Daemon Starting provisioning Mar 17 17:52:24.902249 waagent[1846]: 2025-03-17T17:52:24.902175Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 17:52:24.902388 waagent[1846]: 2025-03-17T17:52:24.902342Z INFO Daemon Daemon Set hostname [ci-4230.1.0-a-303ed89b81] Mar 17 17:52:24.946309 waagent[1846]: 2025-03-17T17:52:24.946250Z INFO Daemon Daemon Publish hostname [ci-4230.1.0-a-303ed89b81] Mar 17 17:52:24.951343 waagent[1846]: 2025-03-17T17:52:24.951290Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 17:52:24.953106 waagent[1846]: 2025-03-17T17:52:24.953051Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 17:52:24.967483 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:52:24.967494 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:52:24.967533 systemd-networkd[1399]: eth0: DHCP lease lost Mar 17 17:52:24.969626 waagent[1846]: 2025-03-17T17:52:24.969561Z INFO Daemon Daemon Create user account if not exists Mar 17 17:52:24.971738 waagent[1846]: 2025-03-17T17:52:24.971632Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 17:52:24.972376 waagent[1846]: 2025-03-17T17:52:24.972328Z INFO Daemon Daemon Configure sudoer Mar 17 17:52:24.973427 waagent[1846]: 2025-03-17T17:52:24.973376Z INFO Daemon Daemon Configure sshd Mar 17 17:52:24.974199 waagent[1846]: 2025-03-17T17:52:24.974153Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 17 17:52:24.975508 waagent[1846]: 2025-03-17T17:52:24.975435Z INFO Daemon Daemon Deploy ssh public key. Mar 17 17:52:25.037556 systemd-networkd[1399]: eth0: DHCPv4 address 10.200.4.10/24, gateway 10.200.4.1 acquired from 168.63.129.16 Mar 17 17:52:25.112873 waagent[1846]: 2025-03-17T17:52:25.112780Z INFO Daemon Daemon Decode custom data Mar 17 17:52:25.114625 waagent[1846]: 2025-03-17T17:52:25.114551Z INFO Daemon Daemon Save custom data Mar 17 17:52:25.369592 login[1848]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 17:52:25.373942 systemd-logind[1700]: New session 2 of user core. Mar 17 17:52:25.383653 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 17 17:52:26.194849 waagent[1846]: 2025-03-17T17:52:26.194781Z INFO Daemon Daemon Provisioning complete Mar 17 17:52:26.204124 waagent[1846]: 2025-03-17T17:52:26.204071Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 17:52:26.205703 waagent[1846]: 2025-03-17T17:52:26.205648Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 17:52:26.206399 waagent[1846]: 2025-03-17T17:52:26.206352Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Mar 17 17:52:26.368292 waagent[1985]: 2025-03-17T17:52:26.368199Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Mar 17 17:52:26.368653 waagent[1985]: 2025-03-17T17:52:26.368349Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.0 Mar 17 17:52:26.368653 waagent[1985]: 2025-03-17T17:52:26.368425Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 17 17:52:26.375879 waagent[1985]: 2025-03-17T17:52:26.375820Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 17:52:26.376078 waagent[1985]: 2025-03-17T17:52:26.376027Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:26.376156 waagent[1985]: 2025-03-17T17:52:26.376118Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:26.384678 waagent[1985]: 2025-03-17T17:52:26.384612Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:52:26.389588 waagent[1985]: 2025-03-17T17:52:26.389540Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.166 Mar 17 17:52:26.390043 waagent[1985]: 2025-03-17T17:52:26.389995Z INFO ExtHandler Mar 17 17:52:26.390127 waagent[1985]: 2025-03-17T17:52:26.390089Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 16e35f5a-ba42-4f04-bf2c-9a48aa24781e eTag: 9471761050266859410 source: Fabric] Mar 17 17:52:26.390454 waagent[1985]: 2025-03-17T17:52:26.390409Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 17:52:26.391197 waagent[1985]: 2025-03-17T17:52:26.391141Z INFO ExtHandler Mar 17 17:52:26.391269 waagent[1985]: 2025-03-17T17:52:26.391235Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:52:26.394434 waagent[1985]: 2025-03-17T17:52:26.394398Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 17:52:26.457647 waagent[1985]: 2025-03-17T17:52:26.457528Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:52:26.458102 waagent[1985]: 2025-03-17T17:52:26.458051Z INFO ExtHandler Fetch goal state completed Mar 17 17:52:26.469391 waagent[1985]: 2025-03-17T17:52:26.469335Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1985 Mar 17 17:52:26.469554 waagent[1985]: 2025-03-17T17:52:26.469495Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 17 17:52:26.471368 waagent[1985]: 2025-03-17T17:52:26.471319Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 17:52:26.471771 waagent[1985]: 2025-03-17T17:52:26.471725Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 17:52:26.531293 waagent[1985]: 2025-03-17T17:52:26.531247Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 17:52:26.531475 waagent[1985]: 2025-03-17T17:52:26.531431Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 17:52:26.538067 waagent[1985]: 2025-03-17T17:52:26.538033Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 17:52:26.545274 systemd[1]: Reload requested from client PID 1998 ('systemctl') (unit waagent.service)... Mar 17 17:52:26.545289 systemd[1]: Reloading... Mar 17 17:52:26.608548 zram_generator::config[2039]: No configuration found. Mar 17 17:52:26.779624 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 17:52:26.920392 systemd[1]: Reloading finished in 374 ms. Mar 17 17:52:26.939093 waagent[1985]: 2025-03-17T17:52:26.939001Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Mar 17 17:52:26.944861 systemd[1]: Reload requested from client PID 2095 ('systemctl') (unit waagent.service)... Mar 17 17:52:26.944877 systemd[1]: Reloading... Mar 17 17:52:27.012543 zram_generator::config[2138]: No configuration found. Mar 17 17:52:27.151308 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 17:52:27.305857 systemd[1]: Reloading finished in 360 ms. Mar 17 17:52:27.322926 waagent[1985]: 2025-03-17T17:52:27.322833Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 17 17:52:27.323042 waagent[1985]: 2025-03-17T17:52:27.322998Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 17 17:52:27.699097 waagent[1985]: 2025-03-17T17:52:27.698999Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 17:52:27.699668 waagent[1985]: 2025-03-17T17:52:27.699610Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Mar 17 17:52:27.700437 waagent[1985]: 2025-03-17T17:52:27.700384Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 17:52:27.700814 waagent[1985]: 2025-03-17T17:52:27.700758Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:27.701008 waagent[1985]: 2025-03-17T17:52:27.700959Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 17:52:27.701588 waagent[1985]: 2025-03-17T17:52:27.701536Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:27.701734 waagent[1985]: 2025-03-17T17:52:27.701663Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 17:52:27.701820 waagent[1985]: 2025-03-17T17:52:27.701766Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:52:27.701908 waagent[1985]: 2025-03-17T17:52:27.701854Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 17:52:27.702342 waagent[1985]: 2025-03-17T17:52:27.702279Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 17:52:27.702813 waagent[1985]: 2025-03-17T17:52:27.702762Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:52:27.702934 waagent[1985]: 2025-03-17T17:52:27.702878Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 17:52:27.703025 waagent[1985]: 2025-03-17T17:52:27.702974Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 17:52:27.703303 waagent[1985]: 2025-03-17T17:52:27.703251Z INFO EnvHandler ExtHandler Configure routes Mar 17 17:52:27.703427 waagent[1985]: 2025-03-17T17:52:27.703385Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 17:52:27.703871 waagent[1985]: 2025-03-17T17:52:27.703686Z INFO EnvHandler ExtHandler Gateway:None Mar 17 17:52:27.703925 waagent[1985]: 2025-03-17T17:52:27.703868Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 17:52:27.703925 waagent[1985]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 17:52:27.703925 waagent[1985]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 17:52:27.703925 waagent[1985]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 17:52:27.703925 waagent[1985]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:27.703925 waagent[1985]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:27.703925 waagent[1985]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:52:27.704459 waagent[1985]: 2025-03-17T17:52:27.704410Z INFO EnvHandler ExtHandler Routes:None Mar 17 17:52:27.710814 waagent[1985]: 2025-03-17T17:52:27.710760Z INFO ExtHandler ExtHandler Mar 17 17:52:27.711127 waagent[1985]: 2025-03-17T17:52:27.711081Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 7c7b33bd-f6ab-4b55-b0df-37aec1e32ec6 correlation f9c01c5f-6b75-43f2-acd2-83a9ab328bfe created: 2025-03-17T17:51:05.996605Z] Mar 17 17:52:27.712186 waagent[1985]: 2025-03-17T17:52:27.712136Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 17:52:27.714029 waagent[1985]: 2025-03-17T17:52:27.713982Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 17 17:52:27.771755 waagent[1985]: 2025-03-17T17:52:27.771693Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 0ADD4E8E-5C69-4893-8A2B-05C1C36B76EE;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Mar 17 17:52:27.804066 waagent[1985]: 2025-03-17T17:52:27.804003Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 17:52:27.804066 waagent[1985]: Executing ['ip', '-a', '-o', 'link']: Mar 17 17:52:27.804066 waagent[1985]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Mar 17 17:52:27.804066 waagent[1985]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:04:d7:db brd ff:ff:ff:ff:ff:ff Mar 17 17:52:27.804066 waagent[1985]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 17:52:27.804066 waagent[1985]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 17:52:27.804066 waagent[1985]: 2: eth0 inet 10.200.4.10/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 17:52:27.804066 waagent[1985]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 17:52:27.804066 waagent[1985]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 17 17:52:27.804066 waagent[1985]: 2: eth0 inet6 fe80::7e1e:52ff:fe04:d7db/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 17 17:52:27.876079 waagent[1985]: 2025-03-17T17:52:27.876001Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Mar 17 17:52:27.876079 waagent[1985]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.876079 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.876079 waagent[1985]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.876079 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.876079 waagent[1985]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.876079 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.876079 waagent[1985]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:52:27.876079 waagent[1985]: 4 594 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:52:27.876079 waagent[1985]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:52:27.880357 waagent[1985]: 2025-03-17T17:52:27.880301Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 17:52:27.880357 waagent[1985]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.880357 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.880357 waagent[1985]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.880357 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.880357 waagent[1985]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:52:27.880357 waagent[1985]: pkts bytes target prot opt in out source destination Mar 17 17:52:27.880357 waagent[1985]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:52:27.880357 waagent[1985]: 5 646 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:52:27.880357 waagent[1985]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:52:27.880632 waagent[1985]: 2025-03-17T17:52:27.880601Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Mar 17 17:52:27.880804 waagent[1985]: 2025-03-17T17:52:27.880762Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 17:52:39.538910 setup-nvidia[1865]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 478M 0 240k 0 0 223k 0 0:36:30 0:00:01 0:36:29 223k\u000d 2 478M 2 14.1M 0 0 6883k 0 0:01:11 0:00:02 0:01:09 6883k\u000d 8 478M 8 38.4M 0 0 12.1M 0 0:00:39 0:00:03 0:00:36 12.1M\u000d 11 478M 11 57.4M 0 0 14.1M 0 0:00:33 0:00:04 0:00:29 14.1M\u000d 17 478M 17 81.8M 0 0 16.0M 0 0:00:29 0:00:05 0:00:24 16.2M\u000d 22 478M 22 107M 0 0 17.4M 0 0:00:27 0:00:06 0:00:21 21.0M\u000d 26 478M 26 124M 0 0 17.6M 0 0:00:27 0:00:07 0:00:20 22.3M\u000d 31 478M 31 150M 0 0 18.5M 0 0:00:25 0:00:08 0:00:17 22.5M\u000d 36 478M 36 174M 0 0 19.1M 0 0:00:25 0:00:09 0:00:16 23.0M\u000d 40 478M 40 194M 0 0 19.3M 0 0:00:24 0:00:10 0:00:14 22.6M\u000d 45 478M 45 219M 0 0 19.7M 0 0:00:24 0:00:11 0:00:13 22.6M\u000d 50 478M 50 242M 0 0 19.9M 0 0:00:23 0:00:12 0:00:11 23.1M\u000d 55 478M 55 264M 0 0 20.2M 0 0:00:23 0:00:13 0:00:10 23.0M\u000d 60 478M 60 289M 0 0 20.5M 0 0:00:23 0:00:14 0:00:09 23.0M\u000d 65 478M 65 314M 0 0 20.9M 0 0:00:22 0:00:15 0:00:07 24.1M\u000d 70 478M 70 336M 0 0 20.9M 0 0:00:22 0:00:16 0:00:06 23.5M\u000d 75 478M 75 360M 0 0 21.0M 0 0:00:22 0:00:17 0:00:05 23.7M\u000d 80 478M 80 384M 0 0 21.3M 0 0:00:22 0:00:18 0:00:04 24.1M\u000d 85 478M 85 407M 0 0 21.3M 0 0:00:22 0:00:19 0:00:03 23.7M\u000d 89 478M 89 430M 0 0 21.3M 0 0:00:22 0:00:20 0:00:02 22.6M\u000d 94 478M 94 452M 0 0 21.5M 0 0:00:22 0:00:21 0:00:01 23.4M\u000d 99 478M 99 477M 0 0 21.6M 0 0:00:22 0:00:22 --:--:-- 23.5M\u000d100 478M 100 478M 0 0 21.6M 0 0:00:22 0:00:22 --:--:-- 23.3M Mar 17 17:52:39.911932 chronyd[1710]: Selected source PHC0 Mar 17 17:52:53.872070 setup-nvidia[1695]: Downloading NVIDIA 535.230.02 Driver Mar 17 17:52:53.876681 setup-nvidia[2235]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 17 17:52:53.876915 setup-nvidia[2235]: Dload Upload Total Spent Left Speed Mar 17 17:52:55.479178 setup-nvidia[2235]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 34 326M 34 111M 0 0 185M 0 0:00:01 --:--:-- 0:00:01 185M\u000d 99 326M 99 325M 0 0 203M 0 0:00:01 0:00:01 --:--:-- 203M\u000d100 326M 100 326M 0 0 203M 0 0:00:01 0:00:01 --:--:-- 203M Mar 17 17:52:55.565439 setup-nvidia[1695]: Extract the NVIDIA Driver Installer 535.230.02 Mar 17 17:52:55.565715 setup-nvidia[1695]: /opt/nvidia/workdir/nvidia-workdir / Mar 17 17:52:55.583488 setup-nvidia[2238]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Mar 17 17:52:55.870460 setup-nvidia[2238]: Verifying archive integrity... OK Mar 17 17:52:56.586308 setup-nvidia[2238]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Mar 17 17:52:58.417378 setup-nvidia[2264]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Mar 17 17:52:58.510176 setup-nvidia[1695]: / Mar 17 17:52:58.510176 setup-nvidia[1695]: Spawn system-nspawn container to install the NVIDIA drivers Mar 17 17:52:58.961085 sudo[2271]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Mar 17 17:52:58.962807 sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 17 17:52:58.972598 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 17 17:52:58.989845 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 17 17:52:59.005314 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 17 17:52:59.010135 systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 17 17:52:59.016373 (systemd)[2274]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 17 17:52:59.018801 systemd-logind[1700]: New session c3 of user root. Mar 17 17:52:59.104159 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Mar 17 17:52:59.195269 systemd[2274]: Queued start job for default target default.target. Mar 17 17:52:59.205623 systemd[2274]: Created slice app.slice - User Application Slice. Mar 17 17:52:59.205653 systemd[2274]: Reached target paths.target - Paths. Mar 17 17:52:59.205701 systemd[2274]: Reached target timers.target - Timers. Mar 17 17:52:59.206909 systemd[2274]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:52:59.217437 systemd[2274]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:52:59.217496 systemd[2274]: Reached target sockets.target - Sockets. Mar 17 17:52:59.217561 systemd[2274]: Reached target basic.target - Basic System. Mar 17 17:52:59.217617 systemd[2274]: Reached target default.target - Main User Target. Mar 17 17:52:59.217651 systemd[2274]: Startup finished in 193ms. Mar 17 17:52:59.217803 systemd[1]: Started user@0.service - User Manager for UID 0. Mar 17 17:52:59.220649 systemd[1]: Started session-c2.scope - Session c2 of User root. Mar 17 17:52:59.332536 kernel: loop6: detected capacity change from 0 to 12644352 Mar 17 17:52:59.368539 kernel: loop6: p9 Mar 17 17:52:59.818670 setup-nvidia[2283]: ░ Spawning container flatcardevelopercontainer-4230.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.1.0.bin. Mar 17 17:52:59.866545 kernel: EXT4-fs (loop6p9): mounted filesystem a6481100-8bf3-468a-a5db-d879944440da ro without journal. Quota mode: none. Mar 17 17:53:01.330715 update_engine[1703]: I20250317 17:53:01.330636 1703 update_attempter.cc:509] Updating boot flags... Mar 17 17:53:01.397549 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 42 scanned by (udev-worker) (2302) Mar 17 17:53:01.469542 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 42 scanned by (udev-worker) (2302) Mar 17 17:53:01.555635 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 42 scanned by (udev-worker) (2302) Mar 17 17:53:13.381886 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 17 17:53:13.396759 systemd[1]: Started sshd@0-10.200.4.10:22-10.200.16.10:57654.service - OpenSSH per-connection server daemon (10.200.16.10:57654). Mar 17 17:53:14.371872 sshd[3471]: Accepted publickey for core from 10.200.16.10 port 57654 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:14.373196 sshd-session[3471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:14.377330 systemd-logind[1700]: New session 3 of user core. Mar 17 17:53:14.393643 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 17 17:53:14.898220 systemd[1]: Started sshd@1-10.200.4.10:22-10.200.16.10:57664.service - OpenSSH per-connection server daemon (10.200.16.10:57664). Mar 17 17:53:15.404024 kernel: hv_netvsc 7c1e5204-d7db-7c1e-5204-d7db7c1e5204 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 17 17:53:15.494342 sshd[3476]: Accepted publickey for core from 10.200.16.10 port 57664 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:15.495674 sshd-session[3476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:15.499975 systemd-logind[1700]: New session 4 of user core. Mar 17 17:53:15.511664 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 17 17:53:15.926028 sshd[3478]: Connection closed by 10.200.16.10 port 57664 Mar 17 17:53:15.926410 sshd-session[3476]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:15.929548 systemd[1]: sshd@1-10.200.4.10:22-10.200.16.10:57664.service: Deactivated successfully. Mar 17 17:53:15.931114 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 17:53:15.931801 systemd-logind[1700]: Session 4 logged out. Waiting for processes to exit. Mar 17 17:53:15.932563 systemd-logind[1700]: Removed session 4. Mar 17 17:53:16.032176 systemd[1]: Started sshd@2-10.200.4.10:22-10.200.16.10:57674.service - OpenSSH per-connection server daemon (10.200.16.10:57674). Mar 17 17:53:16.630372 sshd[3484]: Accepted publickey for core from 10.200.16.10 port 57674 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:16.631685 sshd-session[3484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:16.635833 systemd-logind[1700]: New session 5 of user core. Mar 17 17:53:16.645677 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 17 17:53:17.061726 sshd[3486]: Connection closed by 10.200.16.10 port 57674 Mar 17 17:53:17.062171 sshd-session[3484]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:17.065844 systemd[1]: sshd@2-10.200.4.10:22-10.200.16.10:57674.service: Deactivated successfully. Mar 17 17:53:17.067627 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 17:53:17.068311 systemd-logind[1700]: Session 5 logged out. Waiting for processes to exit. Mar 17 17:53:17.069100 systemd-logind[1700]: Removed session 5. Mar 17 17:53:27.175285 systemd[1]: Started sshd@3-10.200.4.10:22-10.200.16.10:39402.service - OpenSSH per-connection server daemon (10.200.16.10:39402). Mar 17 17:53:27.777060 sshd[4490]: Accepted publickey for core from 10.200.16.10 port 39402 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:27.778346 sshd-session[4490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:27.782673 systemd-logind[1700]: New session 6 of user core. Mar 17 17:53:27.792656 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 17 17:53:28.210899 sshd[4913]: Connection closed by 10.200.16.10 port 39402 Mar 17 17:53:28.211239 sshd-session[4490]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:28.215452 systemd[1]: sshd@3-10.200.4.10:22-10.200.16.10:39402.service: Deactivated successfully. Mar 17 17:53:28.218132 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 17:53:28.219076 systemd-logind[1700]: Session 6 logged out. Waiting for processes to exit. Mar 17 17:53:28.220043 systemd-logind[1700]: Removed session 6. Mar 17 17:53:28.321322 systemd[1]: Started sshd@4-10.200.4.10:22-10.200.16.10:39412.service - OpenSSH per-connection server daemon (10.200.16.10:39412). Mar 17 17:53:28.929945 sshd[5091]: Accepted publickey for core from 10.200.16.10 port 39412 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:28.931236 sshd-session[5091]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:28.935506 systemd-logind[1700]: New session 7 of user core. Mar 17 17:53:28.946655 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 17 17:53:29.365575 sshd[5406]: Connection closed by 10.200.16.10 port 39412 Mar 17 17:53:29.365939 sshd-session[5091]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:29.371334 systemd[1]: sshd@4-10.200.4.10:22-10.200.16.10:39412.service: Deactivated successfully. Mar 17 17:53:29.373088 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 17:53:29.373800 systemd-logind[1700]: Session 7 logged out. Waiting for processes to exit. Mar 17 17:53:29.374832 systemd-logind[1700]: Removed session 7. Mar 17 17:53:39.492862 systemd[1]: Started sshd@5-10.200.4.10:22-10.200.16.10:51664.service - OpenSSH per-connection server daemon (10.200.16.10:51664). Mar 17 17:53:40.106293 sshd[7949]: Accepted publickey for core from 10.200.16.10 port 51664 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:40.108187 sshd-session[7949]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:40.114381 systemd-logind[1700]: New session 8 of user core. Mar 17 17:53:40.122170 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 17 17:53:40.542942 sshd[8056]: Connection closed by 10.200.16.10 port 51664 Mar 17 17:53:40.543798 sshd-session[7949]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:40.546795 systemd-logind[1700]: Session 8 logged out. Waiting for processes to exit. Mar 17 17:53:40.548858 systemd[1]: sshd@5-10.200.4.10:22-10.200.16.10:51664.service: Deactivated successfully. Mar 17 17:53:40.551477 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 17:53:40.552988 systemd-logind[1700]: Removed session 8. Mar 17 17:53:40.728777 systemd[1]: Started sshd@6-10.200.4.10:22-10.200.16.10:51670.service - OpenSSH per-connection server daemon (10.200.16.10:51670). Mar 17 17:53:41.327692 sshd[8213]: Accepted publickey for core from 10.200.16.10 port 51670 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:41.328968 sshd-session[8213]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:41.333603 systemd-logind[1700]: New session 9 of user core. Mar 17 17:53:41.342693 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 17 17:53:41.762839 sshd[8590]: Connection closed by 10.200.16.10 port 51670 Mar 17 17:53:41.763220 sshd-session[8213]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:41.766456 systemd[1]: sshd@6-10.200.4.10:22-10.200.16.10:51670.service: Deactivated successfully. Mar 17 17:53:41.768024 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 17:53:41.768714 systemd-logind[1700]: Session 9 logged out. Waiting for processes to exit. Mar 17 17:53:41.769788 systemd-logind[1700]: Removed session 9. Mar 17 17:53:51.894803 systemd[1]: Started sshd@7-10.200.4.10:22-10.200.16.10:34690.service - OpenSSH per-connection server daemon (10.200.16.10:34690). Mar 17 17:53:52.507876 sshd[11600]: Accepted publickey for core from 10.200.16.10 port 34690 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:52.509458 sshd-session[11600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:52.516220 systemd-logind[1700]: New session 10 of user core. Mar 17 17:53:52.522210 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 17 17:53:52.948110 sshd[11632]: Connection closed by 10.200.16.10 port 34690 Mar 17 17:53:52.949634 sshd-session[11600]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:52.953707 systemd[1]: sshd@7-10.200.4.10:22-10.200.16.10:34690.service: Deactivated successfully. Mar 17 17:53:52.955619 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 17:53:52.957935 systemd-logind[1700]: Session 10 logged out. Waiting for processes to exit. Mar 17 17:53:52.959371 systemd-logind[1700]: Removed session 10. Mar 17 17:53:53.062810 systemd[1]: Started sshd@8-10.200.4.10:22-10.200.16.10:34694.service - OpenSSH per-connection server daemon (10.200.16.10:34694). Mar 17 17:53:53.684286 sshd[11650]: Accepted publickey for core from 10.200.16.10 port 34694 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:53:53.685759 sshd-session[11650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:53:53.692159 systemd-logind[1700]: New session 11 of user core. Mar 17 17:53:53.700119 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 17 17:53:54.121883 sshd[11670]: Connection closed by 10.200.16.10 port 34694 Mar 17 17:53:54.123454 sshd-session[11650]: pam_unix(sshd:session): session closed for user core Mar 17 17:53:54.126118 systemd[1]: sshd@8-10.200.4.10:22-10.200.16.10:34694.service: Deactivated successfully. Mar 17 17:53:54.127880 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 17:53:54.129575 systemd-logind[1700]: Session 11 logged out. Waiting for processes to exit. Mar 17 17:53:54.130998 systemd-logind[1700]: Removed session 11. Mar 17 17:54:04.232304 systemd[1]: Started sshd@9-10.200.4.10:22-10.200.16.10:46458.service - OpenSSH per-connection server daemon (10.200.16.10:46458). Mar 17 17:54:04.851819 sshd[12078]: Accepted publickey for core from 10.200.16.10 port 46458 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:04.854998 sshd-session[12078]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:04.862080 systemd-logind[1700]: New session 12 of user core. Mar 17 17:54:04.871319 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 17 17:54:05.289818 sshd[12092]: Connection closed by 10.200.16.10 port 46458 Mar 17 17:54:05.290698 sshd-session[12078]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:05.293740 systemd-logind[1700]: Session 12 logged out. Waiting for processes to exit. Mar 17 17:54:05.295415 systemd[1]: sshd@9-10.200.4.10:22-10.200.16.10:46458.service: Deactivated successfully. Mar 17 17:54:05.297053 systemd[1]: session-12.scope: Deactivated successfully. Mar 17 17:54:05.298864 systemd-logind[1700]: Removed session 12. Mar 17 17:54:05.410878 systemd[1]: Started sshd@10-10.200.4.10:22-10.200.16.10:46472.service - OpenSSH per-connection server daemon (10.200.16.10:46472). Mar 17 17:54:06.015443 sshd[12105]: Accepted publickey for core from 10.200.16.10 port 46472 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:06.017430 sshd-session[12105]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:06.023249 systemd-logind[1700]: New session 13 of user core. Mar 17 17:54:06.032912 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 17 17:54:06.451219 sshd[12136]: Connection closed by 10.200.16.10 port 46472 Mar 17 17:54:06.452713 sshd-session[12105]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:06.455646 systemd-logind[1700]: Session 13 logged out. Waiting for processes to exit. Mar 17 17:54:06.459595 systemd[1]: sshd@10-10.200.4.10:22-10.200.16.10:46472.service: Deactivated successfully. Mar 17 17:54:06.461595 systemd[1]: session-13.scope: Deactivated successfully. Mar 17 17:54:06.463086 systemd-logind[1700]: Removed session 13. Mar 17 17:54:16.564502 systemd[1]: Started sshd@11-10.200.4.10:22-10.200.16.10:57288.service - OpenSSH per-connection server daemon (10.200.16.10:57288). Mar 17 17:54:17.182425 sshd[12466]: Accepted publickey for core from 10.200.16.10 port 57288 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:17.183885 sshd-session[12466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:17.190827 systemd-logind[1700]: New session 14 of user core. Mar 17 17:54:17.198381 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 17 17:54:17.623550 sshd[12481]: Connection closed by 10.200.16.10 port 57288 Mar 17 17:54:17.624034 sshd-session[12466]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:17.626809 systemd[1]: sshd@11-10.200.4.10:22-10.200.16.10:57288.service: Deactivated successfully. Mar 17 17:54:17.631942 systemd[1]: session-14.scope: Deactivated successfully. Mar 17 17:54:17.633698 systemd-logind[1700]: Session 14 logged out. Waiting for processes to exit. Mar 17 17:54:17.634976 systemd-logind[1700]: Removed session 14. Mar 17 17:54:17.746844 systemd[1]: Started sshd@12-10.200.4.10:22-10.200.16.10:57302.service - OpenSSH per-connection server daemon (10.200.16.10:57302). Mar 17 17:54:18.356296 sshd[12498]: Accepted publickey for core from 10.200.16.10 port 57302 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:18.358172 sshd-session[12498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:18.371489 systemd-logind[1700]: New session 15 of user core. Mar 17 17:54:18.376202 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 17 17:54:18.794472 sshd[12512]: Connection closed by 10.200.16.10 port 57302 Mar 17 17:54:18.796447 sshd-session[12498]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:18.799897 systemd[1]: sshd@12-10.200.4.10:22-10.200.16.10:57302.service: Deactivated successfully. Mar 17 17:54:18.802202 systemd[1]: session-15.scope: Deactivated successfully. Mar 17 17:54:18.804503 systemd-logind[1700]: Session 15 logged out. Waiting for processes to exit. Mar 17 17:54:18.805821 systemd-logind[1700]: Removed session 15. Mar 17 17:54:28.175821 waagent[1985]: 2025-03-17T17:54:28.175741Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 17 17:54:28.183428 waagent[1985]: 2025-03-17T17:54:28.183374Z INFO ExtHandler Mar 17 17:54:28.183579 waagent[1985]: 2025-03-17T17:54:28.183511Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 17 17:54:28.187151 waagent[1985]: 2025-03-17T17:54:28.187108Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 17:54:28.243378 waagent[1985]: 2025-03-17T17:54:28.243097Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CF9B014CEE223F0A510BB0235DBE9FF78319B873', 'hasPrivateKey': True} Mar 17 17:54:28.243790 waagent[1985]: 2025-03-17T17:54:28.243742Z INFO ExtHandler Fetch goal state completed Mar 17 17:54:28.244173 waagent[1985]: 2025-03-17T17:54:28.244131Z INFO ExtHandler ExtHandler Mar 17 17:54:28.244259 waagent[1985]: 2025-03-17T17:54:28.244221Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: d628a3fe-a617-4717-85b7-b5d14bb11f55 correlation f9c01c5f-6b75-43f2-acd2-83a9ab328bfe created: 2025-03-17T17:54:22.451324Z] Mar 17 17:54:28.244714 waagent[1985]: 2025-03-17T17:54:28.244669Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 17:54:28.245329 waagent[1985]: 2025-03-17T17:54:28.245276Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 1 ms] Mar 17 17:54:28.903227 systemd[1]: Started sshd@13-10.200.4.10:22-10.200.16.10:58662.service - OpenSSH per-connection server daemon (10.200.16.10:58662). Mar 17 17:54:29.510238 sshd[12742]: Accepted publickey for core from 10.200.16.10 port 58662 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:29.511572 sshd-session[12742]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:29.515649 systemd-logind[1700]: New session 16 of user core. Mar 17 17:54:29.522663 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 17 17:54:29.956453 sshd[12790]: Connection closed by 10.200.16.10 port 58662 Mar 17 17:54:29.956797 sshd-session[12742]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:29.959909 systemd[1]: sshd@13-10.200.4.10:22-10.200.16.10:58662.service: Deactivated successfully. Mar 17 17:54:29.961488 systemd[1]: session-16.scope: Deactivated successfully. Mar 17 17:54:29.962150 systemd-logind[1700]: Session 16 logged out. Waiting for processes to exit. Mar 17 17:54:29.962910 systemd-logind[1700]: Removed session 16. Mar 17 17:54:30.066970 systemd[1]: Started sshd@14-10.200.4.10:22-10.200.16.10:58678.service - OpenSSH per-connection server daemon (10.200.16.10:58678). Mar 17 17:54:30.674360 sshd[12796]: Accepted publickey for core from 10.200.16.10 port 58678 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:30.675959 sshd-session[12796]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:30.680402 systemd-logind[1700]: New session 17 of user core. Mar 17 17:54:30.694761 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 17 17:54:31.106538 sshd[12855]: Connection closed by 10.200.16.10 port 58678 Mar 17 17:54:31.106955 sshd-session[12796]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:31.110208 systemd[1]: sshd@14-10.200.4.10:22-10.200.16.10:58678.service: Deactivated successfully. Mar 17 17:54:31.111790 systemd[1]: session-17.scope: Deactivated successfully. Mar 17 17:54:31.112411 systemd-logind[1700]: Session 17 logged out. Waiting for processes to exit. Mar 17 17:54:31.113210 systemd-logind[1700]: Removed session 17. Mar 17 17:54:31.372638 setup-nvidia[2283]: \u000d Mar 17 17:54:31.372638 setup-nvidia[2283]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Mar 17 17:54:31.372638 setup-nvidia[2283]: frequently when this kernel module was built against the wrong or Mar 17 17:54:31.372638 setup-nvidia[2283]: improperly configured kernel sources, with a version of gcc that differs Mar 17 17:54:31.372638 setup-nvidia[2283]: from the one used to build the target kernel, or if another driver, such Mar 17 17:54:31.372638 setup-nvidia[2283]: as nouveau, is present and prevents the NVIDIA kernel module from Mar 17 17:54:31.372638 setup-nvidia[2283]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Mar 17 17:54:31.372638 setup-nvidia[2283]: installed in this system is supported by this NVIDIA Linux graphics Mar 17 17:54:31.372638 setup-nvidia[2283]: driver release. Mar 17 17:54:31.372638 setup-nvidia[2283]: \u000d Mar 17 17:54:31.372638 setup-nvidia[2283]: Please see the log entries 'Kernel module load error' and 'Kernel Mar 17 17:54:31.372638 setup-nvidia[2283]: messages' at the end of the file Mar 17 17:54:31.372638 setup-nvidia[2283]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Mar 17 17:54:31.372638 setup-nvidia[2283]: information. Mar 17 17:54:31.372638 setup-nvidia[2283]: \u000d Mar 17 17:54:31.389200 setup-nvidia[2283]: dmesg: read kernel buffer failed: Operation not permitted Mar 17 17:54:31.410233 setup-nvidia[2283]: \u000d Mar 17 17:54:31.410233 setup-nvidia[2283]: ERROR: Installation has failed. Please see the file Mar 17 17:54:31.410233 setup-nvidia[2283]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Mar 17 17:54:31.410233 setup-nvidia[2283]: details. You may find suggestions on fixing installation problems in Mar 17 17:54:31.410233 setup-nvidia[2283]: the README available on the Linux driver download page at Mar 17 17:54:31.410233 setup-nvidia[2283]: www.nvidia.com. Mar 17 17:54:31.410233 setup-nvidia[2283]: \u000d Mar 17 17:54:31.410545 setup-nvidia[2283]: Last 50 lines of nvidia-installer.log: Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Mar 17 17:54:31.411348 setup-nvidia[2283]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Mar 17 17:54:31.411348 setup-nvidia[2283]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Mar 17 17:54:31.411348 setup-nvidia[2283]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Mar 17 17:54:31.411348 setup-nvidia[2283]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Mar 17 17:54:31.411348 setup-nvidia[2283]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Mar 17 17:54:31.411348 setup-nvidia[2283]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Mar 17 17:54:31.411348 setup-nvidia[2283]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Mar 17 17:54:31.411348 setup-nvidia[2283]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Mar 17 17:54:31.411348 setup-nvidia[2283]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Mar 17 17:54:31.411348 setup-nvidia[2283]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Mar 17 17:54:31.411348 setup-nvidia[2283]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 17 17:54:31.411348 setup-nvidia[2283]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Mar 17 17:54:31.411348 setup-nvidia[2283]: make[2]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/build' Mar 17 17:54:31.411348 setup-nvidia[2283]: make[1]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/source' Mar 17 17:54:31.411348 setup-nvidia[2283]: -> done. Mar 17 17:54:31.411348 setup-nvidia[2283]: -> Kernel module compilation complete. Mar 17 17:54:31.411348 setup-nvidia[2283]: -> Unable to determine if Secure Boot is enabled: No such file or directory Mar 17 17:54:31.411348 setup-nvidia[2283]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Mar 17 17:54:31.411348 setup-nvidia[2283]: \u000d Mar 17 17:54:31.412573 setup-nvidia[2283]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Mar 17 17:54:31.412573 setup-nvidia[2283]: -> Kernel module load error: Operation not permitted Mar 17 17:54:31.412573 setup-nvidia[2283]: -> Kernel messages: Mar 17 17:54:31.412573 setup-nvidia[2283]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Mar 17 17:54:34.271944 waagent[1985]: 2025-03-17T17:54:34.271881Z INFO ExtHandler Mar 17 17:54:34.272408 waagent[1985]: 2025-03-17T17:54:34.272022Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: d2a3ffb1-cfbf-4b6c-b239-0e0ace8349c0 eTag: 13281832169671286743 source: Fabric] Mar 17 17:54:34.272541 waagent[1985]: 2025-03-17T17:54:34.272480Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 17:54:41.216200 systemd[1]: Started sshd@15-10.200.4.10:22-10.200.16.10:56546.service - OpenSSH per-connection server daemon (10.200.16.10:56546). Mar 17 17:54:41.820810 sshd[12968]: Accepted publickey for core from 10.200.16.10 port 56546 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:41.822285 sshd-session[12968]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:41.826662 systemd-logind[1700]: New session 18 of user core. Mar 17 17:54:41.836647 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 17 17:54:42.252085 sshd[12970]: Connection closed by 10.200.16.10 port 56546 Mar 17 17:54:42.252508 sshd-session[12968]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:42.255821 systemd[1]: sshd@15-10.200.4.10:22-10.200.16.10:56546.service: Deactivated successfully. Mar 17 17:54:42.258306 systemd[1]: session-18.scope: Deactivated successfully. Mar 17 17:54:42.259017 systemd-logind[1700]: Session 18 logged out. Waiting for processes to exit. Mar 17 17:54:42.259905 systemd-logind[1700]: Removed session 18. Mar 17 17:54:42.358990 systemd[1]: Started sshd@16-10.200.4.10:22-10.200.16.10:56552.service - OpenSSH per-connection server daemon (10.200.16.10:56552). Mar 17 17:54:42.965077 sshd[12976]: Accepted publickey for core from 10.200.16.10 port 56552 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:42.966534 sshd-session[12976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:42.970937 systemd-logind[1700]: New session 19 of user core. Mar 17 17:54:42.990654 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 17 17:54:43.396822 sshd[12978]: Connection closed by 10.200.16.10 port 56552 Mar 17 17:54:43.397238 sshd-session[12976]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:43.400548 systemd[1]: sshd@16-10.200.4.10:22-10.200.16.10:56552.service: Deactivated successfully. Mar 17 17:54:43.402166 systemd[1]: session-19.scope: Deactivated successfully. Mar 17 17:54:43.402828 systemd-logind[1700]: Session 19 logged out. Waiting for processes to exit. Mar 17 17:54:43.403665 systemd-logind[1700]: Removed session 19. Mar 17 17:54:44.475543 kernel: EXT4-fs (loop6p9): unmounting filesystem a6481100-8bf3-468a-a5db-d879944440da. Mar 17 17:54:44.475672 setup-nvidia[2283]: Container flatcardevelopercontainer-4230.1.0.bin exited successfully. Mar 17 17:54:44.478026 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.1.0.bin.mount: Deactivated successfully. Mar 17 17:54:44.538339 sudo[2271]: pam_unix(sudo:session): session closed for user root Mar 17 17:54:44.540107 systemd[1]: session-c2.scope: Deactivated successfully. Mar 17 17:54:44.540382 systemd[1]: session-c2.scope: Consumed 5min 14.919s CPU time, 882.2M memory peak. Mar 17 17:54:44.952566 setup-nvidia[1695]: /opt/nvidia / Mar 17 17:54:44.953495 setup-nvidia[1695]: / Mar 17 17:54:44.969935 setup-nvidia[13000]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:54:44.991537 kernel: IPMI message handler: version 39.2 Mar 17 17:54:44.994538 kernel: ipmi device interface Mar 17 17:54:44.995226 setup-nvidia[1695]: /opt/nvidia/current/usr/lib/modules/6.6.83-flatcar/video / Mar 17 17:54:45.046702 kernel: nvidia: loading out-of-tree module taints kernel. Mar 17 17:54:45.046840 kernel: nvidia: module license 'NVIDIA' taints kernel. Mar 17 17:54:45.046864 kernel: Disabling lock debugging due to kernel taint Mar 17 17:54:45.053783 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Mar 17 17:54:45.053836 kernel: nvidia: module license taints kernel. Mar 17 17:54:45.448552 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Mar 17 17:54:45.848737 kernel: Mar 17 17:54:45.848877 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Mar 17 17:54:45.899132 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Mar 17 17:54:45.924538 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Mar 17 17:54:45.944552 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Mar 17 17:54:45.985540 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Mar 17 17:54:45.985663 setup-nvidia[1695]: / Mar 17 17:54:47.539583 setup-nvidia[13030]: Mon Mar 17 17:54:47 2025 Mar 17 17:54:47.539583 setup-nvidia[13030]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:47.539583 setup-nvidia[13030]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Mar 17 17:54:47.539583 setup-nvidia[13030]: |-----------------------------------------+----------------------+----------------------+ Mar 17 17:54:47.539583 setup-nvidia[13030]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Mar 17 17:54:47.539583 setup-nvidia[13030]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Mar 17 17:54:47.539583 setup-nvidia[13030]: | | | MIG M. | Mar 17 17:54:47.539583 setup-nvidia[13030]: |=========================================+======================+======================| Mar 17 17:54:47.543393 setup-nvidia[13030]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Mar 17 17:54:47.543393 setup-nvidia[13030]: | N/A 29C P0 33W / 250W | 0MiB / 16384MiB | 1% Default | Mar 17 17:54:47.543393 setup-nvidia[13030]: | | | N/A | Mar 17 17:54:47.543393 setup-nvidia[13030]: +-----------------------------------------+----------------------+----------------------+ Mar 17 17:54:47.543393 setup-nvidia[13030]: Mar 17 17:54:47.543393 setup-nvidia[13030]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:47.543393 setup-nvidia[13030]: | Processes: | Mar 17 17:54:47.543393 setup-nvidia[13030]: | GPU GI CI PID Type Process name GPU Memory | Mar 17 17:54:47.543393 setup-nvidia[13030]: | ID ID Usage | Mar 17 17:54:47.543393 setup-nvidia[13030]: |=======================================================================================| Mar 17 17:54:47.543673 setup-nvidia[13030]: | No running processes found | Mar 17 17:54:47.543673 setup-nvidia[13030]: +---------------------------------------------------------------------------------------+ Mar 17 17:54:47.916777 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 17 17:54:47.919486 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 17 17:54:47.919756 systemd[1]: Startup finished in 1.134s (firmware) + 27.346s (loader) + 1.841s (kernel) + 10.940s (initrd) + 2min 42.809s (userspace) = 3min 24.072s. Mar 17 17:54:53.508746 systemd[1]: Started sshd@17-10.200.4.10:22-10.200.16.10:32800.service - OpenSSH per-connection server daemon (10.200.16.10:32800). Mar 17 17:54:54.114283 sshd[13037]: Accepted publickey for core from 10.200.16.10 port 32800 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:54.115628 sshd-session[13037]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:54.119894 systemd-logind[1700]: New session 20 of user core. Mar 17 17:54:54.138666 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 17 17:54:54.549235 sshd[13039]: Connection closed by 10.200.16.10 port 32800 Mar 17 17:54:54.549611 sshd-session[13037]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:54.551321 systemd[1]: Stopping user@0.service - User Manager for UID 0... Mar 17 17:54:54.552084 systemd[2274]: Activating special unit exit.target... Mar 17 17:54:54.552158 systemd[2274]: Stopped target default.target - Main User Target. Mar 17 17:54:54.552199 systemd[2274]: Stopped target basic.target - Basic System. Mar 17 17:54:54.552229 systemd[2274]: Stopped target paths.target - Paths. Mar 17 17:54:54.552257 systemd[2274]: Stopped target sockets.target - Sockets. Mar 17 17:54:54.552285 systemd[2274]: Stopped target timers.target - Timers. Mar 17 17:54:54.552650 systemd[2274]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:54:54.552950 systemd[2274]: Removed slice app.slice - User Application Slice. Mar 17 17:54:54.553017 systemd[2274]: Reached target shutdown.target - Shutdown. Mar 17 17:54:54.553066 systemd[1]: sshd@17-10.200.4.10:22-10.200.16.10:32800.service: Deactivated successfully. Mar 17 17:54:54.553221 systemd[2274]: Finished systemd-exit.service - Exit the Session. Mar 17 17:54:54.553267 systemd[2274]: Reached target exit.target - Exit the Session. Mar 17 17:54:54.554903 systemd[1]: session-20.scope: Deactivated successfully. Mar 17 17:54:54.555660 systemd-logind[1700]: Session 20 logged out. Waiting for processes to exit. Mar 17 17:54:54.559422 systemd-logind[1700]: Removed session 20. Mar 17 17:54:54.575542 (sd-pam)[2275]: pam_unix(systemd-user:session): session closed for user root Mar 17 17:54:54.576831 systemd[1]: user@0.service: Deactivated successfully. Mar 17 17:54:54.577189 systemd[1]: Stopped user@0.service - User Manager for UID 0. Mar 17 17:54:54.585841 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 17 17:54:54.594840 systemd[1]: run-user-0.mount: Deactivated successfully. Mar 17 17:54:54.595704 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Mar 17 17:54:54.595886 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 17 17:54:54.597461 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Mar 17 17:54:54.597566 systemd[1]: user-0.slice: Consumed 5min 15.214s CPU time, 891.3M memory peak. Mar 17 17:54:54.597946 systemd-logind[1700]: Removed session c3. Mar 17 17:54:54.658728 systemd[1]: Started sshd@18-10.200.4.10:22-10.200.16.10:32808.service - OpenSSH per-connection server daemon (10.200.16.10:32808). Mar 17 17:54:55.265180 sshd[13049]: Accepted publickey for core from 10.200.16.10 port 32808 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:55.266652 sshd-session[13049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:55.271173 systemd-logind[1700]: New session 21 of user core. Mar 17 17:54:55.285648 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 17 17:54:55.692434 sshd[13051]: Connection closed by 10.200.16.10 port 32808 Mar 17 17:54:55.692467 sshd-session[13049]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:55.695719 systemd[1]: sshd@18-10.200.4.10:22-10.200.16.10:32808.service: Deactivated successfully. Mar 17 17:54:55.697342 systemd[1]: session-21.scope: Deactivated successfully. Mar 17 17:54:55.698042 systemd-logind[1700]: Session 21 logged out. Waiting for processes to exit. Mar 17 17:54:55.698878 systemd-logind[1700]: Removed session 21. Mar 17 17:54:55.800997 systemd[1]: Started sshd@19-10.200.4.10:22-10.200.16.10:32816.service - OpenSSH per-connection server daemon (10.200.16.10:32816). Mar 17 17:54:56.409713 sshd[13057]: Accepted publickey for core from 10.200.16.10 port 32816 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:56.411097 sshd-session[13057]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:56.415323 systemd-logind[1700]: New session 22 of user core. Mar 17 17:54:56.425647 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 17 17:54:56.846345 sshd[13059]: Connection closed by 10.200.16.10 port 32816 Mar 17 17:54:56.846795 sshd-session[13057]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:56.851670 systemd[1]: sshd@19-10.200.4.10:22-10.200.16.10:32816.service: Deactivated successfully. Mar 17 17:54:56.853288 systemd[1]: session-22.scope: Deactivated successfully. Mar 17 17:54:56.859020 systemd-logind[1700]: Session 22 logged out. Waiting for processes to exit. Mar 17 17:54:56.864199 systemd-logind[1700]: Removed session 22. Mar 17 17:54:56.957117 systemd[1]: Started sshd@20-10.200.4.10:22-10.200.16.10:32818.service - OpenSSH per-connection server daemon (10.200.16.10:32818). Mar 17 17:54:57.567143 sshd[13065]: Accepted publickey for core from 10.200.16.10 port 32818 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:58.308474 sshd-session[13065]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:58.313671 systemd-logind[1700]: New session 23 of user core. Mar 17 17:54:58.327664 systemd[1]: Started session-23.scope - Session 23 of User core. Mar 17 17:54:58.675740 sshd[13067]: Connection closed by 10.200.16.10 port 32818 Mar 17 17:54:58.676015 sshd-session[13065]: pam_unix(sshd:session): session closed for user core Mar 17 17:54:58.679101 systemd[1]: sshd@20-10.200.4.10:22-10.200.16.10:32818.service: Deactivated successfully. Mar 17 17:54:58.680727 systemd[1]: session-23.scope: Deactivated successfully. Mar 17 17:54:58.681347 systemd-logind[1700]: Session 23 logged out. Waiting for processes to exit. Mar 17 17:54:58.682196 systemd-logind[1700]: Removed session 23. Mar 17 17:54:58.783036 systemd[1]: Started sshd@21-10.200.4.10:22-10.200.16.10:52884.service - OpenSSH per-connection server daemon (10.200.16.10:52884). Mar 17 17:54:59.384475 sshd[13073]: Accepted publickey for core from 10.200.16.10 port 52884 ssh2: RSA SHA256:sbakvAZImuE2oGoHvioFfR2DhmaBGH1l1QiNv1J6R4A Mar 17 17:54:59.385806 sshd-session[13073]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:54:59.389896 systemd-logind[1700]: New session 24 of user core. Mar 17 17:54:59.408665 systemd[1]: Started session-24.scope - Session 24 of User core. Mar 17 17:55:01.280484 sshd[13075]: Connection closed by 10.200.16.10 port 52884 Mar 17 17:55:01.280916 sshd-session[13073]: pam_unix(sshd:session): session closed for user core Mar 17 17:55:01.284187 systemd[1]: sshd@21-10.200.4.10:22-10.200.16.10:52884.service: Deactivated successfully. Mar 17 17:55:01.285954 systemd[1]: session-24.scope: Deactivated successfully. Mar 17 17:55:01.286151 systemd[1]: session-24.scope: Consumed 1.409s CPU time, 14.1M memory peak. Mar 17 17:55:01.286675 systemd-logind[1700]: Session 24 logged out. Waiting for processes to exit. Mar 17 17:55:01.287547 systemd-logind[1700]: Removed session 24.