Apr 30 12:48:35.214266 kernel: Linux version 6.6.88-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 Tue Apr 29 22:26:36 -00 2025 Apr 30 12:48:35.214297 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=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.214307 kernel: BIOS-provided physical RAM map: Apr 30 12:48:35.214314 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Apr 30 12:48:35.214320 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Apr 30 12:48:35.214327 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Apr 30 12:48:35.214335 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Apr 30 12:48:35.214345 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Apr 30 12:48:35.214382 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Apr 30 12:48:35.214392 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Apr 30 12:48:35.214399 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Apr 30 12:48:35.214406 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Apr 30 12:48:35.214413 kernel: printk: bootconsole [earlyser0] enabled Apr 30 12:48:35.214420 kernel: NX (Execute Disable) protection: active Apr 30 12:48:35.214436 kernel: APIC: Static calls initialized Apr 30 12:48:35.214444 kernel: efi: EFI v2.7 by Microsoft Apr 30 12:48:35.214452 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Apr 30 12:48:35.214460 kernel: random: crng init done Apr 30 12:48:35.214468 kernel: secureboot: Secure boot disabled Apr 30 12:48:35.214475 kernel: SMBIOS 3.1.0 present. Apr 30 12:48:35.214483 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Apr 30 12:48:35.214491 kernel: Hypervisor detected: Microsoft Hyper-V Apr 30 12:48:35.214498 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Apr 30 12:48:35.214506 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Apr 30 12:48:35.214517 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Apr 30 12:48:35.214524 kernel: Hyper-V: Using hypercall for remote TLB flush Apr 30 12:48:35.214532 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Apr 30 12:48:35.214540 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Apr 30 12:48:35.214548 kernel: tsc: Marking TSC unstable due to running on Hyper-V Apr 30 12:48:35.214556 kernel: tsc: Detected 2593.990 MHz processor Apr 30 12:48:35.214564 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 30 12:48:35.214572 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 30 12:48:35.214580 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Apr 30 12:48:35.214591 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Apr 30 12:48:35.214599 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 30 12:48:35.214607 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Apr 30 12:48:35.214628 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Apr 30 12:48:35.214636 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Apr 30 12:48:35.214644 kernel: Using GB pages for direct mapping Apr 30 12:48:35.214652 kernel: ACPI: Early table checksum verification disabled Apr 30 12:48:35.214664 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Apr 30 12:48:35.214675 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214684 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214692 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Apr 30 12:48:35.214700 kernel: ACPI: FACS 0x000000003FFFE000 000040 Apr 30 12:48:35.214708 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214717 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214728 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214736 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214745 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214753 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214761 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.214769 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Apr 30 12:48:35.214777 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Apr 30 12:48:35.214785 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Apr 30 12:48:35.214797 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Apr 30 12:48:35.214805 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Apr 30 12:48:35.214813 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Apr 30 12:48:35.214822 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Apr 30 12:48:35.214830 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Apr 30 12:48:35.214838 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Apr 30 12:48:35.214846 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Apr 30 12:48:35.214854 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Apr 30 12:48:35.214862 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Apr 30 12:48:35.214873 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Apr 30 12:48:35.214881 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Apr 30 12:48:35.214889 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Apr 30 12:48:35.214897 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Apr 30 12:48:35.214905 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Apr 30 12:48:35.214913 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Apr 30 12:48:35.214922 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Apr 30 12:48:35.214930 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Apr 30 12:48:35.214938 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Apr 30 12:48:35.214948 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Apr 30 12:48:35.214957 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Apr 30 12:48:35.214965 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Apr 30 12:48:35.214973 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Apr 30 12:48:35.214982 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Apr 30 12:48:35.214990 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Apr 30 12:48:35.214998 kernel: Zone ranges: Apr 30 12:48:35.215006 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 30 12:48:35.215014 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Apr 30 12:48:35.215025 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Apr 30 12:48:35.215033 kernel: Movable zone start for each node Apr 30 12:48:35.215041 kernel: Early memory node ranges Apr 30 12:48:35.215050 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Apr 30 12:48:35.215058 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Apr 30 12:48:35.215066 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Apr 30 12:48:35.215074 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Apr 30 12:48:35.215082 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Apr 30 12:48:35.215091 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Apr 30 12:48:35.215102 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 30 12:48:35.215110 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Apr 30 12:48:35.215118 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Apr 30 12:48:35.215126 kernel: ACPI: PM-Timer IO Port: 0x408 Apr 30 12:48:35.215134 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Apr 30 12:48:35.215142 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Apr 30 12:48:35.215150 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 30 12:48:35.215159 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 30 12:48:35.215167 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Apr 30 12:48:35.215178 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Apr 30 12:48:35.215186 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Apr 30 12:48:35.215194 kernel: Booting paravirtualized kernel on Hyper-V Apr 30 12:48:35.215203 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 30 12:48:35.215211 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Apr 30 12:48:35.215219 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Apr 30 12:48:35.215228 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Apr 30 12:48:35.215236 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Apr 30 12:48:35.215244 kernel: Hyper-V: PV spinlocks enabled Apr 30 12:48:35.215255 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Apr 30 12:48:35.215264 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=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.215273 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 12:48:35.215281 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Apr 30 12:48:35.215289 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Apr 30 12:48:35.215298 kernel: Fallback order for Node 0: 0 Apr 30 12:48:35.215306 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Apr 30 12:48:35.215314 kernel: Policy zone: Normal Apr 30 12:48:35.215325 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 12:48:35.215342 kernel: software IO TLB: area num 8. Apr 30 12:48:35.215351 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 2110660K reserved, 0K cma-reserved) Apr 30 12:48:35.215363 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Apr 30 12:48:35.215372 kernel: Kernel/User page tables isolation: enabled Apr 30 12:48:35.215380 kernel: ftrace: allocating 37918 entries in 149 pages Apr 30 12:48:35.215389 kernel: ftrace: allocated 149 pages with 4 groups Apr 30 12:48:35.215397 kernel: Dynamic Preempt: voluntary Apr 30 12:48:35.215406 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 12:48:35.215415 kernel: rcu: RCU event tracing is enabled. Apr 30 12:48:35.215424 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Apr 30 12:48:35.215433 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 12:48:35.215445 kernel: Rude variant of Tasks RCU enabled. Apr 30 12:48:35.215454 kernel: Tracing variant of Tasks RCU enabled. Apr 30 12:48:35.215462 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 12:48:35.215471 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Apr 30 12:48:35.215482 kernel: Using NULL legacy PIC Apr 30 12:48:35.215491 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Apr 30 12:48:35.215500 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 12:48:35.215509 kernel: Console: colour dummy device 80x25 Apr 30 12:48:35.215517 kernel: printk: console [tty1] enabled Apr 30 12:48:35.215526 kernel: printk: console [ttyS0] enabled Apr 30 12:48:35.215535 kernel: printk: bootconsole [earlyser0] disabled Apr 30 12:48:35.215543 kernel: ACPI: Core revision 20230628 Apr 30 12:48:35.215552 kernel: Failed to register legacy timer interrupt Apr 30 12:48:35.215561 kernel: APIC: Switch to symmetric I/O mode setup Apr 30 12:48:35.215572 kernel: Hyper-V: enabling crash_kexec_post_notifiers Apr 30 12:48:35.215581 kernel: Hyper-V: Using IPI hypercalls Apr 30 12:48:35.215589 kernel: APIC: send_IPI() replaced with hv_send_ipi() Apr 30 12:48:35.215598 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Apr 30 12:48:35.215607 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Apr 30 12:48:35.215641 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Apr 30 12:48:35.215650 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Apr 30 12:48:35.215659 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Apr 30 12:48:35.215667 kernel: Hyper-V: Using enlightened APIC (xapic mode) Apr 30 12:48:35.215680 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Apr 30 12:48:35.215688 kernel: APIC: read() replaced with hv_apic_read() Apr 30 12:48:35.215697 kernel: APIC: write() replaced with hv_apic_write() Apr 30 12:48:35.215706 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Apr 30 12:48:35.215714 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Apr 30 12:48:35.215724 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Apr 30 12:48:35.215732 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Apr 30 12:48:35.215741 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Apr 30 12:48:35.215750 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 30 12:48:35.215761 kernel: Spectre V2 : Mitigation: Retpolines Apr 30 12:48:35.215770 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Apr 30 12:48:35.215778 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Apr 30 12:48:35.215786 kernel: Speculative Store Bypass: Vulnerable Apr 30 12:48:35.215795 kernel: MDS: Mitigation: Clear CPU buffers Apr 30 12:48:35.215803 kernel: TAA: Mitigation: Clear CPU buffers Apr 30 12:48:35.215812 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Apr 30 12:48:35.215825 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Apr 30 12:48:35.215836 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Apr 30 12:48:35.215845 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Apr 30 12:48:35.215853 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Apr 30 12:48:35.215866 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Apr 30 12:48:35.215875 kernel: Freeing SMP alternatives memory: 32K Apr 30 12:48:35.215883 kernel: pid_max: default: 32768 minimum: 301 Apr 30 12:48:35.215892 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 12:48:35.215900 kernel: landlock: Up and running. Apr 30 12:48:35.215908 kernel: SELinux: Initializing. Apr 30 12:48:35.215917 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.215926 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.215934 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Apr 30 12:48:35.215943 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.215952 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.215964 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.215972 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Apr 30 12:48:35.215981 kernel: signal: max sigframe size: 1776 Apr 30 12:48:35.215990 kernel: rcu: Hierarchical SRCU implementation. Apr 30 12:48:35.215999 kernel: rcu: Max phase no-delay instances is 400. Apr 30 12:48:35.216007 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Apr 30 12:48:35.216016 kernel: smp: Bringing up secondary CPUs ... Apr 30 12:48:35.216024 kernel: smpboot: x86: Booting SMP configuration: Apr 30 12:48:35.216033 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Apr 30 12:48:35.216045 kernel: smp: Brought up 1 node, 6 CPUs Apr 30 12:48:35.216053 kernel: smpboot: Max logical packages: 1 Apr 30 12:48:35.216062 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Apr 30 12:48:35.216071 kernel: devtmpfs: initialized Apr 30 12:48:35.216079 kernel: x86/mm: Memory block size: 1024MB Apr 30 12:48:35.216088 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Apr 30 12:48:35.216097 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 12:48:35.216106 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Apr 30 12:48:35.216114 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 12:48:35.216126 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 12:48:35.216134 kernel: audit: initializing netlink subsys (disabled) Apr 30 12:48:35.216143 kernel: audit: type=2000 audit(1746017314.029:1): state=initialized audit_enabled=0 res=1 Apr 30 12:48:35.216151 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 12:48:35.216160 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 30 12:48:35.216169 kernel: cpuidle: using governor menu Apr 30 12:48:35.216177 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 12:48:35.216186 kernel: dca service started, version 1.12.1 Apr 30 12:48:35.216194 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Apr 30 12:48:35.216206 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 30 12:48:35.216215 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 30 12:48:35.216223 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Apr 30 12:48:35.216232 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 12:48:35.216241 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 12:48:35.216249 kernel: ACPI: Added _OSI(Module Device) Apr 30 12:48:35.216258 kernel: ACPI: Added _OSI(Processor Device) Apr 30 12:48:35.216267 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 12:48:35.216276 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 12:48:35.216287 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 12:48:35.216296 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 30 12:48:35.216304 kernel: ACPI: Interpreter enabled Apr 30 12:48:35.216313 kernel: ACPI: PM: (supports S0 S5) Apr 30 12:48:35.216322 kernel: ACPI: Using IOAPIC for interrupt routing Apr 30 12:48:35.216330 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 30 12:48:35.216339 kernel: PCI: Ignoring E820 reservations for host bridge windows Apr 30 12:48:35.216348 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Apr 30 12:48:35.216356 kernel: iommu: Default domain type: Translated Apr 30 12:48:35.216368 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 30 12:48:35.216376 kernel: efivars: Registered efivars operations Apr 30 12:48:35.216385 kernel: PCI: Using ACPI for IRQ routing Apr 30 12:48:35.216394 kernel: PCI: System does not support PCI Apr 30 12:48:35.216402 kernel: vgaarb: loaded Apr 30 12:48:35.216411 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Apr 30 12:48:35.216419 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 12:48:35.216428 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 12:48:35.216437 kernel: pnp: PnP ACPI init Apr 30 12:48:35.216448 kernel: pnp: PnP ACPI: found 3 devices Apr 30 12:48:35.216457 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 30 12:48:35.216465 kernel: NET: Registered PF_INET protocol family Apr 30 12:48:35.216474 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.216482 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.216491 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 12:48:35.216500 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 30 12:48:35.216509 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.216520 kernel: TCP: Hash tables configured (established 524288 bind 65536) Apr 30 12:48:35.216529 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.216538 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.216546 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 12:48:35.216555 kernel: NET: Registered PF_XDP protocol family Apr 30 12:48:35.216563 kernel: PCI: CLS 0 bytes, default 64 Apr 30 12:48:35.216572 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Apr 30 12:48:35.216581 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Apr 30 12:48:35.216590 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Apr 30 12:48:35.216601 kernel: Initialise system trusted keyrings Apr 30 12:48:35.216609 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Apr 30 12:48:35.216628 kernel: Key type asymmetric registered Apr 30 12:48:35.216637 kernel: Asymmetric key parser 'x509' registered Apr 30 12:48:35.216645 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 30 12:48:35.216654 kernel: io scheduler mq-deadline registered Apr 30 12:48:35.216662 kernel: io scheduler kyber registered Apr 30 12:48:35.216671 kernel: io scheduler bfq registered Apr 30 12:48:35.216680 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 30 12:48:35.216688 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 12:48:35.216701 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 30 12:48:35.216709 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Apr 30 12:48:35.216718 kernel: i8042: PNP: No PS/2 controller found. Apr 30 12:48:35.216890 kernel: rtc_cmos 00:02: registered as rtc0 Apr 30 12:48:35.217010 kernel: rtc_cmos 00:02: setting system clock to 2025-04-30T12:48:34 UTC (1746017314) Apr 30 12:48:35.217118 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Apr 30 12:48:35.217131 kernel: intel_pstate: CPU model not supported Apr 30 12:48:35.217145 kernel: efifb: probing for efifb Apr 30 12:48:35.217154 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Apr 30 12:48:35.217163 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Apr 30 12:48:35.217172 kernel: efifb: scrolling: redraw Apr 30 12:48:35.217180 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Apr 30 12:48:35.217189 kernel: Console: switching to colour frame buffer device 128x48 Apr 30 12:48:35.217198 kernel: fb0: EFI VGA frame buffer device Apr 30 12:48:35.217207 kernel: pstore: Using crash dump compression: deflate Apr 30 12:48:35.217216 kernel: pstore: Registered efi_pstore as persistent store backend Apr 30 12:48:35.217227 kernel: NET: Registered PF_INET6 protocol family Apr 30 12:48:35.217236 kernel: Segment Routing with IPv6 Apr 30 12:48:35.217244 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 12:48:35.217253 kernel: NET: Registered PF_PACKET protocol family Apr 30 12:48:35.217262 kernel: Key type dns_resolver registered Apr 30 12:48:35.217270 kernel: IPI shorthand broadcast: enabled Apr 30 12:48:35.217279 kernel: sched_clock: Marking stable (1786005000, 101468600)->(2233751300, -346277700) Apr 30 12:48:35.217288 kernel: registered taskstats version 1 Apr 30 12:48:35.217296 kernel: Loading compiled-in X.509 certificates Apr 30 12:48:35.217308 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: 10d2d341d26c1df942e743344427c053ef3a2a5f' Apr 30 12:48:35.217316 kernel: Key type .fscrypt registered Apr 30 12:48:35.217325 kernel: Key type fscrypt-provisioning registered Apr 30 12:48:35.217333 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 12:48:35.217342 kernel: ima: Allocated hash algorithm: sha1 Apr 30 12:48:35.217350 kernel: ima: No architecture policies found Apr 30 12:48:35.217359 kernel: clk: Disabling unused clocks Apr 30 12:48:35.217368 kernel: Freeing unused kernel image (initmem) memory: 43484K Apr 30 12:48:35.217376 kernel: Write protecting the kernel read-only data: 38912k Apr 30 12:48:35.217388 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K Apr 30 12:48:35.217396 kernel: Run /init as init process Apr 30 12:48:35.217405 kernel: with arguments: Apr 30 12:48:35.217413 kernel: /init Apr 30 12:48:35.217422 kernel: with environment: Apr 30 12:48:35.217430 kernel: HOME=/ Apr 30 12:48:35.217438 kernel: TERM=linux Apr 30 12:48:35.217447 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 12:48:35.217457 systemd[1]: Successfully made /usr/ read-only. Apr 30 12:48:35.217472 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) Apr 30 12:48:35.217482 systemd[1]: Detected virtualization microsoft. Apr 30 12:48:35.217491 systemd[1]: Detected architecture x86-64. Apr 30 12:48:35.217499 systemd[1]: Running in initrd. Apr 30 12:48:35.217508 systemd[1]: No hostname configured, using default hostname. Apr 30 12:48:35.217517 systemd[1]: Hostname set to . Apr 30 12:48:35.217526 systemd[1]: Initializing machine ID from random generator. Apr 30 12:48:35.217538 systemd[1]: Queued start job for default target initrd.target. Apr 30 12:48:35.217547 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:48:35.217556 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:48:35.217566 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 12:48:35.217575 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:48:35.217584 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 12:48:35.217594 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 12:48:35.217607 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 12:48:35.217629 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 12:48:35.217638 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:48:35.217648 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:48:35.217657 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:48:35.217666 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:48:35.217675 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:48:35.217684 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:48:35.217697 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:48:35.217706 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:48:35.217715 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 12:48:35.217724 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Apr 30 12:48:35.217734 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:48:35.217743 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:48:35.217752 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:48:35.217762 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:48:35.217771 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 12:48:35.217783 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:48:35.217792 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 12:48:35.217801 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 12:48:35.217810 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:48:35.217819 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:48:35.217849 systemd-journald[204]: Collecting audit messages is disabled. Apr 30 12:48:35.217876 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:35.217887 systemd-journald[204]: Journal started Apr 30 12:48:35.217908 systemd-journald[204]: Runtime Journal (/run/log/journal/bac0496e62d9409fb67b48a9dd1d0656) is 8M, max 2.2G, 2.1G free. Apr 30 12:48:35.224772 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:48:35.228210 systemd-modules-load[206]: Inserted module 'overlay' Apr 30 12:48:35.228701 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 12:48:35.231450 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:48:35.232160 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 12:48:35.261545 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 12:48:35.273875 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:48:35.285445 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:35.287223 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 12:48:35.298186 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:35.317298 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:48:35.324639 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:48:35.345114 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:48:35.350576 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 12:48:35.360512 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:35.362954 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 12:48:35.384974 systemd-modules-load[206]: Inserted module 'br_netfilter' Apr 30 12:48:35.386576 kernel: Bridge firewalling registered Apr 30 12:48:35.385939 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:48:35.394584 dracut-cmdline[234]: dracut-dracut-053 Apr 30 12:48:35.394584 dracut-cmdline[234]: 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=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.394018 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:48:35.411992 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:48:35.449765 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:48:35.483867 systemd-resolved[266]: Positive Trust Anchors: Apr 30 12:48:35.483886 systemd-resolved[266]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:48:35.483916 systemd-resolved[266]: 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 Apr 30 12:48:35.486455 systemd-resolved[266]: Defaulting to hostname 'linux'. Apr 30 12:48:35.487519 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:48:35.497124 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:48:35.563635 kernel: SCSI subsystem initialized Apr 30 12:48:35.574634 kernel: Loading iSCSI transport class v2.0-870. Apr 30 12:48:35.586634 kernel: iscsi: registered transport (tcp) Apr 30 12:48:35.609998 kernel: iscsi: registered transport (qla4xxx) Apr 30 12:48:35.610029 kernel: QLogic iSCSI HBA Driver Apr 30 12:48:35.650523 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 12:48:35.663781 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 12:48:35.694652 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 12:48:35.694699 kernel: device-mapper: uevent: version 1.0.3 Apr 30 12:48:35.699195 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 12:48:35.741638 kernel: raid6: avx2x4 gen() 30877 MB/s Apr 30 12:48:35.760627 kernel: raid6: avx2x2 gen() 30874 MB/s Apr 30 12:48:35.781817 kernel: raid6: avx2x1 gen() 27558 MB/s Apr 30 12:48:35.781842 kernel: raid6: using algorithm avx2x4 gen() 30877 MB/s Apr 30 12:48:35.804874 kernel: raid6: .... xor() 10105 MB/s, rmw enabled Apr 30 12:48:35.804904 kernel: raid6: using avx2x2 recovery algorithm Apr 30 12:48:35.827634 kernel: xor: automatically using best checksumming function avx Apr 30 12:48:35.981645 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 12:48:35.992630 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:48:36.008743 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:48:36.031404 systemd-udevd[425]: Using default interface naming scheme 'v255'. Apr 30 12:48:36.037007 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:48:36.038664 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 12:48:36.070060 dracut-pre-trigger[430]: rd.md=0: removing MD RAID activation Apr 30 12:48:36.100719 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:48:36.116729 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:48:36.174520 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:48:36.190806 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 12:48:36.202099 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 12:48:36.204370 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:48:36.212421 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:48:36.222947 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:48:36.225193 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 12:48:36.250212 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:48:36.259493 kernel: cryptd: max_cpu_qlen set to 1000 Apr 30 12:48:36.250412 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:36.259635 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:36.269174 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:48:36.269235 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:36.283699 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:36.306934 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:36.318949 kernel: AVX2 version of gcm_enc/dec engaged. Apr 30 12:48:36.324347 kernel: hv_vmbus: Vmbus version:4.0 Apr 30 12:48:36.312148 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:48:36.338818 kernel: AES CTR mode by8 optimization enabled Apr 30 12:48:36.338843 kernel: hv_vmbus: registering driver hyperv_keyboard Apr 30 12:48:36.324363 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:36.334833 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:36.355745 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 Apr 30 12:48:36.365542 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 30 12:48:36.365578 kernel: pps_core: LinuxPPS API ver. 1 registered Apr 30 12:48:36.371655 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 30 12:48:36.376585 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:36.395787 kernel: hv_vmbus: registering driver hid_hyperv Apr 30 12:48:36.395821 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Apr 30 12:48:36.395836 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Apr 30 12:48:36.396032 kernel: PTP clock support registered Apr 30 12:48:36.396053 kernel: hv_vmbus: registering driver hv_pci Apr 30 12:48:36.396065 kernel: hv_vmbus: registering driver hv_netvsc Apr 30 12:48:36.407650 kernel: hv_vmbus: registering driver hv_storvsc Apr 30 12:48:36.407704 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Apr 30 12:48:37.400916 kernel: scsi host1: storvsc_host_t Apr 30 12:48:37.401107 kernel: hv_utils: Registering HyperV Utility Driver Apr 30 12:48:37.401123 kernel: scsi host0: storvsc_host_t Apr 30 12:48:37.401274 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Apr 30 12:48:37.401412 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Apr 30 12:48:37.401652 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Apr 30 12:48:37.401781 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Apr 30 12:48:37.401904 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Apr 30 12:48:37.402075 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Apr 30 12:48:37.402230 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Apr 30 12:48:37.402386 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Apr 30 12:48:37.402554 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Apr 30 12:48:37.402793 kernel: hv_vmbus: registering driver hv_utils Apr 30 12:48:37.402808 kernel: pci 0001:00:00.0: Enabling HDA controller Apr 30 12:48:37.402963 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Apr 30 12:48:37.403124 kernel: hv_utils: Shutdown IC version 3.2 Apr 30 12:48:37.403151 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Apr 30 12:48:37.403311 kernel: hv_utils: TimeSync IC version 4.0 Apr 30 12:48:37.403330 kernel: hv_utils: Heartbeat IC version 3.0 Apr 30 12:48:37.403342 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Apr 30 12:48:37.403527 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Apr 30 12:48:37.403741 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Apr 30 12:48:37.403756 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Apr 30 12:48:37.403914 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Apr 30 12:48:37.404055 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Apr 30 12:48:37.404225 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Apr 30 12:48:37.404414 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Apr 30 12:48:37.437229 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Apr 30 12:48:37.443262 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Apr 30 12:48:37.443423 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Apr 30 12:48:37.443591 kernel: sd 0:0:0:0: [sdb] Write Protect is off Apr 30 12:48:37.443738 kernel: sd 0:0:0:1: [sda] Write Protect is off Apr 30 12:48:37.443882 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Apr 30 12:48:37.444029 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Apr 30 12:48:37.444172 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Apr 30 12:48:37.444309 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Apr 30 12:48:37.444443 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:37.444463 kernel: sda: sda1 Apr 30 12:48:37.444475 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Apr 30 12:48:37.444640 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Apr 30 12:48:37.364090 systemd-resolved[266]: Clock change detected. Flushing caches. Apr 30 12:48:38.114519 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sdb6 scanned by (udev-worker) (489) Apr 30 12:48:38.136317 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Apr 30 12:48:38.157293 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Apr 30 12:48:38.275521 kernel: BTRFS: device fsid 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 devid 1 transid 40 /dev/sdb3 scanned by (udev-worker) (503) Apr 30 12:48:38.276712 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Apr 30 12:48:38.304596 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Apr 30 12:48:38.305421 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Apr 30 12:48:38.339630 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 12:48:38.358514 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:38.365507 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:39.368516 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:39.368628 disk-uuid[640]: The operation has completed successfully. Apr 30 12:48:40.011558 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 12:48:40.011675 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 12:48:40.038650 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 12:48:40.048977 sh[729]: Success Apr 30 12:48:40.072509 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Apr 30 12:48:40.394550 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 12:48:40.415032 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 12:48:40.416726 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 12:48:40.441864 kernel: BTRFS info (device dm-0): first mount of filesystem 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 Apr 30 12:48:40.441903 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:40.447041 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 12:48:40.450724 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 12:48:40.453903 kernel: BTRFS info (device dm-0): using free space tree Apr 30 12:48:40.807521 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 12:48:40.809015 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 12:48:40.826771 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 12:48:40.831008 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 12:48:40.868338 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:40.868385 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:40.871510 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:40.891516 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:40.899514 kernel: BTRFS info (device sdb6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:40.903138 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 12:48:40.913733 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 12:48:40.928546 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:48:40.930909 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:48:40.968106 systemd-networkd[910]: lo: Link UP Apr 30 12:48:40.968117 systemd-networkd[910]: lo: Gained carrier Apr 30 12:48:40.969321 systemd-networkd[910]: Enumeration completed Apr 30 12:48:40.969411 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:48:40.969703 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:48:40.969708 systemd-networkd[910]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:48:40.970503 systemd-networkd[910]: eth0: Link UP Apr 30 12:48:40.975171 systemd[1]: Reached target network.target - Network. Apr 30 12:48:40.981405 systemd-networkd[910]: eth0: Gained carrier Apr 30 12:48:40.981416 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:48:41.039534 systemd-networkd[910]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:48:42.230629 systemd-networkd[910]: eth0: Gained IPv6LL Apr 30 12:48:42.517800 ignition[881]: Ignition 2.20.0 Apr 30 12:48:42.517815 ignition[881]: Stage: fetch-offline Apr 30 12:48:42.517861 ignition[881]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.517872 ignition[881]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.517981 ignition[881]: parsed url from cmdline: "" Apr 30 12:48:42.517985 ignition[881]: no config URL provided Apr 30 12:48:42.517991 ignition[881]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:48:42.518001 ignition[881]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:48:42.518007 ignition[881]: failed to fetch config: resource requires networking Apr 30 12:48:42.518191 ignition[881]: Ignition finished successfully Apr 30 12:48:42.548583 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:48:42.569637 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 30 12:48:42.581866 ignition[923]: Ignition 2.20.0 Apr 30 12:48:42.581877 ignition[923]: Stage: fetch Apr 30 12:48:42.582049 ignition[923]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.582063 ignition[923]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.582164 ignition[923]: parsed url from cmdline: "" Apr 30 12:48:42.582169 ignition[923]: no config URL provided Apr 30 12:48:42.582174 ignition[923]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:48:42.582185 ignition[923]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:48:42.582211 ignition[923]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Apr 30 12:48:42.656270 ignition[923]: GET result: OK Apr 30 12:48:42.656321 ignition[923]: config has been read from IMDS userdata Apr 30 12:48:42.656332 ignition[923]: parsing config with SHA512: 8a1eb2ab1521ed166f182cf7c78cab5c9bd7c8ed24f37444998c736667ea32333c30dc06195e7e6b6d25cfb2d2f5e08d06d87a9ce911ad8fc7d0cdef7089e8c3 Apr 30 12:48:42.660693 unknown[923]: fetched base config from "system" Apr 30 12:48:42.660909 ignition[923]: fetch: fetch complete Apr 30 12:48:42.660720 unknown[923]: fetched base config from "system" Apr 30 12:48:42.660914 ignition[923]: fetch: fetch passed Apr 30 12:48:42.660726 unknown[923]: fetched user config from "azure" Apr 30 12:48:42.660964 ignition[923]: Ignition finished successfully Apr 30 12:48:42.667148 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 30 12:48:42.695638 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 12:48:42.717759 ignition[934]: Ignition 2.20.0 Apr 30 12:48:42.717770 ignition[934]: Stage: kargs Apr 30 12:48:42.717946 ignition[934]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.723018 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 12:48:42.717959 ignition[934]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.718603 ignition[934]: kargs: kargs passed Apr 30 12:48:42.718653 ignition[934]: Ignition finished successfully Apr 30 12:48:42.747671 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 12:48:42.759977 ignition[944]: Ignition 2.20.0 Apr 30 12:48:42.759989 ignition[944]: Stage: disks Apr 30 12:48:42.760147 ignition[944]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.760158 ignition[944]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.765666 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 12:48:42.760715 ignition[944]: disks: disks passed Apr 30 12:48:42.772005 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 12:48:42.760761 ignition[944]: Ignition finished successfully Apr 30 12:48:42.775438 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 12:48:42.786534 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:48:42.797194 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:48:42.799464 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:48:42.834650 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 12:48:42.890931 systemd-fsck[956]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Apr 30 12:48:42.897568 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 12:48:42.922564 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 12:48:43.016509 kernel: EXT4-fs (sdb9): mounted filesystem 59d16236-967d-47d1-a9bd-4b055a17ab77 r/w with ordered data mode. Quota mode: none. Apr 30 12:48:43.017235 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 12:48:43.019863 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 12:48:43.064578 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:48:43.069318 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 12:48:43.082133 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sdb6 scanned by mount (967) Apr 30 12:48:43.071422 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Apr 30 12:48:43.105728 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:43.105752 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:43.105765 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:43.105777 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:43.088911 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 12:48:43.088948 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:48:43.108783 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:48:43.122184 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 12:48:43.135482 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 12:48:44.424475 coreos-metadata[969]: Apr 30 12:48:44.424 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Apr 30 12:48:44.430550 coreos-metadata[969]: Apr 30 12:48:44.426 INFO Fetch successful Apr 30 12:48:44.430550 coreos-metadata[969]: Apr 30 12:48:44.426 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Apr 30 12:48:44.443028 coreos-metadata[969]: Apr 30 12:48:44.433 INFO Fetch successful Apr 30 12:48:44.447146 coreos-metadata[969]: Apr 30 12:48:44.447 INFO wrote hostname ci-4230.1.1-a-f2059e57ea to /sysroot/etc/hostname Apr 30 12:48:44.454292 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:48:44.629734 initrd-setup-root[997]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 12:48:44.685708 initrd-setup-root[1004]: cut: /sysroot/etc/group: No such file or directory Apr 30 12:48:44.751035 initrd-setup-root[1011]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 12:48:44.757149 initrd-setup-root[1018]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 12:48:46.733807 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 12:48:46.753525 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 12:48:46.761431 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 12:48:46.774322 kernel: BTRFS info (device sdb6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:46.775102 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 12:48:46.892209 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 12:48:46.918376 ignition[1090]: INFO : Ignition 2.20.0 Apr 30 12:48:46.918376 ignition[1090]: INFO : Stage: mount Apr 30 12:48:46.924112 ignition[1090]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:46.924112 ignition[1090]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:46.924112 ignition[1090]: INFO : mount: mount passed Apr 30 12:48:46.924112 ignition[1090]: INFO : Ignition finished successfully Apr 30 12:48:46.940508 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 12:48:46.956611 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 12:48:46.965370 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:48:47.007515 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sdb6 scanned by mount (1102) Apr 30 12:48:47.007584 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:47.016069 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:47.019251 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:47.024508 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:47.026465 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:48:47.055732 ignition[1119]: INFO : Ignition 2.20.0 Apr 30 12:48:47.055732 ignition[1119]: INFO : Stage: files Apr 30 12:48:47.061472 ignition[1119]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:47.061472 ignition[1119]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:47.061472 ignition[1119]: DEBUG : files: compiled without relabeling support, skipping Apr 30 12:48:47.259796 ignition[1119]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 12:48:47.259796 ignition[1119]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 12:48:47.340928 ignition[1119]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 12:48:47.346503 ignition[1119]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 12:48:47.352074 unknown[1119]: wrote ssh authorized keys file for user: core Apr 30 12:48:47.355884 ignition[1119]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 12:48:47.365902 ignition[1119]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:48:47.372980 ignition[1119]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:48:47.372980 ignition[1119]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:48:47.372980 ignition[1119]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:48:47.372980 ignition[1119]: INFO : files: files passed Apr 30 12:48:47.372980 ignition[1119]: INFO : Ignition finished successfully Apr 30 12:48:47.388245 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 12:48:47.415629 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 12:48:47.417150 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 12:48:47.426058 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 12:48:47.426154 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 12:48:47.454411 initrd-setup-root-after-ignition[1151]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:47.454411 initrd-setup-root-after-ignition[1151]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:47.471648 initrd-setup-root-after-ignition[1155]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:47.456427 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:48:47.462150 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 12:48:47.485630 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 12:48:47.508930 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 12:48:47.509035 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 12:48:47.511314 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 12:48:47.530187 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 12:48:47.531734 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 12:48:47.532527 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 12:48:47.551898 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:48:47.555145 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 12:48:47.573948 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:48:47.575420 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:48:47.591808 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 12:48:47.593327 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 12:48:47.593388 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:48:47.606973 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 12:48:47.615454 systemd[1]: Stopped target basic.target - Basic System. Apr 30 12:48:47.622815 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 12:48:47.630362 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:48:47.639000 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 12:48:47.641178 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 12:48:47.652521 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:48:47.664396 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 12:48:47.673183 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 12:48:47.680968 systemd[1]: Stopped target swap.target - Swaps. Apr 30 12:48:47.687871 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 12:48:47.687959 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:48:47.698588 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:48:47.700031 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:48:47.712420 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 12:48:47.712524 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:48:47.721501 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 12:48:47.721579 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 12:48:47.730764 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 12:48:47.730821 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:48:47.743209 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 12:48:47.743262 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 12:48:47.752230 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Apr 30 12:48:47.752288 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:48:47.777572 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 12:48:47.798458 ignition[1175]: INFO : Ignition 2.20.0 Apr 30 12:48:47.798458 ignition[1175]: INFO : Stage: umount Apr 30 12:48:47.798458 ignition[1175]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:47.798458 ignition[1175]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:47.798458 ignition[1175]: INFO : umount: umount passed Apr 30 12:48:47.798458 ignition[1175]: INFO : Ignition finished successfully Apr 30 12:48:47.784517 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 12:48:47.784577 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:48:47.798613 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 12:48:47.799414 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 12:48:47.799471 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:48:47.809576 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 12:48:47.809633 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:48:47.821087 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 12:48:47.821189 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 12:48:47.823021 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 12:48:47.823111 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 12:48:47.839112 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 12:48:47.839168 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 12:48:47.844831 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 12:48:47.844880 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 12:48:47.852502 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 30 12:48:47.852555 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 30 12:48:47.860967 systemd[1]: Stopped target network.target - Network. Apr 30 12:48:47.868994 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 12:48:47.869054 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:48:47.876387 systemd[1]: Stopped target paths.target - Path Units. Apr 30 12:48:47.885437 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 12:48:47.897076 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:48:47.900642 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 12:48:47.910362 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 12:48:47.919755 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 12:48:47.919815 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:48:47.927481 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 12:48:47.927551 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:48:47.935234 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 12:48:47.935297 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 12:48:47.942854 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 12:48:47.942902 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 12:48:47.950840 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 12:48:47.960138 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 12:48:47.969029 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 12:48:47.969818 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 12:48:47.969922 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 12:48:47.980139 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Apr 30 12:48:47.980400 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 12:48:47.980502 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 12:48:47.982070 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 12:48:47.982131 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:48:47.988396 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 12:48:47.988447 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 12:48:48.017592 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 12:48:48.019764 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 12:48:48.019819 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:48:48.026831 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:48:48.035284 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 12:48:48.035392 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 12:48:48.052182 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Apr 30 12:48:48.054060 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 12:48:48.054169 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:48:48.066210 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 12:48:48.066261 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 12:48:48.074556 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 12:48:48.074610 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:48:48.088810 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 30 12:48:48.088876 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:48:48.089196 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 12:48:48.089337 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:48:48.094127 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 12:48:48.094185 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 12:48:48.102069 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 12:48:48.102110 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:48:48.110692 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 12:48:48.110742 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:48:48.129187 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 12:48:48.129240 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 12:48:48.140206 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:48:48.140259 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:48.181665 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 12:48:48.185176 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 12:48:48.185240 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:48:48.201511 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:48:48.201564 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:48.211736 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Apr 30 12:48:48.211799 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:48:48.212177 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 12:48:48.212282 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 12:48:48.217101 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 12:48:48.217194 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 12:48:48.225940 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 12:48:48.368508 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Apr 30 12:48:48.258708 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 12:48:48.292813 systemd[1]: Switching root. Apr 30 12:48:48.375290 systemd-journald[204]: Journal stopped Apr 30 12:49:02.693855 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 12:49:02.693887 kernel: SELinux: policy capability open_perms=1 Apr 30 12:49:02.693900 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 12:49:02.693909 kernel: SELinux: policy capability always_check_network=0 Apr 30 12:49:02.693918 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 12:49:02.693927 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 12:49:02.693939 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 12:49:02.693951 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 12:49:02.693961 kernel: audit: type=1403 audit(1746017329.716:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 12:49:02.693973 systemd[1]: Successfully loaded SELinux policy in 272.254ms. Apr 30 12:49:02.693985 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.735ms. Apr 30 12:49:02.693996 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) Apr 30 12:49:02.694007 systemd[1]: Detected virtualization microsoft. Apr 30 12:49:02.694017 systemd[1]: Detected architecture x86-64. Apr 30 12:49:02.694031 systemd[1]: Detected first boot. Apr 30 12:49:02.694042 systemd[1]: Hostname set to . Apr 30 12:49:02.694053 systemd[1]: Initializing machine ID from random generator. Apr 30 12:49:02.694064 zram_generator::config[1223]: No configuration found. Apr 30 12:49:02.694075 kernel: Guest personality initialized and is inactive Apr 30 12:49:02.694087 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Apr 30 12:49:02.694098 kernel: Initialized host personality Apr 30 12:49:02.694108 kernel: NET: Registered PF_VSOCK protocol family Apr 30 12:49:02.694118 systemd[1]: Populated /etc with preset unit settings. Apr 30 12:49:02.694129 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Apr 30 12:49:02.694140 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 30 12:49:02.694150 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 30 12:49:02.694161 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 30 12:49:02.694175 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 12:49:02.694186 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 12:49:02.694197 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 12:49:02.694208 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 12:49:02.694219 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 12:49:02.694231 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 12:49:02.694242 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 12:49:02.694255 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 12:49:02.694266 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:49:02.694278 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:49:02.694289 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 12:49:02.694299 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 12:49:02.694310 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 12:49:02.694326 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:49:02.694337 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 30 12:49:02.694349 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:49:02.694362 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 30 12:49:02.694374 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 30 12:49:02.694385 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 30 12:49:02.694396 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 12:49:02.694408 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:49:02.694419 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:49:02.694430 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:49:02.694443 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:49:02.694454 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 12:49:02.694465 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 12:49:02.694477 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Apr 30 12:49:02.694488 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:49:02.694513 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:49:02.694525 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:49:02.694536 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 12:49:02.694547 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 12:49:02.694558 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 12:49:02.694569 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 12:49:02.694581 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:02.694592 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 12:49:02.694606 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 12:49:02.694618 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 12:49:02.694630 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). Apr 30 12:49:02.694641 systemd[1]: Reached target machines.target - Containers. Apr 30 12:49:02.694652 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 12:49:02.694664 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:49:02.694675 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:49:02.694687 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 12:49:02.694700 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:49:02.694712 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:49:02.694724 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:49:02.694735 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 12:49:02.694746 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:49:02.694758 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 12:49:02.694769 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 30 12:49:02.694780 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 30 12:49:02.694791 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 30 12:49:02.694805 systemd[1]: Stopped systemd-fsck-usr.service. Apr 30 12:49:02.694817 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). Apr 30 12:49:02.694829 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:49:02.694840 kernel: loop: module loaded Apr 30 12:49:02.694851 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:49:02.694862 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 12:49:02.694873 kernel: fuse: init (API version 7.39) Apr 30 12:49:02.694887 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 12:49:02.694898 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Apr 30 12:49:02.694910 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:49:02.694921 systemd[1]: verity-setup.service: Deactivated successfully. Apr 30 12:49:02.694932 systemd[1]: Stopped verity-setup.service. Apr 30 12:49:02.694943 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:02.694955 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 12:49:02.694966 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 12:49:02.694977 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 12:49:02.694991 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 12:49:02.695002 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 12:49:02.695014 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 12:49:02.695025 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:49:02.695036 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 12:49:02.695051 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 12:49:02.695063 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:49:02.695074 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:49:02.695088 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:49:02.695100 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:49:02.695111 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 12:49:02.695122 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 12:49:02.695133 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:49:02.695144 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:49:02.695156 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 12:49:02.695167 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 12:49:02.695178 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:49:02.695192 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 12:49:02.695225 systemd-journald[1301]: Collecting audit messages is disabled. Apr 30 12:49:02.695249 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 12:49:02.695265 systemd-journald[1301]: Journal started Apr 30 12:49:02.695288 systemd-journald[1301]: Runtime Journal (/run/log/journal/82e7aa90bf554268b71ee49078f42d0d) is 8M, max 2.2G, 2.1G free. Apr 30 12:49:01.676067 systemd[1]: Queued start job for default target multi-user.target. Apr 30 12:49:01.690839 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Apr 30 12:49:01.691351 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 30 12:49:02.707126 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:49:02.710822 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 12:49:02.716128 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 12:49:02.721449 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:49:02.735487 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 12:49:02.740074 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 12:49:02.740110 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:49:02.745680 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Apr 30 12:49:02.763640 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 12:49:02.769350 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 12:49:02.773537 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:49:03.121731 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 12:49:03.127338 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 12:49:03.132165 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:49:03.133234 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 12:49:03.138516 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:49:03.143548 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 12:49:03.149176 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 12:49:03.155252 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Apr 30 12:49:03.160684 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 12:49:03.168618 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 12:49:03.191426 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:49:03.209613 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 12:49:03.220097 udevadm[1372]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 30 12:49:03.458622 systemd-journald[1301]: Time spent on flushing to /var/log/journal/82e7aa90bf554268b71ee49078f42d0d is 18.180ms for 937 entries. Apr 30 12:49:03.458622 systemd-journald[1301]: System Journal (/var/log/journal/82e7aa90bf554268b71ee49078f42d0d) is 8M, max 2.6G, 2.6G free. Apr 30 12:49:04.346825 systemd-journald[1301]: Received client request to flush runtime journal. Apr 30 12:49:04.346924 kernel: loop0: detected capacity change from 0 to 147912 Apr 30 12:49:04.346956 kernel: ACPI: bus type drm_connector registered Apr 30 12:49:03.775045 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:49:03.806737 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 12:49:03.811733 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 12:49:03.826635 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Apr 30 12:49:04.089969 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:49:04.090184 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:49:04.348129 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 12:49:04.354076 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 12:49:04.367674 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:49:04.825047 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 12:49:04.825863 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Apr 30 12:49:05.222904 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Apr 30 12:49:05.222927 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Apr 30 12:49:05.228704 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:49:06.020531 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 12:49:06.231531 kernel: loop1: detected capacity change from 0 to 28272 Apr 30 12:49:08.277527 kernel: loop2: detected capacity change from 0 to 138176 Apr 30 12:49:08.331433 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 12:49:08.348662 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:49:08.381258 systemd-udevd[1390]: Using default interface naming scheme 'v255'. Apr 30 12:49:09.992531 kernel: loop3: detected capacity change from 0 to 147912 Apr 30 12:49:10.006513 kernel: loop4: detected capacity change from 0 to 28272 Apr 30 12:49:10.016517 kernel: loop5: detected capacity change from 0 to 138176 Apr 30 12:49:10.017957 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:49:10.034730 (sd-merge)[1392]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Apr 30 12:49:10.035210 (sd-merge)[1392]: Merged extensions into '/usr'. Apr 30 12:49:10.035677 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:49:10.054127 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 12:49:10.070923 systemd[1]: Starting ensure-sysext.service... Apr 30 12:49:10.077654 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:49:10.083757 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 30 12:49:10.123551 kernel: mousedev: PS/2 mouse device common for all mice Apr 30 12:49:10.169552 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 12:49:10.169855 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 12:49:10.170780 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 12:49:10.171066 systemd-tmpfiles[1429]: ACLs are not supported, ignoring. Apr 30 12:49:10.171136 systemd-tmpfiles[1429]: ACLs are not supported, ignoring. Apr 30 12:49:10.182861 systemd[1]: Reload requested from client PID 1427 ('systemctl') (unit ensure-sysext.service)... Apr 30 12:49:10.182876 systemd[1]: Reloading... Apr 30 12:49:10.235579 zram_generator::config[1471]: No configuration found. Apr 30 12:49:10.526630 kernel: hv_vmbus: registering driver hyperv_fb Apr 30 12:49:10.526730 kernel: hv_vmbus: registering driver hv_balloon Apr 30 12:49:10.529612 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Apr 30 12:49:10.535653 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Apr 30 12:49:10.535723 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Apr 30 12:49:10.542912 systemd-tmpfiles[1429]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:49:10.542927 systemd-tmpfiles[1429]: Skipping /boot Apr 30 12:49:10.544761 kernel: Console: switching to colour dummy device 80x25 Apr 30 12:49:10.549474 kernel: Console: switching to colour frame buffer device 128x48 Apr 30 12:49:10.554958 systemd-tmpfiles[1429]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:49:10.554973 systemd-tmpfiles[1429]: Skipping /boot Apr 30 12:49:10.586510 kernel: EDAC MC: Ver: 3.0.0 Apr 30 12:49:10.611540 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. Apr 30 12:49:10.718571 systemd[1]: Reloading finished in 535 ms. Apr 30 12:49:10.726526 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (1420) Apr 30 12:49:10.765898 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:49:10.813022 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 12:49:10.841847 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Apr 30 12:49:10.848431 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:10.860718 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 12:49:10.865912 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 12:49:10.870888 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:49:10.872158 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 12:49:10.877900 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:49:10.885003 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:49:10.890485 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:49:10.896190 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:49:10.900410 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:49:10.901528 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 12:49:10.906170 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). Apr 30 12:49:10.907382 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 12:49:10.914047 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:49:10.918518 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 12:49:10.936736 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 12:49:10.942960 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 12:49:10.945547 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:49:10.945860 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:10.948913 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:49:10.949381 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:49:10.950013 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:49:10.950195 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:49:10.950664 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:49:10.950855 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:49:10.951356 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:49:10.951559 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:49:10.955469 systemd[1]: Finished ensure-sysext.service. Apr 30 12:49:10.958851 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:49:10.958913 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:49:11.277796 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 12:49:11.289860 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 12:49:11.299536 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 12:49:11.488035 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 12:49:11.492477 lvm[1600]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:49:11.533279 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 12:49:11.535008 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:49:11.543727 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 12:49:11.547807 lvm[1646]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:49:11.572155 systemd-networkd[1406]: lo: Link UP Apr 30 12:49:11.572165 systemd-networkd[1406]: lo: Gained carrier Apr 30 12:49:11.573578 systemd-networkd[1406]: Enumeration completed Apr 30 12:49:11.573704 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:49:11.573966 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:11.573977 systemd-networkd[1406]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:49:11.574546 systemd-networkd[1406]: eth0: Link UP Apr 30 12:49:11.574556 systemd-networkd[1406]: eth0: Gained carrier Apr 30 12:49:11.574570 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:11.589694 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Apr 30 12:49:11.592440 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 12:49:11.601057 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 12:49:11.618553 systemd-networkd[1406]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:49:11.626544 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Apr 30 12:49:11.638594 systemd-resolved[1608]: Positive Trust Anchors: Apr 30 12:49:11.638612 systemd-resolved[1608]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:49:11.638644 systemd-resolved[1608]: 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 Apr 30 12:49:11.639813 augenrules[1655]: No rules Apr 30 12:49:11.641080 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 12:49:11.641326 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 12:49:11.642639 systemd-resolved[1608]: Using system hostname 'ci-4230.1.1-a-f2059e57ea'. Apr 30 12:49:11.644582 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:49:11.645162 systemd[1]: Reached target network.target - Network. Apr 30 12:49:11.645711 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:49:11.760602 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:49:12.475130 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 12:49:12.480518 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). Apr 30 12:49:13.334665 systemd-networkd[1406]: eth0: Gained IPv6LL Apr 30 12:49:13.337524 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 12:49:13.342870 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 12:49:19.060509 ldconfig[1352]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 12:49:19.069896 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 12:49:19.090746 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 12:49:19.103194 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 12:49:19.107985 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:49:19.112376 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 12:49:19.117383 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 12:49:19.122654 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 12:49:19.127083 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 12:49:19.132202 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 12:49:19.137287 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 12:49:19.137328 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:49:19.141049 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:49:19.145897 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 12:49:19.151800 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 12:49:19.158522 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Apr 30 12:49:19.163782 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Apr 30 12:49:19.168818 systemd[1]: Reached target ssh-access.target - SSH Access Available. Apr 30 12:49:19.182035 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 12:49:19.186476 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Apr 30 12:49:19.192057 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 12:49:19.196532 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:49:19.200469 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:49:19.204321 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:49:19.204359 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:49:19.217587 systemd[1]: Starting chronyd.service - NTP client/server... Apr 30 12:49:19.222115 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 12:49:19.227594 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 30 12:49:19.232629 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 12:49:19.237235 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 12:49:19.242297 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 12:49:19.246406 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). Apr 30 12:49:19.246452 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Apr 30 12:49:19.247427 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Apr 30 12:49:19.251638 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Apr 30 12:49:19.252646 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 12:49:19.257566 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 12:49:19.261707 KVP[1681]: KVP starting; pid is:1681 Apr 30 12:49:19.263613 KVP[1681]: KVP LIC Version: 3.1 Apr 30 12:49:19.263895 jq[1679]: false Apr 30 12:49:19.262270 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 12:49:19.266513 kernel: hv_utils: KVP IC version 4.0 Apr 30 12:49:19.270186 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 12:49:19.271449 (chronyd)[1672]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Apr 30 12:49:19.276781 chronyd[1688]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Apr 30 12:49:19.279349 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 12:49:19.284246 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 12:49:19.284704 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 12:49:19.285324 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 12:49:19.289875 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 12:49:19.296582 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 12:49:19.298034 jq[1694]: true Apr 30 12:49:19.296801 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 12:49:19.297119 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 12:49:19.297308 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 12:49:19.311150 jq[1696]: true Apr 30 12:49:19.341511 chronyd[1688]: Timezone right/UTC failed leap second check, ignoring Apr 30 12:49:19.341710 chronyd[1688]: Loaded seccomp filter (level 2) Apr 30 12:49:19.343234 systemd[1]: Started chronyd.service - NTP client/server. Apr 30 12:49:19.347626 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 12:49:19.347871 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 12:49:19.362864 (ntainerd)[1727]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 12:49:19.402783 extend-filesystems[1680]: Found loop3 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found loop4 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found loop5 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sda Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sda1 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb1 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb2 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb3 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found usr Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb4 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb6 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb7 Apr 30 12:49:19.405768 extend-filesystems[1680]: Found sdb9 Apr 30 12:49:19.405768 extend-filesystems[1680]: Checking size of /dev/sdb9 Apr 30 12:49:19.411319 systemd-logind[1689]: New seat seat0. Apr 30 12:49:19.414691 systemd-logind[1689]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 30 12:49:19.414900 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 12:49:19.448402 update_engine[1692]: I20250430 12:49:19.448325 1692 main.cc:92] Flatcar Update Engine starting Apr 30 12:49:19.459098 bash[1718]: Updated "/home/core/.ssh/authorized_keys" Apr 30 12:49:19.460587 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 12:49:19.466435 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Apr 30 12:49:19.513419 sshd_keygen[1721]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 12:49:19.535387 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 12:49:19.547855 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 12:49:19.552532 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Apr 30 12:49:19.557939 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 12:49:19.558160 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 12:49:19.563757 extend-filesystems[1680]: Old size kept for /dev/sdb9 Apr 30 12:49:19.567521 extend-filesystems[1680]: Found sr0 Apr 30 12:49:19.564412 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 12:49:19.574319 update_engine[1692]: I20250430 12:49:19.571172 1692 update_check_scheduler.cc:74] Next update check in 9m26s Apr 30 12:49:19.568541 dbus-daemon[1675]: [system] SELinux support is enabled Apr 30 12:49:19.564759 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 12:49:19.574572 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 12:49:19.583742 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Apr 30 12:49:19.595303 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). Apr 30 12:49:19.595424 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 12:49:19.596548 dbus-daemon[1675]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 30 12:49:19.596709 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (1738) Apr 30 12:49:19.613716 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 12:49:19.618024 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). Apr 30 12:49:19.618063 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 12:49:19.635069 systemd[1]: Started update-engine.service - Update Engine. Apr 30 12:49:19.648767 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 12:49:19.655345 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 12:49:19.688819 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 12:49:19.694073 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 30 12:49:19.698614 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 12:49:19.701203 coreos-metadata[1674]: Apr 30 12:49:19.701 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Apr 30 12:49:19.703681 coreos-metadata[1674]: Apr 30 12:49:19.703 INFO Fetch successful Apr 30 12:49:19.703873 coreos-metadata[1674]: Apr 30 12:49:19.703 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Apr 30 12:49:19.707555 coreos-metadata[1674]: Apr 30 12:49:19.707 INFO Fetch successful Apr 30 12:49:19.707839 coreos-metadata[1674]: Apr 30 12:49:19.707 INFO Fetching http://168.63.129.16/machine/5f29c43b-5330-40c7-9b7a-730095b28b93/df47c145%2D09b4%2D40b3%2D9ba7%2D20daae955bfd.%5Fci%2D4230.1.1%2Da%2Df2059e57ea?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Apr 30 12:49:19.709226 coreos-metadata[1674]: Apr 30 12:49:19.709 INFO Fetch successful Apr 30 12:49:19.709373 coreos-metadata[1674]: Apr 30 12:49:19.709 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Apr 30 12:49:19.716027 coreos-metadata[1674]: Apr 30 12:49:19.716 INFO Fetch successful Apr 30 12:49:19.741544 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 30 12:49:19.746460 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 12:49:19.790397 setup-nvidia[1683]: Downloading Flatcar Container Linux Developer Container for version: 4230.1.1 Apr 30 12:49:19.967340 locksmithd[1795]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 12:49:20.454165 setup-nvidia[1839]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 12:49:20.454550 setup-nvidia[1839]: Dload Upload Total Spent Left Speed Apr 30 12:49:21.240695 containerd[1727]: time="2025-04-30T12:49:21.240611000Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 30 12:49:21.258870 containerd[1727]: time="2025-04-30T12:49:21.258819500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.260486 containerd[1727]: time="2025-04-30T12:49:21.260453200Z" 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.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:21.260486 containerd[1727]: time="2025-04-30T12:49:21.260482700Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 12:49:21.260562 containerd[1727]: time="2025-04-30T12:49:21.260509700Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 12:49:21.260746 containerd[1727]: time="2025-04-30T12:49:21.260716100Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 12:49:21.260792 containerd[1727]: time="2025-04-30T12:49:21.260753700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.260847 containerd[1727]: time="2025-04-30T12:49:21.260824400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:21.260847 containerd[1727]: time="2025-04-30T12:49:21.260843400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261073 containerd[1727]: time="2025-04-30T12:49:21.261048900Z" 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 Apr 30 12:49:21.261106 containerd[1727]: time="2025-04-30T12:49:21.261071000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261106 containerd[1727]: time="2025-04-30T12:49:21.261084900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261106 containerd[1727]: time="2025-04-30T12:49:21.261094900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261204 containerd[1727]: time="2025-04-30T12:49:21.261183400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261427 containerd[1727]: time="2025-04-30T12:49:21.261405300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:21.261573 containerd[1727]: time="2025-04-30T12:49:21.261551900Z" 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 Apr 30 12:49:21.261573 containerd[1727]: time="2025-04-30T12:49:21.261571100Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 12:49:21.261674 containerd[1727]: time="2025-04-30T12:49:21.261653700Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 12:49:21.261747 containerd[1727]: time="2025-04-30T12:49:21.261727900Z" level=info msg="metadata content store policy set" policy=shared Apr 30 12:49:21.273870 containerd[1727]: time="2025-04-30T12:49:21.273818600Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 12:49:21.273870 containerd[1727]: time="2025-04-30T12:49:21.273867700Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 12:49:21.273972 containerd[1727]: time="2025-04-30T12:49:21.273884900Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 12:49:21.273972 containerd[1727]: time="2025-04-30T12:49:21.273900900Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 12:49:21.273972 containerd[1727]: time="2025-04-30T12:49:21.273915400Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 12:49:21.274053 containerd[1727]: time="2025-04-30T12:49:21.274042800Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 12:49:21.274329 containerd[1727]: time="2025-04-30T12:49:21.274297500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 12:49:21.274465 containerd[1727]: time="2025-04-30T12:49:21.274441800Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 12:49:21.274513 containerd[1727]: time="2025-04-30T12:49:21.274467000Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 12:49:21.274513 containerd[1727]: time="2025-04-30T12:49:21.274484400Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 12:49:21.274576 containerd[1727]: time="2025-04-30T12:49:21.274513100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274576 containerd[1727]: time="2025-04-30T12:49:21.274529000Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274576 containerd[1727]: time="2025-04-30T12:49:21.274542700Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274576 containerd[1727]: time="2025-04-30T12:49:21.274571300Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274590300Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274604900Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274618000Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274630300Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274651200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274666 containerd[1727]: time="2025-04-30T12:49:21.274665100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274677600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274690700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274702500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274715100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274728200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274740700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274804 containerd[1727]: time="2025-04-30T12:49:21.274753400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274996 containerd[1727]: time="2025-04-30T12:49:21.274859700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274996 containerd[1727]: time="2025-04-30T12:49:21.274884900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274996 containerd[1727]: time="2025-04-30T12:49:21.274903600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.274996 containerd[1727]: time="2025-04-30T12:49:21.274917300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.275089 containerd[1727]: time="2025-04-30T12:49:21.275006300Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 12:49:21.275089 containerd[1727]: time="2025-04-30T12:49:21.275044100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.275089 containerd[1727]: time="2025-04-30T12:49:21.275065200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.275089 containerd[1727]: time="2025-04-30T12:49:21.275080200Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 12:49:21.275176 containerd[1727]: time="2025-04-30T12:49:21.275132100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 12:49:21.275176 containerd[1727]: time="2025-04-30T12:49:21.275156500Z" 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 Apr 30 12:49:21.275176 containerd[1727]: time="2025-04-30T12:49:21.275172000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 12:49:21.275251 containerd[1727]: time="2025-04-30T12:49:21.275189500Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 12:49:21.275251 containerd[1727]: time="2025-04-30T12:49:21.275203900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.275251 containerd[1727]: time="2025-04-30T12:49:21.275221100Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 12:49:21.275251 containerd[1727]: time="2025-04-30T12:49:21.275232600Z" level=info msg="NRI interface is disabled by configuration." Apr 30 12:49:21.275251 containerd[1727]: time="2025-04-30T12:49:21.275246800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 12:49:21.275726 containerd[1727]: time="2025-04-30T12:49:21.275664900Z" 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}" Apr 30 12:49:21.275882 containerd[1727]: time="2025-04-30T12:49:21.275736000Z" level=info msg="Connect containerd service" Apr 30 12:49:21.275882 containerd[1727]: time="2025-04-30T12:49:21.275776100Z" level=info msg="using legacy CRI server" Apr 30 12:49:21.275882 containerd[1727]: time="2025-04-30T12:49:21.275785800Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 12:49:21.275957 containerd[1727]: time="2025-04-30T12:49:21.275900900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 12:49:21.276724 containerd[1727]: time="2025-04-30T12:49:21.276695100Z" 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" Apr 30 12:49:21.276854 containerd[1727]: time="2025-04-30T12:49:21.276819900Z" level=info msg="Start subscribing containerd event" Apr 30 12:49:21.276889 containerd[1727]: time="2025-04-30T12:49:21.276869200Z" level=info msg="Start recovering state" Apr 30 12:49:21.276949 containerd[1727]: time="2025-04-30T12:49:21.276927200Z" level=info msg="Start event monitor" Apr 30 12:49:21.276949 containerd[1727]: time="2025-04-30T12:49:21.276951000Z" level=info msg="Start snapshots syncer" Apr 30 12:49:21.277047 containerd[1727]: time="2025-04-30T12:49:21.276961000Z" level=info msg="Start cni network conf syncer for default" Apr 30 12:49:21.277047 containerd[1727]: time="2025-04-30T12:49:21.276968200Z" level=info msg="Start streaming server" Apr 30 12:49:21.277047 containerd[1727]: time="2025-04-30T12:49:21.277006300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 12:49:21.277128 containerd[1727]: time="2025-04-30T12:49:21.277057000Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 12:49:21.277219 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 12:49:21.278684 containerd[1727]: time="2025-04-30T12:49:21.278548700Z" level=info msg="containerd successfully booted in 0.039019s" Apr 30 12:49:26.166603 waagent[1768]: 2025-04-30T12:49:26.166481Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.168376Z INFO Daemon Daemon OS: flatcar 4230.1.1 Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.169105Z INFO Daemon Daemon Python: 3.11.11 Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.170787Z INFO Daemon Daemon Run daemon Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.171406Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.1' Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.172800Z INFO Daemon Daemon Using waagent for provisioning Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.174404Z INFO Daemon Daemon Activate resource disk Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.175038Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.177744Z INFO Daemon Daemon Found device: sda Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.184032Z INFO Daemon Daemon Examining partition table Apr 30 12:49:26.209592 waagent[1768]: 2025-04-30T12:49:26.201668Z INFO Daemon Daemon GPT not detected, determining filesystem Apr 30 12:49:26.289598 waagent[1768]: 2025-04-30T12:49:26.289476Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Apr 30 12:49:26.294731 waagent[1768]: 2025-04-30T12:49:26.291268Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Apr 30 12:49:27.215396 login[1819]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Apr 30 12:49:27.217281 login[1820]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 12:49:27.228906 systemd-logind[1689]: New session 2 of user core. Apr 30 12:49:27.230015 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 12:49:27.238714 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 12:49:27.251711 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 12:49:27.254142 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 12:49:27.480750 (systemd)[1885]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 12:49:27.484096 systemd-logind[1689]: New session c1 of user core. Apr 30 12:49:27.684067 systemd[1885]: Queued start job for default target default.target. Apr 30 12:49:27.693668 systemd[1885]: Created slice app.slice - User Application Slice. Apr 30 12:49:27.693700 systemd[1885]: Reached target paths.target - Paths. Apr 30 12:49:27.693753 systemd[1885]: Reached target timers.target - Timers. Apr 30 12:49:27.695167 systemd[1885]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 12:49:27.706414 systemd[1885]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:49:27.706557 systemd[1885]: Reached target sockets.target - Sockets. Apr 30 12:49:27.706614 systemd[1885]: Reached target basic.target - Basic System. Apr 30 12:49:27.706660 systemd[1885]: Reached target default.target - Main User Target. Apr 30 12:49:27.706700 systemd[1885]: Startup finished in 215ms. Apr 30 12:49:27.706804 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 12:49:27.708425 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 12:49:28.215788 login[1819]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 12:49:28.220139 systemd-logind[1689]: New session 1 of user core. Apr 30 12:49:28.237623 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 12:49:34.404521 kernel: sda: sda1 Apr 30 12:49:34.409526 kernel: sda: sda1 Apr 30 12:49:37.151995 waagent[1768]: 2025-04-30T12:49:37.151880Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Apr 30 12:49:37.164243 waagent[1768]: 2025-04-30T12:49:37.164182Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Apr 30 12:49:37.165134 kernel: sda: sda1 Apr 30 12:49:42.796784 waagent[1768]: 2025-04-30T12:49:42.796673Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Apr 30 12:49:42.825988 waagent[1768]: 2025-04-30T12:49:42.825919Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Apr 30 12:49:42.826512 kernel: EXT4-fs (sda1): mounted filesystem 5399d74a-3d26-4de1-9cea-87c8f8031692 r/w with ordered data mode. Quota mode: none. Apr 30 12:49:42.828339 waagent[1768]: 2025-04-30T12:49:42.828285Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 30 12:49:42.829862 waagent[1768]: 2025-04-30T12:49:42.829809Z INFO Daemon Daemon Running default provisioning handler Apr 30 12:49:42.846530 waagent[1768]: 2025-04-30T12:49:42.845426Z 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. Apr 30 12:49:42.848593 waagent[1768]: 2025-04-30T12:49:42.848541Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Apr 30 12:49:42.848891 waagent[1768]: 2025-04-30T12:49:42.848848Z INFO Daemon Daemon cloud-init is enabled: False Apr 30 12:49:42.850303 waagent[1768]: 2025-04-30T12:49:42.850262Z INFO Daemon Daemon Copying ovf-env.xml Apr 30 12:49:43.047845 waagent[1768]: 2025-04-30T12:49:43.047753Z INFO Daemon Daemon Successfully mounted dvd Apr 30 12:49:43.076888 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Apr 30 12:49:43.077216 waagent[1768]: 2025-04-30T12:49:43.076932Z INFO Daemon Daemon Detect protocol endpoint Apr 30 12:49:43.078672 waagent[1768]: 2025-04-30T12:49:43.078621Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 30 12:49:43.079447 waagent[1768]: 2025-04-30T12:49:43.079404Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Apr 30 12:49:43.080167 waagent[1768]: 2025-04-30T12:49:43.080127Z INFO Daemon Daemon Test for route to 168.63.129.16 Apr 30 12:49:43.081788 waagent[1768]: 2025-04-30T12:49:43.081745Z INFO Daemon Daemon Route to 168.63.129.16 exists Apr 30 12:49:43.082421 waagent[1768]: 2025-04-30T12:49:43.082381Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Apr 30 12:49:43.123159 chronyd[1688]: Selected source PHC0 Apr 30 12:49:43.129348 waagent[1768]: 2025-04-30T12:49:43.129302Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Apr 30 12:49:43.131180 waagent[1768]: 2025-04-30T12:49:43.131148Z INFO Daemon Daemon Wire protocol version:2012-11-30 Apr 30 12:49:43.132485 waagent[1768]: 2025-04-30T12:49:43.132436Z INFO Daemon Daemon Server preferred version:2015-04-05 Apr 30 12:49:43.266597 waagent[1768]: 2025-04-30T12:49:43.266466Z INFO Daemon Daemon Initializing goal state during protocol detection Apr 30 12:49:43.268445 waagent[1768]: 2025-04-30T12:49:43.268384Z INFO Daemon Daemon Forcing an update of the goal state. Apr 30 12:49:43.275378 waagent[1768]: 2025-04-30T12:49:43.275324Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Apr 30 12:49:43.304179 waagent[1768]: 2025-04-30T12:49:43.304030Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.166 Apr 30 12:49:43.307772 waagent[1768]: 2025-04-30T12:49:43.307711Z INFO Daemon Apr 30 12:49:43.307945 waagent[1768]: 2025-04-30T12:49:43.307902Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 9ca959a2-4cc9-4fb6-894c-b8ea63c0f461 eTag: 12658513366520383524 source: Fabric] Apr 30 12:49:43.309684 waagent[1768]: 2025-04-30T12:49:43.309636Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Apr 30 12:49:43.310680 waagent[1768]: 2025-04-30T12:49:43.310633Z INFO Daemon Apr 30 12:49:43.310836 waagent[1768]: 2025-04-30T12:49:43.310796Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Apr 30 12:49:43.316107 waagent[1768]: 2025-04-30T12:49:43.316057Z INFO Daemon Daemon Downloading artifacts profile blob Apr 30 12:49:43.427142 waagent[1768]: 2025-04-30T12:49:43.427070Z INFO Daemon Downloaded certificate {'thumbprint': '84D67176ED6AB4C86A88E5B580056124619A1BB2', 'hasPrivateKey': True} Apr 30 12:49:43.429259 waagent[1768]: 2025-04-30T12:49:43.429212Z INFO Daemon Fetch goal state completed Apr 30 12:49:43.467982 waagent[1768]: 2025-04-30T12:49:43.467925Z INFO Daemon Daemon Starting provisioning Apr 30 12:49:43.469630 waagent[1768]: 2025-04-30T12:49:43.469577Z INFO Daemon Daemon Handle ovf-env.xml. Apr 30 12:49:43.471080 waagent[1768]: 2025-04-30T12:49:43.471029Z INFO Daemon Daemon Set hostname [ci-4230.1.1-a-f2059e57ea] Apr 30 12:49:43.493717 waagent[1768]: 2025-04-30T12:49:43.493661Z INFO Daemon Daemon Publish hostname [ci-4230.1.1-a-f2059e57ea] Apr 30 12:49:43.505898 waagent[1768]: 2025-04-30T12:49:43.495524Z INFO Daemon Daemon Examine /proc/net/route for primary interface Apr 30 12:49:43.505898 waagent[1768]: 2025-04-30T12:49:43.497031Z INFO Daemon Daemon Primary interface is [eth0] Apr 30 12:49:43.507127 systemd-networkd[1406]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:43.507137 systemd-networkd[1406]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:49:43.507165 systemd-networkd[1406]: eth0: DHCP lease lost Apr 30 12:49:43.507640 waagent[1768]: 2025-04-30T12:49:43.507569Z INFO Daemon Daemon Create user account if not exists Apr 30 12:49:43.520609 waagent[1768]: 2025-04-30T12:49:43.520543Z INFO Daemon Daemon User core already exists, skip useradd Apr 30 12:49:43.522259 waagent[1768]: 2025-04-30T12:49:43.522211Z INFO Daemon Daemon Configure sudoer Apr 30 12:49:43.524416 waagent[1768]: 2025-04-30T12:49:43.524321Z INFO Daemon Daemon Configure sshd Apr 30 12:49:43.526401 waagent[1768]: 2025-04-30T12:49:43.526282Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Apr 30 12:49:43.528068 waagent[1768]: 2025-04-30T12:49:43.528023Z INFO Daemon Daemon Deploy ssh public key. Apr 30 12:49:43.585538 systemd-networkd[1406]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:49:44.680410 waagent[1768]: 2025-04-30T12:49:44.680345Z INFO Daemon Daemon Provisioning complete Apr 30 12:49:44.690144 waagent[1768]: 2025-04-30T12:49:44.690086Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Apr 30 12:49:44.692772 waagent[1768]: 2025-04-30T12:49:44.692658Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Apr 30 12:49:44.694009 waagent[1768]: 2025-04-30T12:49:44.693964Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Apr 30 12:49:44.859083 waagent[1992]: 2025-04-30T12:49:44.858970Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Apr 30 12:49:44.859477 waagent[1992]: 2025-04-30T12:49:44.859143Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.1 Apr 30 12:49:44.859477 waagent[1992]: 2025-04-30T12:49:44.859223Z INFO ExtHandler ExtHandler Python: 3.11.11 Apr 30 12:49:44.960932 waagent[1992]: 2025-04-30T12:49:44.960771Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Apr 30 12:49:44.961098 waagent[1992]: 2025-04-30T12:49:44.961051Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:49:44.961185 waagent[1992]: 2025-04-30T12:49:44.961146Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:49:44.968601 waagent[1992]: 2025-04-30T12:49:44.968530Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Apr 30 12:49:44.973407 waagent[1992]: 2025-04-30T12:49:44.973358Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.166 Apr 30 12:49:44.973928 waagent[1992]: 2025-04-30T12:49:44.973877Z INFO ExtHandler Apr 30 12:49:44.974014 waagent[1992]: 2025-04-30T12:49:44.973975Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 19de99ab-30e6-4ddd-9c41-a2ee8671e323 eTag: 12658513366520383524 source: Fabric] Apr 30 12:49:44.974394 waagent[1992]: 2025-04-30T12:49:44.974343Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Apr 30 12:49:44.975227 waagent[1992]: 2025-04-30T12:49:44.975075Z INFO ExtHandler Apr 30 12:49:44.975389 waagent[1992]: 2025-04-30T12:49:44.975330Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Apr 30 12:49:44.979558 waagent[1992]: 2025-04-30T12:49:44.979509Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Apr 30 12:49:45.041352 waagent[1992]: 2025-04-30T12:49:45.041262Z INFO ExtHandler Downloaded certificate {'thumbprint': '84D67176ED6AB4C86A88E5B580056124619A1BB2', 'hasPrivateKey': True} Apr 30 12:49:45.042182 waagent[1992]: 2025-04-30T12:49:45.042126Z INFO ExtHandler Fetch goal state completed Apr 30 12:49:45.052877 waagent[1992]: 2025-04-30T12:49:45.052815Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1992 Apr 30 12:49:45.053026 waagent[1992]: 2025-04-30T12:49:45.052984Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Apr 30 12:49:45.054862 waagent[1992]: 2025-04-30T12:49:45.054811Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.1', '', 'Flatcar Container Linux by Kinvolk'] Apr 30 12:49:45.055232 waagent[1992]: 2025-04-30T12:49:45.055188Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Apr 30 12:49:45.137734 waagent[1992]: 2025-04-30T12:49:45.137662Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Apr 30 12:49:45.137950 waagent[1992]: 2025-04-30T12:49:45.137906Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Apr 30 12:49:45.144643 waagent[1992]: 2025-04-30T12:49:45.144597Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Apr 30 12:49:45.152059 systemd[1]: Reload requested from client PID 2005 ('systemctl') (unit waagent.service)... Apr 30 12:49:45.152075 systemd[1]: Reloading... Apr 30 12:49:45.210528 zram_generator::config[2045]: No configuration found. Apr 30 12:49:45.355171 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. Apr 30 12:49:45.517316 systemd[1]: Reloading finished in 364 ms. Apr 30 12:49:45.530313 waagent[1992]: 2025-04-30T12:49:45.530235Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Apr 30 12:49:45.536154 systemd[1]: Reload requested from client PID 2102 ('systemctl') (unit waagent.service)... Apr 30 12:49:45.536171 systemd[1]: Reloading... Apr 30 12:49:45.601528 zram_generator::config[2142]: No configuration found. Apr 30 12:49:45.736160 setup-nvidia[1839]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 480M 0 10665 0 0 16959 0 8:14:47 --:--:-- 8:14:47 16955\u000d 0 480M 0 2751k 0 0 1668k 0 0:04:54 0:00:01 0:04:53 1668k\u000d 4 480M 4 22.5M 0 0 9071k 0 0:00:54 0:00:02 0:00:52 9071k\u000d 9 480M 9 46.6M 0 0 12.9M 0 0:00:36 0:00:03 0:00:33 12.9M\u000d 14 480M 14 71.5M 0 0 15.8M 0 0:00:30 0:00:04 0:00:26 15.8M\u000d 19 480M 19 92.3M 0 0 16.6M 0 0:00:28 0:00:05 0:00:23 18.7M\u000d 22 480M 22 105M 0 0 15.9M 0 0:00:30 0:00:06 0:00:24 20.6M\u000d 26 480M 26 127M 0 0 16.8M 0 0:00:28 0:00:07 0:00:21 20.9M\u000d 31 480M 31 149M 0 0 17.4M 0 0:00:27 0:00:08 0:00:19 20.7M\u000d 36 480M 36 173M 0 0 18.0M 0 0:00:26 0:00:09 0:00:17 19.9M\u000d 40 480M 40 194M 0 0 18.5M 0 0:00:25 0:00:10 0:00:15 20.5M\u000d 44 480M 44 212M 0 0 17.1M 0 0:00:27 0:00:12 0:00:15 18.5M\u000d 45 480M 45 216M 0 0 17.2M 0 0:00:27 0:00:12 0:00:15 17.9M\u000d 46 480M 46 221M 0 0 16.3M 0 0:00:29 0:00:13 0:00:16 14.3M\u000d 51 480M 51 246M 0 0 16.9M 0 0:00:28 0:00:14 0:00:14 14.8M\u000d 55 480M 55 265M 0 0 17.0M 0 0:00:28 0:00:15 0:00:13 14.0M\u000d 60 480M 60 288M 0 0 17.4M 0 0:00:27 0:00:16 0:00:11 18.1M\u000d 65 480M 65 313M 0 0 17.7M 0 0:00:27 0:00:17 0:00:10 18.9M\u000d 69 480M 69 331M 0 0 17.9M 0 0:00:26 0:00:18 0:00:08 22.3M\u000d 73 480M 73 354M 0 0 18.1M 0 0:00:26 0:00:19 0:00:07 21.3M\u000d 78 480M 78 375M 0 0 18.2M 0 0:00:26 0:00:20 0:00:06 21.8M\u000d 82 480M 82 395M 0 0 18.3M 0 0:00:26 0:00:21 0:00:05 21.7M\u000d 87 480M 87 418M 0 0 18.5M 0 0:00:25 0:00:22 0:00:03 21.1M\u000d 92 480M 92 442M 0 0 18.7M 0 0:00:25 0:00:23 0:00:02 21.5M\u000d 96 480M 96 463M 0 0 18.8M 0 0:00:25 0:00:24 0:00:01 21.8M\u000d100 480M 100 480M 0 0 18.9M 0 0:00:25 0:00:25 --:--:-- 22.2M Apr 30 12:49:45.739014 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. Apr 30 12:49:45.906394 systemd[1]: Reloading finished in 369 ms. Apr 30 12:49:45.921569 waagent[1992]: 2025-04-30T12:49:45.920664Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Apr 30 12:49:45.921569 waagent[1992]: 2025-04-30T12:49:45.920831Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Apr 30 12:49:46.693469 waagent[1992]: 2025-04-30T12:49:46.693366Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Apr 30 12:49:46.694064 waagent[1992]: 2025-04-30T12:49:46.694002Z 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] Apr 30 12:49:46.695481 waagent[1992]: 2025-04-30T12:49:46.694899Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:49:46.695481 waagent[1992]: 2025-04-30T12:49:46.695012Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:49:46.695481 waagent[1992]: 2025-04-30T12:49:46.695215Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Apr 30 12:49:46.695481 waagent[1992]: 2025-04-30T12:49:46.695427Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Apr 30 12:49:46.695481 waagent[1992]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Apr 30 12:49:46.695481 waagent[1992]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Apr 30 12:49:46.695481 waagent[1992]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Apr 30 12:49:46.695481 waagent[1992]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:49:46.695481 waagent[1992]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:49:46.695481 waagent[1992]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:49:46.695757 waagent[1992]: 2025-04-30T12:49:46.695479Z INFO ExtHandler ExtHandler Starting env monitor service. Apr 30 12:49:46.696055 waagent[1992]: 2025-04-30T12:49:46.695980Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Apr 30 12:49:46.697510 waagent[1992]: 2025-04-30T12:49:46.696417Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Apr 30 12:49:46.697510 waagent[1992]: 2025-04-30T12:49:46.696563Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Apr 30 12:49:46.697510 waagent[1992]: 2025-04-30T12:49:46.697013Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Apr 30 12:49:46.697510 waagent[1992]: 2025-04-30T12:49:46.697077Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Apr 30 12:49:46.697510 waagent[1992]: 2025-04-30T12:49:46.697271Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Apr 30 12:49:46.699644 waagent[1992]: 2025-04-30T12:49:46.699583Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:49:46.700264 waagent[1992]: 2025-04-30T12:49:46.700210Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:49:46.700828 waagent[1992]: 2025-04-30T12:49:46.700766Z INFO EnvHandler ExtHandler Configure routes Apr 30 12:49:46.702361 waagent[1992]: 2025-04-30T12:49:46.702308Z INFO EnvHandler ExtHandler Gateway:None Apr 30 12:49:46.702924 waagent[1992]: 2025-04-30T12:49:46.702871Z INFO EnvHandler ExtHandler Routes:None Apr 30 12:49:46.704303 waagent[1992]: 2025-04-30T12:49:46.704245Z INFO ExtHandler ExtHandler Apr 30 12:49:46.704873 waagent[1992]: 2025-04-30T12:49:46.704797Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 7b0382bf-55ed-4299-b2e9-2c00480c4319 correlation 403fcb08-fe3a-415c-a768-8551fe7e2a83 created: 2025-04-30T12:47:39.979172Z] Apr 30 12:49:46.706373 waagent[1992]: 2025-04-30T12:49:46.706323Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Apr 30 12:49:46.706962 waagent[1992]: 2025-04-30T12:49:46.706917Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Apr 30 12:49:46.752228 waagent[1992]: 2025-04-30T12:49:46.752168Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 85B4A1D0-218F-42B2-9CB1-AFF084242B57;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Apr 30 12:49:46.804161 waagent[1992]: 2025-04-30T12:49:46.804071Z INFO MonitorHandler ExtHandler Network interfaces: Apr 30 12:49:46.804161 waagent[1992]: Executing ['ip', '-a', '-o', 'link']: Apr 30 12:49:46.804161 waagent[1992]: 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 Apr 30 12:49:46.804161 waagent[1992]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:38:c1:03 brd ff:ff:ff:ff:ff:ff Apr 30 12:49:46.804161 waagent[1992]: Executing ['ip', '-4', '-a', '-o', 'address']: Apr 30 12:49:46.804161 waagent[1992]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Apr 30 12:49:46.804161 waagent[1992]: 2: eth0 inet 10.200.4.32/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Apr 30 12:49:46.804161 waagent[1992]: Executing ['ip', '-6', '-a', '-o', 'address']: Apr 30 12:49:46.804161 waagent[1992]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Apr 30 12:49:46.804161 waagent[1992]: 2: eth0 inet6 fe80::7eed:8dff:fe38:c103/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Apr 30 12:49:47.009405 waagent[1992]: 2025-04-30T12:49:47.008350Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Apr 30 12:49:47.009405 waagent[1992]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.009405 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.009405 waagent[1992]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.009405 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.009405 waagent[1992]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.009405 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.009405 waagent[1992]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 30 12:49:47.009405 waagent[1992]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 30 12:49:47.009405 waagent[1992]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 30 12:49:47.012894 waagent[1992]: 2025-04-30T12:49:47.012843Z INFO EnvHandler ExtHandler Current Firewall rules: Apr 30 12:49:47.012894 waagent[1992]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.012894 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.012894 waagent[1992]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.012894 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.012894 waagent[1992]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:49:47.012894 waagent[1992]: pkts bytes target prot opt in out source destination Apr 30 12:49:47.012894 waagent[1992]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 30 12:49:47.012894 waagent[1992]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 30 12:49:47.012894 waagent[1992]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 30 12:49:47.013447 waagent[1992]: 2025-04-30T12:49:47.013407Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Apr 30 12:49:47.013913 waagent[1992]: 2025-04-30T12:49:47.013717Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Apr 30 12:49:56.648280 setup-nvidia[1683]: Downloading NVIDIA 535.230.02 Driver Apr 30 12:49:56.652809 setup-nvidia[2244]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 12:49:56.653039 setup-nvidia[2244]: Dload Upload Total Spent Left Speed Apr 30 12:49:57.346295 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 12:49:57.355774 systemd[1]: Started sshd@0-10.200.4.32:22-10.200.16.10:39022.service - OpenSSH per-connection server daemon (10.200.16.10:39022). Apr 30 12:49:58.106948 sshd[2246]: Accepted publickey for core from 10.200.16.10 port 39022 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:49:58.108395 sshd-session[2246]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:49:58.112645 systemd-logind[1689]: New session 3 of user core. Apr 30 12:49:58.121621 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 12:49:58.621825 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Apr 30 12:49:58.662221 systemd[1]: Started sshd@1-10.200.4.32:22-10.200.16.10:39034.service - OpenSSH per-connection server daemon (10.200.16.10:39034). Apr 30 12:49:59.260425 sshd[2251]: Accepted publickey for core from 10.200.16.10 port 39034 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:49:59.261828 sshd-session[2251]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:49:59.266171 systemd-logind[1689]: New session 4 of user core. Apr 30 12:49:59.281627 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 12:49:59.687669 sshd[2253]: Connection closed by 10.200.16.10 port 39034 Apr 30 12:49:59.688105 sshd-session[2251]: pam_unix(sshd:session): session closed for user core Apr 30 12:49:59.691564 systemd[1]: sshd@1-10.200.4.32:22-10.200.16.10:39034.service: Deactivated successfully. Apr 30 12:49:59.693256 systemd[1]: session-4.scope: Deactivated successfully. Apr 30 12:49:59.693934 systemd-logind[1689]: Session 4 logged out. Waiting for processes to exit. Apr 30 12:49:59.694738 systemd-logind[1689]: Removed session 4. Apr 30 12:49:59.794210 systemd[1]: Started sshd@2-10.200.4.32:22-10.200.16.10:57824.service - OpenSSH per-connection server daemon (10.200.16.10:57824). Apr 30 12:50:00.399168 sshd[2259]: Accepted publickey for core from 10.200.16.10 port 57824 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:00.400675 sshd-session[2259]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:00.404975 systemd-logind[1689]: New session 5 of user core. Apr 30 12:50:00.422656 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 12:50:00.850787 sshd[2261]: Connection closed by 10.200.16.10 port 57824 Apr 30 12:50:00.851133 sshd-session[2259]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:00.855558 systemd[1]: sshd@2-10.200.4.32:22-10.200.16.10:57824.service: Deactivated successfully. Apr 30 12:50:00.857275 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 12:50:00.858043 systemd-logind[1689]: Session 5 logged out. Waiting for processes to exit. Apr 30 12:50:00.858926 systemd-logind[1689]: Removed session 5. Apr 30 12:50:01.183852 setup-nvidia[2244]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 1269 0 0 2872 0 33:04:07 --:--:-- 33:04:07 2871\u000d 13 326M 13 44.1M 0 0 33.6M 0 0:00:09 0:00:01 0:00:08 33.6M\u000d 37 326M 37 122M 0 0 52.8M 0 0:00:06 0:00:02 0:00:04 52.7M\u000d 66 326M 66 216M 0 0 65.1M 0 0:00:05 0:00:03 0:00:02 65.1M\u000d 94 326M 94 309M 0 0 71.8M 0 0:00:04 0:00:04 --:--:-- 71.8M\u000d100 326M 100 326M 0 0 71.9M 0 0:00:04 0:00:04 --:--:-- 79.7M Apr 30 12:50:01.331746 setup-nvidia[1683]: Extract the NVIDIA Driver Installer 535.230.02 Apr 30 12:50:01.331746 setup-nvidia[1683]: /opt/nvidia/workdir/nvidia-workdir / Apr 30 12:50:01.352820 setup-nvidia[2268]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Apr 30 12:50:01.647418 setup-nvidia[2268]: Verifying archive integrity... OK Apr 30 12:50:02.472925 setup-nvidia[2268]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Apr 30 12:50:04.352357 setup-nvidia[2294]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Apr 30 12:50:04.435549 setup-nvidia[1683]: / Apr 30 12:50:04.435864 setup-nvidia[1683]: Spawn system-nspawn container to install the NVIDIA drivers Apr 30 12:50:04.693104 update_engine[1692]: I20250430 12:50:04.693029 1692 update_attempter.cc:509] Updating boot flags... Apr 30 12:50:04.738523 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (2317) Apr 30 12:50:04.756670 sudo[2301]: 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.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Apr 30 12:50:04.758819 sudo[2301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 30 12:50:04.787766 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Apr 30 12:50:04.800739 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Apr 30 12:50:04.811827 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Apr 30 12:50:04.817663 systemd[1]: Starting user@0.service - User Manager for UID 0... Apr 30 12:50:04.824117 (systemd)[2372]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Apr 30 12:50:04.828629 systemd-logind[1689]: New session c3 of user root. Apr 30 12:50:04.830518 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (2316) Apr 30 12:50:04.891038 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (2316) Apr 30 12:50:05.006572 systemd[2372]: Queued start job for default target default.target. Apr 30 12:50:05.042940 systemd[2372]: Created slice app.slice - User Application Slice. Apr 30 12:50:05.043111 systemd[2372]: Reached target paths.target - Paths. Apr 30 12:50:05.043165 systemd[2372]: Reached target timers.target - Timers. Apr 30 12:50:05.059601 systemd[2372]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 12:50:05.066170 systemd[2372]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:50:05.066241 systemd[2372]: Reached target sockets.target - Sockets. Apr 30 12:50:05.066287 systemd[2372]: Reached target basic.target - Basic System. Apr 30 12:50:05.066333 systemd[2372]: Reached target default.target - Main User Target. Apr 30 12:50:05.066369 systemd[2372]: Startup finished in 231ms. Apr 30 12:50:05.066610 systemd[1]: Started user@0.service - User Manager for UID 0. Apr 30 12:50:05.069568 systemd[1]: Started session-c2.scope - Session c2 of User root. Apr 30 12:50:05.195518 kernel: loop6: detected capacity change from 0 to 12644352 Apr 30 12:50:05.237519 kernel: loop6: p9 Apr 30 12:50:05.400163 setup-nvidia[2478]: ░ Spawning container flatcardevelopercontainer-4230.1.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.1.1.bin. Apr 30 12:50:05.440521 kernel: EXT4-fs (loop6p9): mounted filesystem 274cd76a-6c6d-4a84-abd5-d946fe9a9770 ro without journal. Quota mode: none. Apr 30 12:50:10.962718 systemd[1]: Started sshd@3-10.200.4.32:22-10.200.16.10:39400.service - OpenSSH per-connection server daemon (10.200.16.10:39400). Apr 30 12:50:11.560943 sshd[2579]: Accepted publickey for core from 10.200.16.10 port 39400 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:11.562352 sshd-session[2579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:11.566572 systemd-logind[1689]: New session 6 of user core. Apr 30 12:50:11.584612 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 30 12:50:11.993482 sshd[2595]: Connection closed by 10.200.16.10 port 39400 Apr 30 12:50:11.993904 sshd-session[2579]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:11.997329 systemd[1]: sshd@3-10.200.4.32:22-10.200.16.10:39400.service: Deactivated successfully. Apr 30 12:50:11.999000 systemd[1]: session-6.scope: Deactivated successfully. Apr 30 12:50:11.999644 systemd-logind[1689]: Session 6 logged out. Waiting for processes to exit. Apr 30 12:50:12.000413 systemd-logind[1689]: Removed session 6. Apr 30 12:50:12.106136 systemd[1]: Started sshd@4-10.200.4.32:22-10.200.16.10:39416.service - OpenSSH per-connection server daemon (10.200.16.10:39416). Apr 30 12:50:12.703028 sshd[2664]: Accepted publickey for core from 10.200.16.10 port 39416 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:12.704431 sshd-session[2664]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:12.708994 systemd-logind[1689]: New session 7 of user core. Apr 30 12:50:12.720694 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 30 12:50:13.134995 sshd[2816]: Connection closed by 10.200.16.10 port 39416 Apr 30 12:50:13.135383 sshd-session[2664]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:13.138775 systemd[1]: sshd@4-10.200.4.32:22-10.200.16.10:39416.service: Deactivated successfully. Apr 30 12:50:13.140431 systemd[1]: session-7.scope: Deactivated successfully. Apr 30 12:50:13.141095 systemd-logind[1689]: Session 7 logged out. Waiting for processes to exit. Apr 30 12:50:13.142007 systemd-logind[1689]: Removed session 7. Apr 30 12:50:23.242158 systemd[1]: Started sshd@5-10.200.4.32:22-10.200.16.10:49566.service - OpenSSH per-connection server daemon (10.200.16.10:49566). Apr 30 12:50:23.847960 sshd[3517]: Accepted publickey for core from 10.200.16.10 port 49566 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:23.849913 sshd-session[3517]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:23.854293 systemd-logind[1689]: New session 8 of user core. Apr 30 12:50:23.865631 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 30 12:50:24.277630 sshd[3519]: Connection closed by 10.200.16.10 port 49566 Apr 30 12:50:24.278076 sshd-session[3517]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:24.281689 systemd[1]: sshd@5-10.200.4.32:22-10.200.16.10:49566.service: Deactivated successfully. Apr 30 12:50:24.283448 systemd[1]: session-8.scope: Deactivated successfully. Apr 30 12:50:24.284115 systemd-logind[1689]: Session 8 logged out. Waiting for processes to exit. Apr 30 12:50:24.285032 systemd-logind[1689]: Removed session 8. Apr 30 12:50:24.384192 systemd[1]: Started sshd@6-10.200.4.32:22-10.200.16.10:49576.service - OpenSSH per-connection server daemon (10.200.16.10:49576). Apr 30 12:50:24.982734 sshd[3525]: Accepted publickey for core from 10.200.16.10 port 49576 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:24.985017 sshd-session[3525]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:24.989372 systemd-logind[1689]: New session 9 of user core. Apr 30 12:50:25.000621 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 30 12:50:25.416164 sshd[3527]: Connection closed by 10.200.16.10 port 49576 Apr 30 12:50:25.416607 sshd-session[3525]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:25.420001 systemd[1]: sshd@6-10.200.4.32:22-10.200.16.10:49576.service: Deactivated successfully. Apr 30 12:50:25.421669 systemd[1]: session-9.scope: Deactivated successfully. Apr 30 12:50:25.422304 systemd-logind[1689]: Session 9 logged out. Waiting for processes to exit. Apr 30 12:50:25.423137 systemd-logind[1689]: Removed session 9. Apr 30 12:50:35.535407 systemd[1]: Started sshd@7-10.200.4.32:22-10.200.16.10:34998.service - OpenSSH per-connection server daemon (10.200.16.10:34998). Apr 30 12:50:36.141106 sshd[5553]: Accepted publickey for core from 10.200.16.10 port 34998 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:36.142632 sshd-session[5553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:36.146947 systemd-logind[1689]: New session 10 of user core. Apr 30 12:50:36.165674 systemd[1]: Started session-10.scope - Session 10 of User core. Apr 30 12:50:36.604485 sshd[5689]: Connection closed by 10.200.16.10 port 34998 Apr 30 12:50:36.606191 sshd-session[5553]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:36.612508 systemd-logind[1689]: Session 10 logged out. Waiting for processes to exit. Apr 30 12:50:36.613512 systemd[1]: sshd@7-10.200.4.32:22-10.200.16.10:34998.service: Deactivated successfully. Apr 30 12:50:36.616023 systemd[1]: session-10.scope: Deactivated successfully. Apr 30 12:50:36.617388 systemd-logind[1689]: Removed session 10. Apr 30 12:50:36.725042 systemd[1]: Started sshd@8-10.200.4.32:22-10.200.16.10:35014.service - OpenSSH per-connection server daemon (10.200.16.10:35014). Apr 30 12:50:37.337454 sshd[5785]: Accepted publickey for core from 10.200.16.10 port 35014 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:37.339369 sshd-session[5785]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:37.345003 systemd-logind[1689]: New session 11 of user core. Apr 30 12:50:37.359693 systemd[1]: Started session-11.scope - Session 11 of User core. Apr 30 12:50:37.796648 sshd[5972]: Connection closed by 10.200.16.10 port 35014 Apr 30 12:50:37.797242 sshd-session[5785]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:37.800603 systemd[1]: sshd@8-10.200.4.32:22-10.200.16.10:35014.service: Deactivated successfully. Apr 30 12:50:37.803695 systemd[1]: session-11.scope: Deactivated successfully. Apr 30 12:50:37.804795 systemd-logind[1689]: Session 11 logged out. Waiting for processes to exit. Apr 30 12:50:37.807029 systemd-logind[1689]: Removed session 11. Apr 30 12:50:47.916000 systemd[1]: Started sshd@9-10.200.4.32:22-10.200.16.10:55292.service - OpenSSH per-connection server daemon (10.200.16.10:55292). Apr 30 12:50:48.676047 sshd[9186]: Accepted publickey for core from 10.200.16.10 port 55292 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:48.677622 sshd-session[9186]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:48.686232 systemd-logind[1689]: New session 12 of user core. Apr 30 12:50:48.693215 systemd[1]: Started session-12.scope - Session 12 of User core. Apr 30 12:50:49.134247 sshd[9375]: Connection closed by 10.200.16.10 port 55292 Apr 30 12:50:49.134926 sshd-session[9186]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:49.138774 systemd[1]: sshd@9-10.200.4.32:22-10.200.16.10:55292.service: Deactivated successfully. Apr 30 12:50:49.139428 systemd-logind[1689]: Session 12 logged out. Waiting for processes to exit. Apr 30 12:50:49.142040 systemd[1]: session-12.scope: Deactivated successfully. Apr 30 12:50:49.144668 systemd-logind[1689]: Removed session 12. Apr 30 12:50:49.249801 systemd[1]: Started sshd@10-10.200.4.32:22-10.200.16.10:58720.service - OpenSSH per-connection server daemon (10.200.16.10:58720). Apr 30 12:50:49.928169 sshd[9564]: Accepted publickey for core from 10.200.16.10 port 58720 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:49.930189 sshd-session[9564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:49.939054 systemd-logind[1689]: New session 13 of user core. Apr 30 12:50:49.948039 systemd[1]: Started session-13.scope - Session 13 of User core. Apr 30 12:50:50.408742 sshd[9782]: Connection closed by 10.200.16.10 port 58720 Apr 30 12:50:50.410967 sshd-session[9564]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:50.413989 systemd-logind[1689]: Session 13 logged out. Waiting for processes to exit. Apr 30 12:50:50.415835 systemd[1]: sshd@10-10.200.4.32:22-10.200.16.10:58720.service: Deactivated successfully. Apr 30 12:50:50.417842 systemd[1]: session-13.scope: Deactivated successfully. Apr 30 12:50:50.421383 systemd-logind[1689]: Removed session 13. Apr 30 12:51:00.518363 systemd[1]: Started sshd@11-10.200.4.32:22-10.200.16.10:33820.service - OpenSSH per-connection server daemon (10.200.16.10:33820). Apr 30 12:51:01.125817 sshd[11743]: Accepted publickey for core from 10.200.16.10 port 33820 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:01.127827 sshd-session[11743]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:01.134867 systemd-logind[1689]: New session 14 of user core. Apr 30 12:51:01.143297 systemd[1]: Started session-14.scope - Session 14 of User core. Apr 30 12:51:01.584778 sshd[11793]: Connection closed by 10.200.16.10 port 33820 Apr 30 12:51:01.586443 sshd-session[11743]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:01.592885 systemd[1]: sshd@11-10.200.4.32:22-10.200.16.10:33820.service: Deactivated successfully. Apr 30 12:51:01.594840 systemd[1]: session-14.scope: Deactivated successfully. Apr 30 12:51:01.595884 systemd-logind[1689]: Session 14 logged out. Waiting for processes to exit. Apr 30 12:51:01.598007 systemd-logind[1689]: Removed session 14. Apr 30 12:51:01.691847 systemd[1]: Started sshd@12-10.200.4.32:22-10.200.16.10:33828.service - OpenSSH per-connection server daemon (10.200.16.10:33828). Apr 30 12:51:02.302224 sshd[11850]: Accepted publickey for core from 10.200.16.10 port 33828 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:02.304202 sshd-session[11850]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:02.310751 systemd-logind[1689]: New session 15 of user core. Apr 30 12:51:02.314720 systemd[1]: Started session-15.scope - Session 15 of User core. Apr 30 12:51:02.739511 sshd[11875]: Connection closed by 10.200.16.10 port 33828 Apr 30 12:51:02.740401 sshd-session[11850]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:02.743567 systemd-logind[1689]: Session 15 logged out. Waiting for processes to exit. Apr 30 12:51:02.744421 systemd[1]: sshd@12-10.200.4.32:22-10.200.16.10:33828.service: Deactivated successfully. Apr 30 12:51:02.747158 systemd[1]: session-15.scope: Deactivated successfully. Apr 30 12:51:02.749667 systemd-logind[1689]: Removed session 15. Apr 30 12:51:12.858760 systemd[1]: Started sshd@13-10.200.4.32:22-10.200.16.10:41100.service - OpenSSH per-connection server daemon (10.200.16.10:41100). Apr 30 12:51:13.466365 sshd[12168]: Accepted publickey for core from 10.200.16.10 port 41100 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:13.467448 sshd-session[12168]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:13.474032 systemd-logind[1689]: New session 16 of user core. Apr 30 12:51:13.484122 systemd[1]: Started session-16.scope - Session 16 of User core. Apr 30 12:51:13.901347 sshd[12193]: Connection closed by 10.200.16.10 port 41100 Apr 30 12:51:13.903729 sshd-session[12168]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:13.907385 systemd[1]: sshd@13-10.200.4.32:22-10.200.16.10:41100.service: Deactivated successfully. Apr 30 12:51:13.909718 systemd[1]: session-16.scope: Deactivated successfully. Apr 30 12:51:13.910805 systemd-logind[1689]: Session 16 logged out. Waiting for processes to exit. Apr 30 12:51:13.912136 systemd-logind[1689]: Removed session 16. Apr 30 12:51:14.012788 systemd[1]: Started sshd@14-10.200.4.32:22-10.200.16.10:41110.service - OpenSSH per-connection server daemon (10.200.16.10:41110). Apr 30 12:51:14.623101 sshd[12217]: Accepted publickey for core from 10.200.16.10 port 41110 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:14.626152 sshd-session[12217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:14.632947 systemd-logind[1689]: New session 17 of user core. Apr 30 12:51:14.641107 systemd[1]: Started session-17.scope - Session 17 of User core. Apr 30 12:51:15.081297 sshd[12237]: Connection closed by 10.200.16.10 port 41110 Apr 30 12:51:15.081673 sshd-session[12217]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:15.084732 systemd-logind[1689]: Session 17 logged out. Waiting for processes to exit. Apr 30 12:51:15.087669 systemd[1]: sshd@14-10.200.4.32:22-10.200.16.10:41110.service: Deactivated successfully. Apr 30 12:51:15.089330 systemd[1]: session-17.scope: Deactivated successfully. Apr 30 12:51:15.092409 systemd-logind[1689]: Removed session 17. Apr 30 12:51:25.191103 systemd[1]: Started sshd@15-10.200.4.32:22-10.200.16.10:43482.service - OpenSSH per-connection server daemon (10.200.16.10:43482). Apr 30 12:51:25.796181 sshd[12537]: Accepted publickey for core from 10.200.16.10 port 43482 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:25.798228 sshd-session[12537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:25.805548 systemd-logind[1689]: New session 18 of user core. Apr 30 12:51:25.813767 systemd[1]: Started session-18.scope - Session 18 of User core. Apr 30 12:51:26.230443 sshd[12557]: Connection closed by 10.200.16.10 port 43482 Apr 30 12:51:26.231330 sshd-session[12537]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:26.236031 systemd[1]: sshd@15-10.200.4.32:22-10.200.16.10:43482.service: Deactivated successfully. Apr 30 12:51:26.237964 systemd[1]: session-18.scope: Deactivated successfully. Apr 30 12:51:26.238695 systemd-logind[1689]: Session 18 logged out. Waiting for processes to exit. Apr 30 12:51:26.240479 systemd-logind[1689]: Removed session 18. Apr 30 12:51:26.348784 systemd[1]: Started sshd@16-10.200.4.32:22-10.200.16.10:43492.service - OpenSSH per-connection server daemon (10.200.16.10:43492). Apr 30 12:51:26.970212 sshd[12575]: Accepted publickey for core from 10.200.16.10 port 43492 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:26.971789 sshd-session[12575]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:26.980451 systemd-logind[1689]: New session 19 of user core. Apr 30 12:51:26.991202 systemd[1]: Started session-19.scope - Session 19 of User core. Apr 30 12:51:27.426965 sshd[12589]: Connection closed by 10.200.16.10 port 43492 Apr 30 12:51:27.427852 sshd-session[12575]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:27.431678 systemd[1]: sshd@16-10.200.4.32:22-10.200.16.10:43492.service: Deactivated successfully. Apr 30 12:51:27.434973 systemd[1]: session-19.scope: Deactivated successfully. Apr 30 12:51:27.439737 systemd-logind[1689]: Session 19 logged out. Waiting for processes to exit. Apr 30 12:51:27.441129 systemd-logind[1689]: Removed session 19. Apr 30 12:51:37.534574 systemd[1]: Started sshd@17-10.200.4.32:22-10.200.16.10:34340.service - OpenSSH per-connection server daemon (10.200.16.10:34340). Apr 30 12:51:38.136179 sshd[12824]: Accepted publickey for core from 10.200.16.10 port 34340 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:38.138285 sshd-session[12824]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:38.142799 systemd-logind[1689]: New session 20 of user core. Apr 30 12:51:38.154620 systemd[1]: Started session-20.scope - Session 20 of User core. Apr 30 12:51:38.563731 sshd[12826]: Connection closed by 10.200.16.10 port 34340 Apr 30 12:51:38.564073 sshd-session[12824]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:38.568034 systemd-logind[1689]: Session 20 logged out. Waiting for processes to exit. Apr 30 12:51:38.568999 systemd[1]: sshd@17-10.200.4.32:22-10.200.16.10:34340.service: Deactivated successfully. Apr 30 12:51:38.571702 systemd[1]: session-20.scope: Deactivated successfully. Apr 30 12:51:38.574275 systemd-logind[1689]: Removed session 20. Apr 30 12:51:38.703845 systemd[1]: Started sshd@18-10.200.4.32:22-10.200.16.10:34354.service - OpenSSH per-connection server daemon (10.200.16.10:34354). Apr 30 12:51:39.301631 sshd[12855]: Accepted publickey for core from 10.200.16.10 port 34354 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:39.303142 sshd-session[12855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:39.308176 systemd-logind[1689]: New session 21 of user core. Apr 30 12:51:39.318728 systemd[1]: Started session-21.scope - Session 21 of User core. Apr 30 12:51:39.724040 setup-nvidia[2478]: \u000d Apr 30 12:51:39.724040 setup-nvidia[2478]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Apr 30 12:51:39.724040 setup-nvidia[2478]: frequently when this kernel module was built against the wrong or Apr 30 12:51:39.724040 setup-nvidia[2478]: improperly configured kernel sources, with a version of gcc that differs Apr 30 12:51:39.724040 setup-nvidia[2478]: from the one used to build the target kernel, or if another driver, such Apr 30 12:51:39.724040 setup-nvidia[2478]: as nouveau, is present and prevents the NVIDIA kernel module from Apr 30 12:51:39.724040 setup-nvidia[2478]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Apr 30 12:51:39.724040 setup-nvidia[2478]: installed in this system is supported by this NVIDIA Linux graphics Apr 30 12:51:39.724040 setup-nvidia[2478]: driver release. Apr 30 12:51:39.724040 setup-nvidia[2478]: \u000d Apr 30 12:51:39.724040 setup-nvidia[2478]: Please see the log entries 'Kernel module load error' and 'Kernel Apr 30 12:51:39.724040 setup-nvidia[2478]: messages' at the end of the file Apr 30 12:51:39.724040 setup-nvidia[2478]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Apr 30 12:51:39.724040 setup-nvidia[2478]: information. Apr 30 12:51:39.724040 setup-nvidia[2478]: \u000d Apr 30 12:51:39.735169 sshd[12901]: Connection closed by 10.200.16.10 port 34354 Apr 30 12:51:39.735579 sshd-session[12855]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:39.738810 systemd[1]: sshd@18-10.200.4.32:22-10.200.16.10:34354.service: Deactivated successfully. Apr 30 12:51:39.739044 setup-nvidia[2478]: dmesg: read kernel buffer failed: Operation not permitted Apr 30 12:51:39.740417 systemd[1]: session-21.scope: Deactivated successfully. Apr 30 12:51:39.741163 systemd-logind[1689]: Session 21 logged out. Waiting for processes to exit. Apr 30 12:51:39.742068 systemd-logind[1689]: Removed session 21. Apr 30 12:51:39.761089 setup-nvidia[2478]: \u000d Apr 30 12:51:39.761089 setup-nvidia[2478]: ERROR: Installation has failed. Please see the file Apr 30 12:51:39.761089 setup-nvidia[2478]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Apr 30 12:51:39.761089 setup-nvidia[2478]: details. You may find suggestions on fixing installation problems in Apr 30 12:51:39.761089 setup-nvidia[2478]: the README available on the Linux driver download page at Apr 30 12:51:39.761089 setup-nvidia[2478]: www.nvidia.com. Apr 30 12:51:39.761089 setup-nvidia[2478]: \u000d Apr 30 12:51:39.761405 setup-nvidia[2478]: Last 50 lines of nvidia-installer.log: Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Apr 30 12:51:39.762278 setup-nvidia[2478]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Apr 30 12:51:39.762278 setup-nvidia[2478]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Apr 30 12:51:39.762278 setup-nvidia[2478]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Apr 30 12:51:39.762278 setup-nvidia[2478]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Apr 30 12:51:39.762278 setup-nvidia[2478]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Apr 30 12:51:39.762278 setup-nvidia[2478]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Apr 30 12:51:39.762278 setup-nvidia[2478]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Apr 30 12:51:39.762278 setup-nvidia[2478]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Apr 30 12:51:39.762278 setup-nvidia[2478]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Apr 30 12:51:39.762278 setup-nvidia[2478]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Apr 30 12:51:39.762278 setup-nvidia[2478]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Apr 30 12:51:39.762278 setup-nvidia[2478]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Apr 30 12:51:39.762278 setup-nvidia[2478]: make[2]: Leaving directory '/usr/lib/modules/6.6.88-flatcar/build' Apr 30 12:51:39.762278 setup-nvidia[2478]: make[1]: Leaving directory '/usr/lib/modules/6.6.88-flatcar/source' Apr 30 12:51:39.762278 setup-nvidia[2478]: -> done. Apr 30 12:51:39.762278 setup-nvidia[2478]: -> Kernel module compilation complete. Apr 30 12:51:39.762278 setup-nvidia[2478]: -> Unable to determine if Secure Boot is enabled: No such file or directory Apr 30 12:51:39.762278 setup-nvidia[2478]: 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. Apr 30 12:51:39.762278 setup-nvidia[2478]: \u000d Apr 30 12:51:39.763371 setup-nvidia[2478]: 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. Apr 30 12:51:39.763371 setup-nvidia[2478]: -> Kernel module load error: Operation not permitted Apr 30 12:51:39.763371 setup-nvidia[2478]: -> Kernel messages: Apr 30 12:51:39.763371 setup-nvidia[2478]: 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. Apr 30 12:51:49.841895 systemd[1]: Started sshd@19-10.200.4.32:22-10.200.16.10:33942.service - OpenSSH per-connection server daemon (10.200.16.10:33942). Apr 30 12:51:50.444163 sshd[13003]: Accepted publickey for core from 10.200.16.10 port 33942 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:50.445750 sshd-session[13003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:50.450003 systemd-logind[1689]: New session 22 of user core. Apr 30 12:51:50.465641 systemd[1]: Started session-22.scope - Session 22 of User core. Apr 30 12:51:50.896721 sshd[13005]: Connection closed by 10.200.16.10 port 33942 Apr 30 12:51:50.897199 sshd-session[13003]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:50.900670 systemd[1]: sshd@19-10.200.4.32:22-10.200.16.10:33942.service: Deactivated successfully. Apr 30 12:51:50.902440 systemd[1]: session-22.scope: Deactivated successfully. Apr 30 12:51:50.903319 systemd-logind[1689]: Session 22 logged out. Waiting for processes to exit. Apr 30 12:51:50.904132 systemd-logind[1689]: Removed session 22. Apr 30 12:51:51.009643 systemd[1]: Started sshd@20-10.200.4.32:22-10.200.16.10:33958.service - OpenSSH per-connection server daemon (10.200.16.10:33958). Apr 30 12:51:51.614073 sshd[13011]: Accepted publickey for core from 10.200.16.10 port 33958 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:51.615573 sshd-session[13011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:51.620530 systemd-logind[1689]: New session 23 of user core. Apr 30 12:51:51.638634 systemd[1]: Started session-23.scope - Session 23 of User core. Apr 30 12:51:52.067746 sshd[13013]: Connection closed by 10.200.16.10 port 33958 Apr 30 12:51:52.069508 sshd-session[13011]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:52.072436 systemd[1]: sshd@20-10.200.4.32:22-10.200.16.10:33958.service: Deactivated successfully. Apr 30 12:51:52.074339 systemd[1]: session-23.scope: Deactivated successfully. Apr 30 12:51:52.075674 systemd-logind[1689]: Session 23 logged out. Waiting for processes to exit. Apr 30 12:51:52.076486 systemd-logind[1689]: Removed session 23. Apr 30 12:51:58.353541 kernel: EXT4-fs (loop6p9): unmounting filesystem 274cd76a-6c6d-4a84-abd5-d946fe9a9770. Apr 30 12:51:58.353698 setup-nvidia[2478]: Container flatcardevelopercontainer-4230.1.1.bin exited successfully. Apr 30 12:51:58.356162 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.1.1.bin.mount: Deactivated successfully. Apr 30 12:51:58.420409 sudo[2301]: pam_unix(sudo:session): session closed for user root Apr 30 12:51:58.422262 systemd[1]: session-c2.scope: Deactivated successfully. Apr 30 12:51:58.422566 systemd[1]: session-c2.scope: Consumed 5min 25.740s CPU time, 882.7M memory peak. Apr 30 12:51:58.869511 setup-nvidia[1683]: /opt/nvidia / Apr 30 12:51:58.870557 setup-nvidia[1683]: / Apr 30 12:51:58.887138 setup-nvidia[13036]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 12:51:58.913520 kernel: IPMI message handler: version 39.2 Apr 30 12:51:58.916514 kernel: ipmi device interface Apr 30 12:51:58.917401 setup-nvidia[1683]: /opt/nvidia/current/usr/lib/modules/6.6.88-flatcar/video / Apr 30 12:51:58.981689 kernel: nvidia: loading out-of-tree module taints kernel. Apr 30 12:51:58.981838 kernel: nvidia: module license 'NVIDIA' taints kernel. Apr 30 12:51:58.981860 kernel: Disabling lock debugging due to kernel taint Apr 30 12:51:58.989017 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Apr 30 12:51:58.989058 kernel: nvidia: module license taints kernel. Apr 30 12:51:59.377516 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Apr 30 12:51:59.884345 kernel: Apr 30 12:51:59.884470 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Apr 30 12:51:59.930135 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Apr 30 12:51:59.956514 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Apr 30 12:51:59.978536 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Apr 30 12:52:00.024520 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Apr 30 12:52:00.024617 setup-nvidia[1683]: / Apr 30 12:52:01.620764 setup-nvidia[13066]: Wed Apr 30 12:52:01 2025 Apr 30 12:52:01.620764 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:01.620764 setup-nvidia[13066]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Apr 30 12:52:01.620764 setup-nvidia[13066]: |-----------------------------------------+----------------------+----------------------+ Apr 30 12:52:01.620764 setup-nvidia[13066]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Apr 30 12:52:01.620764 setup-nvidia[13066]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Apr 30 12:52:01.620764 setup-nvidia[13066]: | | | MIG M. | Apr 30 12:52:01.620764 setup-nvidia[13066]: |=========================================+======================+======================| Apr 30 12:52:01.624250 setup-nvidia[13066]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Apr 30 12:52:01.624250 setup-nvidia[13066]: | N/A 29C P0 36W / 250W | 0MiB / 16384MiB | 1% Default | Apr 30 12:52:01.624250 setup-nvidia[13066]: | | | N/A | Apr 30 12:52:01.624250 setup-nvidia[13066]: +-----------------------------------------+----------------------+----------------------+ Apr 30 12:52:01.624366 setup-nvidia[13066]: Apr 30 12:52:01.624366 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:01.624366 setup-nvidia[13066]: | Processes: | Apr 30 12:52:01.624366 setup-nvidia[13066]: | GPU GI CI PID Type Process name GPU Memory | Apr 30 12:52:01.624366 setup-nvidia[13066]: | ID ID Usage | Apr 30 12:52:01.624366 setup-nvidia[13066]: |=======================================================================================| Apr 30 12:52:01.624534 setup-nvidia[13066]: | No running processes found | Apr 30 12:52:01.624534 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:01.998434 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 12:52:02.000579 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 12:52:02.000900 systemd[1]: Startup finished in 1.237s (firmware) + 32.322s (loader) + 1.969s (kernel) + 13.787s (initrd) + 3min 12.555s (userspace) = 4min 1.871s. Apr 30 12:52:02.175203 systemd[1]: Started sshd@21-10.200.4.32:22-10.200.16.10:52964.service - OpenSSH per-connection server daemon (10.200.16.10:52964). Apr 30 12:52:02.772676 sshd[13073]: Accepted publickey for core from 10.200.16.10 port 52964 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:02.774162 sshd-session[13073]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:02.778524 systemd-logind[1689]: New session 24 of user core. Apr 30 12:52:02.786656 systemd[1]: Started session-24.scope - Session 24 of User core. Apr 30 12:52:03.200106 sshd[13075]: Connection closed by 10.200.16.10 port 52964 Apr 30 12:52:03.200604 sshd-session[13073]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:03.204097 systemd[1]: sshd@21-10.200.4.32:22-10.200.16.10:52964.service: Deactivated successfully. Apr 30 12:52:03.205828 systemd[1]: session-24.scope: Deactivated successfully. Apr 30 12:52:03.206473 systemd-logind[1689]: Session 24 logged out. Waiting for processes to exit. Apr 30 12:52:03.207250 systemd-logind[1689]: Removed session 24. Apr 30 12:52:03.312177 systemd[1]: Started sshd@22-10.200.4.32:22-10.200.16.10:52966.service - OpenSSH per-connection server daemon (10.200.16.10:52966). Apr 30 12:52:03.913666 sshd[13081]: Accepted publickey for core from 10.200.16.10 port 52966 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:03.936483 sshd-session[13081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:03.940964 systemd-logind[1689]: New session 25 of user core. Apr 30 12:52:03.954625 systemd[1]: Started session-25.scope - Session 25 of User core. Apr 30 12:52:04.362968 sshd[13083]: Connection closed by 10.200.16.10 port 52966 Apr 30 12:52:04.363419 sshd-session[13081]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:04.366960 systemd[1]: sshd@22-10.200.4.32:22-10.200.16.10:52966.service: Deactivated successfully. Apr 30 12:52:04.368672 systemd[1]: session-25.scope: Deactivated successfully. Apr 30 12:52:04.369323 systemd-logind[1689]: Session 25 logged out. Waiting for processes to exit. Apr 30 12:52:04.370104 systemd-logind[1689]: Removed session 25. Apr 30 12:52:04.470046 systemd[1]: Started sshd@23-10.200.4.32:22-10.200.16.10:52978.service - OpenSSH per-connection server daemon (10.200.16.10:52978). Apr 30 12:52:05.072353 sshd[13089]: Accepted publickey for core from 10.200.16.10 port 52978 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:05.073864 sshd-session[13089]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:05.078153 systemd-logind[1689]: New session 26 of user core. Apr 30 12:52:05.086619 systemd[1]: Started session-26.scope - Session 26 of User core. Apr 30 12:52:05.525046 sshd[13091]: Connection closed by 10.200.16.10 port 52978 Apr 30 12:52:05.525558 sshd-session[13089]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:05.529154 systemd[1]: sshd@23-10.200.4.32:22-10.200.16.10:52978.service: Deactivated successfully. Apr 30 12:52:05.530928 systemd[1]: session-26.scope: Deactivated successfully. Apr 30 12:52:05.531614 systemd-logind[1689]: Session 26 logged out. Waiting for processes to exit. Apr 30 12:52:05.532393 systemd-logind[1689]: Removed session 26. Apr 30 12:52:05.633170 systemd[1]: Started sshd@24-10.200.4.32:22-10.200.16.10:52990.service - OpenSSH per-connection server daemon (10.200.16.10:52990). Apr 30 12:52:06.235666 sshd[13097]: Accepted publickey for core from 10.200.16.10 port 52990 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:06.237141 sshd-session[13097]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:06.241341 systemd-logind[1689]: New session 27 of user core. Apr 30 12:52:06.252628 systemd[1]: Started session-27.scope - Session 27 of User core. Apr 30 12:52:06.693031 sshd[13099]: Connection closed by 10.200.16.10 port 52990 Apr 30 12:52:06.693467 sshd-session[13097]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:06.696893 systemd[1]: sshd@24-10.200.4.32:22-10.200.16.10:52990.service: Deactivated successfully. Apr 30 12:52:06.698617 systemd[1]: session-27.scope: Deactivated successfully. Apr 30 12:52:06.699250 systemd-logind[1689]: Session 27 logged out. Waiting for processes to exit. Apr 30 12:52:06.700059 systemd-logind[1689]: Removed session 27. Apr 30 12:52:06.799322 systemd[1]: Started sshd@25-10.200.4.32:22-10.200.16.10:52998.service - OpenSSH per-connection server daemon (10.200.16.10:52998). Apr 30 12:52:07.399966 sshd[13105]: Accepted publickey for core from 10.200.16.10 port 52998 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:07.401447 sshd-session[13105]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:07.405721 systemd-logind[1689]: New session 28 of user core. Apr 30 12:52:07.418617 systemd[1]: Started session-28.scope - Session 28 of User core. Apr 30 12:52:08.605887 systemd[1]: Stopping user@0.service - User Manager for UID 0... Apr 30 12:52:08.606740 systemd[2372]: Activating special unit exit.target... Apr 30 12:52:08.606833 systemd[2372]: Stopped target default.target - Main User Target. Apr 30 12:52:08.606874 systemd[2372]: Stopped target basic.target - Basic System. Apr 30 12:52:08.606903 systemd[2372]: Stopped target paths.target - Paths. Apr 30 12:52:08.606931 systemd[2372]: Stopped target sockets.target - Sockets. Apr 30 12:52:08.606956 systemd[2372]: Stopped target timers.target - Timers. Apr 30 12:52:08.607331 systemd[2372]: Closed dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:52:08.607566 systemd[2372]: Removed slice app.slice - User Application Slice. Apr 30 12:52:08.607601 systemd[2372]: Reached target shutdown.target - Shutdown. Apr 30 12:52:08.607760 systemd[2372]: Finished systemd-exit.service - Exit the Session. Apr 30 12:52:08.607800 systemd[2372]: Reached target exit.target - Exit the Session. Apr 30 12:52:08.711668 (sd-pam)[2377]: pam_unix(systemd-user:session): session closed for user root Apr 30 12:52:08.713298 systemd[1]: user@0.service: Deactivated successfully. Apr 30 12:52:08.713691 systemd[1]: Stopped user@0.service - User Manager for UID 0. Apr 30 12:52:08.721659 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Apr 30 12:52:08.730961 systemd[1]: run-user-0.mount: Deactivated successfully. Apr 30 12:52:08.731849 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Apr 30 12:52:08.732040 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Apr 30 12:52:08.733659 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Apr 30 12:52:08.733750 systemd[1]: user-0.slice: Consumed 5min 25.986s CPU time, 891.8M memory peak. Apr 30 12:52:08.734148 systemd-logind[1689]: Removed session c3. Apr 30 12:52:09.358382 sshd[13107]: Connection closed by 10.200.16.10 port 52998 Apr 30 12:52:09.358864 sshd-session[13105]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:09.362270 systemd[1]: sshd@25-10.200.4.32:22-10.200.16.10:52998.service: Deactivated successfully. Apr 30 12:52:09.364011 systemd[1]: session-28.scope: Deactivated successfully. Apr 30 12:52:09.364217 systemd[1]: session-28.scope: Consumed 1.468s CPU time, 14M memory peak. Apr 30 12:52:09.364661 systemd-logind[1689]: Session 28 logged out. Waiting for processes to exit. Apr 30 12:52:09.365438 systemd-logind[1689]: Removed session 28.