May 14 18:08:15.176306 kernel: Linux version 6.12.20-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed May 14 16:37:27 -00 2025
May 14 18:08:15.176336 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=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0
May 14 18:08:15.176346 kernel: BIOS-provided physical RAM map:
May 14 18:08:15.176353 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
May 14 18:08:15.176359 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved
May 14 18:08:15.176366 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable
May 14 18:08:15.176373 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved
May 14 18:08:15.176383 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data
May 14 18:08:15.176389 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS
May 14 18:08:15.176396 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable
May 14 18:08:15.176403 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable
May 14 18:08:15.176409 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable
May 14 18:08:15.176416 kernel: printk: legacy bootconsole [earlyser0] enabled
May 14 18:08:15.176438 kernel: NX (Execute Disable) protection: active
May 14 18:08:15.176450 kernel: APIC: Static calls initialized
May 14 18:08:15.176458 kernel: efi: EFI v2.7 by Microsoft
May 14 18:08:15.176466 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 
May 14 18:08:15.176473 kernel: random: crng init done
May 14 18:08:15.176481 kernel: secureboot: Secure boot disabled
May 14 18:08:15.176488 kernel: SMBIOS 3.1.0 present.
May 14 18:08:15.176496 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024
May 14 18:08:15.176503 kernel: DMI: Memory slots populated: 3/3
May 14 18:08:15.176510 kernel: Hypervisor detected: Microsoft Hyper-V
May 14 18:08:15.176518 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2
May 14 18:08:15.176528 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40
May 14 18:08:15.176535 kernel: Hyper-V: Using hypercall for remote TLB flush
May 14 18:08:15.176543 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
May 14 18:08:15.176550 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
May 14 18:08:15.176558 kernel: tsc: Marking TSC unstable due to running on Hyper-V
May 14 18:08:15.176566 kernel: tsc: Detected 2593.993 MHz processor
May 14 18:08:15.176573 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
May 14 18:08:15.176581 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
May 14 18:08:15.176589 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000
May 14 18:08:15.176599 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs
May 14 18:08:15.176607 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
May 14 18:08:15.176614 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved
May 14 18:08:15.176621 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved
May 14 18:08:15.176629 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000
May 14 18:08:15.176636 kernel: Using GB pages for direct mapping
May 14 18:08:15.176644 kernel: ACPI: Early table checksum verification disabled
May 14 18:08:15.176655 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL)
May 14 18:08:15.176666 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176674 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176682 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01   00000001 MSFT 05000000)
May 14 18:08:15.176692 kernel: ACPI: FACS 0x000000003FFFE000 000040
May 14 18:08:15.176701 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176709 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176720 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176728 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176736 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176744 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176752 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
May 14 18:08:15.176760 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113]
May 14 18:08:15.176768 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183]
May 14 18:08:15.176776 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f]
May 14 18:08:15.176787 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063]
May 14 18:08:15.176795 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f]
May 14 18:08:15.176803 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027]
May 14 18:08:15.176811 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077]
May 14 18:08:15.176819 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf]
May 14 18:08:15.176827 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037]
May 14 18:08:15.176834 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033]
May 14 18:08:15.176842 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug
May 14 18:08:15.176850 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug
May 14 18:08:15.176861 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug
May 14 18:08:15.176869 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug
May 14 18:08:15.176877 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug
May 14 18:08:15.176884 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug
May 14 18:08:15.176892 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug
May 14 18:08:15.176900 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug
May 14 18:08:15.176908 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff]
May 14 18:08:15.176916 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff]
May 14 18:08:15.176925 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff]
May 14 18:08:15.176935 kernel: Zone ranges:
May 14 18:08:15.176943 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
May 14 18:08:15.176951 kernel:   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
May 14 18:08:15.176959 kernel:   Normal   [mem 0x0000000100000000-0x0000005cbfffffff]
May 14 18:08:15.176967 kernel:   Device   empty
May 14 18:08:15.176975 kernel: Movable zone start for each node
May 14 18:08:15.176983 kernel: Early memory node ranges
May 14 18:08:15.176990 kernel:   node   0: [mem 0x0000000000001000-0x000000000009ffff]
May 14 18:08:15.176998 kernel:   node   0: [mem 0x0000000000100000-0x000000003ff40fff]
May 14 18:08:15.177009 kernel:   node   0: [mem 0x000000003ffff000-0x000000003fffffff]
May 14 18:08:15.177017 kernel:   node   0: [mem 0x0000000100000000-0x0000000fdfffffff]
May 14 18:08:15.177025 kernel:   node   0: [mem 0x0000004fe0000000-0x0000005cbfffffff]
May 14 18:08:15.177033 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff]
May 14 18:08:15.177041 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
May 14 18:08:15.177048 kernel: On node 0, zone DMA: 96 pages in unavailable ranges
May 14 18:08:15.177062 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges
May 14 18:08:15.177076 kernel: ACPI: PM-Timer IO Port: 0x408
May 14 18:08:15.177084 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
May 14 18:08:15.177096 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23
May 14 18:08:15.177104 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
May 14 18:08:15.177112 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
May 14 18:08:15.177120 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200
May 14 18:08:15.177128 kernel: CPU topo: Max. logical packages:   1
May 14 18:08:15.177136 kernel: CPU topo: Max. logical dies:       1
May 14 18:08:15.177144 kernel: CPU topo: Max. dies per package:   1
May 14 18:08:15.177151 kernel: CPU topo: Max. threads per core:   1
May 14 18:08:15.177159 kernel: CPU topo: Num. cores per package:     6
May 14 18:08:15.177170 kernel: CPU topo: Num. threads per package:   6
May 14 18:08:15.177178 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs
May 14 18:08:15.177186 kernel: [mem 0x40000000-0xffffffff] available for PCI devices
May 14 18:08:15.177194 kernel: Booting paravirtualized kernel on Hyper-V
May 14 18:08:15.177202 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
May 14 18:08:15.177210 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1
May 14 18:08:15.177218 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144
May 14 18:08:15.177226 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152
May 14 18:08:15.177234 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - 
May 14 18:08:15.177244 kernel: Hyper-V: PV spinlocks enabled
May 14 18:08:15.177252 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
May 14 18:08:15.177261 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=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0
May 14 18:08:15.177270 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
May 14 18:08:15.177278 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear)
May 14 18:08:15.177286 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
May 14 18:08:15.177294 kernel: Fallback order for Node 0: 0 
May 14 18:08:15.177302 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 29359841
May 14 18:08:15.177315 kernel: Policy zone: Normal
May 14 18:08:15.177332 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
May 14 18:08:15.177340 kernel: software IO TLB: area num 8.
May 14 18:08:15.177351 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
May 14 18:08:15.177360 kernel: Kernel/User page tables isolation: enabled
May 14 18:08:15.177368 kernel: ftrace: allocating 40065 entries in 157 pages
May 14 18:08:15.177376 kernel: ftrace: allocated 157 pages with 5 groups
May 14 18:08:15.177385 kernel: Dynamic Preempt: voluntary
May 14 18:08:15.177393 kernel: rcu: Preemptible hierarchical RCU implementation.
May 14 18:08:15.177402 kernel: rcu:         RCU event tracing is enabled.
May 14 18:08:15.177411 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6.
May 14 18:08:15.177420 kernel:         Trampoline variant of Tasks RCU enabled.
May 14 18:08:15.177440 kernel:         Rude variant of Tasks RCU enabled.
May 14 18:08:15.177449 kernel:         Tracing variant of Tasks RCU enabled.
May 14 18:08:15.177457 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
May 14 18:08:15.177466 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6
May 14 18:08:15.177474 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
May 14 18:08:15.177486 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
May 14 18:08:15.177494 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
May 14 18:08:15.177503 kernel: Using NULL legacy PIC
May 14 18:08:15.177511 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0
May 14 18:08:15.177520 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
May 14 18:08:15.177528 kernel: Console: colour dummy device 80x25
May 14 18:08:15.177537 kernel: printk: legacy console [tty1] enabled
May 14 18:08:15.177545 kernel: printk: legacy console [ttyS0] enabled
May 14 18:08:15.177553 kernel: printk: legacy bootconsole [earlyser0] disabled
May 14 18:08:15.177564 kernel: ACPI: Core revision 20240827
May 14 18:08:15.177573 kernel: Failed to register legacy timer interrupt
May 14 18:08:15.177581 kernel: APIC: Switch to symmetric I/O mode setup
May 14 18:08:15.177590 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0
May 14 18:08:15.177598 kernel: Hyper-V: enabling crash_kexec_post_notifiers
May 14 18:08:15.177606 kernel: Hyper-V: Using IPI hypercalls
May 14 18:08:15.177615 kernel: APIC: send_IPI() replaced with hv_send_ipi()
May 14 18:08:15.177623 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask()
May 14 18:08:15.177632 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself()
May 14 18:08:15.177643 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself()
May 14 18:08:15.177651 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all()
May 14 18:08:15.177659 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self()
May 14 18:08:15.177668 kernel: Hyper-V: Using enlightened APIC (xapic mode)
May 14 18:08:15.177676 kernel: APIC: eoi() replaced with hv_apic_eoi_write()
May 14 18:08:15.177685 kernel: APIC: read() replaced with hv_apic_read()
May 14 18:08:15.177693 kernel: APIC: write() replaced with hv_apic_write()
May 14 18:08:15.177701 kernel: APIC: icr_write() replaced with hv_apic_icr_write()
May 14 18:08:15.177710 kernel: APIC: icr_read() replaced with hv_apic_icr_read()
May 14 18:08:15.177721 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993)
May 14 18:08:15.177729 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8
May 14 18:08:15.177738 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4
May 14 18:08:15.177752 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
May 14 18:08:15.177764 kernel: Spectre V2 : Mitigation: Retpolines
May 14 18:08:15.177777 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
May 14 18:08:15.177788 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
May 14 18:08:15.177801 kernel: Speculative Store Bypass: Vulnerable
May 14 18:08:15.177810 kernel: MDS: Mitigation: Clear CPU buffers
May 14 18:08:15.177818 kernel: TAA: Mitigation: Clear CPU buffers
May 14 18:08:15.177828 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
May 14 18:08:15.177837 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
May 14 18:08:15.177845 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
May 14 18:08:15.177853 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
May 14 18:08:15.177862 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
May 14 18:08:15.177870 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
May 14 18:08:15.177879 kernel: Freeing SMP alternatives memory: 32K
May 14 18:08:15.177887 kernel: pid_max: default: 32768 minimum: 301
May 14 18:08:15.177895 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
May 14 18:08:15.177903 kernel: landlock: Up and running.
May 14 18:08:15.177912 kernel: SELinux:  Initializing.
May 14 18:08:15.177923 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
May 14 18:08:15.177931 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
May 14 18:08:15.177939 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1)
May 14 18:08:15.177948 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only.
May 14 18:08:15.177956 kernel: signal: max sigframe size: 1776
May 14 18:08:15.177965 kernel: rcu: Hierarchical SRCU implementation.
May 14 18:08:15.177973 kernel: rcu:         Max phase no-delay instances is 400.
May 14 18:08:15.177988 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
May 14 18:08:15.178002 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
May 14 18:08:15.178011 kernel: smp: Bringing up secondary CPUs ...
May 14 18:08:15.178026 kernel: smpboot: x86: Booting SMP configuration:
May 14 18:08:15.178034 kernel: .... node  #0, CPUs:      #1 #2 #3 #4 #5
May 14 18:08:15.178043 kernel: smp: Brought up 1 node, 6 CPUs
May 14 18:08:15.178051 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS)
May 14 18:08:15.178060 kernel: Memory: 115339084K/117439364K available (14336K kernel code, 2438K rwdata, 9944K rodata, 54424K init, 2536K bss, 2090004K reserved, 0K cma-reserved)
May 14 18:08:15.178068 kernel: devtmpfs: initialized
May 14 18:08:15.178077 kernel: x86/mm: Memory block size: 1024MB
May 14 18:08:15.178085 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes)
May 14 18:08:15.178094 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
May 14 18:08:15.178105 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
May 14 18:08:15.178114 kernel: pinctrl core: initialized pinctrl subsystem
May 14 18:08:15.178122 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
May 14 18:08:15.178131 kernel: audit: initializing netlink subsys (disabled)
May 14 18:08:15.178139 kernel: audit: type=2000 audit(1747246092.029:1): state=initialized audit_enabled=0 res=1
May 14 18:08:15.178148 kernel: thermal_sys: Registered thermal governor 'step_wise'
May 14 18:08:15.178156 kernel: thermal_sys: Registered thermal governor 'user_space'
May 14 18:08:15.178164 kernel: cpuidle: using governor menu
May 14 18:08:15.178173 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
May 14 18:08:15.178184 kernel: dca service started, version 1.12.1
May 14 18:08:15.178192 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff]
May 14 18:08:15.178200 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
May 14 18:08:15.178209 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
May 14 18:08:15.178217 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
May 14 18:08:15.178226 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
May 14 18:08:15.178234 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
May 14 18:08:15.178243 kernel: ACPI: Added _OSI(Module Device)
May 14 18:08:15.178253 kernel: ACPI: Added _OSI(Processor Device)
May 14 18:08:15.178262 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
May 14 18:08:15.178270 kernel: ACPI: Added _OSI(Processor Aggregator Device)
May 14 18:08:15.178279 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
May 14 18:08:15.178287 kernel: ACPI: Interpreter enabled
May 14 18:08:15.178295 kernel: ACPI: PM: (supports S0 S5)
May 14 18:08:15.178304 kernel: ACPI: Using IOAPIC for interrupt routing
May 14 18:08:15.178312 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
May 14 18:08:15.178321 kernel: PCI: Ignoring E820 reservations for host bridge windows
May 14 18:08:15.178329 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F
May 14 18:08:15.178340 kernel: iommu: Default domain type: Translated
May 14 18:08:15.178349 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
May 14 18:08:15.178357 kernel: efivars: Registered efivars operations
May 14 18:08:15.178365 kernel: PCI: Using ACPI for IRQ routing
May 14 18:08:15.178374 kernel: PCI: System does not support PCI
May 14 18:08:15.178382 kernel: vgaarb: loaded
May 14 18:08:15.178391 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page
May 14 18:08:15.178399 kernel: VFS: Disk quotas dquot_6.6.0
May 14 18:08:15.178407 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
May 14 18:08:15.178418 kernel: pnp: PnP ACPI init
May 14 18:08:15.178435 kernel: pnp: PnP ACPI: found 3 devices
May 14 18:08:15.178444 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
May 14 18:08:15.178452 kernel: NET: Registered PF_INET protocol family
May 14 18:08:15.178461 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
May 14 18:08:15.178469 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear)
May 14 18:08:15.178478 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
May 14 18:08:15.178486 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear)
May 14 18:08:15.178498 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
May 14 18:08:15.178506 kernel: TCP: Hash tables configured (established 524288 bind 65536)
May 14 18:08:15.178515 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear)
May 14 18:08:15.178523 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear)
May 14 18:08:15.178532 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
May 14 18:08:15.178540 kernel: NET: Registered PF_XDP protocol family
May 14 18:08:15.178549 kernel: PCI: CLS 0 bytes, default 64
May 14 18:08:15.178558 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
May 14 18:08:15.178566 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB)
May 14 18:08:15.178577 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
May 14 18:08:15.178586 kernel: Initialise system trusted keyrings
May 14 18:08:15.178594 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0
May 14 18:08:15.178602 kernel: Key type asymmetric registered
May 14 18:08:15.178611 kernel: Asymmetric key parser 'x509' registered
May 14 18:08:15.178619 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
May 14 18:08:15.178627 kernel: io scheduler mq-deadline registered
May 14 18:08:15.178636 kernel: io scheduler kyber registered
May 14 18:08:15.178644 kernel: io scheduler bfq registered
May 14 18:08:15.178655 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
May 14 18:08:15.178664 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
May 14 18:08:15.178672 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
May 14 18:08:15.178681 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
May 14 18:08:15.178689 kernel: i8042: PNP: No PS/2 controller found.
May 14 18:08:15.178832 kernel: rtc_cmos 00:02: registered as rtc0
May 14 18:08:15.178931 kernel: rtc_cmos 00:02: setting system clock to 2025-05-14T18:08:14 UTC (1747246094)
May 14 18:08:15.179022 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram
May 14 18:08:15.179039 kernel: intel_pstate: CPU model not supported
May 14 18:08:15.179048 kernel: efifb: probing for efifb
May 14 18:08:15.179056 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k
May 14 18:08:15.179073 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1
May 14 18:08:15.179082 kernel: efifb: scrolling: redraw
May 14 18:08:15.179091 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
May 14 18:08:15.179099 kernel: Console: switching to colour frame buffer device 128x48
May 14 18:08:15.179108 kernel: fb0: EFI VGA frame buffer device
May 14 18:08:15.179116 kernel: pstore: Using crash dump compression: deflate
May 14 18:08:15.179128 kernel: pstore: Registered efi_pstore as persistent store backend
May 14 18:08:15.179137 kernel: NET: Registered PF_INET6 protocol family
May 14 18:08:15.179145 kernel: Segment Routing with IPv6
May 14 18:08:15.179154 kernel: In-situ OAM (IOAM) with IPv6
May 14 18:08:15.179162 kernel: NET: Registered PF_PACKET protocol family
May 14 18:08:15.179170 kernel: Key type dns_resolver registered
May 14 18:08:15.179179 kernel: IPI shorthand broadcast: enabled
May 14 18:08:15.179187 kernel: sched_clock: Marking stable (3704004100, 109508200)->(4110514400, -297002100)
May 14 18:08:15.179196 kernel: registered taskstats version 1
May 14 18:08:15.179207 kernel: Loading compiled-in X.509 certificates
May 14 18:08:15.179215 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: 41e2a150aa08ec2528be2394819b3db677e5f4ef'
May 14 18:08:15.179224 kernel: Demotion targets for Node 0: null
May 14 18:08:15.179232 kernel: Key type .fscrypt registered
May 14 18:08:15.179240 kernel: Key type fscrypt-provisioning registered
May 14 18:08:15.179249 kernel: ima: No TPM chip found, activating TPM-bypass!
May 14 18:08:15.179257 kernel: ima: Allocated hash algorithm: sha1
May 14 18:08:15.179266 kernel: ima: No architecture policies found
May 14 18:08:15.179274 kernel: clk: Disabling unused clocks
May 14 18:08:15.179285 kernel: Warning: unable to open an initial console.
May 14 18:08:15.179294 kernel: Freeing unused kernel image (initmem) memory: 54424K
May 14 18:08:15.179302 kernel: Write protecting the kernel read-only data: 24576k
May 14 18:08:15.179311 kernel: Freeing unused kernel image (rodata/data gap) memory: 296K
May 14 18:08:15.179319 kernel: Run /init as init process
May 14 18:08:15.179327 kernel:   with arguments:
May 14 18:08:15.179336 kernel:     /init
May 14 18:08:15.179344 kernel:   with environment:
May 14 18:08:15.179352 kernel:     HOME=/
May 14 18:08:15.179363 kernel:     TERM=linux
May 14 18:08:15.179371 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
May 14 18:08:15.179381 systemd[1]: Successfully made /usr/ read-only.
May 14 18:08:15.179392 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)
May 14 18:08:15.179401 systemd[1]: Detected virtualization microsoft.
May 14 18:08:15.179410 systemd[1]: Detected architecture x86-64.
May 14 18:08:15.179419 systemd[1]: Running in initrd.
May 14 18:08:15.179455 systemd[1]: No hostname configured, using default hostname.
May 14 18:08:15.179465 systemd[1]: Hostname set to <localhost>.
May 14 18:08:15.179474 systemd[1]: Initializing machine ID from random generator.
May 14 18:08:15.179483 systemd[1]: Queued start job for default target initrd.target.
May 14 18:08:15.179492 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 14 18:08:15.179501 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 14 18:08:15.179511 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
May 14 18:08:15.179520 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
May 14 18:08:15.179532 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
May 14 18:08:15.179542 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
May 14 18:08:15.179552 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
May 14 18:08:15.179561 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
May 14 18:08:15.179570 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 14 18:08:15.179579 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
May 14 18:08:15.179588 systemd[1]: Reached target paths.target - Path Units.
May 14 18:08:15.179600 systemd[1]: Reached target slices.target - Slice Units.
May 14 18:08:15.179609 systemd[1]: Reached target swap.target - Swaps.
May 14 18:08:15.179618 systemd[1]: Reached target timers.target - Timer Units.
May 14 18:08:15.179627 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
May 14 18:08:15.179636 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
May 14 18:08:15.179645 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
May 14 18:08:15.179654 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
May 14 18:08:15.179663 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
May 14 18:08:15.179672 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
May 14 18:08:15.179684 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
May 14 18:08:15.179693 systemd[1]: Reached target sockets.target - Socket Units.
May 14 18:08:15.179702 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
May 14 18:08:15.179711 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
May 14 18:08:15.179720 systemd[1]: Finished network-cleanup.service - Network Cleanup.
May 14 18:08:15.179729 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply).
May 14 18:08:15.179738 systemd[1]: Starting systemd-fsck-usr.service...
May 14 18:08:15.179747 systemd[1]: Starting systemd-journald.service - Journal Service...
May 14 18:08:15.179759 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
May 14 18:08:15.179768 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 14 18:08:15.179798 systemd-journald[232]: Collecting audit messages is disabled.
May 14 18:08:15.179820 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
May 14 18:08:15.179834 systemd-journald[232]: Journal started
May 14 18:08:15.179857 systemd-journald[232]: Runtime Journal (/run/log/journal/bbb5d36e4d04478996d0eb304db18ddc) is 8M, max 2.2G, 2.1G free.
May 14 18:08:15.178031 systemd-modules-load[234]: Inserted module 'overlay'
May 14 18:08:15.195892 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
May 14 18:08:15.196345 systemd[1]: Finished systemd-fsck-usr.service.
May 14 18:08:15.196962 systemd[1]: Started systemd-journald.service - Journal Service.
May 14 18:08:15.198594 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
May 14 18:08:15.199689 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
May 14 18:08:15.251443 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
May 14 18:08:15.252886 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 14 18:08:15.263811 kernel: Bridge firewalling registered
May 14 18:08:15.254749 systemd-modules-load[234]: Inserted module 'br_netfilter'
May 14 18:08:15.255314 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
May 14 18:08:15.269732 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
May 14 18:08:15.272219 systemd-tmpfiles[246]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
May 14 18:08:15.277290 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
May 14 18:08:15.281167 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
May 14 18:08:15.290468 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
May 14 18:08:15.292463 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
May 14 18:08:15.336414 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
May 14 18:08:15.336714 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 14 18:08:15.338876 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
May 14 18:08:15.370814 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
May 14 18:08:15.373541 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
May 14 18:08:15.388193 systemd-resolved[266]: Positive Trust Anchors:
May 14 18:08:15.388202 systemd-resolved[266]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
May 14 18:08:15.388232 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
May 14 18:08:15.390779 systemd-resolved[266]: Defaulting to hostname 'linux'.
May 14 18:08:15.391763 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
May 14 18:08:15.397628 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
May 14 18:08:15.457841 dracut-cmdline[275]: Using kernel command line parameters:  rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1   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=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0
May 14 18:08:15.563448 kernel: SCSI subsystem initialized
May 14 18:08:15.574438 kernel: Loading iSCSI transport class v2.0-870.
May 14 18:08:15.586445 kernel: iscsi: registered transport (tcp)
May 14 18:08:15.610527 kernel: iscsi: registered transport (qla4xxx)
May 14 18:08:15.610554 kernel: QLogic iSCSI HBA Driver
May 14 18:08:15.630790 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
May 14 18:08:15.666881 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
May 14 18:08:15.668032 systemd[1]: Reached target network-pre.target - Preparation for Network.
May 14 18:08:15.722361 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
May 14 18:08:15.723675 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
May 14 18:08:15.801448 kernel: raid6: avx2x4   gen() 31168 MB/s
May 14 18:08:15.820443 kernel: raid6: avx2x2   gen() 31250 MB/s
May 14 18:08:15.842595 kernel: raid6: avx2x1   gen() 27890 MB/s
May 14 18:08:15.842622 kernel: raid6: using algorithm avx2x2 gen() 31250 MB/s
May 14 18:08:15.864713 kernel: raid6: .... xor() 18483 MB/s, rmw enabled
May 14 18:08:15.864745 kernel: raid6: using avx2x2 recovery algorithm
May 14 18:08:15.887441 kernel: xor: automatically using best checksumming function   avx       
May 14 18:08:16.055452 kernel: Btrfs loaded, zoned=no, fsverity=no
May 14 18:08:16.062256 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
May 14 18:08:16.063963 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 14 18:08:16.102100 systemd-udevd[484]: Using default interface naming scheme 'v255'.
May 14 18:08:16.110183 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 14 18:08:16.111137 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
May 14 18:08:16.146505 dracut-pre-trigger[487]: rd.md=0: removing MD RAID activation
May 14 18:08:16.169483 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
May 14 18:08:16.178488 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
May 14 18:08:16.254951 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
May 14 18:08:16.261273 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
May 14 18:08:16.283608 kernel: cryptd: max_cpu_qlen set to 1000
May 14 18:08:16.297441 kernel: AES CTR mode by8 optimization enabled
May 14 18:08:16.302453 kernel: hv_vmbus: Vmbus version:4.0
May 14 18:08:16.304027 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 14 18:08:16.327853 kernel: hv_vmbus: registering driver hyperv_keyboard
May 14 18:08:16.327887 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
May 14 18:08:16.304161 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 14 18:08:16.339139 kernel: pps_core: LinuxPPS API ver. 1 registered
May 14 18:08:16.339160 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
May 14 18:08:16.319502 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
May 14 18:08:16.321955 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 14 18:08:16.343746 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
May 14 18:08:16.362443 kernel: PTP clock support registered
May 14 18:08:16.362471 kernel: hv_vmbus: registering driver hv_netvsc
May 14 18:08:16.365525 kernel: hv_vmbus: registering driver hv_storvsc
May 14 18:08:16.368514 kernel: hid: raw HID events driver (C) Jiri Kosina
May 14 18:08:16.373634 kernel: hv_vmbus: registering driver hv_pci
May 14 18:08:16.373677 kernel: scsi host0: storvsc_host_t
May 14 18:08:16.376533 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004
May 14 18:08:17.900067 kernel: scsi host1: storvsc_host_t
May 14 18:08:17.900227 kernel: scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
May 14 18:08:17.994663 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00
May 14 18:08:17.994881 kernel: hv_utils: Registering HyperV Utility Driver
May 14 18:08:17.994907 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window]
May 14 18:08:17.995065 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window]
May 14 18:08:17.995198 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff]
May 14 18:08:17.995319 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint
May 14 18:08:17.995473 kernel: hv_vmbus: registering driver hv_utils
May 14 18:08:17.995487 kernel: hv_utils: Heartbeat IC version 3.0
May 14 18:08:17.995499 kernel: scsi 0:0:0:1: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
May 14 18:08:17.995642 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]
May 14 18:08:17.995808 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]
May 14 18:08:17.996485 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]
May 14 18:08:17.996679 kernel: pci 0001:00:00.0: Enabling HDA controller
May 14 18:08:17.996855 kernel: hv_utils: Shutdown IC version 3.2
May 14 18:08:17.996870 kernel: scsi 0:0:0:2: CD-ROM            Msft     Virtual DVD-ROM  1.0  PQ: 0 ANSI: 0
May 14 18:08:17.997023 kernel: hv_utils: TimeSync IC version 4.0
May 14 18:08:17.997044 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00
May 14 18:08:17.997197 kernel: hv_vmbus: registering driver hid_hyperv
May 14 18:08:17.997216 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned
May 14 18:08:17.997386 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB)
May 14 18:08:17.997518 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB)
May 14 18:08:17.997645 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned
May 14 18:08:17.997797 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned
May 14 18:08:17.997936 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks
May 14 18:08:17.998063 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
May 14 18:08:17.998192 kernel: sd 0:0:0:1: [sdb] Write Protect is off
May 14 18:08:17.998322 kernel: sd 0:0:0:0: [sda] Write Protect is off
May 14 18:08:17.998445 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00
May 14 18:08:17.998569 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA
May 14 18:08:17.998693 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00
May 14 18:08:17.998830 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:17.998955 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA
May 14 18:08:17.999081 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#515 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:17.999192 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:17.999301 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#70 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:17.999410 kernel:  sdb: sdb1
May 14 18:08:17.999423 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk
May 14 18:08:17.999546 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1
May 14 18:08:17.999559 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
May 14 18:08:17.999576 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on 
May 14 18:08:17.999686 kernel: sd 0:0:0:0: [sda] Attached SCSI disk
May 14 18:08:17.999831 kernel: sr 0:0:0:2: [sr0] scsi-1 drive
May 14 18:08:18.003903 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
May 14 18:08:18.003927 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0
May 14 18:08:17.861959 systemd-resolved[266]: Clock change detected. Flushing caches.
May 14 18:08:17.959954 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 14 18:08:18.848544 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM.
May 14 18:08:18.904528 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT.
May 14 18:08:18.923845 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A.
May 14 18:08:18.935684 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A.
May 14 18:08:18.957384 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM.
May 14 18:08:18.968582 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
May 14 18:08:19.008272 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#310 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:19.018765 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
May 14 18:08:19.027810 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#111 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:19.043771 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
May 14 18:08:20.051241 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#118 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:20.066371 disk-uuid[720]: The operation has completed successfully.
May 14 18:08:20.070143 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
May 14 18:08:21.717018 systemd[1]: disk-uuid.service: Deactivated successfully.
May 14 18:08:21.717124 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
May 14 18:08:21.718685 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
May 14 18:08:21.728835 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
May 14 18:08:21.749602 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
May 14 18:08:21.753485 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
May 14 18:08:21.764497 systemd[1]: Reached target remote-fs.target - Remote File Systems.
May 14 18:08:21.796177 sh[884]: Success
May 14 18:08:21.777780 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
May 14 18:08:21.818979 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
May 14 18:08:21.845141 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
May 14 18:08:21.845180 kernel: device-mapper: uevent: version 1.0.3
May 14 18:08:21.849929 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
May 14 18:08:21.862767 kernel: device-mapper: verity: sha256 using shash "sha256-avx2"
May 14 18:08:22.125310 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
May 14 18:08:22.135891 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
May 14 18:08:22.169379 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
May 14 18:08:22.194870 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay'
May 14 18:08:22.194903 kernel: BTRFS: device fsid dedcf745-d4ff-44ac-b61c-5ec1bad114c7 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (910)
May 14 18:08:22.201776 kernel: BTRFS info (device dm-0): first mount of filesystem dedcf745-d4ff-44ac-b61c-5ec1bad114c7
May 14 18:08:22.201803 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
May 14 18:08:22.207861 kernel: BTRFS info (device dm-0): using free-space-tree
May 14 18:08:22.690319 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
May 14 18:08:22.697688 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
May 14 18:08:22.707005 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
May 14 18:08:22.716047 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
May 14 18:08:22.724038 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
May 14 18:08:22.752773 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (942)
May 14 18:08:22.758776 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998
May 14 18:08:22.758809 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
May 14 18:08:22.765418 kernel: BTRFS info (device sda6): using free-space-tree
May 14 18:08:22.789767 kernel: BTRFS info (device sda6): last unmount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998
May 14 18:08:22.790505 systemd[1]: Finished ignition-setup.service - Ignition (setup).
May 14 18:08:22.791589 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
May 14 18:08:22.829129 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
May 14 18:08:22.832230 systemd[1]: Starting systemd-networkd.service - Network Configuration...
May 14 18:08:22.878941 systemd-networkd[1079]: lo: Link UP
May 14 18:08:22.878951 systemd-networkd[1079]: lo: Gained carrier
May 14 18:08:22.880197 systemd-networkd[1079]: Enumeration completed
May 14 18:08:22.880299 systemd[1]: Started systemd-networkd.service - Network Configuration.
May 14 18:08:22.880557 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 14 18:08:22.880562 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 14 18:08:22.881294 systemd-networkd[1079]: eth0: Link UP
May 14 18:08:22.881457 systemd-networkd[1079]: eth0: Gained carrier
May 14 18:08:22.881467 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 14 18:08:22.881963 systemd[1]: Reached target network.target - Network.
May 14 18:08:22.937785 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16
May 14 18:08:24.510866 systemd-networkd[1079]: eth0: Gained IPv6LL
May 14 18:08:24.635834 ignition[1039]: Ignition 2.21.0
May 14 18:08:24.635849 ignition[1039]: Stage: fetch-offline
May 14 18:08:24.635940 ignition[1039]: no configs at "/usr/lib/ignition/base.d"
May 14 18:08:24.635950 ignition[1039]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:24.636036 ignition[1039]: parsed url from cmdline: ""
May 14 18:08:24.636040 ignition[1039]: no config URL provided
May 14 18:08:24.636045 ignition[1039]: reading system config file "/usr/lib/ignition/user.ign"
May 14 18:08:24.636054 ignition[1039]: no config at "/usr/lib/ignition/user.ign"
May 14 18:08:24.636060 ignition[1039]: failed to fetch config: resource requires networking
May 14 18:08:24.636248 ignition[1039]: Ignition finished successfully
May 14 18:08:24.668104 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
May 14 18:08:24.674121 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
May 14 18:08:24.711531 ignition[1091]: Ignition 2.21.0
May 14 18:08:24.711545 ignition[1091]: Stage: fetch
May 14 18:08:24.711720 ignition[1091]: no configs at "/usr/lib/ignition/base.d"
May 14 18:08:24.711731 ignition[1091]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:24.711840 ignition[1091]: parsed url from cmdline: ""
May 14 18:08:24.711844 ignition[1091]: no config URL provided
May 14 18:08:24.711850 ignition[1091]: reading system config file "/usr/lib/ignition/user.ign"
May 14 18:08:24.711859 ignition[1091]: no config at "/usr/lib/ignition/user.ign"
May 14 18:08:24.711893 ignition[1091]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1
May 14 18:08:24.793557 ignition[1091]: GET result: OK
May 14 18:08:24.793738 ignition[1091]: config has been read from IMDS userdata
May 14 18:08:24.793771 ignition[1091]: parsing config with SHA512: c4511d68bb9300a166a52b758400239b0fdd32f6b331e61cb16dd98fe7628f8066e97e9d72d9cb6a1592ef5d46370dd43fec531f5f5d7062a7e6de5814a4dd29
May 14 18:08:24.798145 unknown[1091]: fetched base config from "system"
May 14 18:08:24.798308 ignition[1091]: fetch: fetch complete
May 14 18:08:24.798153 unknown[1091]: fetched base config from "system"
May 14 18:08:24.798313 ignition[1091]: fetch: fetch passed
May 14 18:08:24.798158 unknown[1091]: fetched user config from "azure"
May 14 18:08:24.798358 ignition[1091]: Ignition finished successfully
May 14 18:08:24.814431 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
May 14 18:08:24.817396 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
May 14 18:08:24.858086 ignition[1101]: Ignition 2.21.0
May 14 18:08:24.858099 ignition[1101]: Stage: kargs
May 14 18:08:24.858328 ignition[1101]: no configs at "/usr/lib/ignition/base.d"
May 14 18:08:24.858340 ignition[1101]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:24.859091 ignition[1101]: kargs: kargs passed
May 14 18:08:24.859156 ignition[1101]: Ignition finished successfully
May 14 18:08:24.873222 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
May 14 18:08:24.876349 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
May 14 18:08:24.921627 ignition[1111]: Ignition 2.21.0
May 14 18:08:24.921641 ignition[1111]: Stage: disks
May 14 18:08:24.921899 ignition[1111]: no configs at "/usr/lib/ignition/base.d"
May 14 18:08:24.921911 ignition[1111]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:24.922803 ignition[1111]: disks: disks passed
May 14 18:08:24.932912 systemd[1]: Finished ignition-disks.service - Ignition (disks).
May 14 18:08:24.922873 ignition[1111]: Ignition finished successfully
May 14 18:08:24.936973 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
May 14 18:08:24.943266 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
May 14 18:08:24.956939 systemd[1]: Reached target local-fs.target - Local File Systems.
May 14 18:08:24.963919 systemd[1]: Reached target sysinit.target - System Initialization.
May 14 18:08:24.972716 systemd[1]: Reached target basic.target - Basic System.
May 14 18:08:24.982302 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
May 14 18:08:25.093884 systemd-fsck[1123]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks
May 14 18:08:25.101243 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
May 14 18:08:25.112833 systemd[1]: Mounting sysroot.mount - /sysroot...
May 14 18:08:25.359774 kernel: EXT4-fs (sda9): mounted filesystem d6072e19-4548-4806-a012-87bb17c59f4c r/w with ordered data mode. Quota mode: none.
May 14 18:08:25.360890 systemd[1]: Mounted sysroot.mount - /sysroot.
May 14 18:08:25.361521 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
May 14 18:08:25.395654 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
May 14 18:08:25.396604 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
May 14 18:08:25.422305 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent...
May 14 18:08:25.436135 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
May 14 18:08:25.436173 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
May 14 18:08:25.467008 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1137)
May 14 18:08:25.467033 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998
May 14 18:08:25.467047 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
May 14 18:08:25.467059 kernel: BTRFS info (device sda6): using free-space-tree
May 14 18:08:25.464147 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
May 14 18:08:25.470701 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
May 14 18:08:25.479206 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
May 14 18:08:26.436644 coreos-metadata[1139]: May 14 18:08:26.436 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
May 14 18:08:26.442701 coreos-metadata[1139]: May 14 18:08:26.438 INFO Fetch successful
May 14 18:08:26.442701 coreos-metadata[1139]: May 14 18:08:26.439 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1
May 14 18:08:26.454836 coreos-metadata[1139]: May 14 18:08:26.446 INFO Fetch successful
May 14 18:08:26.460414 coreos-metadata[1139]: May 14 18:08:26.460 INFO wrote hostname ci-4334.0.0-a-41d4cf11f8 to /sysroot/etc/hostname
May 14 18:08:26.467708 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
May 14 18:08:26.624818 initrd-setup-root[1167]: cut: /sysroot/etc/passwd: No such file or directory
May 14 18:08:26.670566 initrd-setup-root[1174]: cut: /sysroot/etc/group: No such file or directory
May 14 18:08:26.678345 initrd-setup-root[1181]: cut: /sysroot/etc/shadow: No such file or directory
May 14 18:08:26.686555 initrd-setup-root[1188]: cut: /sysroot/etc/gshadow: No such file or directory
May 14 18:08:28.050653 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
May 14 18:08:28.056453 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
May 14 18:08:28.069116 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
May 14 18:08:28.093675 systemd[1]: sysroot-oem.mount: Deactivated successfully.
May 14 18:08:28.102645 kernel: BTRFS info (device sda6): last unmount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998
May 14 18:08:28.117893 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
May 14 18:08:28.134924 ignition[1257]: INFO     : Ignition 2.21.0
May 14 18:08:28.134924 ignition[1257]: INFO     : Stage: mount
May 14 18:08:28.141036 ignition[1257]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 14 18:08:28.141036 ignition[1257]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:28.141036 ignition[1257]: INFO     : mount: mount passed
May 14 18:08:28.141036 ignition[1257]: INFO     : Ignition finished successfully
May 14 18:08:28.139342 systemd[1]: Finished ignition-mount.service - Ignition (mount).
May 14 18:08:28.142066 systemd[1]: Starting ignition-files.service - Ignition (files)...
May 14 18:08:28.178440 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
May 14 18:08:28.201006 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1270)
May 14 18:08:28.201037 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998
May 14 18:08:28.205585 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
May 14 18:08:28.208806 kernel: BTRFS info (device sda6): using free-space-tree
May 14 18:08:28.212621 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
May 14 18:08:28.253858 ignition[1287]: INFO     : Ignition 2.21.0
May 14 18:08:28.253858 ignition[1287]: INFO     : Stage: files
May 14 18:08:28.259785 ignition[1287]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 14 18:08:28.259785 ignition[1287]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:28.259785 ignition[1287]: DEBUG    : files: compiled without relabeling support, skipping
May 14 18:08:28.273379 ignition[1287]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
May 14 18:08:28.273379 ignition[1287]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
May 14 18:08:28.295546 ignition[1287]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
May 14 18:08:28.301048 ignition[1287]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
May 14 18:08:28.301048 ignition[1287]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
May 14 18:08:28.296194 unknown[1287]: wrote ssh authorized keys file for user: core
May 14 18:08:28.337344 ignition[1287]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
May 14 18:08:28.344425 ignition[1287]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
May 14 18:08:28.344425 ignition[1287]: INFO     : files: createResultFile: createFiles: op(4): [started]  writing file "/sysroot/etc/.ignition-result.json"
May 14 18:08:28.344425 ignition[1287]: INFO     : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json"
May 14 18:08:28.344425 ignition[1287]: INFO     : files: files passed
May 14 18:08:28.344425 ignition[1287]: INFO     : Ignition finished successfully
May 14 18:08:28.358969 systemd[1]: Finished ignition-files.service - Ignition (files).
May 14 18:08:28.368859 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
May 14 18:08:28.371734 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
May 14 18:08:28.403836 systemd[1]: ignition-quench.service: Deactivated successfully.
May 14 18:08:28.403941 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
May 14 18:08:28.427489 initrd-setup-root-after-ignition[1319]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
May 14 18:08:28.437661 initrd-setup-root-after-ignition[1319]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
May 14 18:08:28.429331 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
May 14 18:08:28.461847 initrd-setup-root-after-ignition[1323]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
May 14 18:08:28.437922 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
May 14 18:08:28.452597 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
May 14 18:08:28.490124 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
May 14 18:08:28.490238 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
May 14 18:08:28.498513 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
May 14 18:08:28.503059 systemd[1]: Reached target initrd.target - Initrd Default Target.
May 14 18:08:28.510679 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
May 14 18:08:28.511569 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
May 14 18:08:28.543738 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
May 14 18:08:28.545061 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
May 14 18:08:28.561999 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
May 14 18:08:28.562467 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
May 14 18:08:28.576255 systemd[1]: Stopped target timers.target - Timer Units.
May 14 18:08:28.584526 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
May 14 18:08:28.584642 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
May 14 18:08:28.596537 systemd[1]: Stopped target initrd.target - Initrd Default Target.
May 14 18:08:28.601401 systemd[1]: Stopped target basic.target - Basic System.
May 14 18:08:28.609354 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
May 14 18:08:28.616312 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
May 14 18:08:28.624946 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
May 14 18:08:28.634184 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
May 14 18:08:28.642992 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
May 14 18:08:28.651674 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
May 14 18:08:28.659745 systemd[1]: Stopped target sysinit.target - System Initialization.
May 14 18:08:28.669544 systemd[1]: Stopped target local-fs.target - Local File Systems.
May 14 18:08:28.677542 systemd[1]: Stopped target swap.target - Swaps.
May 14 18:08:28.685346 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
May 14 18:08:28.685462 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
May 14 18:08:28.699542 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
May 14 18:08:28.703761 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 14 18:08:28.712246 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
May 14 18:08:28.712366 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 14 18:08:28.721633 systemd[1]: dracut-initqueue.service: Deactivated successfully.
May 14 18:08:28.721768 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
May 14 18:08:28.740108 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
May 14 18:08:28.740228 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
May 14 18:08:28.744016 systemd[1]: ignition-files.service: Deactivated successfully.
May 14 18:08:28.744133 systemd[1]: Stopped ignition-files.service - Ignition (files).
May 14 18:08:28.753283 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
May 14 18:08:28.753396 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
May 14 18:08:28.762597 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
May 14 18:08:28.788000 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
May 14 18:08:28.791843 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
May 14 18:08:28.792014 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
May 14 18:08:28.799166 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
May 14 18:08:28.832974 ignition[1343]: INFO     : Ignition 2.21.0
May 14 18:08:28.832974 ignition[1343]: INFO     : Stage: umount
May 14 18:08:28.832974 ignition[1343]: INFO     : no configs at "/usr/lib/ignition/base.d"
May 14 18:08:28.832974 ignition[1343]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
May 14 18:08:28.832974 ignition[1343]: INFO     : umount: umount passed
May 14 18:08:28.832974 ignition[1343]: INFO     : Ignition finished successfully
May 14 18:08:28.799287 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
May 14 18:08:28.821383 systemd[1]: initrd-cleanup.service: Deactivated successfully.
May 14 18:08:28.821481 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
May 14 18:08:28.832454 systemd[1]: sysroot-boot.mount: Deactivated successfully.
May 14 18:08:28.834404 systemd[1]: ignition-mount.service: Deactivated successfully.
May 14 18:08:28.834504 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
May 14 18:08:28.836477 systemd[1]: ignition-disks.service: Deactivated successfully.
May 14 18:08:28.836554 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
May 14 18:08:28.842879 systemd[1]: ignition-kargs.service: Deactivated successfully.
May 14 18:08:28.842929 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
May 14 18:08:28.850820 systemd[1]: ignition-fetch.service: Deactivated successfully.
May 14 18:08:28.850865 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
May 14 18:08:28.861064 systemd[1]: Stopped target network.target - Network.
May 14 18:08:28.869429 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
May 14 18:08:28.869486 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
May 14 18:08:28.876504 systemd[1]: Stopped target paths.target - Path Units.
May 14 18:08:28.888920 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
May 14 18:08:28.889030 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 14 18:08:28.892636 systemd[1]: Stopped target slices.target - Slice Units.
May 14 18:08:28.905930 systemd[1]: Stopped target sockets.target - Socket Units.
May 14 18:08:28.913152 systemd[1]: iscsid.socket: Deactivated successfully.
May 14 18:08:28.913194 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
May 14 18:08:28.916806 systemd[1]: iscsiuio.socket: Deactivated successfully.
May 14 18:08:28.916846 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
May 14 18:08:28.924340 systemd[1]: ignition-setup.service: Deactivated successfully.
May 14 18:08:28.924392 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
May 14 18:08:28.932354 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
May 14 18:08:28.932398 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
May 14 18:08:28.940155 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
May 14 18:08:28.947978 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
May 14 18:08:28.955734 systemd[1]: sysroot-boot.service: Deactivated successfully.
May 14 18:08:28.955851 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
May 14 18:08:28.964525 systemd[1]: initrd-setup-root.service: Deactivated successfully.
May 14 18:08:28.964590 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
May 14 18:08:28.972592 systemd[1]: systemd-resolved.service: Deactivated successfully.
May 14 18:08:28.972693 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
May 14 18:08:28.988852 systemd[1]: systemd-networkd.service: Deactivated successfully.
May 14 18:08:28.989001 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
May 14 18:08:29.001883 systemd[1]: Stopped target network-pre.target - Preparation for Network.
May 14 18:08:29.005157 systemd[1]: systemd-networkd.socket: Deactivated successfully.
May 14 18:08:29.005214 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
May 14 18:08:29.016355 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
May 14 18:08:29.023590 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
May 14 18:08:29.023642 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
May 14 18:08:29.030718 systemd[1]: systemd-sysctl.service: Deactivated successfully.
May 14 18:08:29.030778 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
May 14 18:08:29.049105 systemd[1]: systemd-modules-load.service: Deactivated successfully.
May 14 18:08:29.049152 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
May 14 18:08:29.068308 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
May 14 18:08:29.068372 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
May 14 18:08:29.080739 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 14 18:08:29.085776 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
May 14 18:08:29.085838 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
May 14 18:08:29.085877 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
May 14 18:08:29.085921 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
May 14 18:08:29.122726 systemd[1]: systemd-udevd.service: Deactivated successfully.
May 14 18:08:29.122920 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 14 18:08:29.135687 systemd[1]: network-cleanup.service: Deactivated successfully.
May 14 18:08:29.135833 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
May 14 18:08:29.140093 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
May 14 18:08:29.140152 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
May 14 18:08:29.147831 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
May 14 18:08:29.147866 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
May 14 18:08:29.155804 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
May 14 18:08:29.155854 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
May 14 18:08:29.172402 systemd[1]: dracut-cmdline.service: Deactivated successfully.
May 14 18:08:29.172466 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
May 14 18:08:29.189587 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
May 14 18:08:29.189644 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
May 14 18:08:29.203347 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
May 14 18:08:29.206639 systemd[1]: systemd-network-generator.service: Deactivated successfully.
May 14 18:08:29.206694 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
May 14 18:08:29.225322 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
May 14 18:08:29.225377 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 14 18:08:29.240362 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
May 14 18:08:29.240412 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
May 14 18:08:29.255316 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
May 14 18:08:29.255363 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
May 14 18:08:29.265743 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
May 14 18:08:29.265803 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
May 14 18:08:29.281613 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully.
May 14 18:08:29.281672 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully.
May 14 18:08:29.281709 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully.
May 14 18:08:29.281745 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
May 14 18:08:29.282164 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
May 14 18:08:29.282267 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
May 14 18:08:29.283808 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
May 14 18:08:29.292495 systemd[1]: Starting initrd-switch-root.service - Switch Root...
May 14 18:08:29.342829 systemd[1]: Switching root.
May 14 18:08:29.477148 systemd-journald[232]: Journal stopped
May 14 18:08:34.661580 systemd-journald[232]: Received SIGTERM from PID 1 (systemd).
May 14 18:08:34.661611 kernel: SELinux:  policy capability network_peer_controls=1
May 14 18:08:34.661627 kernel: SELinux:  policy capability open_perms=1
May 14 18:08:34.661637 kernel: SELinux:  policy capability extended_socket_class=1
May 14 18:08:34.661646 kernel: SELinux:  policy capability always_check_network=0
May 14 18:08:34.661655 kernel: SELinux:  policy capability cgroup_seclabel=1
May 14 18:08:34.661665 kernel: SELinux:  policy capability nnp_nosuid_transition=1
May 14 18:08:34.661678 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
May 14 18:08:34.661692 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
May 14 18:08:34.661701 kernel: SELinux:  policy capability userspace_initial_context=0
May 14 18:08:34.661711 kernel: audit: type=1403 audit(1747246110.452:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
May 14 18:08:34.661722 systemd[1]: Successfully loaded SELinux policy in 176.373ms.
May 14 18:08:34.661733 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.441ms.
May 14 18:08:34.661744 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)
May 14 18:08:34.661771 systemd[1]: Detected virtualization microsoft.
May 14 18:08:34.661783 systemd[1]: Detected architecture x86-64.
May 14 18:08:34.661793 systemd[1]: Detected first boot.
May 14 18:08:34.661805 systemd[1]: Hostname set to <ci-4334.0.0-a-41d4cf11f8>.
May 14 18:08:34.661815 systemd[1]: Initializing machine ID from random generator.
May 14 18:08:34.661829 kernel: Guest personality initialized and is inactive
May 14 18:08:34.661839 kernel: VMCI host device registered (name=vmci, major=10, minor=124)
May 14 18:08:34.661848 kernel: Initialized host personality
May 14 18:08:34.661858 kernel: NET: Registered PF_VSOCK protocol family
May 14 18:08:34.661869 zram_generator::config[1389]: No configuration found.
May 14 18:08:34.661880 systemd[1]: Populated /etc with preset unit settings.
May 14 18:08:34.661891 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
May 14 18:08:34.661905 systemd[1]: initrd-switch-root.service: Deactivated successfully.
May 14 18:08:34.661915 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
May 14 18:08:34.661926 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
May 14 18:08:34.661940 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
May 14 18:08:34.661951 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
May 14 18:08:34.661962 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
May 14 18:08:34.661973 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
May 14 18:08:34.661987 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
May 14 18:08:34.661998 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
May 14 18:08:34.662009 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
May 14 18:08:34.662020 systemd[1]: Created slice user.slice - User and Session Slice.
May 14 18:08:34.662031 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
May 14 18:08:34.662042 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
May 14 18:08:34.662053 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
May 14 18:08:34.662068 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
May 14 18:08:34.662080 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
May 14 18:08:34.662095 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
May 14 18:08:34.662106 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
May 14 18:08:34.662117 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
May 14 18:08:34.662128 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
May 14 18:08:34.662139 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
May 14 18:08:34.662150 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
May 14 18:08:34.662161 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
May 14 18:08:34.662175 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
May 14 18:08:34.662189 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
May 14 18:08:34.662200 systemd[1]: Reached target remote-fs.target - Remote File Systems.
May 14 18:08:34.662211 systemd[1]: Reached target slices.target - Slice Units.
May 14 18:08:34.662222 systemd[1]: Reached target swap.target - Swaps.
May 14 18:08:34.662233 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
May 14 18:08:34.662244 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
May 14 18:08:34.662259 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
May 14 18:08:34.662270 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
May 14 18:08:34.662282 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
May 14 18:08:34.662293 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
May 14 18:08:34.662305 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
May 14 18:08:34.662316 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
May 14 18:08:34.662330 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
May 14 18:08:34.662341 systemd[1]: Mounting media.mount - External Media Directory...
May 14 18:08:34.662352 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 14 18:08:34.662364 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
May 14 18:08:34.662375 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
May 14 18:08:34.662386 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
May 14 18:08:34.662398 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).
May 14 18:08:34.662409 systemd[1]: Reached target machines.target - Containers.
May 14 18:08:34.662423 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
May 14 18:08:34.662438 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 14 18:08:34.662449 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
May 14 18:08:34.662460 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
May 14 18:08:34.662472 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 14 18:08:34.662483 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
May 14 18:08:34.662494 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 14 18:08:34.662506 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
May 14 18:08:34.662520 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 14 18:08:34.662532 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
May 14 18:08:34.662543 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
May 14 18:08:34.662555 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
May 14 18:08:34.662566 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
May 14 18:08:34.662577 systemd[1]: Stopped systemd-fsck-usr.service.
May 14 18:08:34.662588 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).
May 14 18:08:34.662600 systemd[1]: Starting systemd-journald.service - Journal Service...
May 14 18:08:34.662611 kernel: fuse: init (API version 7.41)
May 14 18:08:34.662624 kernel: loop: module loaded
May 14 18:08:34.662635 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
May 14 18:08:34.662668 systemd-journald[1496]: Collecting audit messages is disabled.
May 14 18:08:34.662692 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
May 14 18:08:34.662709 systemd-journald[1496]: Journal started
May 14 18:08:34.662734 systemd-journald[1496]: Runtime Journal (/run/log/journal/4bd6ffd709734050a6c7f374974bf669) is 8M, max 2.2G, 2.1G free.
May 14 18:08:33.924561 systemd[1]: Queued start job for default target multi-user.target.
May 14 18:08:33.947052 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6.
May 14 18:08:33.947551 systemd[1]: systemd-journald.service: Deactivated successfully.
May 14 18:08:34.670758 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
May 14 18:08:34.677780 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
May 14 18:08:34.693816 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
May 14 18:08:34.703716 systemd[1]: verity-setup.service: Deactivated successfully.
May 14 18:08:34.703759 systemd[1]: Stopped verity-setup.service.
May 14 18:08:34.715768 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 14 18:08:34.722772 systemd[1]: Started systemd-journald.service - Journal Service.
May 14 18:08:34.727152 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
May 14 18:08:34.731402 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
May 14 18:08:34.736342 systemd[1]: Mounted media.mount - External Media Directory.
May 14 18:08:34.740504 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
May 14 18:08:34.745170 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
May 14 18:08:34.750767 kernel: ACPI: bus type drm_connector registered
May 14 18:08:34.752637 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
May 14 18:08:34.756987 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
May 14 18:08:34.762120 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
May 14 18:08:34.767545 systemd[1]: modprobe@configfs.service: Deactivated successfully.
May 14 18:08:34.767742 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
May 14 18:08:34.772890 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 14 18:08:34.773064 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 14 18:08:34.777931 systemd[1]: modprobe@drm.service: Deactivated successfully.
May 14 18:08:34.778105 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
May 14 18:08:34.782672 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 14 18:08:34.782868 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 14 18:08:34.788165 systemd[1]: modprobe@fuse.service: Deactivated successfully.
May 14 18:08:34.788343 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
May 14 18:08:34.792907 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 14 18:08:34.793082 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 14 18:08:34.797629 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
May 14 18:08:34.802392 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
May 14 18:08:34.807767 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
May 14 18:08:34.813061 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
May 14 18:08:34.819964 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
May 14 18:08:34.833656 systemd[1]: Reached target network-pre.target - Preparation for Network.
May 14 18:08:34.839507 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
May 14 18:08:34.859073 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
May 14 18:08:34.863631 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
May 14 18:08:34.863668 systemd[1]: Reached target local-fs.target - Local File Systems.
May 14 18:08:34.868964 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
May 14 18:08:34.875425 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
May 14 18:08:34.879942 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 14 18:08:34.881029 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
May 14 18:08:34.886174 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
May 14 18:08:34.890733 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
May 14 18:08:34.891699 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
May 14 18:08:34.896094 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
May 14 18:08:34.897024 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
May 14 18:08:34.902094 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
May 14 18:08:34.907742 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
May 14 18:08:34.912793 systemd-journald[1496]: Time spent on flushing to /var/log/journal/4bd6ffd709734050a6c7f374974bf669 is 13.843ms for 950 entries.
May 14 18:08:34.912793 systemd-journald[1496]: System Journal (/var/log/journal/4bd6ffd709734050a6c7f374974bf669) is 8M, max 2.6G, 2.6G free.
May 14 18:08:34.948798 systemd-journald[1496]: Received client request to flush runtime journal.
May 14 18:08:34.914131 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
May 14 18:08:34.923865 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
May 14 18:08:34.929089 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
May 14 18:08:34.935477 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
May 14 18:08:34.943882 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
May 14 18:08:34.962903 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
May 14 18:08:34.979557 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
May 14 18:08:34.994770 kernel: loop0: detected capacity change from 0 to 28536
May 14 18:08:35.014686 systemd-tmpfiles[1534]: ACLs are not supported, ignoring.
May 14 18:08:35.014707 systemd-tmpfiles[1534]: ACLs are not supported, ignoring.
May 14 18:08:35.019814 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
May 14 18:08:35.027394 systemd[1]: Starting systemd-sysusers.service - Create System Users...
May 14 18:08:35.061613 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
May 14 18:08:35.062243 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
May 14 18:08:35.133551 systemd[1]: Finished systemd-sysusers.service - Create System Users.
May 14 18:08:35.139727 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
May 14 18:08:35.170697 systemd-tmpfiles[1549]: ACLs are not supported, ignoring.
May 14 18:08:35.170717 systemd-tmpfiles[1549]: ACLs are not supported, ignoring.
May 14 18:08:35.175184 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
May 14 18:08:35.569780 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
May 14 18:08:35.727781 kernel: loop1: detected capacity change from 0 to 146240
May 14 18:08:36.324268 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
May 14 18:08:36.331096 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
May 14 18:08:36.380917 systemd-udevd[1557]: Using default interface naming scheme 'v255'.
May 14 18:08:36.459783 kernel: loop2: detected capacity change from 0 to 113872
May 14 18:08:36.785779 kernel: loop3: detected capacity change from 0 to 28536
May 14 18:08:36.797827 kernel: loop4: detected capacity change from 0 to 146240
May 14 18:08:36.807532 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
May 14 18:08:36.820821 kernel: loop5: detected capacity change from 0 to 113872
May 14 18:08:36.821882 systemd[1]: Starting systemd-networkd.service - Network Configuration...
May 14 18:08:36.827562 (sd-merge)[1560]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'.
May 14 18:08:36.828398 (sd-merge)[1560]: Merged extensions into '/usr'.
May 14 18:08:36.832457 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
May 14 18:08:36.851688 systemd[1]: Starting ensure-sysext.service...
May 14 18:08:36.855388 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
May 14 18:08:36.864481 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
May 14 18:08:36.882813 kernel: hv_vmbus: registering driver hyperv_fb
May 14 18:08:36.894940 kernel: hyperv_fb: Synthvid Version major 3, minor 5
May 14 18:08:36.895008 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
May 14 18:08:36.900999 kernel: Console: switching to colour dummy device 80x25
May 14 18:08:36.904815 kernel: mousedev: PS/2 mouse device common for all mice
May 14 18:08:36.904864 kernel: Console: switching to colour frame buffer device 128x48
May 14 18:08:36.914937 systemd[1]: Reload requested from client PID 1601 ('systemctl') (unit ensure-sysext.service)...
May 14 18:08:36.914958 systemd[1]: Reloading...
May 14 18:08:36.921411 systemd-tmpfiles[1602]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
May 14 18:08:36.921449 systemd-tmpfiles[1602]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
May 14 18:08:36.921725 systemd-tmpfiles[1602]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
May 14 18:08:36.923394 systemd-tmpfiles[1602]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
May 14 18:08:36.924367 systemd-tmpfiles[1602]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
May 14 18:08:36.924649 systemd-tmpfiles[1602]: ACLs are not supported, ignoring.
May 14 18:08:36.924709 systemd-tmpfiles[1602]: ACLs are not supported, ignoring.
May 14 18:08:36.931860 systemd-tmpfiles[1602]: Detected autofs mount point /boot during canonicalization of boot.
May 14 18:08:36.931959 systemd-tmpfiles[1602]: Skipping /boot
May 14 18:08:36.945775 kernel: hv_vmbus: registering driver hv_balloon
May 14 18:08:36.945910 systemd-tmpfiles[1602]: Detected autofs mount point /boot during canonicalization of boot.
May 14 18:08:36.946000 systemd-tmpfiles[1602]: Skipping /boot
May 14 18:08:36.952808 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
May 14 18:08:36.969777 zram_generator::config[1658]: No configuration found.
May 14 18:08:37.048774 kernel: EDAC MC: Ver: 3.0.0
May 14 18:08:37.146108 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.
May 14 18:08:37.285880 systemd[1]: Reloading finished in 370 ms.
May 14 18:08:37.336825 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
May 14 18:08:37.389430 systemd[1]: Finished ensure-sysext.service.
May 14 18:08:37.412986 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM.
May 14 18:08:37.417993 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 14 18:08:37.419349 systemd[1]: Starting audit-rules.service - Load Audit Rules...
May 14 18:08:37.424183 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
May 14 18:08:37.428966 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
May 14 18:08:37.447578 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
May 14 18:08:37.452792 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
May 14 18:08:37.457708 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
May 14 18:08:37.463178 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
May 14 18:08:37.467466 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
May 14 18:08:37.468435 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
May 14 18:08:37.473266 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).
May 14 18:08:37.474427 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
May 14 18:08:37.480850 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
May 14 18:08:37.485019 systemd[1]: Reached target time-set.target - System Time Set.
May 14 18:08:37.490114 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
May 14 18:08:37.496168 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
May 14 18:08:37.502674 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
May 14 18:08:37.507003 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
May 14 18:08:37.508069 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
May 14 18:08:37.508270 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
May 14 18:08:37.513205 systemd[1]: modprobe@drm.service: Deactivated successfully.
May 14 18:08:37.513383 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
May 14 18:08:37.518085 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
May 14 18:08:37.518259 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
May 14 18:08:37.525518 systemd[1]: modprobe@loop.service: Deactivated successfully.
May 14 18:08:37.525699 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
May 14 18:08:37.534881 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
May 14 18:08:37.535021 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
May 14 18:08:37.536416 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
May 14 18:08:37.591466 systemd[1]: Started systemd-userdbd.service - User Database Manager.
May 14 18:08:37.648739 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
May 14 18:08:37.700460 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
May 14 18:08:37.702122 augenrules[1835]: No rules
May 14 18:08:37.706328 systemd[1]: audit-rules.service: Deactivated successfully.
May 14 18:08:37.706548 systemd[1]: Finished audit-rules.service - Load Audit Rules.
May 14 18:08:37.784022 systemd-resolved[1795]: Positive Trust Anchors:
May 14 18:08:37.784037 systemd-resolved[1795]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
May 14 18:08:37.784069 systemd-resolved[1795]: 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
May 14 18:08:37.788279 systemd-resolved[1795]: Using system hostname 'ci-4334.0.0-a-41d4cf11f8'.
May 14 18:08:37.789923 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
May 14 18:08:37.790164 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
May 14 18:08:38.011094 systemd-networkd[1587]: lo: Link UP
May 14 18:08:38.011104 systemd-networkd[1587]: lo: Gained carrier
May 14 18:08:38.012430 systemd-networkd[1587]: Enumeration completed
May 14 18:08:38.012558 systemd[1]: Started systemd-networkd.service - Network Configuration.
May 14 18:08:38.012826 systemd[1]: Reached target network.target - Network.
May 14 18:08:38.015117 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
May 14 18:08:38.016644 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
May 14 18:08:38.017257 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 14 18:08:38.017267 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 14 18:08:38.017885 systemd-networkd[1587]: eth0: Link UP
May 14 18:08:38.017893 systemd-networkd[1587]: eth0: Gained carrier
May 14 18:08:38.017909 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 14 18:08:38.065791 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16
May 14 18:08:38.071110 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
May 14 18:08:38.095084 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
May 14 18:08:38.131226 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
May 14 18:08:38.137360 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).
May 14 18:08:39.806888 systemd-networkd[1587]: eth0: Gained IPv6LL
May 14 18:08:39.809233 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
May 14 18:08:39.814879 systemd[1]: Reached target network-online.target - Network is Online.
May 14 18:08:42.235954 ldconfig[1527]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
May 14 18:08:42.249342 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
May 14 18:08:42.258628 systemd[1]: Starting systemd-update-done.service - Update is Completed...
May 14 18:08:42.286250 systemd[1]: Finished systemd-update-done.service - Update is Completed.
May 14 18:08:42.290977 systemd[1]: Reached target sysinit.target - System Initialization.
May 14 18:08:42.300636 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
May 14 18:08:42.305700 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
May 14 18:08:42.310949 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
May 14 18:08:42.315904 systemd[1]: Started logrotate.timer - Daily rotation of log files.
May 14 18:08:42.320383 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
May 14 18:08:42.325604 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
May 14 18:08:42.330909 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
May 14 18:08:42.330956 systemd[1]: Reached target paths.target - Path Units.
May 14 18:08:42.334742 systemd[1]: Reached target timers.target - Timer Units.
May 14 18:08:42.339436 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
May 14 18:08:42.345238 systemd[1]: Starting docker.socket - Docker Socket for the API...
May 14 18:08:42.360197 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
May 14 18:08:42.365703 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
May 14 18:08:42.371366 systemd[1]: Reached target ssh-access.target - SSH Access Available.
May 14 18:08:42.377823 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
May 14 18:08:42.382691 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
May 14 18:08:42.388278 systemd[1]: Listening on docker.socket - Docker Socket for the API.
May 14 18:08:42.393715 systemd[1]: Reached target sockets.target - Socket Units.
May 14 18:08:42.397724 systemd[1]: Reached target basic.target - Basic System.
May 14 18:08:42.401610 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
May 14 18:08:42.401654 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
May 14 18:08:42.404022 systemd[1]: Starting chronyd.service - NTP client/server...
May 14 18:08:42.408678 systemd[1]: Starting containerd.service - containerd container runtime...
May 14 18:08:42.430642 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
May 14 18:08:42.435781 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
May 14 18:08:42.440521 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
May 14 18:08:42.446056 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
May 14 18:08:42.451185 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
May 14 18:08:42.453840 jq[1863]: false
May 14 18:08:42.455400 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).
May 14 18:08:42.456572 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
May 14 18:08:42.461680 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
May 14 18:08:42.466659 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
May 14 18:08:42.473334 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
May 14 18:08:42.479057 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
May 14 18:08:42.485057 (chronyd)[1855]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS
May 14 18:08:42.486110 systemd[1]: Starting systemd-logind.service - User Login Management...
May 14 18:08:42.491193 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
May 14 18:08:42.491635 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
May 14 18:08:42.492877 systemd[1]: Starting update-engine.service - Update Engine...
May 14 18:08:42.497725 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
May 14 18:08:42.504912 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
May 14 18:08:42.505691 jq[1884]: true
May 14 18:08:42.510194 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
May 14 18:08:42.510428 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
May 14 18:08:42.510718 systemd[1]: motdgen.service: Deactivated successfully.
May 14 18:08:42.510930 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
May 14 18:08:42.515878 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
May 14 18:08:42.516099 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
May 14 18:08:42.517950 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing passwd entry cache
May 14 18:08:42.517959 oslogin_cache_refresh[1865]: Refreshing passwd entry cache
May 14 18:08:42.532151 (ntainerd)[1888]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
May 14 18:08:42.534842 jq[1887]: true
May 14 18:08:42.539508 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting users, quitting
May 14 18:08:42.539508 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
May 14 18:08:42.539508 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing group entry cache
May 14 18:08:42.539392 oslogin_cache_refresh[1865]: Failure getting users, quitting
May 14 18:08:42.539412 oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
May 14 18:08:42.539455 oslogin_cache_refresh[1865]: Refreshing group entry cache
May 14 18:08:42.543202 extend-filesystems[1864]: Found loop3
May 14 18:08:42.546126 extend-filesystems[1864]: Found loop4
May 14 18:08:42.546126 extend-filesystems[1864]: Found loop5
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda1
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda2
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda3
May 14 18:08:42.546126 extend-filesystems[1864]: Found usr
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda4
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda6
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda7
May 14 18:08:42.546126 extend-filesystems[1864]: Found sda9
May 14 18:08:42.546126 extend-filesystems[1864]: Checking size of /dev/sda9
May 14 18:08:42.589146 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting groups, quitting
May 14 18:08:42.589146 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
May 14 18:08:42.546274 oslogin_cache_refresh[1865]: Failure getting groups, quitting
May 14 18:08:42.547843 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
May 14 18:08:42.546286 oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
May 14 18:08:42.548067 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
May 14 18:08:42.546777 chronyd[1904]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG)
May 14 18:08:42.576629 systemd[1]: Started chronyd.service - NTP client/server.
May 14 18:08:42.575658 chronyd[1904]: Timezone right/UTC failed leap second check, ignoring
May 14 18:08:42.575858 chronyd[1904]: Loaded seccomp filter (level 2)
May 14 18:08:42.596185 extend-filesystems[1864]: Old size kept for /dev/sda9
May 14 18:08:42.599866 extend-filesystems[1864]: Found sdb
May 14 18:08:42.599866 extend-filesystems[1864]: Found sdb1
May 14 18:08:42.599866 extend-filesystems[1864]: Found sr0
May 14 18:08:42.597300 systemd[1]: extend-filesystems.service: Deactivated successfully.
May 14 18:08:42.597529 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
May 14 18:08:42.602883 systemd-logind[1874]: New seat seat0.
May 14 18:08:42.604037 systemd-logind[1874]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
May 14 18:08:42.607218 systemd[1]: Started systemd-logind.service - User Login Management.
May 14 18:08:42.637576 update_engine[1879]: I20250514 18:08:42.637496  1879 main.cc:92] Flatcar Update Engine starting
May 14 18:08:42.642736 dbus-daemon[1858]: [system] SELinux support is enabled
May 14 18:08:42.642920 systemd[1]: Started dbus.service - D-Bus System Message Bus.
May 14 18:08:42.648708 update_engine[1879]: I20250514 18:08:42.648115  1879 update_check_scheduler.cc:74] Next update check in 3m39s
May 14 18:08:42.650856 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).
May 14 18:08:42.650896 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
May 14 18:08:42.656082 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).
May 14 18:08:42.651624 dbus-daemon[1858]: [system] Successfully activated service 'org.freedesktop.systemd1'
May 14 18:08:42.656103 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
May 14 18:08:42.661377 systemd[1]: Started update-engine.service - Update Engine.
May 14 18:08:42.670610 systemd[1]: Started locksmithd.service - Cluster reboot manager.
May 14 18:08:42.699617 bash[1915]: Updated "/home/core/.ssh/authorized_keys"
May 14 18:08:42.701951 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
May 14 18:08:42.713990 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
May 14 18:08:42.719692 setup-nvidia[1868]: Downloading Flatcar Container Linux Developer Container for version: 4334.0.0+afterburn-5.8.0
May 14 18:08:42.730482 sshd_keygen[1885]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
May 14 18:08:42.751774 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
May 14 18:08:42.758107 systemd[1]: Starting issuegen.service - Generate /run/issue...
May 14 18:08:42.773161 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent...
May 14 18:08:42.777180 coreos-metadata[1857]: May 14 18:08:42.777 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
May 14 18:08:42.779373 coreos-metadata[1857]: May 14 18:08:42.779 INFO Fetch successful
May 14 18:08:42.779521 coreos-metadata[1857]: May 14 18:08:42.779 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1
May 14 18:08:42.780879 systemd[1]: issuegen.service: Deactivated successfully.
May 14 18:08:42.781086 systemd[1]: Finished issuegen.service - Generate /run/issue.
May 14 18:08:42.783121 coreos-metadata[1857]: May 14 18:08:42.783 INFO Fetch successful
May 14 18:08:42.785203 coreos-metadata[1857]: May 14 18:08:42.783 INFO Fetching http://168.63.129.16/machine/600a25ca-58d5-4714-9cd7-150ab8360a95/f01a38dc%2D0f2f%2D4965%2D89a3%2D0b46b21b6e9d.%5Fci%2D4334.0.0%2Da%2D41d4cf11f8?comp=config&type=sharedConfig&incarnation=1: Attempt #1
May 14 18:08:42.788411 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
May 14 18:08:42.798604 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent.
May 14 18:08:42.803597 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
May 14 18:08:42.809658 coreos-metadata[1857]: May 14 18:08:42.809 INFO Fetch successful
May 14 18:08:42.809880 coreos-metadata[1857]: May 14 18:08:42.809 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1
May 14 18:08:42.811008 systemd[1]: Started getty@tty1.service - Getty on tty1.
May 14 18:08:42.816265 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
May 14 18:08:42.817598 coreos-metadata[1857]: May 14 18:08:42.817 INFO Fetch successful
May 14 18:08:42.820968 systemd[1]: Reached target getty.target - Login Prompts.
May 14 18:08:42.854636 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
May 14 18:08:42.859509 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
May 14 18:08:43.023143 locksmithd[1971]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
May 14 18:08:43.845546 containerd[1888]: time="2025-05-14T18:08:43Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8
May 14 18:08:43.846224 containerd[1888]: time="2025-05-14T18:08:43.846192200Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4
May 14 18:08:43.855778 containerd[1888]: time="2025-05-14T18:08:43.855709700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.3µs"
May 14 18:08:43.855778 containerd[1888]: time="2025-05-14T18:08:43.855771200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
May 14 18:08:43.855845 containerd[1888]: time="2025-05-14T18:08:43.855790700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
May 14 18:08:43.855942 containerd[1888]: time="2025-05-14T18:08:43.855920400Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
May 14 18:08:43.855973 containerd[1888]: time="2025-05-14T18:08:43.855942100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
May 14 18:08:43.855973 containerd[1888]: time="2025-05-14T18:08:43.855965800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
May 14 18:08:43.856037 containerd[1888]: time="2025-05-14T18:08:43.856017600Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
May 14 18:08:43.856037 containerd[1888]: time="2025-05-14T18:08:43.856035000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
May 14 18:08:43.856239 containerd[1888]: time="2025-05-14T18:08:43.856217900Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
May 14 18:08:43.856239 containerd[1888]: time="2025-05-14T18:08:43.856237000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
May 14 18:08:43.856292 containerd[1888]: time="2025-05-14T18:08:43.856248100Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
May 14 18:08:43.856292 containerd[1888]: time="2025-05-14T18:08:43.856255800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
May 14 18:08:43.856360 containerd[1888]: time="2025-05-14T18:08:43.856338200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
May 14 18:08:43.856621 containerd[1888]: time="2025-05-14T18:08:43.856599600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
May 14 18:08:43.856648 containerd[1888]: time="2025-05-14T18:08:43.856636400Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
May 14 18:08:43.856670 containerd[1888]: time="2025-05-14T18:08:43.856657000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
May 14 18:08:43.856704 containerd[1888]: time="2025-05-14T18:08:43.856687400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
May 14 18:08:43.857536 containerd[1888]: time="2025-05-14T18:08:43.857464800Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
May 14 18:08:43.857672 containerd[1888]: time="2025-05-14T18:08:43.857648500Z" level=info msg="metadata content store policy set" policy=shared
May 14 18:08:43.883881 containerd[1888]: time="2025-05-14T18:08:43.883843100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
May 14 18:08:43.883940 containerd[1888]: time="2025-05-14T18:08:43.883889600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
May 14 18:08:43.883940 containerd[1888]: time="2025-05-14T18:08:43.883907300Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
May 14 18:08:43.883940 containerd[1888]: time="2025-05-14T18:08:43.883920300Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
May 14 18:08:43.883940 containerd[1888]: time="2025-05-14T18:08:43.883933000Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883943300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883954100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883965600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883976100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883986200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.883995300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
May 14 18:08:43.884027 containerd[1888]: time="2025-05-14T18:08:43.884007600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
May 14 18:08:43.884166 containerd[1888]: time="2025-05-14T18:08:43.884114500Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
May 14 18:08:43.884166 containerd[1888]: time="2025-05-14T18:08:43.884135500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
May 14 18:08:43.884166 containerd[1888]: time="2025-05-14T18:08:43.884148900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
May 14 18:08:43.884166 containerd[1888]: time="2025-05-14T18:08:43.884158900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884183300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884196500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884206900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884217900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884229800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
May 14 18:08:43.884247 containerd[1888]: time="2025-05-14T18:08:43.884240500Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
May 14 18:08:43.884372 containerd[1888]: time="2025-05-14T18:08:43.884250100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
May 14 18:08:43.884372 containerd[1888]: time="2025-05-14T18:08:43.884304200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
May 14 18:08:43.884372 containerd[1888]: time="2025-05-14T18:08:43.884317400Z" level=info msg="Start snapshots syncer"
May 14 18:08:43.884372 containerd[1888]: time="2025-05-14T18:08:43.884344800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
May 14 18:08:43.884634 containerd[1888]: time="2025-05-14T18:08:43.884568200Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}"
May 14 18:08:43.884747 containerd[1888]: time="2025-05-14T18:08:43.884648500Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
May 14 18:08:43.884747 containerd[1888]: time="2025-05-14T18:08:43.884723800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
May 14 18:08:43.884873 containerd[1888]: time="2025-05-14T18:08:43.884848300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
May 14 18:08:43.884910 containerd[1888]: time="2025-05-14T18:08:43.884888200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
May 14 18:08:43.884934 containerd[1888]: time="2025-05-14T18:08:43.884909500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
May 14 18:08:43.884934 containerd[1888]: time="2025-05-14T18:08:43.884920900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
May 14 18:08:43.884934 containerd[1888]: time="2025-05-14T18:08:43.884931400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
May 14 18:08:43.884992 containerd[1888]: time="2025-05-14T18:08:43.884942800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
May 14 18:08:43.884992 containerd[1888]: time="2025-05-14T18:08:43.884962000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
May 14 18:08:43.884992 containerd[1888]: time="2025-05-14T18:08:43.884987400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
May 14 18:08:43.885058 containerd[1888]: time="2025-05-14T18:08:43.884998900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
May 14 18:08:43.885058 containerd[1888]: time="2025-05-14T18:08:43.885009100Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
May 14 18:08:43.885058 containerd[1888]: time="2025-05-14T18:08:43.885035600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885049800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885075100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885089300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885103300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885113300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
May 14 18:08:43.885126 containerd[1888]: time="2025-05-14T18:08:43.885124400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
May 14 18:08:43.885248 containerd[1888]: time="2025-05-14T18:08:43.885141200Z" level=info msg="runtime interface created"
May 14 18:08:43.885248 containerd[1888]: time="2025-05-14T18:08:43.885146800Z" level=info msg="created NRI interface"
May 14 18:08:43.885248 containerd[1888]: time="2025-05-14T18:08:43.885155300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
May 14 18:08:43.885248 containerd[1888]: time="2025-05-14T18:08:43.885165300Z" level=info msg="Connect containerd service"
May 14 18:08:43.885248 containerd[1888]: time="2025-05-14T18:08:43.885189400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
May 14 18:08:43.885885 containerd[1888]: time="2025-05-14T18:08:43.885858900Z" 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"
May 14 18:08:45.273905 waagent[2011]: 2025-05-14T18:08:45.273824Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4
May 14 18:08:45.278550 waagent[2011]: 2025-05-14T18:08:45.278503Z INFO Daemon Daemon OS: flatcar 4334.0.0
May 14 18:08:45.282336 waagent[2011]: 2025-05-14T18:08:45.282290Z INFO Daemon Daemon Python: 3.11.12
May 14 18:08:45.286077 waagent[2011]: 2025-05-14T18:08:45.286033Z INFO Daemon Daemon Run daemon
May 14 18:08:45.289426 waagent[2011]: 2025-05-14T18:08:45.289381Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4334.0.0'
May 14 18:08:45.296178 waagent[2011]: 2025-05-14T18:08:45.296127Z INFO Daemon Daemon Using waagent for provisioning
May 14 18:08:45.300963 waagent[2011]: 2025-05-14T18:08:45.300910Z INFO Daemon Daemon Activate resource disk
May 14 18:08:45.304826 waagent[2011]: 2025-05-14T18:08:45.304777Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
May 14 18:08:45.312399 waagent[2011]: 2025-05-14T18:08:45.312346Z INFO Daemon Daemon Found device: sdb
May 14 18:08:45.321628 waagent[2011]: 2025-05-14T18:08:45.321578Z INFO Daemon Daemon Examining partition table
May 14 18:08:45.363433 waagent[2011]: 2025-05-14T18:08:45.363381Z INFO Daemon Daemon GPT not detected, determining filesystem
May 14 18:08:45.368315 containerd[1888]: time="2025-05-14T18:08:45.368269700Z" level=info msg="Start subscribing containerd event"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368326400Z" level=info msg="Start recovering state"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368457300Z" level=info msg="Start event monitor"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368482000Z" level=info msg="Start cni network conf syncer for default"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368492200Z" level=info msg="Start streaming server"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368460800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368507300Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368574200Z" level=info msg="runtime interface starting up..."
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368585400Z" level=info msg="starting plugins..."
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368611300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
May 14 18:08:45.368740 containerd[1888]: time="2025-05-14T18:08:45.368575400Z" level=info msg=serving... address=/run/containerd/containerd.sock
May 14 18:08:45.368978 containerd[1888]: time="2025-05-14T18:08:45.368865800Z" level=info msg="containerd successfully booted in 1.523827s"
May 14 18:08:45.368865 systemd[1]: Started containerd.service - containerd container runtime.
May 14 18:08:45.406043 waagent[2011]: 2025-05-14T18:08:45.405982Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded
May 14 18:08:45.411171 waagent[2011]: 2025-05-14T18:08:45.411118Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83
May 14 18:08:45.728029 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#95 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:45.740777 kernel:  sdb: sdb1
May 14 18:08:45.747773 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#69 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:45.758943 waagent[2011]: 2025-05-14T18:08:45.758872Z INFO Daemon Daemon sfdisk --part-type  /dev/sdb 1 83 succeeded
May 14 18:08:45.760762 kernel:  sdb: sdb1
May 14 18:08:45.779780 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#111 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
May 14 18:08:45.792578 waagent[2011]: 2025-05-14T18:08:45.792538Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1]
May 14 18:08:45.792763 kernel:  sdb: sdb1
May 14 18:08:50.882972 login[2013]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying
May 14 18:08:50.932443 login[2014]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
May 14 18:08:50.947453 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
May 14 18:08:50.948577 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
May 14 18:08:50.959585 systemd-logind[1874]: New session 2 of user core.
May 14 18:08:51.148094 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
May 14 18:08:51.150855 systemd[1]: Starting user@500.service - User Manager for UID 500...
May 14 18:08:51.328260 (systemd)[2124]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
May 14 18:08:51.330893 systemd-logind[1874]: New session c1 of user core.
May 14 18:08:51.495598 waagent[2011]: 2025-05-14T18:08:51.495471Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource]
May 14 18:08:51.600588 waagent[2011]: 2025-05-14T18:08:51.600510Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4
May 14 18:08:51.600772 kernel: EXT4-fs (sdb1): mounted filesystem 8a597630-3e44-45b9-8347-ff15152122c9 r/w with ordered data mode. Quota mode: none.
May 14 18:08:51.606681 waagent[2011]: 2025-05-14T18:08:51.606636Z INFO Daemon Daemon Clean protocol and wireserver endpoint
May 14 18:08:51.611434 waagent[2011]: 2025-05-14T18:08:51.611388Z INFO Daemon Daemon Running default provisioning handler
May 14 18:08:51.622536 waagent[2011]: 2025-05-14T18:08:51.622485Z 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.
May 14 18:08:51.632905 waagent[2011]: 2025-05-14T18:08:51.632855Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
May 14 18:08:51.633109 waagent[2011]: 2025-05-14T18:08:51.633068Z INFO Daemon Daemon cloud-init is enabled: False
May 14 18:08:51.634589 waagent[2011]: 2025-05-14T18:08:51.634553Z INFO Daemon Daemon Copying ovf-env.xml
May 14 18:08:51.653913 systemd[2124]: Queued start job for default target default.target.
May 14 18:08:51.681123 systemd[2124]: Created slice app.slice - User Application Slice.
May 14 18:08:51.681169 systemd[2124]: Reached target paths.target - Paths.
May 14 18:08:51.681217 systemd[2124]: Reached target timers.target - Timers.
May 14 18:08:51.682434 systemd[2124]: Starting dbus.socket - D-Bus User Message Bus Socket...
May 14 18:08:51.693440 systemd[2124]: Listening on dbus.socket - D-Bus User Message Bus Socket.
May 14 18:08:51.693555 systemd[2124]: Reached target sockets.target - Sockets.
May 14 18:08:51.693610 systemd[2124]: Reached target basic.target - Basic System.
May 14 18:08:51.693652 systemd[2124]: Reached target default.target - Main User Target.
May 14 18:08:51.693683 systemd[2124]: Startup finished in 356ms.
May 14 18:08:51.693781 systemd[1]: Started user@500.service - User Manager for UID 500.
May 14 18:08:51.695221 systemd[1]: Started session-2.scope - Session 2 of User core.
May 14 18:08:51.761303 waagent[2011]: 2025-05-14T18:08:51.761188Z INFO Daemon Daemon Successfully mounted dvd
May 14 18:08:51.802403 waagent[2011]: 2025-05-14T18:08:51.802353Z INFO Daemon Daemon Detect protocol endpoint
May 14 18:08:51.802553 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
May 14 18:08:51.802658 waagent[2011]: 2025-05-14T18:08:51.802615Z INFO Daemon Daemon Clean protocol and wireserver endpoint
May 14 18:08:51.804142 waagent[2011]: 2025-05-14T18:08:51.804091Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
May 14 18:08:51.805563 waagent[2011]: 2025-05-14T18:08:51.805526Z INFO Daemon Daemon Test for route to 168.63.129.16
May 14 18:08:51.807100 waagent[2011]: 2025-05-14T18:08:51.807061Z INFO Daemon Daemon Route to 168.63.129.16 exists
May 14 18:08:51.807736 waagent[2011]: 2025-05-14T18:08:51.807702Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
May 14 18:08:51.831342 waagent[2011]: 2025-05-14T18:08:51.831291Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
May 14 18:08:51.831658 waagent[2011]: 2025-05-14T18:08:51.831630Z INFO Daemon Daemon Wire protocol version:2012-11-30
May 14 18:08:51.831790 waagent[2011]: 2025-05-14T18:08:51.831743Z INFO Daemon Daemon Server preferred version:2015-04-05
May 14 18:08:51.883362 login[2013]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
May 14 18:08:51.887854 systemd-logind[1874]: New session 1 of user core.
May 14 18:08:51.900910 systemd[1]: Started session-1.scope - Session 1 of User core.
May 14 18:08:51.975213 waagent[2011]: 2025-05-14T18:08:51.975127Z INFO Daemon Daemon Initializing goal state during protocol detection
May 14 18:08:51.975486 waagent[2011]: 2025-05-14T18:08:51.975445Z INFO Daemon Daemon Forcing an update of the goal state.
May 14 18:08:51.980193 waagent[2011]: 2025-05-14T18:08:51.980150Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1]
May 14 18:08:52.005549 waagent[2011]: 2025-05-14T18:08:52.005507Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164
May 14 18:08:52.006120 waagent[2011]: 2025-05-14T18:08:52.006079Z INFO Daemon
May 14 18:08:52.006487 waagent[2011]: 2025-05-14T18:08:52.006453Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: baa9a038-2a62-4a75-850b-819eb7c03f90 eTag: 8877987970594279713 source: Fabric]
May 14 18:08:52.008205 waagent[2011]: 2025-05-14T18:08:52.008165Z INFO Daemon The vmSettings originated via Fabric; will ignore them.
May 14 18:08:52.008971 waagent[2011]: 2025-05-14T18:08:52.008933Z INFO Daemon
May 14 18:08:52.009315 waagent[2011]: 2025-05-14T18:08:52.009284Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1]
May 14 18:08:52.014121 waagent[2011]: 2025-05-14T18:08:52.014054Z INFO Daemon Daemon Downloading artifacts profile blob
May 14 18:08:52.130607 waagent[2011]: 2025-05-14T18:08:52.130537Z INFO Daemon Downloaded certificate {'thumbprint': 'C2E7CC3877C07897B2079E18105025241A2B4E19', 'hasPrivateKey': True}
May 14 18:08:52.131183 waagent[2011]: 2025-05-14T18:08:52.131142Z INFO Daemon Downloaded certificate {'thumbprint': '5D09E533251A7839C3EC2C6B09878BE48C81271A', 'hasPrivateKey': False}
May 14 18:08:52.131897 waagent[2011]: 2025-05-14T18:08:52.131856Z INFO Daemon Fetch goal state completed
May 14 18:08:52.162381 waagent[2011]: 2025-05-14T18:08:52.162341Z INFO Daemon Daemon Starting provisioning
May 14 18:08:52.162581 waagent[2011]: 2025-05-14T18:08:52.162540Z INFO Daemon Daemon Handle ovf-env.xml.
May 14 18:08:52.164025 waagent[2011]: 2025-05-14T18:08:52.163978Z INFO Daemon Daemon Set hostname [ci-4334.0.0-a-41d4cf11f8]
May 14 18:08:52.188605 waagent[2011]: 2025-05-14T18:08:52.188518Z INFO Daemon Daemon Publish hostname [ci-4334.0.0-a-41d4cf11f8]
May 14 18:08:52.188991 waagent[2011]: 2025-05-14T18:08:52.188946Z INFO Daemon Daemon Examine /proc/net/route for primary interface
May 14 18:08:52.189703 waagent[2011]: 2025-05-14T18:08:52.189664Z INFO Daemon Daemon Primary interface is [eth0]
May 14 18:08:52.199270 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
May 14 18:08:52.199279 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
May 14 18:08:52.199310 systemd-networkd[1587]: eth0: DHCP lease lost
May 14 18:08:52.199765 waagent[2011]: 2025-05-14T18:08:52.199705Z INFO Daemon Daemon Create user account if not exists
May 14 18:08:52.200514 waagent[2011]: 2025-05-14T18:08:52.200476Z INFO Daemon Daemon User core already exists, skip useradd
May 14 18:08:52.201945 waagent[2011]: 2025-05-14T18:08:52.201911Z INFO Daemon Daemon Configure sudoer
May 14 18:08:52.207446 waagent[2011]: 2025-05-14T18:08:52.207405Z INFO Daemon Daemon Configure sshd
May 14 18:08:52.212062 waagent[2011]: 2025-05-14T18:08:52.212017Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive.
May 14 18:08:52.212319 waagent[2011]: 2025-05-14T18:08:52.212285Z INFO Daemon Daemon Deploy ssh public key.
May 14 18:08:52.280792 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16
May 14 18:08:53.376394 waagent[2011]: 2025-05-14T18:08:53.376326Z INFO Daemon Daemon Provisioning complete
May 14 18:08:53.384857 waagent[2011]: 2025-05-14T18:08:53.384797Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
May 14 18:08:53.385095 waagent[2011]: 2025-05-14T18:08:53.385056Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
May 14 18:08:53.386616 waagent[2011]: 2025-05-14T18:08:53.386581Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent
May 14 18:08:53.529802 waagent[2187]: 2025-05-14T18:08:53.529700Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4)
May 14 18:08:53.530136 waagent[2187]: 2025-05-14T18:08:53.529868Z INFO ExtHandler ExtHandler OS: flatcar 4334.0.0
May 14 18:08:53.530136 waagent[2187]: 2025-05-14T18:08:53.529937Z INFO ExtHandler ExtHandler Python: 3.11.12
May 14 18:08:53.530136 waagent[2187]: 2025-05-14T18:08:53.530006Z INFO ExtHandler ExtHandler CPU Arch: x86_64
May 14 18:08:53.637061 waagent[2187]: 2025-05-14T18:08:53.636960Z INFO ExtHandler ExtHandler Distro: flatcar-4334.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0;
May 14 18:08:53.637193 waagent[2187]: 2025-05-14T18:08:53.637153Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 14 18:08:53.637268 waagent[2187]: 2025-05-14T18:08:53.637235Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
May 14 18:08:53.643982 waagent[2187]: 2025-05-14T18:08:53.643917Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
May 14 18:08:53.648812 waagent[2187]: 2025-05-14T18:08:53.648763Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164
May 14 18:08:53.649243 waagent[2187]: 2025-05-14T18:08:53.649202Z INFO ExtHandler
May 14 18:08:53.649321 waagent[2187]: 2025-05-14T18:08:53.649288Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 97f34514-edd3-4884-b3ad-55cce62def09 eTag: 8877987970594279713 source: Fabric]
May 14 18:08:53.649632 waagent[2187]: 2025-05-14T18:08:53.649593Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
May 14 18:08:53.650243 waagent[2187]: 2025-05-14T18:08:53.650204Z INFO ExtHandler
May 14 18:08:53.650314 waagent[2187]: 2025-05-14T18:08:53.650283Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
May 14 18:08:53.653600 waagent[2187]: 2025-05-14T18:08:53.653557Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
May 14 18:08:53.721233 waagent[2187]: 2025-05-14T18:08:53.721173Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C2E7CC3877C07897B2079E18105025241A2B4E19', 'hasPrivateKey': True}
May 14 18:08:53.721597 waagent[2187]: 2025-05-14T18:08:53.721548Z INFO ExtHandler Downloaded certificate {'thumbprint': '5D09E533251A7839C3EC2C6B09878BE48C81271A', 'hasPrivateKey': False}
May 14 18:08:53.722056 waagent[2187]: 2025-05-14T18:08:53.722007Z INFO ExtHandler Fetch goal state completed
May 14 18:08:53.732764 waagent[2187]: 2025-05-14T18:08:53.732707Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025)
May 14 18:08:53.737844 waagent[2187]: 2025-05-14T18:08:53.737796Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2187
May 14 18:08:53.737977 waagent[2187]: 2025-05-14T18:08:53.737941Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ********
May 14 18:08:53.738303 waagent[2187]: 2025-05-14T18:08:53.738265Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ********
May 14 18:08:53.739926 waagent[2187]: 2025-05-14T18:08:53.739884Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk']
May 14 18:08:53.740378 waagent[2187]: 2025-05-14T18:08:53.740327Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported
May 14 18:08:53.740519 waagent[2187]: 2025-05-14T18:08:53.740473Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False
May 14 18:08:53.741150 waagent[2187]: 2025-05-14T18:08:53.741098Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
May 14 18:08:53.793824 waagent[2187]: 2025-05-14T18:08:53.793776Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
May 14 18:08:53.794008 waagent[2187]: 2025-05-14T18:08:53.793969Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
May 14 18:08:53.802217 waagent[2187]: 2025-05-14T18:08:53.802185Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
May 14 18:08:53.809229 systemd[1]: Reload requested from client PID 2204 ('systemctl') (unit waagent.service)...
May 14 18:08:53.809245 systemd[1]: Reloading...
May 14 18:08:53.871786 zram_generator::config[2245]: No configuration found.
May 14 18:08:53.986303 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.
May 14 18:08:54.147867 systemd[1]: Reloading finished in 338 ms.
May 14 18:08:54.173334 waagent[2187]: 2025-05-14T18:08:54.173258Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service
May 14 18:08:54.173447 waagent[2187]: 2025-05-14T18:08:54.173411Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully
May 14 18:08:54.732724 waagent[2187]: 2025-05-14T18:08:54.732642Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up.
May 14 18:08:54.733111 waagent[2187]: 2025-05-14T18:08:54.733018Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True]
May 14 18:08:54.733846 waagent[2187]: 2025-05-14T18:08:54.733795Z INFO ExtHandler ExtHandler Starting env monitor service.
May 14 18:08:54.734080 waagent[2187]: 2025-05-14T18:08:54.734034Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 14 18:08:54.734422 waagent[2187]: 2025-05-14T18:08:54.734385Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
May 14 18:08:54.734455 waagent[2187]: 2025-05-14T18:08:54.734422Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
May 14 18:08:54.734836 waagent[2187]: 2025-05-14T18:08:54.734792Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
May 14 18:08:54.735144 waagent[2187]: 2025-05-14T18:08:54.735099Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
May 14 18:08:54.735281 waagent[2187]: 2025-05-14T18:08:54.735225Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
May 14 18:08:54.735422 waagent[2187]: 2025-05-14T18:08:54.735385Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
May 14 18:08:54.735812 waagent[2187]: 2025-05-14T18:08:54.735746Z INFO EnvHandler ExtHandler Configure routes
May 14 18:08:54.736005 waagent[2187]: 2025-05-14T18:08:54.735958Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
May 14 18:08:54.736160 waagent[2187]: 2025-05-14T18:08:54.736123Z INFO EnvHandler ExtHandler Gateway:None
May 14 18:08:54.736513 waagent[2187]: 2025-05-14T18:08:54.736473Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
May 14 18:08:54.736513 waagent[2187]: Iface        Destination        Gateway         Flags        RefCnt        Use        Metric        Mask                MTU        Window        IRTT
May 14 18:08:54.736513 waagent[2187]: eth0        00000000        0108C80A        0003        0        0        1024        00000000        0        0        0
May 14 18:08:54.736513 waagent[2187]: eth0        0008C80A        00000000        0001        0        0        1024        00FFFFFF        0        0        0
May 14 18:08:54.736513 waagent[2187]: eth0        0108C80A        00000000        0005        0        0        1024        FFFFFFFF        0        0        0
May 14 18:08:54.736513 waagent[2187]: eth0        10813FA8        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 14 18:08:54.736513 waagent[2187]: eth0        FEA9FEA9        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
May 14 18:08:54.736700 waagent[2187]: 2025-05-14T18:08:54.736646Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
May 14 18:08:54.736734 waagent[2187]: 2025-05-14T18:08:54.736703Z INFO EnvHandler ExtHandler Routes:None
May 14 18:08:54.736914 waagent[2187]: 2025-05-14T18:08:54.736874Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
May 14 18:08:54.737519 waagent[2187]: 2025-05-14T18:08:54.737480Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
May 14 18:08:54.743443 waagent[2187]: 2025-05-14T18:08:54.743397Z INFO ExtHandler ExtHandler
May 14 18:08:54.743767 waagent[2187]: 2025-05-14T18:08:54.743718Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 95e1d005-fe47-46b3-a087-89458fef7b7b correlation 699487f6-c4d4-47db-ae09-3c75bc0caec7 created: 2025-05-14T18:07:09.689972Z]
May 14 18:08:54.744846 waagent[2187]: 2025-05-14T18:08:54.744804Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
May 14 18:08:54.747028 waagent[2187]: 2025-05-14T18:08:54.746979Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms]
May 14 18:08:54.808505 waagent[2187]: 2025-05-14T18:08:54.808459Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command
May 14 18:08:54.808505 waagent[2187]: Try `iptables -h' or 'iptables --help' for more information.)
May 14 18:08:54.808889 waagent[2187]: 2025-05-14T18:08:54.808851Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 8716AFA8-762E-4841-90C3-F6C4EF8DC507;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;]
May 14 18:08:54.928597 waagent[2187]: 2025-05-14T18:08:54.928525Z INFO MonitorHandler ExtHandler Network interfaces:
May 14 18:08:54.928597 waagent[2187]: Executing ['ip', '-a', '-o', 'link']:
May 14 18:08:54.928597 waagent[2187]: 1: lo: <LOOPBACK,UP,LOWER_UP> 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
May 14 18:08:54.928597 waagent[2187]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\    link/ether 60:45:bd:de:59:f4 brd ff:ff:ff:ff:ff:ff
May 14 18:08:54.928597 waagent[2187]: Executing ['ip', '-4', '-a', '-o', 'address']:
May 14 18:08:54.928597 waagent[2187]: 1: lo    inet 127.0.0.1/8 scope host lo\       valid_lft forever preferred_lft forever
May 14 18:08:54.928597 waagent[2187]: 2: eth0    inet 10.200.8.36/24 metric 1024 brd 10.200.8.255 scope global eth0\       valid_lft forever preferred_lft forever
May 14 18:08:54.928597 waagent[2187]: Executing ['ip', '-6', '-a', '-o', 'address']:
May 14 18:08:54.928597 waagent[2187]: 1: lo    inet6 ::1/128 scope host noprefixroute \       valid_lft forever preferred_lft forever
May 14 18:08:54.928597 waagent[2187]: 2: eth0    inet6 fe80::6245:bdff:fede:59f4/64 scope link proto kernel_ll \       valid_lft forever preferred_lft forever
May 14 18:08:54.991550 waagent[2187]: 2025-05-14T18:08:54.991455Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric:
May 14 18:08:54.991550 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
May 14 18:08:54.991550 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.991550 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
May 14 18:08:54.991550 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.991550 waagent[2187]: Chain OUTPUT (policy ACCEPT 6 packets, 520 bytes)
May 14 18:08:54.991550 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.991550 waagent[2187]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
May 14 18:08:54.991550 waagent[2187]:        9      995 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
May 14 18:08:54.991550 waagent[2187]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
May 14 18:08:54.995339 waagent[2187]: 2025-05-14T18:08:54.995297Z INFO EnvHandler ExtHandler Current Firewall rules:
May 14 18:08:54.995339 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
May 14 18:08:54.995339 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.995339 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
May 14 18:08:54.995339 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.995339 waagent[2187]: Chain OUTPUT (policy ACCEPT 9 packets, 869 bytes)
May 14 18:08:54.995339 waagent[2187]:     pkts      bytes target     prot opt in     out     source               destination
May 14 18:08:54.995339 waagent[2187]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
May 14 18:08:54.995339 waagent[2187]:        9      995 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
May 14 18:08:54.995339 waagent[2187]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
May 14 18:08:54.995582 waagent[2187]: 2025-05-14T18:08:54.995549Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300
May 14 18:08:54.995728 waagent[2187]: 2025-05-14T18:08:54.995698Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300
May 14 18:09:06.355876 chronyd[1904]: Selected source PHC0
May 14 18:09:25.104459 kernel: hv_balloon: Max. dynamic memory size: 114688 MB
May 14 18:09:26.763325 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
May 14 18:09:26.764598 systemd[1]: Started sshd@0-10.200.8.36:22-10.200.16.10:50816.service - OpenSSH per-connection server daemon (10.200.16.10:50816).
May 14 18:09:27.661372 sshd[2338]: Accepted publickey for core from 10.200.16.10 port 50816 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:27.662642 sshd-session[2338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:27.666867 systemd-logind[1874]: New session 3 of user core.
May 14 18:09:27.686888 systemd[1]: Started session-3.scope - Session 3 of User core.
May 14 18:09:28.216686 systemd[1]: Started sshd@1-10.200.8.36:22-10.200.16.10:50822.service - OpenSSH per-connection server daemon (10.200.16.10:50822).
May 14 18:09:28.313468 update_engine[1879]: I20250514 18:09:28.313409  1879 update_attempter.cc:509] Updating boot flags...
May 14 18:09:28.853843 sshd[2343]: Accepted publickey for core from 10.200.16.10 port 50822 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:28.855110 sshd-session[2343]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:28.859593 systemd-logind[1874]: New session 4 of user core.
May 14 18:09:28.878876 systemd[1]: Started session-4.scope - Session 4 of User core.
May 14 18:09:29.302448 sshd[2461]: Connection closed by 10.200.16.10 port 50822
May 14 18:09:29.302813 sshd-session[2343]: pam_unix(sshd:session): session closed for user core
May 14 18:09:29.306351 systemd[1]: sshd@1-10.200.8.36:22-10.200.16.10:50822.service: Deactivated successfully.
May 14 18:09:29.308154 systemd[1]: session-4.scope: Deactivated successfully.
May 14 18:09:29.308996 systemd-logind[1874]: Session 4 logged out. Waiting for processes to exit.
May 14 18:09:29.310039 systemd-logind[1874]: Removed session 4.
May 14 18:09:29.424261 systemd[1]: Started sshd@2-10.200.8.36:22-10.200.16.10:34644.service - OpenSSH per-connection server daemon (10.200.16.10:34644).
May 14 18:09:30.062840 sshd[2467]: Accepted publickey for core from 10.200.16.10 port 34644 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:30.085241 sshd-session[2467]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:30.090875 systemd-logind[1874]: New session 5 of user core.
May 14 18:09:30.101864 systemd[1]: Started session-5.scope - Session 5 of User core.
May 14 18:09:30.514512 sshd[2469]: Connection closed by 10.200.16.10 port 34644
May 14 18:09:30.514870 sshd-session[2467]: pam_unix(sshd:session): session closed for user core
May 14 18:09:30.517559 systemd[1]: sshd@2-10.200.8.36:22-10.200.16.10:34644.service: Deactivated successfully.
May 14 18:09:30.519145 systemd[1]: session-5.scope: Deactivated successfully.
May 14 18:09:30.520333 systemd-logind[1874]: Session 5 logged out. Waiting for processes to exit.
May 14 18:09:30.521312 systemd-logind[1874]: Removed session 5.
May 14 18:09:40.640120 systemd[1]: Started sshd@3-10.200.8.36:22-10.200.16.10:50526.service - OpenSSH per-connection server daemon (10.200.16.10:50526).
May 14 18:09:41.278192 sshd[2475]: Accepted publickey for core from 10.200.16.10 port 50526 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:41.279464 sshd-session[2475]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:41.284076 systemd-logind[1874]: New session 6 of user core.
May 14 18:09:41.303866 systemd[1]: Started session-6.scope - Session 6 of User core.
May 14 18:09:41.727237 sshd[2477]: Connection closed by 10.200.16.10 port 50526
May 14 18:09:41.727576 sshd-session[2475]: pam_unix(sshd:session): session closed for user core
May 14 18:09:41.730775 systemd[1]: sshd@3-10.200.8.36:22-10.200.16.10:50526.service: Deactivated successfully.
May 14 18:09:41.732354 systemd[1]: session-6.scope: Deactivated successfully.
May 14 18:09:41.733048 systemd-logind[1874]: Session 6 logged out. Waiting for processes to exit.
May 14 18:09:41.733973 systemd-logind[1874]: Removed session 6.
May 14 18:09:41.838971 systemd[1]: Started sshd@4-10.200.8.36:22-10.200.16.10:50538.service - OpenSSH per-connection server daemon (10.200.16.10:50538).
May 14 18:09:42.490097 sshd[2483]: Accepted publickey for core from 10.200.16.10 port 50538 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:42.491404 sshd-session[2483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:42.495739 systemd-logind[1874]: New session 7 of user core.
May 14 18:09:42.505874 systemd[1]: Started session-7.scope - Session 7 of User core.
May 14 18:09:42.945301 sshd[2485]: Connection closed by 10.200.16.10 port 50538
May 14 18:09:42.945653 sshd-session[2483]: pam_unix(sshd:session): session closed for user core
May 14 18:09:42.948887 systemd[1]: sshd@4-10.200.8.36:22-10.200.16.10:50538.service: Deactivated successfully.
May 14 18:09:42.950394 systemd[1]: session-7.scope: Deactivated successfully.
May 14 18:09:42.951049 systemd-logind[1874]: Session 7 logged out. Waiting for processes to exit.
May 14 18:09:42.951966 systemd-logind[1874]: Removed session 7.
May 14 18:09:43.065066 setup-nvidia[2490]:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
May 14 18:09:43.065743 setup-nvidia[2490]:                                  Dload  Upload   Total   Spent    Left  Speed
May 14 18:09:53.064730 systemd[1]: Started sshd@5-10.200.8.36:22-10.200.16.10:41630.service - OpenSSH per-connection server daemon (10.200.16.10:41630).
May 14 18:09:53.702596 sshd[2492]: Accepted publickey for core from 10.200.16.10 port 41630 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:53.703909 sshd-session[2492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:53.708017 systemd-logind[1874]: New session 8 of user core.
May 14 18:09:53.724865 systemd[1]: Started session-8.scope - Session 8 of User core.
May 14 18:09:54.153133 sshd[2494]: Connection closed by 10.200.16.10 port 41630
May 14 18:09:54.153487 sshd-session[2492]: pam_unix(sshd:session): session closed for user core
May 14 18:09:54.156392 systemd[1]: sshd@5-10.200.8.36:22-10.200.16.10:41630.service: Deactivated successfully.
May 14 18:09:54.158718 systemd[1]: session-8.scope: Deactivated successfully.
May 14 18:09:54.160071 systemd-logind[1874]: Session 8 logged out. Waiting for processes to exit.
May 14 18:09:54.161153 systemd-logind[1874]: Removed session 8.
May 14 18:09:54.268934 systemd[1]: Started sshd@6-10.200.8.36:22-10.200.16.10:41642.service - OpenSSH per-connection server daemon (10.200.16.10:41642).
May 14 18:09:54.922914 sshd[2500]: Accepted publickey for core from 10.200.16.10 port 41642 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:09:54.924211 sshd-session[2500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:09:54.928948 systemd-logind[1874]: New session 9 of user core.
May 14 18:09:54.940900 systemd[1]: Started session-9.scope - Session 9 of User core.
May 14 18:09:55.379603 sshd[2502]: Connection closed by 10.200.16.10 port 41642
May 14 18:09:55.379906 sshd-session[2500]: pam_unix(sshd:session): session closed for user core
May 14 18:09:55.383118 systemd[1]: sshd@6-10.200.8.36:22-10.200.16.10:41642.service: Deactivated successfully.
May 14 18:09:55.384631 systemd[1]: session-9.scope: Deactivated successfully.
May 14 18:09:55.385364 systemd-logind[1874]: Session 9 logged out. Waiting for processes to exit.
May 14 18:09:55.386289 systemd-logind[1874]: Removed session 9.
May 14 18:10:05.498203 systemd[1]: Started sshd@7-10.200.8.36:22-10.200.16.10:49420.service - OpenSSH per-connection server daemon (10.200.16.10:49420).
May 14 18:10:06.140412 sshd[2508]: Accepted publickey for core from 10.200.16.10 port 49420 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:06.141718 sshd-session[2508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:06.146013 systemd-logind[1874]: New session 10 of user core.
May 14 18:10:06.161870 systemd[1]: Started session-10.scope - Session 10 of User core.
May 14 18:10:06.589278 sshd[2510]: Connection closed by 10.200.16.10 port 49420
May 14 18:10:06.589624 sshd-session[2508]: pam_unix(sshd:session): session closed for user core
May 14 18:10:06.592942 systemd[1]: sshd@7-10.200.8.36:22-10.200.16.10:49420.service: Deactivated successfully.
May 14 18:10:06.594437 systemd[1]: session-10.scope: Deactivated successfully.
May 14 18:10:06.595088 systemd-logind[1874]: Session 10 logged out. Waiting for processes to exit.
May 14 18:10:06.596074 systemd-logind[1874]: Removed session 10.
May 14 18:10:06.705193 systemd[1]: Started sshd@8-10.200.8.36:22-10.200.16.10:49434.service - OpenSSH per-connection server daemon (10.200.16.10:49434).
May 14 18:10:07.271107 setup-nvidia[2490]: \u000d  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0\u000d  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0\u000d  0  543M    0 1616k    0     0  1149k      0  0:08:04  0:00:01  0:08:03 1148k\u000d  4  543M    4 26.4M    0     0  11.0M      0  0:00:49  0:00:02  0:00:47 11.0M\u000d  9  543M    9 51.4M    0     0  14.6M      0  0:00:37  0:00:03  0:00:34 14.6M\u000d 13  543M   13 75.5M    0     0  16.7M      0  0:00:32  0:00:04  0:00:28 16.7M\u000d 18  543M   18 99.5M    0     0  18.1M      0  0:00:29  0:00:05  0:00:24 19.7M\u000d 22  543M   22  124M    0     0  19.2M      0  0:00:28  0:00:06  0:00:22 24.2M\u000d 27  543M   27  147M    0     0  19.7M      0  0:00:27  0:00:07  0:00:20 23.9M\u000d 31  543M   31  172M    0     0  20.3M      0  0:00:26  0:00:08  0:00:18 24.3M\u000d 35  543M   35  194M    0     0  20.6M      0  0:00:26  0:00:09  0:00:17 24.1M\u000d 40  543M   40  218M    0     0  20.8M      0  0:00:26  0:00:10  0:00:16 23.9M\u000d 44  543M   44  241M    0     0  21.0M      0  0:00:25  0:00:11  0:00:14 23.5M\u000d 48  543M   48  264M    0     0  21.2M      0  0:00:25  0:00:12  0:00:13 23.4M\u000d 52  543M   52  286M    0     0  21.3M      0  0:00:25  0:00:13  0:00:12 23.0M\u000d 57  543M   57  310M    0     0  21.5M      0  0:00:25  0:00:14  0:00:11 23.2M\u000d 61  543M   61  333M    0     0  21.6M      0  0:00:25  0:00:15  0:00:10 23.0M\u000d 65  543M   65  357M    0     0  21.7M      0  0:00:24  0:00:16  0:00:08 23.3M\u000d 70  543M   70  381M    0     0  21.9M      0  0:00:24  0:00:17  0:00:07 23.6M\u000d 74  543M   74  405M    0     0  21.8M      0  0:00:24  0:00:18  0:00:06 23.3M\u000d 78  543M   78  428M    0     0  21.9M      0  0:00:24  0:00:19  0:00:05 23.1M\u000d 83  543M   83  453M    0     0  22.0M      0  0:00:24  0:00:20  0:00:04 23.5M\u000d 87  543M   87  477M    0     0  22.1M      0  0:00:24  0:00:21  0:00:03 23.5M\u000d 92  543M   92  502M    0     0  22.3M      0  0:00:24  0:00:22  0:00:02 23.5M\u000d 96  543M   96  523M    0     0  22.2M      0  0:00:24  0:00:23  0:00:01 23.8M\u000d100  543M  100  543M    0     0  22.4M      0  0:00:24  0:00:24 --:--:-- 24.6M
May 14 18:10:07.343768 sshd[2517]: Accepted publickey for core from 10.200.16.10 port 49434 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:07.344271 sshd-session[2517]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:07.351879 systemd-logind[1874]: New session 11 of user core.
May 14 18:10:07.356896 systemd[1]: Started session-11.scope - Session 11 of User core.
May 14 18:10:07.799980 sshd[2529]: Connection closed by 10.200.16.10 port 49434
May 14 18:10:07.801611 sshd-session[2517]: pam_unix(sshd:session): session closed for user core
May 14 18:10:07.806048 systemd[1]: sshd@8-10.200.8.36:22-10.200.16.10:49434.service: Deactivated successfully.
May 14 18:10:07.810023 systemd[1]: session-11.scope: Deactivated successfully.
May 14 18:10:07.811364 systemd-logind[1874]: Session 11 logged out. Waiting for processes to exit.
May 14 18:10:07.812399 systemd-logind[1874]: Removed session 11.
May 14 18:10:17.915138 systemd[1]: Started sshd@9-10.200.8.36:22-10.200.16.10:36048.service - OpenSSH per-connection server daemon (10.200.16.10:36048).
May 14 18:10:18.558634 sshd[2535]: Accepted publickey for core from 10.200.16.10 port 36048 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:18.560505 sshd-session[2535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:18.567288 systemd-logind[1874]: New session 12 of user core.
May 14 18:10:18.572998 systemd[1]: Started session-12.scope - Session 12 of User core.
May 14 18:10:19.019706 sshd[2537]: Connection closed by 10.200.16.10 port 36048
May 14 18:10:19.020575 sshd-session[2535]: pam_unix(sshd:session): session closed for user core
May 14 18:10:19.023929 systemd[1]: sshd@9-10.200.8.36:22-10.200.16.10:36048.service: Deactivated successfully.
May 14 18:10:19.025394 systemd[1]: session-12.scope: Deactivated successfully.
May 14 18:10:19.031451 systemd-logind[1874]: Session 12 logged out. Waiting for processes to exit.
May 14 18:10:19.033290 systemd-logind[1874]: Removed session 12.
May 14 18:10:19.138106 systemd[1]: Started sshd@10-10.200.8.36:22-10.200.16.10:43908.service - OpenSSH per-connection server daemon (10.200.16.10:43908).
May 14 18:10:19.803003 sshd[2543]: Accepted publickey for core from 10.200.16.10 port 43908 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:19.804420 sshd-session[2543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:19.812775 systemd-logind[1874]: New session 13 of user core.
May 14 18:10:19.818864 systemd[1]: Started session-13.scope - Session 13 of User core.
May 14 18:10:20.285631 sshd[2545]: Connection closed by 10.200.16.10 port 43908
May 14 18:10:20.285464 sshd-session[2543]: pam_unix(sshd:session): session closed for user core
May 14 18:10:20.289086 systemd[1]: sshd@10-10.200.8.36:22-10.200.16.10:43908.service: Deactivated successfully.
May 14 18:10:20.290677 systemd[1]: session-13.scope: Deactivated successfully.
May 14 18:10:20.295415 systemd-logind[1874]: Session 13 logged out. Waiting for processes to exit.
May 14 18:10:20.296549 systemd-logind[1874]: Removed session 13.
May 14 18:10:20.758421 setup-nvidia[1868]: Downloading NVIDIA 535.230.02 Driver
May 14 18:10:20.762624 setup-nvidia[2551]:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
May 14 18:10:20.762854 setup-nvidia[2551]:                                  Dload  Upload   Total   Spent    Left  Speed
May 14 18:10:22.677037 setup-nvidia[2551]: \u000d  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0\u000d 21  326M   21 70.2M    0     0  99.8M      0  0:00:03 --:--:--  0:00:03 99.7M\u000d 85  326M   85  279M    0     0   164M      0  0:00:01  0:00:01 --:--:--  164M\u000d100  326M  100  326M    0     0   170M      0  0:00:01  0:00:01 --:--:--  170M
May 14 18:10:22.702439 setup-nvidia[1868]: Extract the NVIDIA Driver Installer 535.230.02
May 14 18:10:22.702630 setup-nvidia[1868]: /opt/nvidia/workdir/nvidia-workdir /
May 14 18:10:22.720174 setup-nvidia[2554]: Creating directory NVIDIA-Linux-x86_64-535.230.02
May 14 18:10:23.007118 setup-nvidia[2554]: Verifying archive integrity... OK
May 14 18:10:23.673145 setup-nvidia[2554]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02
May 14 18:10:25.445409 setup-nvidia[2580]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................
May 14 18:10:25.507784 setup-nvidia[1868]: /
May 14 18:10:25.507994 setup-nvidia[1868]: Spawn system-nspawn container to install the NVIDIA drivers
May 14 18:10:25.597072 sudo[2587]:     root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02
May 14 18:10:25.598773 sudo[2587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
May 14 18:10:25.604354 systemd[1]: Created slice user-0.slice - User Slice of UID 0.
May 14 18:10:25.608981 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0...
May 14 18:10:25.643892 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0.
May 14 18:10:25.646285 systemd[1]: Starting user@0.service - User Manager for UID 0...
May 14 18:10:25.668651 (systemd)[2590]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)
May 14 18:10:25.670827 systemd-logind[1874]: New session c3 of user root.
May 14 18:10:25.859376 systemd[2590]: Queued start job for default target default.target.
May 14 18:10:25.877336 systemd[2590]: Created slice app.slice - User Application Slice.
May 14 18:10:25.877368 systemd[2590]: Reached target paths.target - Paths.
May 14 18:10:25.877446 systemd[2590]: Reached target timers.target - Timers.
May 14 18:10:25.878615 systemd[2590]: Starting dbus.socket - D-Bus User Message Bus Socket...
May 14 18:10:25.889130 systemd[2590]: Listening on dbus.socket - D-Bus User Message Bus Socket.
May 14 18:10:25.889242 systemd[2590]: Reached target sockets.target - Sockets.
May 14 18:10:25.889295 systemd[2590]: Reached target basic.target - Basic System.
May 14 18:10:25.889346 systemd[2590]: Reached target default.target - Main User Target.
May 14 18:10:25.889376 systemd[2590]: Startup finished in 211ms.
May 14 18:10:25.889438 systemd[1]: Started user@0.service - User Manager for UID 0.
May 14 18:10:25.891016 systemd[1]: Started session-c2.scope - Session c2 of User root.
May 14 18:10:25.941769 kernel: loop6: detected capacity change from 0 to 12644352
May 14 18:10:25.949773 kernel:  loop6: p9
May 14 18:10:26.021299 setup-nvidia[2599]: ░ Spawning container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.0.bin.
May 14 18:10:26.703067 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup
May 14 18:10:26.703205 kernel: EXT4-fs (loop6p9): mounted filesystem 4c03bb24-ac25-4d8b-af70-04c72efb9bcb ro without journal. Quota mode: none.
May 14 18:10:30.395130 systemd[1]: Started sshd@11-10.200.8.36:22-10.200.16.10:35522.service - OpenSSH per-connection server daemon (10.200.16.10:35522).
May 14 18:10:31.040718 sshd[3231]: Accepted publickey for core from 10.200.16.10 port 35522 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:31.042101 sshd-session[3231]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:31.046464 systemd-logind[1874]: New session 14 of user core.
May 14 18:10:31.059528 systemd[1]: Started session-14.scope - Session 14 of User core.
May 14 18:10:31.494826 sshd[3321]: Connection closed by 10.200.16.10 port 35522
May 14 18:10:31.495170 sshd-session[3231]: pam_unix(sshd:session): session closed for user core
May 14 18:10:31.498558 systemd[1]: sshd@11-10.200.8.36:22-10.200.16.10:35522.service: Deactivated successfully.
May 14 18:10:31.500289 systemd[1]: session-14.scope: Deactivated successfully.
May 14 18:10:31.501085 systemd-logind[1874]: Session 14 logged out. Waiting for processes to exit.
May 14 18:10:31.502314 systemd-logind[1874]: Removed session 14.
May 14 18:10:31.606936 systemd[1]: Started sshd@12-10.200.8.36:22-10.200.16.10:35526.service - OpenSSH per-connection server daemon (10.200.16.10:35526).
May 14 18:10:32.245383 sshd[3327]: Accepted publickey for core from 10.200.16.10 port 35526 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:32.246890 sshd-session[3327]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:32.251294 systemd-logind[1874]: New session 15 of user core.
May 14 18:10:32.262923 systemd[1]: Started session-15.scope - Session 15 of User core.
May 14 18:10:32.699409 sshd[3349]: Connection closed by 10.200.16.10 port 35526
May 14 18:10:32.699742 sshd-session[3327]: pam_unix(sshd:session): session closed for user core
May 14 18:10:32.702979 systemd[1]: sshd@12-10.200.8.36:22-10.200.16.10:35526.service: Deactivated successfully.
May 14 18:10:32.704616 systemd[1]: session-15.scope: Deactivated successfully.
May 14 18:10:32.705286 systemd-logind[1874]: Session 15 logged out. Waiting for processes to exit.
May 14 18:10:32.706242 systemd-logind[1874]: Removed session 15.
May 14 18:10:42.819165 systemd[1]: Started sshd@13-10.200.8.36:22-10.200.16.10:51524.service - OpenSSH per-connection server daemon (10.200.16.10:51524).
May 14 18:10:43.477826 sshd[6098]: Accepted publickey for core from 10.200.16.10 port 51524 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:43.479121 sshd-session[6098]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:43.483434 systemd-logind[1874]: New session 16 of user core.
May 14 18:10:43.495005 systemd[1]: Started session-16.scope - Session 16 of User core.
May 14 18:10:43.928932 sshd[6176]: Connection closed by 10.200.16.10 port 51524
May 14 18:10:43.930657 sshd-session[6098]: pam_unix(sshd:session): session closed for user core
May 14 18:10:43.934866 systemd-logind[1874]: Session 16 logged out. Waiting for processes to exit.
May 14 18:10:43.936258 systemd[1]: sshd@13-10.200.8.36:22-10.200.16.10:51524.service: Deactivated successfully.
May 14 18:10:43.937851 systemd[1]: session-16.scope: Deactivated successfully.
May 14 18:10:43.940994 systemd-logind[1874]: Removed session 16.
May 14 18:10:44.047964 systemd[1]: Started sshd@14-10.200.8.36:22-10.200.16.10:51530.service - OpenSSH per-connection server daemon (10.200.16.10:51530).
May 14 18:10:44.703059 sshd[6356]: Accepted publickey for core from 10.200.16.10 port 51530 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:44.738213 sshd-session[6356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:44.743007 systemd-logind[1874]: New session 17 of user core.
May 14 18:10:44.752940 systemd[1]: Started session-17.scope - Session 17 of User core.
May 14 18:10:45.155581 sshd[6551]: Connection closed by 10.200.16.10 port 51530
May 14 18:10:45.155941 sshd-session[6356]: pam_unix(sshd:session): session closed for user core
May 14 18:10:45.166339 systemd-logind[1874]: Session 17 logged out. Waiting for processes to exit.
May 14 18:10:45.168578 systemd[1]: sshd@14-10.200.8.36:22-10.200.16.10:51530.service: Deactivated successfully.
May 14 18:10:45.171179 systemd[1]: session-17.scope: Deactivated successfully.
May 14 18:10:45.174991 systemd-logind[1874]: Removed session 17.
May 14 18:10:55.273996 systemd[1]: Started sshd@15-10.200.8.36:22-10.200.16.10:58192.service - OpenSSH per-connection server daemon (10.200.16.10:58192).
May 14 18:10:55.927673 sshd[10077]: Accepted publickey for core from 10.200.16.10 port 58192 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:55.931143 sshd-session[10077]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:55.938457 systemd-logind[1874]: New session 18 of user core.
May 14 18:10:55.946457 systemd[1]: Started session-18.scope - Session 18 of User core.
May 14 18:10:56.391610 sshd[10212]: Connection closed by 10.200.16.10 port 58192
May 14 18:10:56.392378 sshd-session[10077]: pam_unix(sshd:session): session closed for user core
May 14 18:10:56.398033 systemd-logind[1874]: Session 18 logged out. Waiting for processes to exit.
May 14 18:10:56.399610 systemd[1]: sshd@15-10.200.8.36:22-10.200.16.10:58192.service: Deactivated successfully.
May 14 18:10:56.402130 systemd[1]: session-18.scope: Deactivated successfully.
May 14 18:10:56.405071 systemd-logind[1874]: Removed session 18.
May 14 18:10:56.517983 systemd[1]: Started sshd@16-10.200.8.36:22-10.200.16.10:58194.service - OpenSSH per-connection server daemon (10.200.16.10:58194).
May 14 18:10:57.178286 sshd[10364]: Accepted publickey for core from 10.200.16.10 port 58194 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:10:57.180063 sshd-session[10364]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:10:57.186661 systemd-logind[1874]: New session 19 of user core.
May 14 18:10:57.192004 systemd[1]: Started session-19.scope - Session 19 of User core.
May 14 18:10:57.632126 sshd[10561]: Connection closed by 10.200.16.10 port 58194
May 14 18:10:57.632504 sshd-session[10364]: pam_unix(sshd:session): session closed for user core
May 14 18:10:57.636944 systemd[1]: sshd@16-10.200.8.36:22-10.200.16.10:58194.service: Deactivated successfully.
May 14 18:10:57.638008 systemd-logind[1874]: Session 19 logged out. Waiting for processes to exit.
May 14 18:10:57.639476 systemd[1]: session-19.scope: Deactivated successfully.
May 14 18:10:57.641976 systemd-logind[1874]: Removed session 19.
May 14 18:11:07.745969 systemd[1]: Started sshd@17-10.200.8.36:22-10.200.16.10:42010.service - OpenSSH per-connection server daemon (10.200.16.10:42010).
May 14 18:11:08.413062 sshd[11674]: Accepted publickey for core from 10.200.16.10 port 42010 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:08.414998 sshd-session[11674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:08.421810 systemd-logind[1874]: New session 20 of user core.
May 14 18:11:08.428844 systemd[1]: Started session-20.scope - Session 20 of User core.
May 14 18:11:08.867102 sshd[11700]: Connection closed by 10.200.16.10 port 42010
May 14 18:11:08.867976 sshd-session[11674]: pam_unix(sshd:session): session closed for user core
May 14 18:11:08.870955 systemd[1]: sshd@17-10.200.8.36:22-10.200.16.10:42010.service: Deactivated successfully.
May 14 18:11:08.872604 systemd[1]: session-20.scope: Deactivated successfully.
May 14 18:11:08.876608 systemd-logind[1874]: Session 20 logged out. Waiting for processes to exit.
May 14 18:11:08.878567 systemd-logind[1874]: Removed session 20.
May 14 18:11:08.988301 systemd[1]: Started sshd@18-10.200.8.36:22-10.200.16.10:47228.service - OpenSSH per-connection server daemon (10.200.16.10:47228).
May 14 18:11:09.656108 sshd[11748]: Accepted publickey for core from 10.200.16.10 port 47228 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:09.657733 sshd-session[11748]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:09.663674 systemd-logind[1874]: New session 21 of user core.
May 14 18:11:09.672919 systemd[1]: Started session-21.scope - Session 21 of User core.
May 14 18:11:10.117374 sshd[11762]: Connection closed by 10.200.16.10 port 47228
May 14 18:11:10.117693 sshd-session[11748]: pam_unix(sshd:session): session closed for user core
May 14 18:11:10.121208 systemd[1]: sshd@18-10.200.8.36:22-10.200.16.10:47228.service: Deactivated successfully.
May 14 18:11:10.125437 systemd[1]: session-21.scope: Deactivated successfully.
May 14 18:11:10.127152 systemd-logind[1874]: Session 21 logged out. Waiting for processes to exit.
May 14 18:11:10.128639 systemd-logind[1874]: Removed session 21.
May 14 18:11:20.241116 systemd[1]: Started sshd@19-10.200.8.36:22-10.200.16.10:36954.service - OpenSSH per-connection server daemon (10.200.16.10:36954).
May 14 18:11:20.901682 sshd[12092]: Accepted publickey for core from 10.200.16.10 port 36954 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:20.905607 sshd-session[12092]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:20.910535 systemd-logind[1874]: New session 22 of user core.
May 14 18:11:20.923922 systemd[1]: Started session-22.scope - Session 22 of User core.
May 14 18:11:21.353588 sshd[12112]: Connection closed by 10.200.16.10 port 36954
May 14 18:11:21.354044 sshd-session[12092]: pam_unix(sshd:session): session closed for user core
May 14 18:11:21.358167 systemd-logind[1874]: Session 22 logged out. Waiting for processes to exit.
May 14 18:11:21.366265 systemd[1]: sshd@19-10.200.8.36:22-10.200.16.10:36954.service: Deactivated successfully.
May 14 18:11:21.368403 systemd[1]: session-22.scope: Deactivated successfully.
May 14 18:11:21.372008 systemd-logind[1874]: Removed session 22.
May 14 18:11:21.480255 systemd[1]: Started sshd@20-10.200.8.36:22-10.200.16.10:36956.service - OpenSSH per-connection server daemon (10.200.16.10:36956).
May 14 18:11:22.144870 sshd[12148]: Accepted publickey for core from 10.200.16.10 port 36956 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:22.146364 sshd-session[12148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:22.153263 systemd-logind[1874]: New session 23 of user core.
May 14 18:11:22.161951 systemd[1]: Started session-23.scope - Session 23 of User core.
May 14 18:11:22.599292 sshd[12174]: Connection closed by 10.200.16.10 port 36956
May 14 18:11:22.599617 sshd-session[12148]: pam_unix(sshd:session): session closed for user core
May 14 18:11:22.603065 systemd[1]: sshd@20-10.200.8.36:22-10.200.16.10:36956.service: Deactivated successfully.
May 14 18:11:22.604434 systemd-logind[1874]: Session 23 logged out. Waiting for processes to exit.
May 14 18:11:22.605865 systemd[1]: session-23.scope: Deactivated successfully.
May 14 18:11:22.610221 systemd-logind[1874]: Removed session 23.
May 14 18:11:32.719420 systemd[1]: Started sshd@21-10.200.8.36:22-10.200.16.10:49256.service - OpenSSH per-connection server daemon (10.200.16.10:49256).
May 14 18:11:33.381837 sshd[12498]: Accepted publickey for core from 10.200.16.10 port 49256 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:33.383258 sshd-session[12498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:33.389565 systemd-logind[1874]: New session 24 of user core.
May 14 18:11:33.395885 systemd[1]: Started session-24.scope - Session 24 of User core.
May 14 18:11:33.834386 sshd[12542]: Connection closed by 10.200.16.10 port 49256
May 14 18:11:33.834662 sshd-session[12498]: pam_unix(sshd:session): session closed for user core
May 14 18:11:33.839510 systemd[1]: sshd@21-10.200.8.36:22-10.200.16.10:49256.service: Deactivated successfully.
May 14 18:11:33.841973 systemd[1]: session-24.scope: Deactivated successfully.
May 14 18:11:33.843204 systemd-logind[1874]: Session 24 logged out. Waiting for processes to exit.
May 14 18:11:33.844195 systemd-logind[1874]: Removed session 24.
May 14 18:11:33.951234 systemd[1]: Started sshd@22-10.200.8.36:22-10.200.16.10:49258.service - OpenSSH per-connection server daemon (10.200.16.10:49258).
May 14 18:11:34.612736 sshd[12575]: Accepted publickey for core from 10.200.16.10 port 49258 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:34.614058 sshd-session[12575]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:34.618399 systemd-logind[1874]: New session 25 of user core.
May 14 18:11:34.631944 systemd[1]: Started session-25.scope - Session 25 of User core.
May 14 18:11:35.061348 sshd[12577]: Connection closed by 10.200.16.10 port 49258
May 14 18:11:35.061687 sshd-session[12575]: pam_unix(sshd:session): session closed for user core
May 14 18:11:35.065035 systemd[1]: sshd@22-10.200.8.36:22-10.200.16.10:49258.service: Deactivated successfully.
May 14 18:11:35.066568 systemd[1]: session-25.scope: Deactivated successfully.
May 14 18:11:35.067229 systemd-logind[1874]: Session 25 logged out. Waiting for processes to exit.
May 14 18:11:35.068225 systemd-logind[1874]: Removed session 25.
May 14 18:11:42.438038 setup-nvidia[2599]: \u000d
May 14 18:11:42.438038 setup-nvidia[2599]: ERROR: Unable to load the kernel module 'nvidia.ko'.  This happens most
May 14 18:11:42.438038 setup-nvidia[2599]:        frequently when this kernel module was built against the wrong or
May 14 18:11:42.438038 setup-nvidia[2599]:        improperly configured kernel sources, with a version of gcc that differs
May 14 18:11:42.438038 setup-nvidia[2599]:        from the one used to build the target kernel, or if another driver, such
May 14 18:11:42.438038 setup-nvidia[2599]:        as nouveau, is present and prevents the NVIDIA kernel module from
May 14 18:11:42.438038 setup-nvidia[2599]:        obtaining ownership of the NVIDIA device(s), or no NVIDIA device
May 14 18:11:42.438038 setup-nvidia[2599]:        installed in this system is supported by this NVIDIA Linux graphics
May 14 18:11:42.438038 setup-nvidia[2599]:        driver release.
May 14 18:11:42.438038 setup-nvidia[2599]:        \u000d
May 14 18:11:42.438038 setup-nvidia[2599]:        Please see the log entries 'Kernel module load error' and 'Kernel
May 14 18:11:42.438038 setup-nvidia[2599]:        messages' at the end of the file
May 14 18:11:42.438038 setup-nvidia[2599]:        '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more
May 14 18:11:42.438038 setup-nvidia[2599]:        information.
May 14 18:11:42.438038 setup-nvidia[2599]: \u000d
May 14 18:11:42.452011 setup-nvidia[2599]: dmesg: read kernel buffer failed: Operation not permitted
May 14 18:11:42.469859 setup-nvidia[2599]: \u000d
May 14 18:11:42.469859 setup-nvidia[2599]: ERROR: Installation has failed.  Please see the file
May 14 18:11:42.469859 setup-nvidia[2599]:        '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for
May 14 18:11:42.469859 setup-nvidia[2599]:        details.  You may find suggestions on fixing installation problems in
May 14 18:11:42.469859 setup-nvidia[2599]:        the README available on the Linux driver download page at
May 14 18:11:42.469859 setup-nvidia[2599]:        www.nvidia.com.
May 14 18:11:42.469859 setup-nvidia[2599]: \u000d
May 14 18:11:42.470172 setup-nvidia[2599]: Last 50 lines of nvidia-installer.log:
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:    /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build
May 14 18:11:42.471015 setup-nvidia[2599]:      MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o
May 14 18:11:42.471015 setup-nvidia[2599]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o
May 14 18:11:42.471015 setup-nvidia[2599]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko
May 14 18:11:42.471015 setup-nvidia[2599]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko
May 14 18:11:42.471015 setup-nvidia[2599]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko
May 14 18:11:42.471015 setup-nvidia[2599]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko
May 14 18:11:42.471015 setup-nvidia[2599]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko
May 14 18:11:42.471015 setup-nvidia[2599]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux
May 14 18:11:42.471015 setup-nvidia[2599]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko
May 14 18:11:42.471015 setup-nvidia[2599]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko
May 14 18:11:42.471015 setup-nvidia[2599]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux
May 14 18:11:42.471015 setup-nvidia[2599]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko
May 14 18:11:42.471015 setup-nvidia[2599]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux
May 14 18:11:42.471015 setup-nvidia[2599]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko
May 14 18:11:42.471015 setup-nvidia[2599]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux
May 14 18:11:42.471015 setup-nvidia[2599]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko
May 14 18:11:42.471015 setup-nvidia[2599]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux
May 14 18:11:42.471015 setup-nvidia[2599]:    make[2]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/build'
May 14 18:11:42.471015 setup-nvidia[2599]:    make[1]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/source'
May 14 18:11:42.471015 setup-nvidia[2599]: -> done.
May 14 18:11:42.471015 setup-nvidia[2599]: -> Kernel module compilation complete.
May 14 18:11:42.471015 setup-nvidia[2599]: -> Unable to determine if Secure Boot is enabled: No such file or directory
May 14 18:11:42.471015 setup-nvidia[2599]: 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.
May 14 18:11:42.472096 setup-nvidia[2599]: \u000d
May 14 18:11:42.472096 setup-nvidia[2599]: 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.
May 14 18:11:42.472096 setup-nvidia[2599]: -> Kernel module load error: Operation not permitted
May 14 18:11:42.472096 setup-nvidia[2599]: -> Kernel messages:
May 14 18:11:42.472096 setup-nvidia[2599]: 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.
May 14 18:11:45.174874 systemd[1]: Started sshd@23-10.200.8.36:22-10.200.16.10:59044.service - OpenSSH per-connection server daemon (10.200.16.10:59044).
May 14 18:11:45.830046 sshd[12763]: Accepted publickey for core from 10.200.16.10 port 59044 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:45.831328 sshd-session[12763]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:45.835603 systemd-logind[1874]: New session 26 of user core.
May 14 18:11:45.843893 systemd[1]: Started session-26.scope - Session 26 of User core.
May 14 18:11:46.280703 sshd[12765]: Connection closed by 10.200.16.10 port 59044
May 14 18:11:46.281048 sshd-session[12763]: pam_unix(sshd:session): session closed for user core
May 14 18:11:46.284247 systemd[1]: sshd@23-10.200.8.36:22-10.200.16.10:59044.service: Deactivated successfully.
May 14 18:11:46.285922 systemd[1]: session-26.scope: Deactivated successfully.
May 14 18:11:46.286651 systemd-logind[1874]: Session 26 logged out. Waiting for processes to exit.
May 14 18:11:46.287911 systemd-logind[1874]: Removed session 26.
May 14 18:11:46.405566 systemd[1]: Started sshd@24-10.200.8.36:22-10.200.16.10:59056.service - OpenSSH per-connection server daemon (10.200.16.10:59056).
May 14 18:11:47.060708 sshd[12771]: Accepted publickey for core from 10.200.16.10 port 59056 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:47.061994 sshd-session[12771]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:47.068499 systemd-logind[1874]: New session 27 of user core.
May 14 18:11:47.077896 systemd[1]: Started session-27.scope - Session 27 of User core.
May 14 18:11:47.510392 sshd[12773]: Connection closed by 10.200.16.10 port 59056
May 14 18:11:47.510718 sshd-session[12771]: pam_unix(sshd:session): session closed for user core
May 14 18:11:47.514017 systemd[1]: sshd@24-10.200.8.36:22-10.200.16.10:59056.service: Deactivated successfully.
May 14 18:11:47.515537 systemd[1]: session-27.scope: Deactivated successfully.
May 14 18:11:47.516200 systemd-logind[1874]: Session 27 logged out. Waiting for processes to exit.
May 14 18:11:47.517116 systemd-logind[1874]: Removed session 27.
May 14 18:11:55.010783 kernel: EXT4-fs (loop6p9): unmounting filesystem 4c03bb24-ac25-4d8b-af70-04c72efb9bcb.
May 14 18:11:55.011025 setup-nvidia[2599]: Container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin exited successfully.
May 14 18:11:55.013315 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4334.0.0afterburn\x2d5.8.0.bin.mount: Deactivated successfully.
May 14 18:11:55.059553 sudo[2587]: pam_unix(sudo:session): session closed for user root
May 14 18:11:55.063715 systemd[1]: session-c2.scope: Deactivated successfully.
May 14 18:11:55.063998 systemd[1]: session-c2.scope: Consumed 5min 10.736s CPU time, 892.5M memory peak.
May 14 18:11:55.476830 setup-nvidia[1868]: /opt/nvidia /
May 14 18:11:55.478825 setup-nvidia[1868]: /
May 14 18:11:55.494281 setup-nvidia[12796]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
May 14 18:11:55.523545 kernel: IPMI message handler: version 39.2
May 14 18:11:55.526764 kernel: ipmi device interface
May 14 18:11:55.533540 setup-nvidia[1868]: /opt/nvidia/current/usr/lib/modules/6.12.20-flatcar/video /
May 14 18:11:55.588117 kernel: nvidia: loading out-of-tree module taints kernel.
May 14 18:11:55.588248 kernel: nvidia: module license 'NVIDIA' taints kernel.
May 14 18:11:55.588285 kernel: Disabling lock debugging due to kernel taint
May 14 18:11:55.595576 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel
May 14 18:11:55.595608 kernel: nvidia: module license taints kernel.
May 14 18:11:55.977781 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243
May 14 18:11:56.374357 kernel: 
May 14 18:11:56.374502 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002)
May 14 18:11:56.580244 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module  535.230.02  Fri Dec 20 21:42:05 UTC 2024
May 14 18:11:56.605771 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms  535.230.02  Fri Dec 20 21:52:34 UTC 2024
May 14 18:11:56.624790 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint.
May 14 18:11:56.669773 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241.
May 14 18:11:56.669838 setup-nvidia[1868]: /
May 14 18:11:57.625587 systemd[1]: Started sshd@25-10.200.8.36:22-10.200.16.10:48048.service - OpenSSH per-connection server daemon (10.200.16.10:48048).
May 14 18:11:58.264601 sshd[12833]: Accepted publickey for core from 10.200.16.10 port 48048 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:58.265998 sshd-session[12833]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:58.270432 systemd-logind[1874]: New session 28 of user core.
May 14 18:11:58.279880 systemd[1]: Started session-28.scope - Session 28 of User core.
May 14 18:11:58.394357 setup-nvidia[12828]: Wed May 14 18:11:58 2025
May 14 18:11:58.394357 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+
May 14 18:11:58.394357 setup-nvidia[12828]: | NVIDIA-SMI 535.230.02             Driver Version: 535.230.02   CUDA Version: 12.2     |
May 14 18:11:58.394357 setup-nvidia[12828]: |-----------------------------------------+----------------------+----------------------+
May 14 18:11:58.394357 setup-nvidia[12828]: | GPU  Name                 Persistence-M | Bus-Id        Disp.A | Volatile Uncorr. ECC |
May 14 18:11:58.394357 setup-nvidia[12828]: | Fan  Temp   Perf          Pwr:Usage/Cap |         Memory-Usage | GPU-Util  Compute M. |
May 14 18:11:58.394357 setup-nvidia[12828]: |                                         |                      |               MIG M. |
May 14 18:11:58.394357 setup-nvidia[12828]: |=========================================+======================+======================|
May 14 18:11:58.398222 setup-nvidia[12828]: |   0  Tesla V100-PCIE-16GB           Off | 00000001:00:00.0 Off |                  Off |
May 14 18:11:58.398222 setup-nvidia[12828]: | N/A   26C    P0              37W / 250W |      0MiB / 16384MiB |      0%      Default |
May 14 18:11:58.398222 setup-nvidia[12828]: |                                         |                      |                  N/A |
May 14 18:11:58.398222 setup-nvidia[12828]: +-----------------------------------------+----------------------+----------------------+
May 14 18:11:58.398222 setup-nvidia[12828]:                                                                                          
May 14 18:11:58.398222 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+
May 14 18:11:58.398222 setup-nvidia[12828]: | Processes:                                                                            |
May 14 18:11:58.398222 setup-nvidia[12828]: |  GPU   GI   CI        PID   Type   Process name                            GPU Memory |
May 14 18:11:58.398222 setup-nvidia[12828]: |        ID   ID                                                             Usage      |
May 14 18:11:58.398222 setup-nvidia[12828]: |=======================================================================================|
May 14 18:11:58.398469 setup-nvidia[12828]: |  No running processes found                                                           |
May 14 18:11:58.398469 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+
May 14 18:11:58.712511 sshd[12835]: Connection closed by 10.200.16.10 port 48048
May 14 18:11:58.712865 sshd-session[12833]: pam_unix(sshd:session): session closed for user core
May 14 18:11:58.716065 systemd[1]: sshd@25-10.200.8.36:22-10.200.16.10:48048.service: Deactivated successfully.
May 14 18:11:58.717659 systemd[1]: session-28.scope: Deactivated successfully.
May 14 18:11:58.718384 systemd-logind[1874]: Session 28 logged out. Waiting for processes to exit.
May 14 18:11:58.719316 systemd-logind[1874]: Removed session 28.
May 14 18:11:58.781064 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
May 14 18:11:58.781652 systemd[1]: Reached target multi-user.target - Multi-User System.
May 14 18:11:58.782116 systemd[1]: Startup finished in 1.409s (firmware) + 32.378s (loader) + 3.834s (kernel) + 14.130s (initrd) + 3min 28.504s (userspace) = 4min 20.257s.
May 14 18:11:58.833991 systemd[1]: Started sshd@26-10.200.8.36:22-10.200.16.10:54426.service - OpenSSH per-connection server daemon (10.200.16.10:54426).
May 14 18:11:59.469912 sshd[12843]: Accepted publickey for core from 10.200.16.10 port 54426 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:11:59.471161 sshd-session[12843]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:11:59.475508 systemd-logind[1874]: New session 29 of user core.
May 14 18:11:59.487872 systemd[1]: Started session-29.scope - Session 29 of User core.
May 14 18:11:59.921048 sshd[12845]: Connection closed by 10.200.16.10 port 54426
May 14 18:11:59.921399 sshd-session[12843]: pam_unix(sshd:session): session closed for user core
May 14 18:11:59.924791 systemd[1]: sshd@26-10.200.8.36:22-10.200.16.10:54426.service: Deactivated successfully.
May 14 18:11:59.926565 systemd[1]: session-29.scope: Deactivated successfully.
May 14 18:11:59.927283 systemd-logind[1874]: Session 29 logged out. Waiting for processes to exit.
May 14 18:11:59.928294 systemd-logind[1874]: Removed session 29.
May 14 18:12:05.132391 systemd[1]: Stopping user@0.service - User Manager for UID 0...
May 14 18:12:05.133172 systemd[2590]: Activating special unit exit.target...
May 14 18:12:05.133258 systemd[2590]: Stopped target default.target - Main User Target.
May 14 18:12:05.133297 systemd[2590]: Stopped target basic.target - Basic System.
May 14 18:12:05.133323 systemd[2590]: Stopped target paths.target - Paths.
May 14 18:12:05.133346 systemd[2590]: Stopped target sockets.target - Sockets.
May 14 18:12:05.133370 systemd[2590]: Stopped target timers.target - Timers.
May 14 18:12:05.133713 systemd[2590]: Closed dbus.socket - D-Bus User Message Bus Socket.
May 14 18:12:05.133936 systemd[2590]: Removed slice app.slice - User Application Slice.
May 14 18:12:05.133968 systemd[2590]: Reached target shutdown.target - Shutdown.
May 14 18:12:05.134104 systemd[2590]: Finished systemd-exit.service - Exit the Session.
May 14 18:12:05.134140 systemd[2590]: Reached target exit.target - Exit the Session.
May 14 18:12:05.147699 (sd-pam)[2591]: pam_unix(systemd-user:session): session closed for user root
May 14 18:12:05.149052 systemd[1]: user@0.service: Deactivated successfully.
May 14 18:12:05.149409 systemd[1]: Stopped user@0.service - User Manager for UID 0.
May 14 18:12:05.151622 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0...
May 14 18:12:05.173443 systemd[1]: run-user-0.mount: Deactivated successfully.
May 14 18:12:05.174325 systemd[1]: user-runtime-dir@0.service: Deactivated successfully.
May 14 18:12:05.174555 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0.
May 14 18:12:05.176146 systemd[1]: Removed slice user-0.slice - User Slice of UID 0.
May 14 18:12:05.176239 systemd[1]: user-0.slice: Consumed 5min 10.966s CPU time, 901.7M memory peak.
May 14 18:12:05.176622 systemd-logind[1874]: Removed session c3.
May 14 18:12:10.039161 systemd[1]: Started sshd@27-10.200.8.36:22-10.200.16.10:53952.service - OpenSSH per-connection server daemon (10.200.16.10:53952).
May 14 18:12:10.697461 sshd[12855]: Accepted publickey for core from 10.200.16.10 port 53952 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:12:10.698742 sshd-session[12855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:12:10.702960 systemd-logind[1874]: New session 30 of user core.
May 14 18:12:10.719868 systemd[1]: Started session-30.scope - Session 30 of User core.
May 14 18:12:11.150538 sshd[12857]: Connection closed by 10.200.16.10 port 53952
May 14 18:12:11.150894 sshd-session[12855]: pam_unix(sshd:session): session closed for user core
May 14 18:12:11.154283 systemd[1]: sshd@27-10.200.8.36:22-10.200.16.10:53952.service: Deactivated successfully.
May 14 18:12:11.155816 systemd[1]: session-30.scope: Deactivated successfully.
May 14 18:12:11.156470 systemd-logind[1874]: Session 30 logged out. Waiting for processes to exit.
May 14 18:12:11.157460 systemd-logind[1874]: Removed session 30.
May 14 18:12:11.267975 systemd[1]: Started sshd@28-10.200.8.36:22-10.200.16.10:53966.service - OpenSSH per-connection server daemon (10.200.16.10:53966).
May 14 18:12:11.922088 sshd[12863]: Accepted publickey for core from 10.200.16.10 port 53966 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:12:11.923344 sshd-session[12863]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:12:11.927557 systemd-logind[1874]: New session 31 of user core.
May 14 18:12:11.936887 systemd[1]: Started session-31.scope - Session 31 of User core.
May 14 18:12:12.372870 sshd[12865]: Connection closed by 10.200.16.10 port 53966
May 14 18:12:12.373154 sshd-session[12863]: pam_unix(sshd:session): session closed for user core
May 14 18:12:12.376874 systemd[1]: sshd@28-10.200.8.36:22-10.200.16.10:53966.service: Deactivated successfully.
May 14 18:12:12.378417 systemd[1]: session-31.scope: Deactivated successfully.
May 14 18:12:12.379096 systemd-logind[1874]: Session 31 logged out. Waiting for processes to exit.
May 14 18:12:12.380109 systemd-logind[1874]: Removed session 31.
May 14 18:12:12.495901 systemd[1]: Started sshd@29-10.200.8.36:22-10.200.16.10:53980.service - OpenSSH per-connection server daemon (10.200.16.10:53980).
May 14 18:12:13.155359 sshd[12871]: Accepted publickey for core from 10.200.16.10 port 53980 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:12:13.156733 sshd-session[12871]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:12:13.160928 systemd-logind[1874]: New session 32 of user core.
May 14 18:12:13.175888 systemd[1]: Started session-32.scope - Session 32 of User core.
May 14 18:12:13.606294 sshd[12873]: Connection closed by 10.200.16.10 port 53980
May 14 18:12:13.606570 sshd-session[12871]: pam_unix(sshd:session): session closed for user core
May 14 18:12:13.609876 systemd[1]: sshd@29-10.200.8.36:22-10.200.16.10:53980.service: Deactivated successfully.
May 14 18:12:13.611407 systemd[1]: session-32.scope: Deactivated successfully.
May 14 18:12:13.612096 systemd-logind[1874]: Session 32 logged out. Waiting for processes to exit.
May 14 18:12:13.613009 systemd-logind[1874]: Removed session 32.
May 14 18:12:13.721919 systemd[1]: Started sshd@30-10.200.8.36:22-10.200.16.10:53984.service - OpenSSH per-connection server daemon (10.200.16.10:53984).
May 14 18:12:14.401977 sshd[12879]: Accepted publickey for core from 10.200.16.10 port 53984 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:12:14.403332 sshd-session[12879]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:12:14.407630 systemd-logind[1874]: New session 33 of user core.
May 14 18:12:14.418881 systemd[1]: Started session-33.scope - Session 33 of User core.
May 14 18:12:14.855819 sshd[12881]: Connection closed by 10.200.16.10 port 53984
May 14 18:12:14.856089 sshd-session[12879]: pam_unix(sshd:session): session closed for user core
May 14 18:12:14.859366 systemd[1]: sshd@30-10.200.8.36:22-10.200.16.10:53984.service: Deactivated successfully.
May 14 18:12:14.860925 systemd[1]: session-33.scope: Deactivated successfully.
May 14 18:12:14.861576 systemd-logind[1874]: Session 33 logged out. Waiting for processes to exit.
May 14 18:12:14.862551 systemd-logind[1874]: Removed session 33.
May 14 18:12:14.971003 systemd[1]: Started sshd@31-10.200.8.36:22-10.200.16.10:53990.service - OpenSSH per-connection server daemon (10.200.16.10:53990).
May 14 18:12:15.630230 sshd[12887]: Accepted publickey for core from 10.200.16.10 port 53990 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs
May 14 18:12:15.635411 sshd-session[12887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
May 14 18:12:15.639789 systemd-logind[1874]: New session 34 of user core.
May 14 18:12:15.648875 systemd[1]: Started session-34.scope - Session 34 of User core.
May 14 18:12:17.550720 sshd[12889]: Connection closed by 10.200.16.10 port 53990
May 14 18:12:17.551113 sshd-session[12887]: pam_unix(sshd:session): session closed for user core
May 14 18:12:17.554617 systemd[1]: sshd@31-10.200.8.36:22-10.200.16.10:53990.service: Deactivated successfully.
May 14 18:12:17.556147 systemd[1]: session-34.scope: Deactivated successfully.
May 14 18:12:17.556338 systemd[1]: session-34.scope: Consumed 1.409s CPU time, 14.1M memory peak.
May 14 18:12:17.556837 systemd-logind[1874]: Session 34 logged out. Waiting for processes to exit.
May 14 18:12:17.557791 systemd-logind[1874]: Removed session 34.