Sep 12 17:44:51.235063 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 12 15:34:39 -00 2025
Sep 12 17:44:51.235091 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858
Sep 12 17:44:51.235101 kernel: BIOS-provided physical RAM map:
Sep 12 17:44:51.235108 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
Sep 12 17:44:51.235145 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved
Sep 12 17:44:51.235152 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable
Sep 12 17:44:51.235160 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved
Sep 12 17:44:51.235171 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data
Sep 12 17:44:51.235178 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS
Sep 12 17:44:51.235184 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable
Sep 12 17:44:51.235191 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable
Sep 12 17:44:51.235198 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable
Sep 12 17:44:51.235205 kernel: printk: legacy bootconsole [earlyser0] enabled
Sep 12 17:44:51.235212 kernel: NX (Execute Disable) protection: active
Sep 12 17:44:51.235223 kernel: APIC: Static calls initialized
Sep 12 17:44:51.235230 kernel: efi: EFI v2.7 by Microsoft
Sep 12 17:44:51.235238 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee60a98 RNG=0x3ffd1018 
Sep 12 17:44:51.235245 kernel: random: crng init done
Sep 12 17:44:51.235253 kernel: secureboot: Secure boot disabled
Sep 12 17:44:51.235261 kernel: SMBIOS 3.1.0 present.
Sep 12 17:44:51.235269 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024
Sep 12 17:44:51.235276 kernel: DMI: Memory slots populated: 3/3
Sep 12 17:44:51.235284 kernel: Hypervisor detected: Microsoft Hyper-V
Sep 12 17:44:51.235291 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2
Sep 12 17:44:51.235301 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40
Sep 12 17:44:51.235309 kernel: Hyper-V: Using hypercall for remote TLB flush
Sep 12 17:44:51.235317 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
Sep 12 17:44:51.235324 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
Sep 12 17:44:51.235333 kernel: tsc: Marking TSC unstable due to running on Hyper-V
Sep 12 17:44:51.235340 kernel: tsc: Detected 2593.992 MHz processor
Sep 12 17:44:51.235348 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Sep 12 17:44:51.235356 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Sep 12 17:44:51.235363 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000
Sep 12 17:44:51.235373 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs
Sep 12 17:44:51.235381 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Sep 12 17:44:51.235388 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved
Sep 12 17:44:51.235396 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved
Sep 12 17:44:51.235403 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000
Sep 12 17:44:51.235413 kernel: Using GB pages for direct mapping
Sep 12 17:44:51.235421 kernel: ACPI: Early table checksum verification disabled
Sep 12 17:44:51.235432 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL)
Sep 12 17:44:51.235443 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235451 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235459 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01   00000001 MSFT 05000000)
Sep 12 17:44:51.235467 kernel: ACPI: FACS 0x000000003FFFE000 000040
Sep 12 17:44:51.235475 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235483 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235493 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235501 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235509 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235517 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235525 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Sep 12 17:44:51.235533 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113]
Sep 12 17:44:51.235541 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183]
Sep 12 17:44:51.235549 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f]
Sep 12 17:44:51.235557 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063]
Sep 12 17:44:51.235567 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f]
Sep 12 17:44:51.235575 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027]
Sep 12 17:44:51.235583 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077]
Sep 12 17:44:51.235591 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf]
Sep 12 17:44:51.235599 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037]
Sep 12 17:44:51.235607 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033]
Sep 12 17:44:51.235615 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug
Sep 12 17:44:51.235623 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug
Sep 12 17:44:51.235634 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug
Sep 12 17:44:51.235642 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug
Sep 12 17:44:51.235650 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug
Sep 12 17:44:51.235658 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug
Sep 12 17:44:51.235666 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug
Sep 12 17:44:51.235674 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug
Sep 12 17:44:51.235682 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff]
Sep 12 17:44:51.235690 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff]
Sep 12 17:44:51.235698 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff]
Sep 12 17:44:51.235709 kernel: Zone ranges:
Sep 12 17:44:51.235717 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Sep 12 17:44:51.235725 kernel:   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
Sep 12 17:44:51.235733 kernel:   Normal   [mem 0x0000000100000000-0x0000005cbfffffff]
Sep 12 17:44:51.235741 kernel:   Device   empty
Sep 12 17:44:51.235749 kernel: Movable zone start for each node
Sep 12 17:44:51.235757 kernel: Early memory node ranges
Sep 12 17:44:51.235765 kernel:   node   0: [mem 0x0000000000001000-0x000000000009ffff]
Sep 12 17:44:51.235772 kernel:   node   0: [mem 0x0000000000100000-0x000000003ff40fff]
Sep 12 17:44:51.235783 kernel:   node   0: [mem 0x000000003ffff000-0x000000003fffffff]
Sep 12 17:44:51.235791 kernel:   node   0: [mem 0x0000000100000000-0x0000000fdfffffff]
Sep 12 17:44:51.235799 kernel:   node   0: [mem 0x0000004fe0000000-0x0000005cbfffffff]
Sep 12 17:44:51.235806 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff]
Sep 12 17:44:51.235814 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Sep 12 17:44:51.235822 kernel: On node 0, zone DMA: 96 pages in unavailable ranges
Sep 12 17:44:51.235830 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges
Sep 12 17:44:51.235838 kernel: ACPI: PM-Timer IO Port: 0x408
Sep 12 17:44:51.235846 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
Sep 12 17:44:51.235856 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23
Sep 12 17:44:51.235864 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Sep 12 17:44:51.235872 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Sep 12 17:44:51.235880 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200
Sep 12 17:44:51.235888 kernel: CPU topo: Max. logical packages:   1
Sep 12 17:44:51.235895 kernel: CPU topo: Max. logical dies:       1
Sep 12 17:44:51.235903 kernel: CPU topo: Max. dies per package:   1
Sep 12 17:44:51.235911 kernel: CPU topo: Max. threads per core:   1
Sep 12 17:44:51.235919 kernel: CPU topo: Num. cores per package:     6
Sep 12 17:44:51.235929 kernel: CPU topo: Num. threads per package:   6
Sep 12 17:44:51.235937 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs
Sep 12 17:44:51.235945 kernel: [mem 0x40000000-0xffffffff] available for PCI devices
Sep 12 17:44:51.235953 kernel: Booting paravirtualized kernel on Hyper-V
Sep 12 17:44:51.235961 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Sep 12 17:44:51.235970 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1
Sep 12 17:44:51.235978 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144
Sep 12 17:44:51.235985 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152
Sep 12 17:44:51.235993 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - 
Sep 12 17:44:51.236004 kernel: Hyper-V: PV spinlocks enabled
Sep 12 17:44:51.236012 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Sep 12 17:44:51.236021 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858
Sep 12 17:44:51.236029 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Sep 12 17:44:51.236037 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear)
Sep 12 17:44:51.236045 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear)
Sep 12 17:44:51.236053 kernel: Fallback order for Node 0: 0 
Sep 12 17:44:51.236061 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 29359841
Sep 12 17:44:51.236072 kernel: Policy zone: Normal
Sep 12 17:44:51.236087 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Sep 12 17:44:51.236096 kernel: software IO TLB: area num 8.
Sep 12 17:44:51.236107 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
Sep 12 17:44:51.236125 kernel: Kernel/User page tables isolation: enabled
Sep 12 17:44:51.236134 kernel: ftrace: allocating 40125 entries in 157 pages
Sep 12 17:44:51.236143 kernel: ftrace: allocated 157 pages with 5 groups
Sep 12 17:44:51.236151 kernel: Dynamic Preempt: voluntary
Sep 12 17:44:51.236159 kernel: rcu: Preemptible hierarchical RCU implementation.
Sep 12 17:44:51.236168 kernel: rcu:         RCU event tracing is enabled.
Sep 12 17:44:51.236177 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6.
Sep 12 17:44:51.236186 kernel:         Trampoline variant of Tasks RCU enabled.
Sep 12 17:44:51.236198 kernel:         Rude variant of Tasks RCU enabled.
Sep 12 17:44:51.236206 kernel:         Tracing variant of Tasks RCU enabled.
Sep 12 17:44:51.236215 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Sep 12 17:44:51.236223 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6
Sep 12 17:44:51.236232 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
Sep 12 17:44:51.236243 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
Sep 12 17:44:51.236252 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6.
Sep 12 17:44:51.236260 kernel: Using NULL legacy PIC
Sep 12 17:44:51.236268 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0
Sep 12 17:44:51.236277 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Sep 12 17:44:51.236285 kernel: Console: colour dummy device 80x25
Sep 12 17:44:51.236294 kernel: printk: legacy console [tty1] enabled
Sep 12 17:44:51.236302 kernel: printk: legacy console [ttyS0] enabled
Sep 12 17:44:51.236311 kernel: printk: legacy bootconsole [earlyser0] disabled
Sep 12 17:44:51.236321 kernel: ACPI: Core revision 20240827
Sep 12 17:44:51.236330 kernel: Failed to register legacy timer interrupt
Sep 12 17:44:51.236338 kernel: APIC: Switch to symmetric I/O mode setup
Sep 12 17:44:51.236347 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0
Sep 12 17:44:51.236355 kernel: Hyper-V: enabling crash_kexec_post_notifiers
Sep 12 17:44:51.236363 kernel: Hyper-V: Using IPI hypercalls
Sep 12 17:44:51.236372 kernel: APIC: send_IPI() replaced with hv_send_ipi()
Sep 12 17:44:51.236591 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask()
Sep 12 17:44:51.236601 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself()
Sep 12 17:44:51.236615 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself()
Sep 12 17:44:51.236659 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all()
Sep 12 17:44:51.236671 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self()
Sep 12 17:44:51.236680 kernel: Hyper-V: Using enlightened APIC (xapic mode)
Sep 12 17:44:51.236689 kernel: APIC: eoi() replaced with hv_apic_eoi_write()
Sep 12 17:44:51.236697 kernel: APIC: read() replaced with hv_apic_read()
Sep 12 17:44:51.236706 kernel: APIC: write() replaced with hv_apic_write()
Sep 12 17:44:51.236715 kernel: APIC: icr_write() replaced with hv_apic_icr_write()
Sep 12 17:44:51.236723 kernel: APIC: icr_read() replaced with hv_apic_icr_read()
Sep 12 17:44:51.236738 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992)
Sep 12 17:44:51.236747 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8
Sep 12 17:44:51.236756 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4
Sep 12 17:44:51.236765 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Sep 12 17:44:51.236773 kernel: Spectre V2 : Mitigation: Retpolines
Sep 12 17:44:51.236782 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
Sep 12 17:44:51.236790 kernel: Speculative Store Bypass: Vulnerable
Sep 12 17:44:51.236799 kernel: MDS: Mitigation: Clear CPU buffers
Sep 12 17:44:51.236807 kernel: TAA: Mitigation: Clear CPU buffers
Sep 12 17:44:51.236815 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
Sep 12 17:44:51.236826 kernel: active return thunk: its_return_thunk
Sep 12 17:44:51.237699 kernel: ITS: Mitigation: Aligned branch/return thunks
Sep 12 17:44:51.237713 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Sep 12 17:44:51.237722 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Sep 12 17:44:51.237731 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Sep 12 17:44:51.237739 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Sep 12 17:44:51.237748 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
Sep 12 17:44:51.237757 kernel: Freeing SMP alternatives memory: 32K
Sep 12 17:44:51.237765 kernel: pid_max: default: 32768 minimum: 301
Sep 12 17:44:51.237773 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
Sep 12 17:44:51.237782 kernel: landlock: Up and running.
Sep 12 17:44:51.237796 kernel: SELinux:  Initializing.
Sep 12 17:44:51.237805 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Sep 12 17:44:51.237814 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Sep 12 17:44:51.237822 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1)
Sep 12 17:44:51.237831 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only.
Sep 12 17:44:51.237840 kernel: signal: max sigframe size: 1776
Sep 12 17:44:51.237848 kernel: rcu: Hierarchical SRCU implementation.
Sep 12 17:44:51.237858 kernel: rcu:         Max phase no-delay instances is 400.
Sep 12 17:44:51.237867 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
Sep 12 17:44:51.237875 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
Sep 12 17:44:51.237887 kernel: smp: Bringing up secondary CPUs ...
Sep 12 17:44:51.237895 kernel: smpboot: x86: Booting SMP configuration:
Sep 12 17:44:51.237904 kernel: .... node  #0, CPUs:      #1 #2 #3 #4 #5
Sep 12 17:44:51.237912 kernel: smp: Brought up 1 node, 6 CPUs
Sep 12 17:44:51.237921 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS)
Sep 12 17:44:51.237930 kernel: Memory: 115339084K/117439364K available (14336K kernel code, 2432K rwdata, 9960K rodata, 54040K init, 2924K bss, 2090012K reserved, 0K cma-reserved)
Sep 12 17:44:51.237939 kernel: devtmpfs: initialized
Sep 12 17:44:51.237948 kernel: x86/mm: Memory block size: 1024MB
Sep 12 17:44:51.237957 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes)
Sep 12 17:44:51.237968 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Sep 12 17:44:51.237977 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
Sep 12 17:44:51.237985 kernel: pinctrl core: initialized pinctrl subsystem
Sep 12 17:44:51.237994 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Sep 12 17:44:51.238003 kernel: audit: initializing netlink subsys (disabled)
Sep 12 17:44:51.238011 kernel: audit: type=2000 audit(1757699087.028:1): state=initialized audit_enabled=0 res=1
Sep 12 17:44:51.238020 kernel: thermal_sys: Registered thermal governor 'step_wise'
Sep 12 17:44:51.238028 kernel: thermal_sys: Registered thermal governor 'user_space'
Sep 12 17:44:51.238037 kernel: cpuidle: using governor menu
Sep 12 17:44:51.238048 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Sep 12 17:44:51.238056 kernel: dca service started, version 1.12.1
Sep 12 17:44:51.238065 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff]
Sep 12 17:44:51.238074 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Sep 12 17:44:51.238082 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Sep 12 17:44:51.238091 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Sep 12 17:44:51.238099 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Sep 12 17:44:51.238108 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Sep 12 17:44:51.238132 kernel: ACPI: Added _OSI(Module Device)
Sep 12 17:44:51.238144 kernel: ACPI: Added _OSI(Processor Device)
Sep 12 17:44:51.238153 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Sep 12 17:44:51.238161 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Sep 12 17:44:51.238170 kernel: ACPI: Interpreter enabled
Sep 12 17:44:51.238178 kernel: ACPI: PM: (supports S0 S5)
Sep 12 17:44:51.238187 kernel: ACPI: Using IOAPIC for interrupt routing
Sep 12 17:44:51.238195 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Sep 12 17:44:51.238204 kernel: PCI: Ignoring E820 reservations for host bridge windows
Sep 12 17:44:51.238212 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F
Sep 12 17:44:51.238223 kernel: iommu: Default domain type: Translated
Sep 12 17:44:51.238232 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Sep 12 17:44:51.238241 kernel: efivars: Registered efivars operations
Sep 12 17:44:51.238249 kernel: PCI: Using ACPI for IRQ routing
Sep 12 17:44:51.238258 kernel: PCI: System does not support PCI
Sep 12 17:44:51.238266 kernel: vgaarb: loaded
Sep 12 17:44:51.238275 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page
Sep 12 17:44:51.238284 kernel: VFS: Disk quotas dquot_6.6.0
Sep 12 17:44:51.238292 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Sep 12 17:44:51.238304 kernel: pnp: PnP ACPI init
Sep 12 17:44:51.238312 kernel: pnp: PnP ACPI: found 3 devices
Sep 12 17:44:51.238321 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Sep 12 17:44:51.238329 kernel: NET: Registered PF_INET protocol family
Sep 12 17:44:51.238338 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Sep 12 17:44:51.238347 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear)
Sep 12 17:44:51.238355 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Sep 12 17:44:51.238364 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Sep 12 17:44:51.238373 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
Sep 12 17:44:51.238384 kernel: TCP: Hash tables configured (established 524288 bind 65536)
Sep 12 17:44:51.238392 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear)
Sep 12 17:44:51.238401 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear)
Sep 12 17:44:51.238410 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Sep 12 17:44:51.238418 kernel: NET: Registered PF_XDP protocol family
Sep 12 17:44:51.238427 kernel: PCI: CLS 0 bytes, default 64
Sep 12 17:44:51.238435 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
Sep 12 17:44:51.238444 kernel: software IO TLB: mapped [mem 0x000000003ae60000-0x000000003ee60000] (64MB)
Sep 12 17:44:51.238452 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
Sep 12 17:44:51.238463 kernel: Initialise system trusted keyrings
Sep 12 17:44:51.238472 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0
Sep 12 17:44:51.238480 kernel: Key type asymmetric registered
Sep 12 17:44:51.238489 kernel: Asymmetric key parser 'x509' registered
Sep 12 17:44:51.238497 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Sep 12 17:44:51.238505 kernel: io scheduler mq-deadline registered
Sep 12 17:44:51.238514 kernel: io scheduler kyber registered
Sep 12 17:44:51.238522 kernel: io scheduler bfq registered
Sep 12 17:44:51.238531 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Sep 12 17:44:51.238542 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Sep 12 17:44:51.238550 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Sep 12 17:44:51.238559 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
Sep 12 17:44:51.238567 kernel: i8042: PNP: No PS/2 controller found.
Sep 12 17:44:51.238731 kernel: rtc_cmos 00:02: registered as rtc0
Sep 12 17:44:51.238835 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T17:44:50 UTC (1757699090)
Sep 12 17:44:51.238928 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram
Sep 12 17:44:51.238945 kernel: intel_pstate: CPU model not supported
Sep 12 17:44:51.238954 kernel: efifb: probing for efifb
Sep 12 17:44:51.238962 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k
Sep 12 17:44:51.238971 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1
Sep 12 17:44:51.238979 kernel: efifb: scrolling: redraw
Sep 12 17:44:51.238988 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Sep 12 17:44:51.238996 kernel: Console: switching to colour frame buffer device 128x48
Sep 12 17:44:51.239005 kernel: fb0: EFI VGA frame buffer device
Sep 12 17:44:51.239014 kernel: pstore: Using crash dump compression: deflate
Sep 12 17:44:51.239025 kernel: pstore: Registered efi_pstore as persistent store backend
Sep 12 17:44:51.239034 kernel: NET: Registered PF_INET6 protocol family
Sep 12 17:44:51.239042 kernel: Segment Routing with IPv6
Sep 12 17:44:51.239050 kernel: In-situ OAM (IOAM) with IPv6
Sep 12 17:44:51.239059 kernel: NET: Registered PF_PACKET protocol family
Sep 12 17:44:51.239067 kernel: Key type dns_resolver registered
Sep 12 17:44:51.239076 kernel: IPI shorthand broadcast: enabled
Sep 12 17:44:51.239084 kernel: sched_clock: Marking stable (3750006500, 117234700)->(4241361300, -374120100)
Sep 12 17:44:51.239093 kernel: registered taskstats version 1
Sep 12 17:44:51.239101 kernel: Loading compiled-in X.509 certificates
Sep 12 17:44:51.239129 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: f1ae8d6e9bfae84d90f4136cf098b0465b2a5bd7'
Sep 12 17:44:51.239139 kernel: Demotion targets for Node 0: null
Sep 12 17:44:51.239147 kernel: Key type .fscrypt registered
Sep 12 17:44:51.239156 kernel: Key type fscrypt-provisioning registered
Sep 12 17:44:51.239164 kernel: ima: No TPM chip found, activating TPM-bypass!
Sep 12 17:44:51.239173 kernel: ima: Allocated hash algorithm: sha1
Sep 12 17:44:51.239181 kernel: ima: No architecture policies found
Sep 12 17:44:51.239190 kernel: clk: Disabling unused clocks
Sep 12 17:44:51.239202 kernel: Warning: unable to open an initial console.
Sep 12 17:44:51.239211 kernel: Freeing unused kernel image (initmem) memory: 54040K
Sep 12 17:44:51.239219 kernel: Write protecting the kernel read-only data: 24576k
Sep 12 17:44:51.239228 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K
Sep 12 17:44:51.239236 kernel: Run /init as init process
Sep 12 17:44:51.239245 kernel:   with arguments:
Sep 12 17:44:51.239253 kernel:     /init
Sep 12 17:44:51.239261 kernel:   with environment:
Sep 12 17:44:51.239270 kernel:     HOME=/
Sep 12 17:44:51.239280 kernel:     TERM=linux
Sep 12 17:44:51.239289 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Sep 12 17:44:51.239299 systemd[1]: Successfully made /usr/ read-only.
Sep 12 17:44:51.239310 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)
Sep 12 17:44:51.239320 systemd[1]: Detected virtualization microsoft.
Sep 12 17:44:51.239329 systemd[1]: Detected architecture x86-64.
Sep 12 17:44:51.239338 systemd[1]: Running in initrd.
Sep 12 17:44:51.239347 systemd[1]: No hostname configured, using default hostname.
Sep 12 17:44:51.239359 systemd[1]: Hostname set to <localhost>.
Sep 12 17:44:51.239368 systemd[1]: Initializing machine ID from random generator.
Sep 12 17:44:51.239377 systemd[1]: Queued start job for default target initrd.target.
Sep 12 17:44:51.239386 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Sep 12 17:44:51.239395 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Sep 12 17:44:51.239405 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Sep 12 17:44:51.239414 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Sep 12 17:44:51.239423 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Sep 12 17:44:51.239436 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Sep 12 17:44:51.239446 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Sep 12 17:44:51.239455 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Sep 12 17:44:51.239464 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Sep 12 17:44:51.239473 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Sep 12 17:44:51.239482 systemd[1]: Reached target paths.target - Path Units.
Sep 12 17:44:51.239494 systemd[1]: Reached target slices.target - Slice Units.
Sep 12 17:44:51.239503 systemd[1]: Reached target swap.target - Swaps.
Sep 12 17:44:51.239512 systemd[1]: Reached target timers.target - Timer Units.
Sep 12 17:44:51.239521 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Sep 12 17:44:51.239530 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Sep 12 17:44:51.239539 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Sep 12 17:44:51.239548 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
Sep 12 17:44:51.239557 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Sep 12 17:44:51.239567 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Sep 12 17:44:51.239578 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Sep 12 17:44:51.239587 systemd[1]: Reached target sockets.target - Socket Units.
Sep 12 17:44:51.239596 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Sep 12 17:44:51.239605 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Sep 12 17:44:51.239614 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Sep 12 17:44:51.239624 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).
Sep 12 17:44:51.239633 systemd[1]: Starting systemd-fsck-usr.service...
Sep 12 17:44:51.239642 systemd[1]: Starting systemd-journald.service - Journal Service...
Sep 12 17:44:51.239653 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Sep 12 17:44:51.239684 systemd-journald[231]: Collecting audit messages is disabled.
Sep 12 17:44:51.239707 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:44:51.239717 systemd-journald[231]: Journal started
Sep 12 17:44:51.239744 systemd-journald[231]: Runtime Journal (/run/log/journal/af6d81be6aa647d6b48be985f0ae6b8a) is 8M, max 2.2G, 2.1G free.
Sep 12 17:44:51.247002 systemd-modules-load[233]: Inserted module 'overlay'
Sep 12 17:44:51.250094 systemd[1]: Started systemd-journald.service - Journal Service.
Sep 12 17:44:51.250439 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Sep 12 17:44:51.251008 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Sep 12 17:44:51.251797 systemd[1]: Finished systemd-fsck-usr.service.
Sep 12 17:44:51.254886 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Sep 12 17:44:51.256165 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Sep 12 17:44:51.307316 systemd-tmpfiles[242]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
Sep 12 17:44:51.308160 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Sep 12 17:44:51.310912 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Sep 12 17:44:51.319167 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:44:51.327419 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Sep 12 17:44:51.342243 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Sep 12 17:44:51.350952 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Sep 12 17:44:51.387056 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Sep 12 17:44:51.389938 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Sep 12 17:44:51.399546 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Sep 12 17:44:51.404908 kernel: Bridge firewalling registered
Sep 12 17:44:51.401465 systemd-modules-load[233]: Inserted module 'br_netfilter'
Sep 12 17:44:51.405052 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Sep 12 17:44:51.429721 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Sep 12 17:44:51.444776 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Sep 12 17:44:51.454941 dracut-cmdline[266]: 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858
Sep 12 17:44:51.454977 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Sep 12 17:44:51.516443 systemd-resolved[285]: Positive Trust Anchors:
Sep 12 17:44:51.516461 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Sep 12 17:44:51.516491 systemd-resolved[285]: 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
Sep 12 17:44:51.518990 systemd-resolved[285]: Defaulting to hostname 'linux'.
Sep 12 17:44:51.519982 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Sep 12 17:44:51.521596 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Sep 12 17:44:51.636142 kernel: SCSI subsystem initialized
Sep 12 17:44:51.647133 kernel: Loading iSCSI transport class v2.0-870.
Sep 12 17:44:51.660132 kernel: iscsi: registered transport (tcp)
Sep 12 17:44:51.684296 kernel: iscsi: registered transport (qla4xxx)
Sep 12 17:44:51.684329 kernel: QLogic iSCSI HBA Driver
Sep 12 17:44:51.704055 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Sep 12 17:44:51.742157 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Sep 12 17:44:51.744852 systemd[1]: Reached target network-pre.target - Preparation for Network.
Sep 12 17:44:51.796883 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Sep 12 17:44:51.802781 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Sep 12 17:44:51.867138 kernel: raid6: avx2x4   gen() 30859 MB/s
Sep 12 17:44:51.886135 kernel: raid6: avx2x2   gen() 31132 MB/s
Sep 12 17:44:51.907405 kernel: raid6: avx2x1   gen() 27880 MB/s
Sep 12 17:44:51.907429 kernel: raid6: using algorithm avx2x2 gen() 31132 MB/s
Sep 12 17:44:51.930426 kernel: raid6: .... xor() 18514 MB/s, rmw enabled
Sep 12 17:44:51.930454 kernel: raid6: using avx2x2 recovery algorithm
Sep 12 17:44:51.953135 kernel: xor: automatically using best checksumming function   avx       
Sep 12 17:44:52.123144 kernel: Btrfs loaded, zoned=no, fsverity=no
Sep 12 17:44:52.129745 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Sep 12 17:44:52.140105 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Sep 12 17:44:52.186160 systemd-udevd[483]: Using default interface naming scheme 'v255'.
Sep 12 17:44:52.192223 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Sep 12 17:44:52.193795 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Sep 12 17:44:52.234746 dracut-pre-trigger[489]: rd.md=0: removing MD RAID activation
Sep 12 17:44:52.264957 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Sep 12 17:44:52.268550 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Sep 12 17:44:52.350802 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Sep 12 17:44:52.353817 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Sep 12 17:44:52.380136 kernel: cryptd: max_cpu_qlen set to 1000
Sep 12 17:44:52.388129 kernel: hv_vmbus: Vmbus version:4.0
Sep 12 17:44:52.393133 kernel: AES CTR mode by8 optimization enabled
Sep 12 17:44:52.409143 kernel: hv_vmbus: registering driver hyperv_keyboard
Sep 12 17:44:52.409176 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
Sep 12 17:44:52.409191 kernel: pps_core: LinuxPPS API ver. 1 registered
Sep 12 17:44:52.409623 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Sep 12 17:44:52.436031 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Sep 12 17:44:52.409748 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:44:52.441554 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:44:52.454192 kernel: hv_vmbus: registering driver hv_netvsc
Sep 12 17:44:52.454214 kernel: PTP clock support registered
Sep 12 17:44:52.454226 kernel: hv_vmbus: registering driver hv_pci
Sep 12 17:44:52.454237 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004
Sep 12 17:44:52.454416 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00
Sep 12 17:44:52.454554 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window]
Sep 12 17:44:52.454699 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window]
Sep 12 17:44:52.454819 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff]
Sep 12 17:44:52.454933 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint
Sep 12 17:44:52.454961 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]
Sep 12 17:44:52.478591 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:44:52.505617 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]
Sep 12 17:44:52.505672 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]
Sep 12 17:44:52.505702 kernel: pci 0001:00:00.0: Enabling HDA controller
Sep 12 17:44:52.505721 kernel: hid: raw HID events driver (C) Jiri Kosina
Sep 12 17:44:52.505919 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
Sep 12 17:44:52.519133 kernel: hv_vmbus: registering driver hv_storvsc
Sep 12 17:44:52.519165 kernel: hv_utils: Registering HyperV Utility Driver
Sep 12 17:44:52.522161 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00
Sep 12 17:44:52.522354 kernel: hv_vmbus: registering driver hv_utils
Sep 12 17:44:52.522377 kernel: hv_utils: Heartbeat IC version 3.0
Sep 12 17:44:52.527127 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned
Sep 12 17:44:52.527312 kernel: hv_utils: Shutdown IC version 3.2
Sep 12 17:44:52.533464 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned
Sep 12 17:44:52.533697 kernel: hv_utils: TimeSync IC version 4.0
Sep 12 17:44:52.540132 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned
Sep 12 17:44:52.542162 kernel: scsi host1: storvsc_host_t
Sep 12 17:44:53.031857 systemd-resolved[285]: Clock change detected. Flushing caches.
Sep 12 17:44:53.042849 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Sep 12 17:44:53.043170 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:44:53.044889 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:44:53.078852 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:44:53.126077 kernel: scsi host0: storvsc_host_t
Sep 12 17:44:53.132078 kernel: scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
Sep 12 17:44:53.137099 kernel: scsi 0:0:0:1: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
Sep 12 17:44:53.143070 kernel: scsi 0:0:0:2: CD-ROM            Msft     Virtual DVD-ROM  1.0  PQ: 0 ANSI: 0
Sep 12 17:44:53.154571 kernel: hv_vmbus: registering driver hid_hyperv
Sep 12 17:44:53.154608 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1
Sep 12 17:44:53.160235 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on 
Sep 12 17:44:53.170147 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB)
Sep 12 17:44:53.170355 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB)
Sep 12 17:44:53.170498 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks
Sep 12 17:44:53.170628 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
Sep 12 17:44:53.175831 kernel: sd 0:0:0:1: [sdb] Write Protect is off
Sep 12 17:44:53.176022 kernel: sd 0:0:0:0: [sda] Write Protect is off
Sep 12 17:44:53.180801 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00
Sep 12 17:44:53.180955 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA
Sep 12 17:44:53.181103 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00
Sep 12 17:44:53.192895 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#71 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:53.193128 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA
Sep 12 17:44:53.202638 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#0 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:53.209752 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#323 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:53.217123 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:53.220076 kernel:  sdb: sdb1
Sep 12 17:44:53.220103 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk
Sep 12 17:44:53.228109 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Sep 12 17:44:53.231076 kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Sep 12 17:44:53.236548 kernel: sr 0:0:0:2: [sr0] scsi-1 drive
Sep 12 17:44:53.236738 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Sep 12 17:44:53.238091 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0
Sep 12 17:44:53.739767 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT.
Sep 12 17:44:53.791787 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM.
Sep 12 17:44:53.823834 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM.
Sep 12 17:44:53.835730 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A.
Sep 12 17:44:53.837269 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A.
Sep 12 17:44:53.863240 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Sep 12 17:44:53.907081 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#22 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:53.925147 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Sep 12 17:44:54.940315 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#352 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:44:54.953654 disk-uuid[724]: The operation has completed successfully.
Sep 12 17:44:54.957521 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Sep 12 17:44:56.244077 systemd[1]: disk-uuid.service: Deactivated successfully.
Sep 12 17:44:56.244194 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Sep 12 17:44:56.256968 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Sep 12 17:44:56.282210 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Sep 12 17:44:56.283563 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Sep 12 17:44:56.295838 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Sep 12 17:44:56.311349 sh[888]: Success
Sep 12 17:44:56.297322 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Sep 12 17:44:56.310665 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Sep 12 17:44:56.343520 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Sep 12 17:44:56.363940 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Sep 12 17:44:56.363973 kernel: device-mapper: uevent: version 1.0.3
Sep 12 17:44:56.368772 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
Sep 12 17:44:56.380088 kernel: device-mapper: verity: sha256 using shash "sha256-avx2"
Sep 12 17:44:56.712765 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Sep 12 17:44:56.722957 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Sep 12 17:44:56.749810 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Sep 12 17:44:56.768104 kernel: BTRFS: device fsid 74707491-1b86-4926-8bdb-c533ce2a0c32 devid 1 transid 38 /dev/mapper/usr (254:0) scanned by mount (921)
Sep 12 17:44:56.768142 kernel: BTRFS info (device dm-0): first mount of filesystem 74707491-1b86-4926-8bdb-c533ce2a0c32
Sep 12 17:44:56.774075 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Sep 12 17:44:57.106170 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Sep 12 17:44:57.106248 kernel: BTRFS info (device dm-0): enabling free space tree
Sep 12 17:44:57.147420 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Sep 12 17:44:57.149576 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
Sep 12 17:44:57.156267 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Sep 12 17:44:57.157069 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Sep 12 17:44:57.181606 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Sep 12 17:44:57.219081 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (952)
Sep 12 17:44:57.219117 kernel: BTRFS info (device sda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761
Sep 12 17:44:57.224086 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Sep 12 17:44:57.275273 kernel: BTRFS info (device sda6): turning on async discard
Sep 12 17:44:57.275334 kernel: BTRFS info (device sda6): enabling free space tree
Sep 12 17:44:57.284103 kernel: BTRFS info (device sda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761
Sep 12 17:44:57.284804 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Sep 12 17:44:57.288626 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Sep 12 17:44:57.309267 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Sep 12 17:44:57.312101 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Sep 12 17:44:57.358558 systemd-networkd[1090]: lo: Link UP
Sep 12 17:44:57.358568 systemd-networkd[1090]: lo: Gained carrier
Sep 12 17:44:57.359602 systemd-networkd[1090]: Enumeration completed
Sep 12 17:44:57.359699 systemd[1]: Started systemd-networkd.service - Network Configuration.
Sep 12 17:44:57.359968 systemd-networkd[1090]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Sep 12 17:44:57.359973 systemd-networkd[1090]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Sep 12 17:44:57.360833 systemd-networkd[1090]: eth0: Link UP
Sep 12 17:44:57.360999 systemd-networkd[1090]: eth0: Gained carrier
Sep 12 17:44:57.361009 systemd-networkd[1090]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Sep 12 17:44:57.369499 systemd[1]: Reached target network.target - Network.
Sep 12 17:44:57.439094 systemd-networkd[1090]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16
Sep 12 17:44:58.801800 ignition[1088]: Ignition 2.21.0
Sep 12 17:44:58.801815 ignition[1088]: Stage: fetch-offline
Sep 12 17:44:58.801908 ignition[1088]: no configs at "/usr/lib/ignition/base.d"
Sep 12 17:44:58.806282 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Sep 12 17:44:58.801918 ignition[1088]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:44:58.817377 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Sep 12 17:44:58.802022 ignition[1088]: parsed url from cmdline: ""
Sep 12 17:44:58.802026 ignition[1088]: no config URL provided
Sep 12 17:44:58.802032 ignition[1088]: reading system config file "/usr/lib/ignition/user.ign"
Sep 12 17:44:58.802040 ignition[1088]: no config at "/usr/lib/ignition/user.ign"
Sep 12 17:44:58.802046 ignition[1088]: failed to fetch config: resource requires networking
Sep 12 17:44:58.802243 ignition[1088]: Ignition finished successfully
Sep 12 17:44:58.863983 ignition[1105]: Ignition 2.21.0
Sep 12 17:44:58.863998 ignition[1105]: Stage: fetch
Sep 12 17:44:58.864364 ignition[1105]: no configs at "/usr/lib/ignition/base.d"
Sep 12 17:44:58.864382 ignition[1105]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:44:58.864595 ignition[1105]: parsed url from cmdline: ""
Sep 12 17:44:58.864608 ignition[1105]: no config URL provided
Sep 12 17:44:58.864617 ignition[1105]: reading system config file "/usr/lib/ignition/user.ign"
Sep 12 17:44:58.864633 ignition[1105]: no config at "/usr/lib/ignition/user.ign"
Sep 12 17:44:58.865185 ignition[1105]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1
Sep 12 17:44:59.008430 ignition[1105]: GET result: OK
Sep 12 17:44:59.008523 ignition[1105]: config has been read from IMDS userdata
Sep 12 17:44:59.008539 ignition[1105]: parsing config with SHA512: 0d127cc7e8c4ffd9a4bfe3480ee53864b77f15a1f4f1842bbfe00b62e27e1a447b3e720fd0025364b998f16765184545e9cdb88c7ce3e2e98f0e8e678192c1c0
Sep 12 17:44:59.012021 unknown[1105]: fetched base config from "system"
Sep 12 17:44:59.012254 ignition[1105]: fetch: fetch complete
Sep 12 17:44:59.012050 unknown[1105]: fetched base config from "system"
Sep 12 17:44:59.012259 ignition[1105]: fetch: fetch passed
Sep 12 17:44:59.012074 unknown[1105]: fetched user config from "azure"
Sep 12 17:44:59.012305 ignition[1105]: Ignition finished successfully
Sep 12 17:44:59.020458 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Sep 12 17:44:59.023595 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Sep 12 17:44:59.070456 ignition[1114]: Ignition 2.21.0
Sep 12 17:44:59.070471 ignition[1114]: Stage: kargs
Sep 12 17:44:59.074680 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Sep 12 17:44:59.070746 ignition[1114]: no configs at "/usr/lib/ignition/base.d"
Sep 12 17:44:59.082301 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Sep 12 17:44:59.070758 ignition[1114]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:44:59.072163 ignition[1114]: kargs: kargs passed
Sep 12 17:44:59.072228 ignition[1114]: Ignition finished successfully
Sep 12 17:44:59.125843 ignition[1123]: Ignition 2.21.0
Sep 12 17:44:59.125861 ignition[1123]: Stage: disks
Sep 12 17:44:59.126188 ignition[1123]: no configs at "/usr/lib/ignition/base.d"
Sep 12 17:44:59.126200 ignition[1123]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:44:59.126781 ignition[1123]: disks: disks passed
Sep 12 17:44:59.126828 ignition[1123]: Ignition finished successfully
Sep 12 17:44:59.139301 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Sep 12 17:44:59.141961 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Sep 12 17:44:59.149878 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Sep 12 17:44:59.162173 systemd-networkd[1090]: eth0: Gained IPv6LL
Sep 12 17:44:59.163378 systemd[1]: Reached target local-fs.target - Local File Systems.
Sep 12 17:44:59.180276 systemd[1]: Reached target sysinit.target - System Initialization.
Sep 12 17:44:59.181774 systemd[1]: Reached target basic.target - Basic System.
Sep 12 17:44:59.195514 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Sep 12 17:44:59.280313 systemd-fsck[1135]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks
Sep 12 17:44:59.288263 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Sep 12 17:44:59.290873 systemd[1]: Mounting sysroot.mount - /sysroot...
Sep 12 17:45:01.120074 kernel: EXT4-fs (sda9): mounted filesystem 26739aba-b0be-4ce3-bfbd-ca4dbcbe2426 r/w with ordered data mode. Quota mode: none.
Sep 12 17:45:01.120713 systemd[1]: Mounted sysroot.mount - /sysroot.
Sep 12 17:45:01.122796 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Sep 12 17:45:01.153244 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Sep 12 17:45:01.174573 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Sep 12 17:45:01.177180 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent...
Sep 12 17:45:01.183523 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Sep 12 17:45:01.211698 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1149)
Sep 12 17:45:01.211724 kernel: BTRFS info (device sda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761
Sep 12 17:45:01.211741 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Sep 12 17:45:01.183553 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Sep 12 17:45:01.233384 kernel: BTRFS info (device sda6): turning on async discard
Sep 12 17:45:01.233404 kernel: BTRFS info (device sda6): enabling free space tree
Sep 12 17:45:01.211391 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Sep 12 17:45:01.233544 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Sep 12 17:45:01.242007 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Sep 12 17:45:01.845636 coreos-metadata[1151]: Sep 12 17:45:01.845 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Sep 12 17:45:01.853473 coreos-metadata[1151]: Sep 12 17:45:01.853 INFO Fetch successful
Sep 12 17:45:01.857514 coreos-metadata[1151]: Sep 12 17:45:01.853 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1
Sep 12 17:45:01.872885 coreos-metadata[1151]: Sep 12 17:45:01.872 INFO Fetch successful
Sep 12 17:45:01.884722 coreos-metadata[1151]: Sep 12 17:45:01.884 INFO wrote hostname ci-4426.1.0-a-6c6fa50560 to /sysroot/etc/hostname
Sep 12 17:45:01.892747 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Sep 12 17:45:02.204911 initrd-setup-root[1180]: cut: /sysroot/etc/passwd: No such file or directory
Sep 12 17:45:02.279256 initrd-setup-root[1187]: cut: /sysroot/etc/group: No such file or directory
Sep 12 17:45:02.294291 initrd-setup-root[1194]: cut: /sysroot/etc/shadow: No such file or directory
Sep 12 17:45:02.313943 initrd-setup-root[1201]: cut: /sysroot/etc/gshadow: No such file or directory
Sep 12 17:45:03.532918 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Sep 12 17:45:03.539217 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Sep 12 17:45:03.557585 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Sep 12 17:45:03.562986 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Sep 12 17:45:03.573710 kernel: BTRFS info (device sda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761
Sep 12 17:45:03.594419 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Sep 12 17:45:03.604190 ignition[1269]: INFO     : Ignition 2.21.0
Sep 12 17:45:03.604190 ignition[1269]: INFO     : Stage: mount
Sep 12 17:45:03.610114 ignition[1269]: INFO     : no configs at "/usr/lib/ignition/base.d"
Sep 12 17:45:03.610114 ignition[1269]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:45:03.610114 ignition[1269]: INFO     : mount: mount passed
Sep 12 17:45:03.610114 ignition[1269]: INFO     : Ignition finished successfully
Sep 12 17:45:03.611477 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Sep 12 17:45:03.623420 systemd[1]: Starting ignition-files.service - Ignition (files)...
Sep 12 17:45:03.658707 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Sep 12 17:45:03.684108 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1285)
Sep 12 17:45:03.693628 kernel: BTRFS info (device sda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761
Sep 12 17:45:03.693657 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Sep 12 17:45:03.703754 kernel: BTRFS info (device sda6): turning on async discard
Sep 12 17:45:03.703784 kernel: BTRFS info (device sda6): enabling free space tree
Sep 12 17:45:03.705890 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Sep 12 17:45:03.754873 ignition[1302]: INFO     : Ignition 2.21.0
Sep 12 17:45:03.754873 ignition[1302]: INFO     : Stage: files
Sep 12 17:45:03.761010 ignition[1302]: INFO     : no configs at "/usr/lib/ignition/base.d"
Sep 12 17:45:03.761010 ignition[1302]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:45:03.761010 ignition[1302]: DEBUG    : files: compiled without relabeling support, skipping
Sep 12 17:45:03.775512 ignition[1302]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Sep 12 17:45:03.775512 ignition[1302]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Sep 12 17:45:03.827789 ignition[1302]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Sep 12 17:45:03.834604 ignition[1302]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Sep 12 17:45:03.840276 unknown[1302]: wrote ssh authorized keys file for user: core
Sep 12 17:45:03.844416 ignition[1302]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Sep 12 17:45:03.921635 ignition[1302]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Sep 12 17:45:03.928959 ignition[1302]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Sep 12 17:45:03.928959 ignition[1302]: INFO     : files: createResultFile: createFiles: op(4): [started]  writing file "/sysroot/etc/.ignition-result.json"
Sep 12 17:45:03.928959 ignition[1302]: INFO     : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json"
Sep 12 17:45:03.928959 ignition[1302]: INFO     : files: files passed
Sep 12 17:45:03.928959 ignition[1302]: INFO     : Ignition finished successfully
Sep 12 17:45:03.959367 systemd[1]: Finished ignition-files.service - Ignition (files).
Sep 12 17:45:03.961929 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Sep 12 17:45:03.990816 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Sep 12 17:45:03.993969 systemd[1]: ignition-quench.service: Deactivated successfully.
Sep 12 17:45:03.994094 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Sep 12 17:45:04.042953 initrd-setup-root-after-ignition[1335]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Sep 12 17:45:04.042953 initrd-setup-root-after-ignition[1335]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Sep 12 17:45:04.056533 initrd-setup-root-after-ignition[1339]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Sep 12 17:45:04.057494 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Sep 12 17:45:04.068812 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Sep 12 17:45:04.079661 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Sep 12 17:45:04.131150 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Sep 12 17:45:04.131263 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Sep 12 17:45:04.132942 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Sep 12 17:45:04.147605 systemd[1]: Reached target initrd.target - Initrd Default Target.
Sep 12 17:45:04.155914 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Sep 12 17:45:04.156793 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Sep 12 17:45:04.187745 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Sep 12 17:45:04.198702 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Sep 12 17:45:04.217664 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Sep 12 17:45:04.219298 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Sep 12 17:45:04.236616 systemd[1]: Stopped target timers.target - Timer Units.
Sep 12 17:45:04.238169 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Sep 12 17:45:04.238293 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Sep 12 17:45:04.252615 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Sep 12 17:45:04.261516 systemd[1]: Stopped target basic.target - Basic System.
Sep 12 17:45:04.263732 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Sep 12 17:45:04.273956 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Sep 12 17:45:04.286379 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Sep 12 17:45:04.295347 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
Sep 12 17:45:04.304380 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Sep 12 17:45:04.306611 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Sep 12 17:45:04.318431 systemd[1]: Stopped target sysinit.target - System Initialization.
Sep 12 17:45:04.328351 systemd[1]: Stopped target local-fs.target - Local File Systems.
Sep 12 17:45:04.336500 systemd[1]: Stopped target swap.target - Swaps.
Sep 12 17:45:04.344003 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Sep 12 17:45:04.344145 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Sep 12 17:45:04.358707 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Sep 12 17:45:04.367305 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Sep 12 17:45:04.376729 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Sep 12 17:45:04.376839 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Sep 12 17:45:04.394197 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Sep 12 17:45:04.394327 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Sep 12 17:45:04.408337 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Sep 12 17:45:04.408463 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Sep 12 17:45:04.417076 systemd[1]: ignition-files.service: Deactivated successfully.
Sep 12 17:45:04.417203 systemd[1]: Stopped ignition-files.service - Ignition (files).
Sep 12 17:45:04.424915 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
Sep 12 17:45:04.425036 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Sep 12 17:45:04.435159 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Sep 12 17:45:04.443380 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Sep 12 17:45:04.449780 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Sep 12 17:45:04.449905 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Sep 12 17:45:04.458232 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Sep 12 17:45:04.496120 ignition[1359]: INFO     : Ignition 2.21.0
Sep 12 17:45:04.496120 ignition[1359]: INFO     : Stage: umount
Sep 12 17:45:04.496120 ignition[1359]: INFO     : no configs at "/usr/lib/ignition/base.d"
Sep 12 17:45:04.496120 ignition[1359]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Sep 12 17:45:04.496120 ignition[1359]: INFO     : umount: umount passed
Sep 12 17:45:04.496120 ignition[1359]: INFO     : Ignition finished successfully
Sep 12 17:45:04.458350 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Sep 12 17:45:04.469793 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Sep 12 17:45:04.473245 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Sep 12 17:45:04.494501 systemd[1]: ignition-mount.service: Deactivated successfully.
Sep 12 17:45:04.494657 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Sep 12 17:45:04.496971 systemd[1]: ignition-disks.service: Deactivated successfully.
Sep 12 17:45:04.497026 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Sep 12 17:45:04.506920 systemd[1]: ignition-kargs.service: Deactivated successfully.
Sep 12 17:45:04.506964 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Sep 12 17:45:04.514350 systemd[1]: ignition-fetch.service: Deactivated successfully.
Sep 12 17:45:04.514397 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Sep 12 17:45:04.515985 systemd[1]: Stopped target network.target - Network.
Sep 12 17:45:04.530897 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Sep 12 17:45:04.530952 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Sep 12 17:45:04.541823 systemd[1]: Stopped target paths.target - Path Units.
Sep 12 17:45:04.549078 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Sep 12 17:45:04.554981 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Sep 12 17:45:04.568101 systemd[1]: Stopped target slices.target - Slice Units.
Sep 12 17:45:04.570323 systemd[1]: Stopped target sockets.target - Socket Units.
Sep 12 17:45:04.580090 systemd[1]: iscsid.socket: Deactivated successfully.
Sep 12 17:45:04.580135 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Sep 12 17:45:04.581533 systemd[1]: iscsiuio.socket: Deactivated successfully.
Sep 12 17:45:04.581572 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Sep 12 17:45:04.597182 systemd[1]: ignition-setup.service: Deactivated successfully.
Sep 12 17:45:04.597246 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Sep 12 17:45:04.604959 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Sep 12 17:45:04.605005 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Sep 12 17:45:04.614029 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Sep 12 17:45:04.625518 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Sep 12 17:45:04.634405 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Sep 12 17:45:04.634978 systemd[1]: systemd-resolved.service: Deactivated successfully.
Sep 12 17:45:04.635101 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Sep 12 17:45:04.651815 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
Sep 12 17:45:04.652131 systemd[1]: systemd-networkd.service: Deactivated successfully.
Sep 12 17:45:04.652251 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Sep 12 17:45:04.663557 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
Sep 12 17:45:04.663789 systemd[1]: sysroot-boot.service: Deactivated successfully.
Sep 12 17:45:04.663881 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Sep 12 17:45:04.666193 systemd[1]: Stopped target network-pre.target - Preparation for Network.
Sep 12 17:45:04.678271 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Sep 12 17:45:04.678323 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Sep 12 17:45:04.686713 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Sep 12 17:45:04.686775 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Sep 12 17:45:04.695254 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Sep 12 17:45:04.703091 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Sep 12 17:45:04.703147 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Sep 12 17:45:04.714849 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Sep 12 17:45:04.714900 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Sep 12 17:45:04.724119 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Sep 12 17:45:04.724165 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Sep 12 17:45:04.731891 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Sep 12 17:45:04.731944 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Sep 12 17:45:04.741824 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Sep 12 17:45:04.755081 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Sep 12 17:45:04.755148 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
Sep 12 17:45:04.793774 systemd[1]: systemd-udevd.service: Deactivated successfully.
Sep 12 17:45:04.793927 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Sep 12 17:45:04.802681 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Sep 12 17:45:04.802726 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Sep 12 17:45:04.810436 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Sep 12 17:45:04.810473 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Sep 12 17:45:04.815152 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Sep 12 17:45:04.815207 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Sep 12 17:45:04.827774 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Sep 12 17:45:04.827826 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Sep 12 17:45:04.844403 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Sep 12 17:45:04.844452 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Sep 12 17:45:04.857623 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Sep 12 17:45:04.868464 systemd[1]: systemd-network-generator.service: Deactivated successfully.
Sep 12 17:45:04.868520 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
Sep 12 17:45:04.885257 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Sep 12 17:45:04.885307 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Sep 12 17:45:04.899195 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
Sep 12 17:45:04.899308 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Sep 12 17:45:04.914623 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Sep 12 17:45:04.914672 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Sep 12 17:45:04.925225 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Sep 12 17:45:04.925272 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:45:04.937040 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully.
Sep 12 17:45:04.937108 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully.
Sep 12 17:45:04.937145 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully.
Sep 12 17:45:04.937184 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
Sep 12 17:45:04.937520 systemd[1]: network-cleanup.service: Deactivated successfully.
Sep 12 17:45:05.091352 systemd-journald[231]: Received SIGTERM from PID 1 (systemd).
Sep 12 17:45:04.937617 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Sep 12 17:45:04.940617 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Sep 12 17:45:04.940700 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Sep 12 17:45:04.950572 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Sep 12 17:45:04.959211 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Sep 12 17:45:04.991784 systemd[1]: Switching root.
Sep 12 17:45:05.104685 systemd-journald[231]: Journal stopped
Sep 12 17:45:12.959932 kernel: SELinux:  policy capability network_peer_controls=1
Sep 12 17:45:12.959963 kernel: SELinux:  policy capability open_perms=1
Sep 12 17:45:12.959976 kernel: SELinux:  policy capability extended_socket_class=1
Sep 12 17:45:12.959985 kernel: SELinux:  policy capability always_check_network=0
Sep 12 17:45:12.959994 kernel: SELinux:  policy capability cgroup_seclabel=1
Sep 12 17:45:12.960003 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Sep 12 17:45:12.960013 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Sep 12 17:45:12.960025 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Sep 12 17:45:12.960035 kernel: SELinux:  policy capability userspace_initial_context=0
Sep 12 17:45:12.960044 kernel: audit: type=1403 audit(1757699106.362:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Sep 12 17:45:12.960066 systemd[1]: Successfully loaded SELinux policy in 223.066ms.
Sep 12 17:45:12.960080 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.668ms.
Sep 12 17:45:12.960096 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)
Sep 12 17:45:12.960106 systemd[1]: Detected virtualization microsoft.
Sep 12 17:45:12.960121 systemd[1]: Detected architecture x86-64.
Sep 12 17:45:12.960132 systemd[1]: Detected first boot.
Sep 12 17:45:12.960143 systemd[1]: Hostname set to <ci-4426.1.0-a-6c6fa50560>.
Sep 12 17:45:12.960153 systemd[1]: Initializing machine ID from random generator.
Sep 12 17:45:12.960164 zram_generator::config[1405]: No configuration found.
Sep 12 17:45:12.960178 kernel: Guest personality initialized and is inactive
Sep 12 17:45:12.960188 kernel: VMCI host device registered (name=vmci, major=10, minor=124)
Sep 12 17:45:12.960197 kernel: Initialized host personality
Sep 12 17:45:12.960207 kernel: NET: Registered PF_VSOCK protocol family
Sep 12 17:45:12.960218 systemd[1]: Populated /etc with preset unit settings.
Sep 12 17:45:12.960229 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
Sep 12 17:45:12.960239 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Sep 12 17:45:12.960252 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Sep 12 17:45:12.960263 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Sep 12 17:45:12.960274 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Sep 12 17:45:12.960285 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Sep 12 17:45:12.960295 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Sep 12 17:45:12.960306 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Sep 12 17:45:12.960317 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Sep 12 17:45:12.960334 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Sep 12 17:45:12.960347 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Sep 12 17:45:12.960358 systemd[1]: Created slice user.slice - User and Session Slice.
Sep 12 17:45:12.960369 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Sep 12 17:45:12.960380 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Sep 12 17:45:12.960390 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Sep 12 17:45:12.960401 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Sep 12 17:45:12.960416 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Sep 12 17:45:12.960427 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Sep 12 17:45:12.960441 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Sep 12 17:45:12.960452 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Sep 12 17:45:12.960463 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Sep 12 17:45:12.960474 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Sep 12 17:45:12.960485 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Sep 12 17:45:12.960495 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Sep 12 17:45:12.960506 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Sep 12 17:45:12.960520 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Sep 12 17:45:12.960531 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Sep 12 17:45:12.960542 systemd[1]: Reached target slices.target - Slice Units.
Sep 12 17:45:12.960553 systemd[1]: Reached target swap.target - Swaps.
Sep 12 17:45:12.960564 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Sep 12 17:45:12.960578 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Sep 12 17:45:12.960592 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
Sep 12 17:45:12.960603 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Sep 12 17:45:12.960614 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Sep 12 17:45:12.960625 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Sep 12 17:45:12.960636 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Sep 12 17:45:12.960647 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Sep 12 17:45:12.960658 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Sep 12 17:45:12.960671 systemd[1]: Mounting media.mount - External Media Directory...
Sep 12 17:45:12.960683 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:12.960694 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Sep 12 17:45:12.960705 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Sep 12 17:45:12.960716 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Sep 12 17:45:12.960727 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).
Sep 12 17:45:12.960739 systemd[1]: Reached target machines.target - Containers.
Sep 12 17:45:12.960750 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Sep 12 17:45:12.960761 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Sep 12 17:45:12.960775 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Sep 12 17:45:12.960786 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Sep 12 17:45:12.960797 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Sep 12 17:45:12.960808 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Sep 12 17:45:12.960822 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Sep 12 17:45:12.960833 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Sep 12 17:45:12.960844 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Sep 12 17:45:12.960855 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Sep 12 17:45:12.960869 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Sep 12 17:45:12.960880 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Sep 12 17:45:12.960891 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Sep 12 17:45:12.960903 systemd[1]: Stopped systemd-fsck-usr.service.
Sep 12 17:45:12.960915 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).
Sep 12 17:45:12.960926 systemd[1]: Starting systemd-journald.service - Journal Service...
Sep 12 17:45:12.960937 kernel: fuse: init (API version 7.41)
Sep 12 17:45:12.960947 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Sep 12 17:45:12.960961 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Sep 12 17:45:12.960972 kernel: loop: module loaded
Sep 12 17:45:12.960983 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Sep 12 17:45:12.960994 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
Sep 12 17:45:12.961005 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Sep 12 17:45:12.961016 systemd[1]: verity-setup.service: Deactivated successfully.
Sep 12 17:45:12.961027 systemd[1]: Stopped verity-setup.service.
Sep 12 17:45:12.961069 systemd-journald[1488]: Collecting audit messages is disabled.
Sep 12 17:45:12.961103 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:12.961120 systemd-journald[1488]: Journal started
Sep 12 17:45:12.961145 systemd-journald[1488]: Runtime Journal (/run/log/journal/570ba6f3622f497a9e48c60b2542490d) is 8M, max 2.2G, 2.1G free.
Sep 12 17:45:12.100370 systemd[1]: Queued start job for default target multi-user.target.
Sep 12 17:45:12.111472 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6.
Sep 12 17:45:12.111971 systemd[1]: systemd-journald.service: Deactivated successfully.
Sep 12 17:45:12.973651 systemd[1]: Started systemd-journald.service - Journal Service.
Sep 12 17:45:12.977900 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Sep 12 17:45:12.982370 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Sep 12 17:45:12.987198 systemd[1]: Mounted media.mount - External Media Directory.
Sep 12 17:45:12.991694 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Sep 12 17:45:12.996315 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Sep 12 17:45:13.001191 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Sep 12 17:45:13.007343 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Sep 12 17:45:13.012530 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Sep 12 17:45:13.017835 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Sep 12 17:45:13.018154 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Sep 12 17:45:13.023557 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Sep 12 17:45:13.023745 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Sep 12 17:45:13.029031 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Sep 12 17:45:13.029294 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Sep 12 17:45:13.035092 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Sep 12 17:45:13.035330 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Sep 12 17:45:13.040346 systemd[1]: modprobe@loop.service: Deactivated successfully.
Sep 12 17:45:13.040527 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Sep 12 17:45:13.045601 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Sep 12 17:45:13.050796 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Sep 12 17:45:13.056787 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Sep 12 17:45:13.062908 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
Sep 12 17:45:13.077284 systemd[1]: Reached target network-pre.target - Preparation for Network.
Sep 12 17:45:13.083368 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Sep 12 17:45:13.099075 kernel: ACPI: bus type drm_connector registered
Sep 12 17:45:13.104286 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Sep 12 17:45:13.109089 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Sep 12 17:45:13.109126 systemd[1]: Reached target local-fs.target - Local File Systems.
Sep 12 17:45:13.115070 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
Sep 12 17:45:13.123775 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Sep 12 17:45:13.128169 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Sep 12 17:45:13.155805 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Sep 12 17:45:13.172805 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Sep 12 17:45:13.177762 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Sep 12 17:45:13.194254 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Sep 12 17:45:13.198872 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Sep 12 17:45:13.200000 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Sep 12 17:45:13.205247 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Sep 12 17:45:13.211638 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Sep 12 17:45:13.222822 systemd[1]: modprobe@drm.service: Deactivated successfully.
Sep 12 17:45:13.223020 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Sep 12 17:45:13.226327 systemd-journald[1488]: Time spent on flushing to /var/log/journal/570ba6f3622f497a9e48c60b2542490d is 14.829ms for 953 entries.
Sep 12 17:45:13.226327 systemd-journald[1488]: System Journal (/var/log/journal/570ba6f3622f497a9e48c60b2542490d) is 8M, max 2.6G, 2.6G free.
Sep 12 17:45:13.297304 systemd-journald[1488]: Received client request to flush runtime journal.
Sep 12 17:45:13.233603 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Sep 12 17:45:13.240134 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Sep 12 17:45:13.245727 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Sep 12 17:45:13.268499 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Sep 12 17:45:13.274241 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Sep 12 17:45:13.280581 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
Sep 12 17:45:13.301300 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Sep 12 17:45:13.325080 kernel: loop0: detected capacity change from 0 to 111000
Sep 12 17:45:13.353172 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Sep 12 17:45:13.353867 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
Sep 12 17:45:13.382226 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Sep 12 17:45:13.437190 systemd-tmpfiles[1548]: ACLs are not supported, ignoring.
Sep 12 17:45:13.437211 systemd-tmpfiles[1548]: ACLs are not supported, ignoring.
Sep 12 17:45:13.454081 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Sep 12 17:45:13.460732 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Sep 12 17:45:14.117759 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Sep 12 17:45:14.124269 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Sep 12 17:45:14.159462 systemd-tmpfiles[1567]: ACLs are not supported, ignoring.
Sep 12 17:45:14.159484 systemd-tmpfiles[1567]: ACLs are not supported, ignoring.
Sep 12 17:45:14.162847 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Sep 12 17:45:14.247086 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Sep 12 17:45:14.353092 kernel: loop1: detected capacity change from 0 to 128016
Sep 12 17:45:14.967099 kernel: loop2: detected capacity change from 0 to 29272
Sep 12 17:45:15.673414 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Sep 12 17:45:15.683875 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Sep 12 17:45:15.714081 kernel: loop3: detected capacity change from 0 to 111000
Sep 12 17:45:15.732081 kernel: loop4: detected capacity change from 0 to 128016
Sep 12 17:45:15.735628 systemd-udevd[1573]: Using default interface naming scheme 'v255'.
Sep 12 17:45:15.748084 kernel: loop5: detected capacity change from 0 to 29272
Sep 12 17:45:15.757905 (sd-merge)[1575]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'.
Sep 12 17:45:15.758383 (sd-merge)[1575]: Merged extensions into '/usr'.
Sep 12 17:45:15.761923 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Sep 12 17:45:15.770025 systemd[1]: Starting ensure-sysext.service...
Sep 12 17:45:15.774264 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Sep 12 17:45:15.820087 systemd[1]: Reload requested from client PID 1577 ('systemctl') (unit ensure-sysext.service)...
Sep 12 17:45:15.820103 systemd[1]: Reloading...
Sep 12 17:45:15.860082 zram_generator::config[1605]: No configuration found.
Sep 12 17:45:15.872160 systemd-tmpfiles[1578]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
Sep 12 17:45:15.872210 systemd-tmpfiles[1578]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
Sep 12 17:45:15.872528 systemd-tmpfiles[1578]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Sep 12 17:45:15.872809 systemd-tmpfiles[1578]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Sep 12 17:45:15.873723 systemd-tmpfiles[1578]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Sep 12 17:45:15.874006 systemd-tmpfiles[1578]: ACLs are not supported, ignoring.
Sep 12 17:45:15.874096 systemd-tmpfiles[1578]: ACLs are not supported, ignoring.
Sep 12 17:45:15.927235 systemd-tmpfiles[1578]: Detected autofs mount point /boot during canonicalization of boot.
Sep 12 17:45:15.927253 systemd-tmpfiles[1578]: Skipping /boot
Sep 12 17:45:15.936818 systemd-tmpfiles[1578]: Detected autofs mount point /boot during canonicalization of boot.
Sep 12 17:45:15.936834 systemd-tmpfiles[1578]: Skipping /boot
Sep 12 17:45:16.096603 systemd[1]: Reloading finished in 276 ms.
Sep 12 17:45:16.151147 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Sep 12 17:45:16.167239 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Sep 12 17:45:16.197231 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Sep 12 17:45:16.214419 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Sep 12 17:45:16.221452 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Sep 12 17:45:16.226821 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Sep 12 17:45:16.233986 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.234210 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Sep 12 17:45:16.236202 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Sep 12 17:45:16.241706 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Sep 12 17:45:16.247956 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Sep 12 17:45:16.252470 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Sep 12 17:45:16.252596 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).
Sep 12 17:45:16.252701 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.254030 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Sep 12 17:45:16.254247 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Sep 12 17:45:16.259582 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Sep 12 17:45:16.259757 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Sep 12 17:45:16.265327 systemd[1]: modprobe@loop.service: Deactivated successfully.
Sep 12 17:45:16.265722 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Sep 12 17:45:16.276463 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.276684 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Sep 12 17:45:16.277805 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Sep 12 17:45:16.292685 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Sep 12 17:45:16.298675 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Sep 12 17:45:16.303173 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Sep 12 17:45:16.303290 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).
Sep 12 17:45:16.303394 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.304801 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Sep 12 17:45:16.310866 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Sep 12 17:45:16.311046 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Sep 12 17:45:16.316732 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Sep 12 17:45:16.316908 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Sep 12 17:45:16.322491 systemd[1]: modprobe@loop.service: Deactivated successfully.
Sep 12 17:45:16.322667 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Sep 12 17:45:16.333305 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv...
Sep 12 17:45:16.337555 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.337836 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Sep 12 17:45:16.339080 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Sep 12 17:45:16.361454 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Sep 12 17:45:16.366510 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Sep 12 17:45:16.373010 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Sep 12 17:45:16.377735 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Sep 12 17:45:16.377867 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).
Sep 12 17:45:16.378079 systemd[1]: Reached target time-set.target - System Time Set.
Sep 12 17:45:16.382624 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Sep 12 17:45:16.384189 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Sep 12 17:45:16.384394 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Sep 12 17:45:16.390141 systemd[1]: modprobe@drm.service: Deactivated successfully.
Sep 12 17:45:16.390332 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Sep 12 17:45:16.395631 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Sep 12 17:45:16.395807 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Sep 12 17:45:16.401700 systemd[1]: modprobe@loop.service: Deactivated successfully.
Sep 12 17:45:16.401890 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Sep 12 17:45:16.409197 systemd[1]: Finished ensure-sysext.service.
Sep 12 17:45:16.416050 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Sep 12 17:45:16.416240 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Sep 12 17:45:16.417893 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Sep 12 17:45:16.473498 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Sep 12 17:45:16.493850 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Sep 12 17:45:16.505297 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Sep 12 17:45:16.549911 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Sep 12 17:45:16.587510 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Sep 12 17:45:16.605081 kernel: hv_vmbus: registering driver hyperv_fb
Sep 12 17:45:16.613841 kernel: hyperv_fb: Synthvid Version major 3, minor 5
Sep 12 17:45:16.613983 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
Sep 12 17:45:16.619095 kernel: hv_vmbus: registering driver hv_balloon
Sep 12 17:45:16.619152 kernel: Console: switching to colour dummy device 80x25
Sep 12 17:45:16.628078 kernel: Console: switching to colour frame buffer device 128x48
Sep 12 17:45:16.627746 systemd-resolved[1670]: Positive Trust Anchors:
Sep 12 17:45:16.627755 systemd-resolved[1670]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Sep 12 17:45:16.627787 systemd-resolved[1670]: 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
Sep 12 17:45:16.646082 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
Sep 12 17:45:16.646096 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped.
Sep 12 17:45:16.655117 kernel: mousedev: PS/2 mouse device common for all mice
Sep 12 17:45:16.660618 systemd-resolved[1670]: Using system hostname 'ci-4426.1.0-a-6c6fa50560'.
Sep 12 17:45:16.667795 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:45:16.681384 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Sep 12 17:45:16.687912 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Sep 12 17:45:16.688149 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:45:16.693027 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Sep 12 17:45:16.699320 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Sep 12 17:45:16.703563 augenrules[1795]: No rules
Sep 12 17:45:16.705253 systemd[1]: audit-rules.service: Deactivated successfully.
Sep 12 17:45:16.705522 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Sep 12 17:45:16.777086 kernel: EDAC MC: Ver: 3.0.0
Sep 12 17:45:16.782540 systemd-networkd[1718]: lo: Link UP
Sep 12 17:45:16.782551 systemd-networkd[1718]: lo: Gained carrier
Sep 12 17:45:16.783924 systemd-networkd[1718]: Enumeration completed
Sep 12 17:45:16.784045 systemd[1]: Started systemd-networkd.service - Network Configuration.
Sep 12 17:45:16.784342 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Sep 12 17:45:16.784352 systemd-networkd[1718]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Sep 12 17:45:16.784911 systemd-networkd[1718]: eth0: Link UP
Sep 12 17:45:16.784920 systemd-networkd[1718]: eth0: Gained carrier
Sep 12 17:45:16.784935 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Sep 12 17:45:16.785672 systemd[1]: Reached target network.target - Network.
Sep 12 17:45:16.787688 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
Sep 12 17:45:16.788958 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Sep 12 17:45:16.846134 systemd-networkd[1718]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16
Sep 12 17:45:16.873710 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
Sep 12 17:45:16.915395 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM.
Sep 12 17:45:16.918150 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Sep 12 17:45:17.030112 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Sep 12 17:45:18.076323 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Sep 12 17:45:18.297211 systemd-networkd[1718]: eth0: Gained IPv6LL
Sep 12 17:45:18.299419 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Sep 12 17:45:18.305358 systemd[1]: Reached target network-online.target - Network is Online.
Sep 12 17:45:18.675924 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Sep 12 17:45:18.681744 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).
Sep 12 17:45:23.586783 ldconfig[1541]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Sep 12 17:45:23.598967 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Sep 12 17:45:23.606518 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Sep 12 17:45:23.648191 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Sep 12 17:45:23.653735 systemd[1]: Reached target sysinit.target - System Initialization.
Sep 12 17:45:23.658876 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Sep 12 17:45:23.664872 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Sep 12 17:45:23.670951 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
Sep 12 17:45:23.676527 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Sep 12 17:45:23.681670 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Sep 12 17:45:23.687546 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Sep 12 17:45:23.693514 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Sep 12 17:45:23.693553 systemd[1]: Reached target paths.target - Path Units.
Sep 12 17:45:23.697936 systemd[1]: Reached target timers.target - Timer Units.
Sep 12 17:45:23.714984 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Sep 12 17:45:23.721125 systemd[1]: Starting docker.socket - Docker Socket for the API...
Sep 12 17:45:23.727327 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Sep 12 17:45:23.733094 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
Sep 12 17:45:23.738532 systemd[1]: Reached target ssh-access.target - SSH Access Available.
Sep 12 17:45:23.744853 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Sep 12 17:45:23.749506 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
Sep 12 17:45:23.755202 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Sep 12 17:45:23.760447 systemd[1]: Reached target sockets.target - Socket Units.
Sep 12 17:45:23.764480 systemd[1]: Reached target basic.target - Basic System.
Sep 12 17:45:23.768500 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Sep 12 17:45:23.768533 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Sep 12 17:45:23.798169 systemd[1]: Starting chronyd.service - NTP client/server...
Sep 12 17:45:23.802911 systemd[1]: Starting containerd.service - containerd container runtime...
Sep 12 17:45:23.820985 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Sep 12 17:45:23.827638 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Sep 12 17:45:23.832431 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Sep 12 17:45:23.838483 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Sep 12 17:45:23.844303 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Sep 12 17:45:23.848455 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).
Sep 12 17:45:23.849481 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
Sep 12 17:45:23.853745 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio).
Sep 12 17:45:23.855459 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon.
Sep 12 17:45:23.857456 jq[1892]: false
Sep 12 17:45:23.860229 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss).
Sep 12 17:45:23.861513 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Sep 12 17:45:23.866479 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Sep 12 17:45:23.871392 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Sep 12 17:45:23.878212 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Sep 12 17:45:23.885031 systemd[1]: Starting systemd-logind.service - User Login Management...
Sep 12 17:45:23.890952 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Sep 12 17:45:23.891462 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Sep 12 17:45:23.910819 KVP[1895]: KVP starting; pid is:1895
Sep 12 17:45:23.911263 chronyd[1884]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG)
Sep 12 17:45:23.912367 systemd[1]: Starting update-engine.service - Update Engine...
Sep 12 17:45:23.917607 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Sep 12 17:45:23.924908 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Refreshing passwd entry cache
Sep 12 17:45:23.924671 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Sep 12 17:45:23.924215 oslogin_cache_refresh[1894]: Refreshing passwd entry cache
Sep 12 17:45:23.928375 jq[1913]: true
Sep 12 17:45:23.932456 KVP[1895]: KVP LIC Version: 3.1
Sep 12 17:45:23.932611 oslogin_cache_refresh[1894]: Failure getting users, quitting
Sep 12 17:45:23.932717 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Failure getting users, quitting
Sep 12 17:45:23.932717 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Sep 12 17:45:23.932717 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Refreshing group entry cache
Sep 12 17:45:23.932628 oslogin_cache_refresh[1894]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Sep 12 17:45:23.932670 oslogin_cache_refresh[1894]: Refreshing group entry cache
Sep 12 17:45:23.933079 kernel: hv_utils: KVP IC version 4.0
Sep 12 17:45:23.933364 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Sep 12 17:45:23.933590 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Sep 12 17:45:23.933911 systemd[1]: motdgen.service: Deactivated successfully.
Sep 12 17:45:23.934157 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Sep 12 17:45:23.937619 oslogin_cache_refresh[1894]: Failure getting groups, quitting
Sep 12 17:45:23.939191 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Failure getting groups, quitting
Sep 12 17:45:23.939191 google_oslogin_nss_cache[1894]: oslogin_cache_refresh[1894]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Sep 12 17:45:23.937631 oslogin_cache_refresh[1894]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Sep 12 17:45:23.939450 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
Sep 12 17:45:23.939659 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
Sep 12 17:45:23.940941 extend-filesystems[1893]: Found /dev/sda6
Sep 12 17:45:23.941573 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Sep 12 17:45:23.941771 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Sep 12 17:45:23.963968 chronyd[1884]: Timezone right/UTC failed leap second check, ignoring
Sep 12 17:45:23.964188 chronyd[1884]: Loaded seccomp filter (level 2)
Sep 12 17:45:23.964279 systemd[1]: Started chronyd.service - NTP client/server.
Sep 12 17:45:23.964626 (ntainerd)[1920]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Sep 12 17:45:23.971004 jq[1919]: true
Sep 12 17:45:24.001613 extend-filesystems[1893]: Found /dev/sda9
Sep 12 17:45:24.007922 extend-filesystems[1893]: Checking size of /dev/sda9
Sep 12 17:45:24.019004 systemd-logind[1904]: New seat seat0.
Sep 12 17:45:24.021888 systemd-logind[1904]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Sep 12 17:45:24.022124 systemd[1]: Started systemd-logind.service - User Login Management.
Sep 12 17:45:24.027429 update_engine[1906]: I20250912 17:45:24.027354  1906 main.cc:92] Flatcar Update Engine starting
Sep 12 17:45:24.051293 extend-filesystems[1893]: Old size kept for /dev/sda9
Sep 12 17:45:24.052334 systemd[1]: extend-filesystems.service: Deactivated successfully.
Sep 12 17:45:24.052583 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Sep 12 17:45:24.100408 bash[1947]: Updated "/home/core/.ssh/authorized_keys"
Sep 12 17:45:24.101483 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Sep 12 17:45:24.131861 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
Sep 12 17:45:24.226803 setup-nvidia[1897]: Downloading Flatcar Container Linux Developer Container for version: 4426.1.0
Sep 12 17:45:24.265192 sshd_keygen[1914]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Sep 12 17:45:24.288205 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Sep 12 17:45:24.294005 systemd[1]: Starting issuegen.service - Generate /run/issue...
Sep 12 17:45:24.311536 dbus-daemon[1887]: [system] SELinux support is enabled
Sep 12 17:45:24.313328 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent...
Sep 12 17:45:24.315182 update_engine[1906]: I20250912 17:45:24.315073  1906 update_check_scheduler.cc:74] Next update check in 9m52s
Sep 12 17:45:24.317798 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Sep 12 17:45:24.326247 systemd[1]: issuegen.service: Deactivated successfully.
Sep 12 17:45:24.326473 systemd[1]: Finished issuegen.service - Generate /run/issue.
Sep 12 17:45:24.333176 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).
Sep 12 17:45:24.333236 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Sep 12 17:45:24.334106 dbus-daemon[1887]: [system] Successfully activated service 'org.freedesktop.systemd1'
Sep 12 17:45:24.348743 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Sep 12 17:45:24.353567 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).
Sep 12 17:45:24.353609 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Sep 12 17:45:24.359892 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent.
Sep 12 17:45:24.365899 systemd[1]: Started update-engine.service - Update Engine.
Sep 12 17:45:24.372239 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Sep 12 17:45:24.377032 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Sep 12 17:45:24.384765 systemd[1]: Started getty@tty1.service - Getty on tty1.
Sep 12 17:45:24.390640 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Sep 12 17:45:24.396308 systemd[1]: Reached target getty.target - Login Prompts.
Sep 12 17:45:24.407095 coreos-metadata[1886]: Sep 12 17:45:24.407 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Sep 12 17:45:24.409567 coreos-metadata[1886]: Sep 12 17:45:24.409 INFO Fetch successful
Sep 12 17:45:24.409736 coreos-metadata[1886]: Sep 12 17:45:24.409 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1
Sep 12 17:45:24.413865 coreos-metadata[1886]: Sep 12 17:45:24.413 INFO Fetch successful
Sep 12 17:45:24.414184 coreos-metadata[1886]: Sep 12 17:45:24.414 INFO Fetching http://168.63.129.16/machine/d33b4c21-2a4b-4a9d-8430-46164890be0d/37e1cad4%2D7376%2D41f7%2D975a%2D49f346d5a6a9.%5Fci%2D4426.1.0%2Da%2D6c6fa50560?comp=config&type=sharedConfig&incarnation=1: Attempt #1
Sep 12 17:45:24.448254 coreos-metadata[1886]: Sep 12 17:45:24.448 INFO Fetch successful
Sep 12 17:45:24.448397 coreos-metadata[1886]: Sep 12 17:45:24.448 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1
Sep 12 17:45:24.456077 coreos-metadata[1886]: Sep 12 17:45:24.456 INFO Fetch successful
Sep 12 17:45:24.490112 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Sep 12 17:45:24.495548 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Sep 12 17:45:24.563902 locksmithd[2041]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Sep 12 17:45:25.144341 containerd[1920]: time="2025-09-12T17:45:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8
Sep 12 17:45:25.144922 containerd[1920]: time="2025-09-12T17:45:25.144883100Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5
Sep 12 17:45:25.153908 containerd[1920]: time="2025-09-12T17:45:25.153876900Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.5µs"
Sep 12 17:45:25.153908 containerd[1920]: time="2025-09-12T17:45:25.153901400Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
Sep 12 17:45:25.153977 containerd[1920]: time="2025-09-12T17:45:25.153917900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
Sep 12 17:45:25.154072 containerd[1920]: time="2025-09-12T17:45:25.154046900Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
Sep 12 17:45:25.154120 containerd[1920]: time="2025-09-12T17:45:25.154083300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
Sep 12 17:45:25.154151 containerd[1920]: time="2025-09-12T17:45:25.154126700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Sep 12 17:45:25.154212 containerd[1920]: time="2025-09-12T17:45:25.154190700Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Sep 12 17:45:25.154212 containerd[1920]: time="2025-09-12T17:45:25.154208300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155337 containerd[1920]: time="2025-09-12T17:45:25.155304800Z" 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
Sep 12 17:45:25.155337 containerd[1920]: time="2025-09-12T17:45:25.155333300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155415 containerd[1920]: time="2025-09-12T17:45:25.155348200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155415 containerd[1920]: time="2025-09-12T17:45:25.155356200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155469 containerd[1920]: time="2025-09-12T17:45:25.155457600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155713 containerd[1920]: time="2025-09-12T17:45:25.155689700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
Sep 12 17:45:25.155742 containerd[1920]: time="2025-09-12T17:45:25.155726600Z" 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
Sep 12 17:45:25.155742 containerd[1920]: time="2025-09-12T17:45:25.155736500Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
Sep 12 17:45:25.155787 containerd[1920]: time="2025-09-12T17:45:25.155764400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
Sep 12 17:45:25.156038 containerd[1920]: time="2025-09-12T17:45:25.156009300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
Sep 12 17:45:25.156193 containerd[1920]: time="2025-09-12T17:45:25.156100300Z" level=info msg="metadata content store policy set" policy=shared
Sep 12 17:45:25.172355 containerd[1920]: time="2025-09-12T17:45:25.172325600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
Sep 12 17:45:25.172416 containerd[1920]: time="2025-09-12T17:45:25.172366100Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
Sep 12 17:45:25.172416 containerd[1920]: time="2025-09-12T17:45:25.172380000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
Sep 12 17:45:25.172416 containerd[1920]: time="2025-09-12T17:45:25.172390900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
Sep 12 17:45:25.172416 containerd[1920]: time="2025-09-12T17:45:25.172403200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
Sep 12 17:45:25.172416 containerd[1920]: time="2025-09-12T17:45:25.172414600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172424800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172434900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172444600Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172453900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172461900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
Sep 12 17:45:25.172528 containerd[1920]: time="2025-09-12T17:45:25.172472900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
Sep 12 17:45:25.172651 containerd[1920]: time="2025-09-12T17:45:25.172573200Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
Sep 12 17:45:25.172651 containerd[1920]: time="2025-09-12T17:45:25.172598700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
Sep 12 17:45:25.172651 containerd[1920]: time="2025-09-12T17:45:25.172612300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
Sep 12 17:45:25.172651 containerd[1920]: time="2025-09-12T17:45:25.172634800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
Sep 12 17:45:25.172651 containerd[1920]: time="2025-09-12T17:45:25.172645300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172655200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172665000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172673700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172684300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172693800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
Sep 12 17:45:25.172764 containerd[1920]: time="2025-09-12T17:45:25.172714500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
Sep 12 17:45:25.172891 containerd[1920]: time="2025-09-12T17:45:25.172766800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
Sep 12 17:45:25.172891 containerd[1920]: time="2025-09-12T17:45:25.172780900Z" level=info msg="Start snapshots syncer"
Sep 12 17:45:25.172891 containerd[1920]: time="2025-09-12T17:45:25.172803300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
Sep 12 17:45:25.173071 containerd[1920]: time="2025-09-12T17:45:25.173016800Z" 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\"}"
Sep 12 17:45:25.173184 containerd[1920]: time="2025-09-12T17:45:25.173085200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
Sep 12 17:45:25.173184 containerd[1920]: time="2025-09-12T17:45:25.173143300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
Sep 12 17:45:25.173279 containerd[1920]: time="2025-09-12T17:45:25.173252800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173282800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173295000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173304500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173316500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173326600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
Sep 12 17:45:25.173354 containerd[1920]: time="2025-09-12T17:45:25.173336900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173355800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173366100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173375300Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173400000Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173413000Z" 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
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173421300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173430800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173437900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173446800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173455800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173472400Z" level=info msg="runtime interface created"
Sep 12 17:45:25.173478 containerd[1920]: time="2025-09-12T17:45:25.173477900Z" level=info msg="created NRI interface"
Sep 12 17:45:25.173703 containerd[1920]: time="2025-09-12T17:45:25.173485800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
Sep 12 17:45:25.173703 containerd[1920]: time="2025-09-12T17:45:25.173495800Z" level=info msg="Connect containerd service"
Sep 12 17:45:25.173703 containerd[1920]: time="2025-09-12T17:45:25.173547300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Sep 12 17:45:25.174245 containerd[1920]: time="2025-09-12T17:45:25.174217300Z" 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"
Sep 12 17:45:25.715245 setup-nvidia[2072]:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
Sep 12 17:45:25.715245 setup-nvidia[2072]:                                  Dload  Upload   Total   Spent    Left  Speed
Sep 12 17:45:25.903497 containerd[1920]: time="2025-09-12T17:45:25.903444700Z" level=info msg="Start subscribing containerd event"
Sep 12 17:45:25.903621 containerd[1920]: time="2025-09-12T17:45:25.903508000Z" level=info msg="Start recovering state"
Sep 12 17:45:25.903671 containerd[1920]: time="2025-09-12T17:45:25.903642800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Sep 12 17:45:25.903698 containerd[1920]: time="2025-09-12T17:45:25.903658600Z" level=info msg="Start event monitor"
Sep 12 17:45:25.903698 containerd[1920]: time="2025-09-12T17:45:25.903685900Z" level=info msg="Start cni network conf syncer for default"
Sep 12 17:45:25.903698 containerd[1920]: time="2025-09-12T17:45:25.903693600Z" level=info msg="Start streaming server"
Sep 12 17:45:25.903762 containerd[1920]: time="2025-09-12T17:45:25.903717700Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
Sep 12 17:45:25.903762 containerd[1920]: time="2025-09-12T17:45:25.903727000Z" level=info msg="runtime interface starting up..."
Sep 12 17:45:25.903762 containerd[1920]: time="2025-09-12T17:45:25.903733700Z" level=info msg="starting plugins..."
Sep 12 17:45:25.903762 containerd[1920]: time="2025-09-12T17:45:25.903728000Z" level=info msg=serving... address=/run/containerd/containerd.sock
Sep 12 17:45:25.903852 containerd[1920]: time="2025-09-12T17:45:25.903749200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
Sep 12 17:45:25.903972 containerd[1920]: time="2025-09-12T17:45:25.903941400Z" level=info msg="containerd successfully booted in 0.760026s"
Sep 12 17:45:25.904067 systemd[1]: Started containerd.service - containerd container runtime.
Sep 12 17:45:27.053545 waagent[2039]: 2025-09-12T17:45:27.053464Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4
Sep 12 17:45:27.058751 waagent[2039]: 2025-09-12T17:45:27.058655Z INFO Daemon Daemon OS: flatcar 4426.1.0
Sep 12 17:45:27.062312 waagent[2039]: 2025-09-12T17:45:27.062260Z INFO Daemon Daemon Python: 3.11.13
Sep 12 17:45:27.065828 waagent[2039]: 2025-09-12T17:45:27.065779Z INFO Daemon Daemon Run daemon
Sep 12 17:45:27.068997 waagent[2039]: 2025-09-12T17:45:27.068949Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4426.1.0'
Sep 12 17:45:27.076066 waagent[2039]: 2025-09-12T17:45:27.076008Z INFO Daemon Daemon Using waagent for provisioning
Sep 12 17:45:27.080252 waagent[2039]: 2025-09-12T17:45:27.080203Z INFO Daemon Daemon Activate resource disk
Sep 12 17:45:27.083944 waagent[2039]: 2025-09-12T17:45:27.083896Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
Sep 12 17:45:27.091674 waagent[2039]: 2025-09-12T17:45:27.091621Z INFO Daemon Daemon Found device: sdb
Sep 12 17:45:27.100735 waagent[2039]: 2025-09-12T17:45:27.100688Z INFO Daemon Daemon Examining partition table
Sep 12 17:45:27.153251 waagent[2039]: 2025-09-12T17:45:27.153194Z INFO Daemon Daemon GPT not detected, determining filesystem
Sep 12 17:45:27.202980 waagent[2039]: 2025-09-12T17:45:27.202908Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded
Sep 12 17:45:27.207732 waagent[2039]: 2025-09-12T17:45:27.207685Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83
Sep 12 17:45:27.521518 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#380 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001
Sep 12 17:45:27.536088 kernel:  sdb: sdb1
Sep 12 17:45:27.556081 waagent[2039]: 2025-09-12T17:45:27.555997Z INFO Daemon Daemon sfdisk --part-type  /dev/sdb 1 83 succeeded
Sep 12 17:45:27.568526 waagent[2039]: 2025-09-12T17:45:27.568472Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1]
Sep 12 17:45:32.362427 login[2043]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying
Sep 12 17:45:32.364160 login[2044]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Sep 12 17:45:32.370729 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Sep 12 17:45:32.371763 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Sep 12 17:45:32.377962 systemd-logind[1904]: New session 1 of user core.
Sep 12 17:45:32.459019 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Sep 12 17:45:32.461536 systemd[1]: Starting user@500.service - User Manager for UID 500...
Sep 12 17:45:32.557021 (systemd)[2134]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Sep 12 17:45:32.559514 systemd-logind[1904]: New session c1 of user core.
Sep 12 17:45:33.183428 waagent[2039]: 2025-09-12T17:45:33.183345Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource]
Sep 12 17:45:33.200881 systemd[2134]: Queued start job for default target default.target.
Sep 12 17:45:33.213124 systemd[2134]: Created slice app.slice - User Application Slice.
Sep 12 17:45:33.213169 systemd[2134]: Reached target paths.target - Paths.
Sep 12 17:45:33.213217 systemd[2134]: Reached target timers.target - Timers.
Sep 12 17:45:33.214373 systemd[2134]: Starting dbus.socket - D-Bus User Message Bus Socket...
Sep 12 17:45:33.224630 systemd[2134]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Sep 12 17:45:33.224688 systemd[2134]: Reached target sockets.target - Sockets.
Sep 12 17:45:33.224737 systemd[2134]: Reached target basic.target - Basic System.
Sep 12 17:45:33.224780 systemd[2134]: Reached target default.target - Main User Target.
Sep 12 17:45:33.224812 systemd[2134]: Startup finished in 654ms.
Sep 12 17:45:33.224876 systemd[1]: Started user@500.service - User Manager for UID 500.
Sep 12 17:45:33.226186 systemd[1]: Started session-1.scope - Session 1 of User core.
Sep 12 17:45:33.313104 kernel: EXT4-fs (sdb1): mounted filesystem bedf6091-a3fe-443c-a8d6-a376f3be6a16 r/w with ordered data mode. Quota mode: none.
Sep 12 17:45:33.313983 waagent[2039]: 2025-09-12T17:45:33.313907Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4
Sep 12 17:45:33.319762 waagent[2039]: 2025-09-12T17:45:33.319707Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Sep 12 17:45:33.324774 waagent[2039]: 2025-09-12T17:45:33.324725Z INFO Daemon Daemon Running default provisioning handler
Sep 12 17:45:33.335415 waagent[2039]: 2025-09-12T17:45:33.335362Z 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.
Sep 12 17:45:33.346576 waagent[2039]: 2025-09-12T17:45:33.346525Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Sep 12 17:45:33.348577 waagent[2039]: 2025-09-12T17:45:33.348469Z INFO Daemon Daemon cloud-init is enabled: False
Sep 12 17:45:33.350113 waagent[2039]: 2025-09-12T17:45:33.350077Z INFO Daemon Daemon Copying ovf-env.xml
Sep 12 17:45:33.362775 login[2043]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Sep 12 17:45:33.367091 systemd-logind[1904]: New session 2 of user core.
Sep 12 17:45:33.380266 systemd[1]: Started session-2.scope - Session 2 of User core.
Sep 12 17:45:33.450735 waagent[2039]: 2025-09-12T17:45:33.450598Z INFO Daemon Daemon Successfully mounted dvd
Sep 12 17:45:33.510899 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
Sep 12 17:45:33.511113 waagent[2039]: 2025-09-12T17:45:33.511049Z INFO Daemon Daemon Detect protocol endpoint
Sep 12 17:45:33.512855 waagent[2039]: 2025-09-12T17:45:33.512814Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Sep 12 17:45:33.513683 waagent[2039]: 2025-09-12T17:45:33.513650Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
Sep 12 17:45:33.515297 waagent[2039]: 2025-09-12T17:45:33.515247Z INFO Daemon Daemon Test for route to 168.63.129.16
Sep 12 17:45:33.517143 waagent[2039]: 2025-09-12T17:45:33.517081Z INFO Daemon Daemon Route to 168.63.129.16 exists
Sep 12 17:45:33.518001 waagent[2039]: 2025-09-12T17:45:33.517959Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
Sep 12 17:45:33.528450 waagent[2039]: 2025-09-12T17:45:33.528406Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
Sep 12 17:45:33.529176 waagent[2039]: 2025-09-12T17:45:33.529152Z INFO Daemon Daemon Wire protocol version:2012-11-30
Sep 12 17:45:33.530907 waagent[2039]: 2025-09-12T17:45:33.530863Z INFO Daemon Daemon Server preferred version:2015-04-05
Sep 12 17:45:33.680193 waagent[2039]: 2025-09-12T17:45:33.680102Z INFO Daemon Daemon Initializing goal state during protocol detection
Sep 12 17:45:33.681942 waagent[2039]: 2025-09-12T17:45:33.681891Z INFO Daemon Daemon Forcing an update of the goal state.
Sep 12 17:45:33.686693 waagent[2039]: 2025-09-12T17:45:33.686644Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1]
Sep 12 17:45:33.717103 waagent[2039]: 2025-09-12T17:45:33.717008Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175
Sep 12 17:45:33.719078 waagent[2039]: 2025-09-12T17:45:33.719007Z INFO Daemon
Sep 12 17:45:33.719419 waagent[2039]: 2025-09-12T17:45:33.719382Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: a0cf9889-e058-45a6-b646-de6fcf4132d4 eTag: 15333040423443537221 source: Fabric]
Sep 12 17:45:33.721259 waagent[2039]: 2025-09-12T17:45:33.721213Z INFO Daemon The vmSettings originated via Fabric; will ignore them.
Sep 12 17:45:33.722038 waagent[2039]: 2025-09-12T17:45:33.721999Z INFO Daemon
Sep 12 17:45:33.723432 waagent[2039]: 2025-09-12T17:45:33.723389Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1]
Sep 12 17:45:33.728480 waagent[2039]: 2025-09-12T17:45:33.728446Z INFO Daemon Daemon Downloading artifacts profile blob
Sep 12 17:45:33.854182 waagent[2039]: 2025-09-12T17:45:33.854111Z INFO Daemon Downloaded certificate {'thumbprint': '459D645D70DB2D9007366C20DD2D3E413D8F7415', 'hasPrivateKey': True}
Sep 12 17:45:33.856122 waagent[2039]: 2025-09-12T17:45:33.856055Z INFO Daemon Fetch goal state completed
Sep 12 17:45:33.896564 waagent[2039]: 2025-09-12T17:45:33.896516Z INFO Daemon Daemon Starting provisioning
Sep 12 17:45:33.898326 waagent[2039]: 2025-09-12T17:45:33.898268Z INFO Daemon Daemon Handle ovf-env.xml.
Sep 12 17:45:33.899925 waagent[2039]: 2025-09-12T17:45:33.899874Z INFO Daemon Daemon Set hostname [ci-4426.1.0-a-6c6fa50560]
Sep 12 17:45:33.965496 waagent[2039]: 2025-09-12T17:45:33.965428Z INFO Daemon Daemon Publish hostname [ci-4426.1.0-a-6c6fa50560]
Sep 12 17:45:33.967462 waagent[2039]: 2025-09-12T17:45:33.967389Z INFO Daemon Daemon Examine /proc/net/route for primary interface
Sep 12 17:45:33.968232 waagent[2039]: 2025-09-12T17:45:33.968195Z INFO Daemon Daemon Primary interface is [eth0]
Sep 12 17:45:33.986865 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Sep 12 17:45:33.986875 systemd-networkd[1718]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Sep 12 17:45:33.986900 systemd-networkd[1718]: eth0: DHCP lease lost
Sep 12 17:45:33.987423 waagent[2039]: 2025-09-12T17:45:33.987374Z INFO Daemon Daemon Create user account if not exists
Sep 12 17:45:33.989188 waagent[2039]: 2025-09-12T17:45:33.989146Z INFO Daemon Daemon User core already exists, skip useradd
Sep 12 17:45:33.989929 waagent[2039]: 2025-09-12T17:45:33.989898Z INFO Daemon Daemon Configure sudoer
Sep 12 17:45:34.003974 waagent[2039]: 2025-09-12T17:45:34.003928Z INFO Daemon Daemon Configure sshd
Sep 12 17:45:34.011186 waagent[2039]: 2025-09-12T17:45:34.011138Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive.
Sep 12 17:45:34.012879 waagent[2039]: 2025-09-12T17:45:34.012832Z INFO Daemon Daemon Deploy ssh public key.
Sep 12 17:45:34.065099 systemd-networkd[1718]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16
Sep 12 17:45:35.137037 waagent[2039]: 2025-09-12T17:45:35.136973Z INFO Daemon Daemon Provisioning complete
Sep 12 17:45:35.147157 waagent[2039]: 2025-09-12T17:45:35.147111Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
Sep 12 17:45:35.148833 waagent[2039]: 2025-09-12T17:45:35.148786Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
Sep 12 17:45:35.150234 waagent[2039]: 2025-09-12T17:45:35.150196Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent
Sep 12 17:45:35.311727 waagent[2197]: 2025-09-12T17:45:35.311638Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4)
Sep 12 17:45:35.312084 waagent[2197]: 2025-09-12T17:45:35.311783Z INFO ExtHandler ExtHandler OS: flatcar 4426.1.0
Sep 12 17:45:35.312084 waagent[2197]: 2025-09-12T17:45:35.311850Z INFO ExtHandler ExtHandler Python: 3.11.13
Sep 12 17:45:35.312084 waagent[2197]: 2025-09-12T17:45:35.311915Z INFO ExtHandler ExtHandler CPU Arch: x86_64
Sep 12 17:45:35.431220 waagent[2197]: 2025-09-12T17:45:35.431079Z INFO ExtHandler ExtHandler Distro: flatcar-4426.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0;
Sep 12 17:45:35.431370 waagent[2197]: 2025-09-12T17:45:35.431331Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Sep 12 17:45:35.431446 waagent[2197]: 2025-09-12T17:45:35.431413Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Sep 12 17:45:35.438068 waagent[2197]: 2025-09-12T17:45:35.438010Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
Sep 12 17:45:35.443021 waagent[2197]: 2025-09-12T17:45:35.442972Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175
Sep 12 17:45:35.443480 waagent[2197]: 2025-09-12T17:45:35.443432Z INFO ExtHandler
Sep 12 17:45:35.443550 waagent[2197]: 2025-09-12T17:45:35.443520Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: cbf0f497-9a49-4fe0-951f-b0332cba357d eTag: 15333040423443537221 source: Fabric]
Sep 12 17:45:35.443856 waagent[2197]: 2025-09-12T17:45:35.443818Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
Sep 12 17:45:35.444470 waagent[2197]: 2025-09-12T17:45:35.444433Z INFO ExtHandler
Sep 12 17:45:35.444596 waagent[2197]: 2025-09-12T17:45:35.444511Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
Sep 12 17:45:35.447949 waagent[2197]: 2025-09-12T17:45:35.447907Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
Sep 12 17:45:35.520491 waagent[2197]: 2025-09-12T17:45:35.520419Z INFO ExtHandler Downloaded certificate {'thumbprint': '459D645D70DB2D9007366C20DD2D3E413D8F7415', 'hasPrivateKey': True}
Sep 12 17:45:35.520995 waagent[2197]: 2025-09-12T17:45:35.520947Z INFO ExtHandler Fetch goal state completed
Sep 12 17:45:35.532409 waagent[2197]: 2025-09-12T17:45:35.532365Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.1 11 Feb 2025 (Library: OpenSSL 3.4.1 11 Feb 2025)
Sep 12 17:45:35.537176 waagent[2197]: 2025-09-12T17:45:35.537128Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2197
Sep 12 17:45:35.537306 waagent[2197]: 2025-09-12T17:45:35.537271Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ********
Sep 12 17:45:35.537723 waagent[2197]: 2025-09-12T17:45:35.537678Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ********
Sep 12 17:45:35.539328 waagent[2197]: 2025-09-12T17:45:35.539275Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4426.1.0', '', 'Flatcar Container Linux by Kinvolk']
Sep 12 17:45:35.539940 waagent[2197]: 2025-09-12T17:45:35.539704Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4426.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported
Sep 12 17:45:35.539940 waagent[2197]: 2025-09-12T17:45:35.539854Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False
Sep 12 17:45:35.540556 waagent[2197]: 2025-09-12T17:45:35.540510Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Sep 12 17:45:35.627936 waagent[2197]: 2025-09-12T17:45:35.627888Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Sep 12 17:45:35.628140 waagent[2197]: 2025-09-12T17:45:35.628102Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Sep 12 17:45:35.634598 waagent[2197]: 2025-09-12T17:45:35.634563Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Sep 12 17:45:35.644177 systemd[1]: Reload requested from client PID 2212 ('systemctl') (unit waagent.service)...
Sep 12 17:45:35.644193 systemd[1]: Reloading...
Sep 12 17:45:35.705093 zram_generator::config[2252]: No configuration found.
Sep 12 17:45:35.991388 systemd[1]: Reloading finished in 346 ms.
Sep 12 17:45:36.011584 waagent[2197]: 2025-09-12T17:45:36.011506Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service
Sep 12 17:45:36.011680 waagent[2197]: 2025-09-12T17:45:36.011653Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully
Sep 12 17:45:36.532163 waagent[2197]: 2025-09-12T17:45:36.532084Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up.
Sep 12 17:45:36.532514 waagent[2197]: 2025-09-12T17:45:36.532418Z 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]
Sep 12 17:45:36.533235 waagent[2197]: 2025-09-12T17:45:36.533188Z INFO ExtHandler ExtHandler Starting env monitor service.
Sep 12 17:45:36.533712 waagent[2197]: 2025-09-12T17:45:36.533673Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Sep 12 17:45:36.533794 waagent[2197]: 2025-09-12T17:45:36.533745Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Sep 12 17:45:36.534114 waagent[2197]: 2025-09-12T17:45:36.534071Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Sep 12 17:45:36.534230 waagent[2197]: 2025-09-12T17:45:36.534189Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Sep 12 17:45:36.534476 waagent[2197]: 2025-09-12T17:45:36.534422Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Sep 12 17:45:36.534602 waagent[2197]: 2025-09-12T17:45:36.534563Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Sep 12 17:45:36.534680 waagent[2197]: 2025-09-12T17:45:36.534644Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Sep 12 17:45:36.535050 waagent[2197]: 2025-09-12T17:45:36.535001Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Sep 12 17:45:36.535434 waagent[2197]: 2025-09-12T17:45:36.535370Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Sep 12 17:45:36.535589 waagent[2197]: 2025-09-12T17:45:36.535539Z INFO EnvHandler ExtHandler Configure routes
Sep 12 17:45:36.535636 waagent[2197]: 2025-09-12T17:45:36.535588Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Sep 12 17:45:36.535816 waagent[2197]: 2025-09-12T17:45:36.535781Z INFO EnvHandler ExtHandler Gateway:None
Sep 12 17:45:36.536265 waagent[2197]: 2025-09-12T17:45:36.536225Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Sep 12 17:45:36.536305 waagent[2197]: 2025-09-12T17:45:36.536267Z INFO EnvHandler ExtHandler Routes:None
Sep 12 17:45:36.537015 waagent[2197]: 2025-09-12T17:45:36.536968Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Sep 12 17:45:36.537015 waagent[2197]: Iface        Destination        Gateway         Flags        RefCnt        Use        Metric        Mask                MTU        Window        IRTT
Sep 12 17:45:36.537015 waagent[2197]: eth0        00000000        0108C80A        0003        0        0        1024        00000000        0        0        0
Sep 12 17:45:36.537015 waagent[2197]: eth0        0008C80A        00000000        0001        0        0        1024        00FFFFFF        0        0        0
Sep 12 17:45:36.537015 waagent[2197]: eth0        0108C80A        00000000        0005        0        0        1024        FFFFFFFF        0        0        0
Sep 12 17:45:36.537015 waagent[2197]: eth0        10813FA8        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
Sep 12 17:45:36.537015 waagent[2197]: eth0        FEA9FEA9        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
Sep 12 17:45:36.542310 waagent[2197]: 2025-09-12T17:45:36.542260Z INFO ExtHandler ExtHandler
Sep 12 17:45:36.542647 waagent[2197]: 2025-09-12T17:45:36.542608Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 3a57181c-26ef-4a43-b1de-d391e54fe39b correlation 21e12c46-ff44-4fc2-8c2b-1f8bf118fd31 created: 2025-09-12T17:43:53.625450Z]
Sep 12 17:45:36.543862 waagent[2197]: 2025-09-12T17:45:36.543816Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
Sep 12 17:45:36.545934 waagent[2197]: 2025-09-12T17:45:36.545894Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms]
Sep 12 17:45:36.573849 waagent[2197]: 2025-09-12T17:45:36.573794Z 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
Sep 12 17:45:36.573849 waagent[2197]: Try `iptables -h' or 'iptables --help' for more information.)
Sep 12 17:45:36.574246 waagent[2197]: 2025-09-12T17:45:36.574205Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 9F72334E-5271-4FD8-8240-DE70406AE69B;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;]
Sep 12 17:45:36.659543 waagent[2197]: 2025-09-12T17:45:36.659473Z INFO MonitorHandler ExtHandler Network interfaces:
Sep 12 17:45:36.659543 waagent[2197]: Executing ['ip', '-a', '-o', 'link']:
Sep 12 17:45:36.659543 waagent[2197]: 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
Sep 12 17:45:36.659543 waagent[2197]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\    link/ether 60:45:bd:df:0f:9f brd ff:ff:ff:ff:ff:ff
Sep 12 17:45:36.659543 waagent[2197]: Executing ['ip', '-4', '-a', '-o', 'address']:
Sep 12 17:45:36.659543 waagent[2197]: 1: lo    inet 127.0.0.1/8 scope host lo\       valid_lft forever preferred_lft forever
Sep 12 17:45:36.659543 waagent[2197]: 2: eth0    inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\       valid_lft forever preferred_lft forever
Sep 12 17:45:36.659543 waagent[2197]: Executing ['ip', '-6', '-a', '-o', 'address']:
Sep 12 17:45:36.659543 waagent[2197]: 1: lo    inet6 ::1/128 scope host noprefixroute \       valid_lft forever preferred_lft forever
Sep 12 17:45:36.659543 waagent[2197]: 2: eth0    inet6 fe80::6245:bdff:fedf:f9f/64 scope link proto kernel_ll \       valid_lft forever preferred_lft forever
Sep 12 17:45:36.709816 waagent[2197]: 2025-09-12T17:45:36.709758Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric:
Sep 12 17:45:36.709816 waagent[2197]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.709816 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.709816 waagent[2197]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.709816 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.709816 waagent[2197]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.709816 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.709816 waagent[2197]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
Sep 12 17:45:36.709816 waagent[2197]:        9      874 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
Sep 12 17:45:36.709816 waagent[2197]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
Sep 12 17:45:36.713195 waagent[2197]: 2025-09-12T17:45:36.713150Z INFO EnvHandler ExtHandler Current Firewall rules:
Sep 12 17:45:36.713195 waagent[2197]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.713195 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.713195 waagent[2197]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.713195 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.713195 waagent[2197]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Sep 12 17:45:36.713195 waagent[2197]:     pkts      bytes target     prot opt in     out     source               destination
Sep 12 17:45:36.713195 waagent[2197]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
Sep 12 17:45:36.713195 waagent[2197]:       14     1520 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
Sep 12 17:45:36.713195 waagent[2197]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
Sep 12 17:45:36.713442 waagent[2197]: 2025-09-12T17:45:36.713408Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300
Sep 12 17:45:36.713593 waagent[2197]: 2025-09-12T17:45:36.713563Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300
Sep 12 17:45:46.787668 setup-nvidia[2072]: \u000d  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0\u000d  0  472M    0  160k    0     0   174k      0  0:46:19 --:--:--  0:46:19  173k\u000d  1  472M    1 6335k    0     0  3374k      0  0:02:23  0:00:01  0:02:22 3373k\u000d  6  472M    6 29.6M    0     0  10.3M      0  0:00:45  0:00:02  0:00:43 10.3M\u000d 11  472M   11 54.4M    0     0  14.1M      0  0:00:33  0:00:03  0:00:30 14.1M\u000d 16  472M   16 78.8M    0     0  16.2M      0  0:00:28  0:00:04  0:00:24 16.2M\u000d 21  472M   21  103M    0     0  17.7M      0  0:00:26  0:00:05  0:00:21 21.0M\u000d 27  472M   27  127M    0     0  18.7M      0  0:00:25  0:00:06  0:00:19 24.6M\u000d 32  472M   32  152M    0     0  19.5M      0  0:00:24  0:00:07  0:00:17 24.8M\u000d 37  472M   37  177M    0     0  20.2M      0  0:00:23  0:00:08  0:00:15 24.9M\u000d 43  472M   43  204M    0     0  20.5M      0  0:00:22  0:00:09  0:00:13 24.6M\u000d 47  472M   47  226M    0     0  20.7M      0  0:00:22  0:00:10  0:00:12 24.2M\u000d 51  472M   51  243M    0     0  20.4M      0  0:00:23  0:00:11  0:00:12 22.6M\u000d 56  472M   56  267M    0     0  20.7M      0  0:00:22  0:00:12  0:00:10 22.7M\u000d 61  472M   61  291M    0     0  21.0M      0  0:00:22  0:00:13  0:00:09 22.4M\u000d 67  472M   67  317M    0     0  21.3M      0  0:00:22  0:00:14  0:00:08 22.8M\u000d 72  472M   72  341M    0     0  21.5M      0  0:00:21  0:00:15  0:00:06 23.2M\u000d 77  472M   77  367M    0     0  21.7M      0  0:00:21  0:00:16  0:00:05 25.0M\u000d 83  472M   83  392M    0     0  21.9M      0  0:00:21  0:00:17  0:00:04 25.0M\u000d 87  472M   87  414M    0     0  22.0M      0  0:00:21  0:00:18  0:00:03 24.8M\u000d 93  472M   93  440M    0     0  22.2M      0  0:00:21  0:00:19  0:00:02 24.8M\u000d 98  472M   98  464M    0     0  22.3M      0  0:00:21  0:00:20  0:00:01 24.9M\u000d100  472M  100  472M    0     0  22.4M      0  0:00:21  0:00:21 --:--:-- 24.8M
Sep 12 17:45:47.746098 chronyd[1884]: Selected source PHC0
Sep 12 17:45:56.797931 setup-nvidia[1897]: Downloading NVIDIA 535.247.01 Driver
Sep 12 17:45:56.802274 setup-nvidia[2355]:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
Sep 12 17:45:56.802511 setup-nvidia[2355]:                                  Dload  Upload   Total   Spent    Left  Speed
Sep 12 17:46:02.072467 setup-nvidia[2355]: \u000d  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0\u000d  0     0    0     0    0     0      0      0 --:--:--  0:00:01 --:--:--     0\u000d  0  326M    0  1238    0     0    726      0   5d 10h  0:00:01   5d 10h   726\u000d 25  326M   25 84.0M    0     0  31.0M      0  0:00:10  0:00:02  0:00:08 31.0M\u000d 52  326M   52  172M    0     0  46.4M      0  0:00:07  0:00:03  0:00:04 46.4M\u000d 82  326M   82  267M    0     0  56.9M      0  0:00:05  0:00:04  0:00:01 56.9M\u000d100  326M  100  326M    0     0  61.8M      0  0:00:05  0:00:05 --:--:-- 78.8M
Sep 12 17:46:02.212016 setup-nvidia[1897]: Extract the NVIDIA Driver Installer 535.247.01
Sep 12 17:46:02.243491 setup-nvidia[1897]: /opt/nvidia/workdir/nvidia-workdir /
Sep 12 17:46:02.245183 setup-nvidia[2358]: Creating directory NVIDIA-Linux-x86_64-535.247.01
Sep 12 17:46:02.510921 setup-nvidia[2358]: Verifying archive integrity... OK
Sep 12 17:46:03.321052 setup-nvidia[2358]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.247.01
Sep 12 17:46:04.740539 kernel: hv_balloon: Max. dynamic memory size: 114688 MB
Sep 12 17:46:05.213379 setup-nvidia[2384]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................
Sep 12 17:46:05.404946 setup-nvidia[1897]: /
Sep 12 17:46:05.404946 setup-nvidia[1897]: Spawn system-nspawn container to install the NVIDIA drivers
Sep 12 17:46:05.933344 sudo[2391]:     root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4426.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.247.01
Sep 12 17:46:05.935264 sudo[2391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Sep 12 17:46:05.941115 systemd[1]: Created slice user-0.slice - User Slice of UID 0.
Sep 12 17:46:05.943718 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0...
Sep 12 17:46:05.966523 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0.
Sep 12 17:46:05.970368 systemd[1]: Starting user@0.service - User Manager for UID 0...
Sep 12 17:46:05.976692 (systemd)[2394]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)
Sep 12 17:46:05.979080 systemd-logind[1904]: New session c3 of user root.
Sep 12 17:46:06.164103 systemd[2394]: Queued start job for default target default.target.
Sep 12 17:46:06.188196 systemd[2394]: Created slice app.slice - User Application Slice.
Sep 12 17:46:06.188224 systemd[2394]: Reached target paths.target - Paths.
Sep 12 17:46:06.188269 systemd[2394]: Reached target timers.target - Timers.
Sep 12 17:46:06.189459 systemd[2394]: Starting dbus.socket - D-Bus User Message Bus Socket...
Sep 12 17:46:06.200828 systemd[2394]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Sep 12 17:46:06.200946 systemd[2394]: Reached target sockets.target - Sockets.
Sep 12 17:46:06.201001 systemd[2394]: Reached target basic.target - Basic System.
Sep 12 17:46:06.201044 systemd[2394]: Reached target default.target - Main User Target.
Sep 12 17:46:06.201095 systemd[2394]: Startup finished in 209ms.
Sep 12 17:46:06.201123 systemd[1]: Started user@0.service - User Manager for UID 0.
Sep 12 17:46:06.205651 systemd[1]: Started session-c2.scope - Session c2 of User root.
Sep 12 17:46:06.372085 kernel: loop6: detected capacity change from 0 to 12644352
Sep 12 17:46:06.382079 kernel:  loop6: p9
Sep 12 17:46:06.492741 setup-nvidia[2403]: ░ Spawning container flatcardevelopercontainer-4426.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4426.1.0.bin.
Sep 12 17:46:07.049165 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup
Sep 12 17:46:07.049290 kernel: EXT4-fs (loop6p9): mounted filesystem 551f0cf8-e67b-4b0c-a816-719b45050a1c ro without journal. Quota mode: none.
Sep 12 17:46:09.340359 update_engine[1906]: I20250912 17:46:09.340287  1906 update_attempter.cc:509] Updating boot flags...
Sep 12 17:46:10.727893 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Sep 12 17:46:10.730681 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:60334.service - OpenSSH per-connection server daemon (10.200.16.10:60334).
Sep 12 17:46:11.775851 sshd[2633]: Accepted publickey for core from 10.200.16.10 port 60334 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:11.777139 sshd-session[2633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:11.781333 systemd-logind[1904]: New session 3 of user core.
Sep 12 17:46:11.798199 systemd[1]: Started session-3.scope - Session 3 of User core.
Sep 12 17:46:12.327293 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:60336.service - OpenSSH per-connection server daemon (10.200.16.10:60336).
Sep 12 17:46:12.952199 sshd[2639]: Accepted publickey for core from 10.200.16.10 port 60336 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:12.953511 sshd-session[2639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:12.959555 systemd-logind[1904]: New session 4 of user core.
Sep 12 17:46:12.976186 systemd[1]: Started session-4.scope - Session 4 of User core.
Sep 12 17:46:13.396611 sshd[2642]: Connection closed by 10.200.16.10 port 60336
Sep 12 17:46:13.396960 sshd-session[2639]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:13.401331 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:60336.service: Deactivated successfully.
Sep 12 17:46:13.402902 systemd[1]: session-4.scope: Deactivated successfully.
Sep 12 17:46:13.403572 systemd-logind[1904]: Session 4 logged out. Waiting for processes to exit.
Sep 12 17:46:13.404624 systemd-logind[1904]: Removed session 4.
Sep 12 17:46:13.507156 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:60350.service - OpenSSH per-connection server daemon (10.200.16.10:60350).
Sep 12 17:46:14.137546 sshd[2648]: Accepted publickey for core from 10.200.16.10 port 60350 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:14.138781 sshd-session[2648]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:14.142996 systemd-logind[1904]: New session 5 of user core.
Sep 12 17:46:14.153188 systemd[1]: Started session-5.scope - Session 5 of User core.
Sep 12 17:46:14.584855 sshd[2651]: Connection closed by 10.200.16.10 port 60350
Sep 12 17:46:14.584941 sshd-session[2648]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:14.588249 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:60350.service: Deactivated successfully.
Sep 12 17:46:14.589846 systemd[1]: session-5.scope: Deactivated successfully.
Sep 12 17:46:14.590522 systemd-logind[1904]: Session 5 logged out. Waiting for processes to exit.
Sep 12 17:46:14.591951 systemd-logind[1904]: Removed session 5.
Sep 12 17:46:24.699112 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:40524.service - OpenSSH per-connection server daemon (10.200.16.10:40524).
Sep 12 17:46:25.325547 sshd[3318]: Accepted publickey for core from 10.200.16.10 port 40524 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:25.326890 sshd-session[3318]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:25.331082 systemd-logind[1904]: New session 6 of user core.
Sep 12 17:46:25.346187 systemd[1]: Started session-6.scope - Session 6 of User core.
Sep 12 17:46:25.767824 sshd[3321]: Connection closed by 10.200.16.10 port 40524
Sep 12 17:46:25.768225 sshd-session[3318]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:25.772602 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:40524.service: Deactivated successfully.
Sep 12 17:46:25.774406 systemd[1]: session-6.scope: Deactivated successfully.
Sep 12 17:46:25.775166 systemd-logind[1904]: Session 6 logged out. Waiting for processes to exit.
Sep 12 17:46:25.776226 systemd-logind[1904]: Removed session 6.
Sep 12 17:46:25.883452 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:40530.service - OpenSSH per-connection server daemon (10.200.16.10:40530).
Sep 12 17:46:26.511052 sshd[3327]: Accepted publickey for core from 10.200.16.10 port 40530 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:26.512459 sshd-session[3327]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:26.517079 systemd-logind[1904]: New session 7 of user core.
Sep 12 17:46:26.533193 systemd[1]: Started session-7.scope - Session 7 of User core.
Sep 12 17:46:26.966425 sshd[3330]: Connection closed by 10.200.16.10 port 40530
Sep 12 17:46:26.966789 sshd-session[3327]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:26.970334 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:40530.service: Deactivated successfully.
Sep 12 17:46:26.971875 systemd[1]: session-7.scope: Deactivated successfully.
Sep 12 17:46:26.972542 systemd-logind[1904]: Session 7 logged out. Waiting for processes to exit.
Sep 12 17:46:26.973742 systemd-logind[1904]: Removed session 7.
Sep 12 17:46:37.082551 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:52372.service - OpenSSH per-connection server daemon (10.200.16.10:52372).
Sep 12 17:46:37.711074 sshd[3380]: Accepted publickey for core from 10.200.16.10 port 52372 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:37.712354 sshd-session[3380]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:37.719987 systemd-logind[1904]: New session 8 of user core.
Sep 12 17:46:37.737200 systemd[1]: Started session-8.scope - Session 8 of User core.
Sep 12 17:46:38.154199 sshd[3383]: Connection closed by 10.200.16.10 port 52372
Sep 12 17:46:38.154565 sshd-session[3380]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:38.158146 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:52372.service: Deactivated successfully.
Sep 12 17:46:38.159759 systemd[1]: session-8.scope: Deactivated successfully.
Sep 12 17:46:38.160432 systemd-logind[1904]: Session 8 logged out. Waiting for processes to exit.
Sep 12 17:46:38.161364 systemd-logind[1904]: Removed session 8.
Sep 12 17:46:38.268332 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:52376.service - OpenSSH per-connection server daemon (10.200.16.10:52376).
Sep 12 17:46:38.894303 sshd[3389]: Accepted publickey for core from 10.200.16.10 port 52376 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:38.895629 sshd-session[3389]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:38.900754 systemd-logind[1904]: New session 9 of user core.
Sep 12 17:46:38.912199 systemd[1]: Started session-9.scope - Session 9 of User core.
Sep 12 17:46:39.339802 sshd[3392]: Connection closed by 10.200.16.10 port 52376
Sep 12 17:46:39.340107 sshd-session[3389]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:39.343267 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:52376.service: Deactivated successfully.
Sep 12 17:46:39.344859 systemd[1]: session-9.scope: Deactivated successfully.
Sep 12 17:46:39.345978 systemd-logind[1904]: Session 9 logged out. Waiting for processes to exit.
Sep 12 17:46:39.346924 systemd-logind[1904]: Removed session 9.
Sep 12 17:46:49.454261 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:58194.service - OpenSSH per-connection server daemon (10.200.16.10:58194).
Sep 12 17:46:50.099719 sshd[5670]: Accepted publickey for core from 10.200.16.10 port 58194 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:50.101584 sshd-session[5670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:50.108526 systemd-logind[1904]: New session 10 of user core.
Sep 12 17:46:50.117274 systemd[1]: Started session-10.scope - Session 10 of User core.
Sep 12 17:46:50.548452 sshd[5878]: Connection closed by 10.200.16.10 port 58194
Sep 12 17:46:50.549054 sshd-session[5670]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:50.553628 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:58194.service: Deactivated successfully.
Sep 12 17:46:50.557173 systemd[1]: session-10.scope: Deactivated successfully.
Sep 12 17:46:50.558657 systemd-logind[1904]: Session 10 logged out. Waiting for processes to exit.
Sep 12 17:46:50.562566 systemd-logind[1904]: Removed session 10.
Sep 12 17:46:50.665702 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:47226.service - OpenSSH per-connection server daemon (10.200.16.10:47226).
Sep 12 17:46:51.330079 sshd[6038]: Accepted publickey for core from 10.200.16.10 port 47226 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:46:51.331724 sshd-session[6038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:46:51.337973 systemd-logind[1904]: New session 11 of user core.
Sep 12 17:46:51.344489 systemd[1]: Started session-11.scope - Session 11 of User core.
Sep 12 17:46:51.780984 sshd[6221]: Connection closed by 10.200.16.10 port 47226
Sep 12 17:46:51.780680 sshd-session[6038]: pam_unix(sshd:session): session closed for user core
Sep 12 17:46:51.785768 systemd-logind[1904]: Session 11 logged out. Waiting for processes to exit.
Sep 12 17:46:51.787519 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:47226.service: Deactivated successfully.
Sep 12 17:46:51.790188 systemd[1]: session-11.scope: Deactivated successfully.
Sep 12 17:46:51.792514 systemd-logind[1904]: Removed session 11.
Sep 12 17:47:01.898716 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:46514.service - OpenSSH per-connection server daemon (10.200.16.10:46514).
Sep 12 17:47:02.546862 sshd[9821]: Accepted publickey for core from 10.200.16.10 port 46514 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:02.548369 sshd-session[9821]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:02.554510 systemd-logind[1904]: New session 12 of user core.
Sep 12 17:47:02.559224 systemd[1]: Started session-12.scope - Session 12 of User core.
Sep 12 17:47:02.993084 sshd[10002]: Connection closed by 10.200.16.10 port 46514
Sep 12 17:47:02.992606 sshd-session[9821]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:02.996785 systemd-logind[1904]: Session 12 logged out. Waiting for processes to exit.
Sep 12 17:47:02.997409 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:46514.service: Deactivated successfully.
Sep 12 17:47:03.000174 systemd[1]: session-12.scope: Deactivated successfully.
Sep 12 17:47:03.002989 systemd-logind[1904]: Removed session 12.
Sep 12 17:47:03.108310 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:46516.service - OpenSSH per-connection server daemon (10.200.16.10:46516).
Sep 12 17:47:03.748392 sshd[10305]: Accepted publickey for core from 10.200.16.10 port 46516 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:03.750159 sshd-session[10305]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:03.757302 systemd-logind[1904]: New session 13 of user core.
Sep 12 17:47:03.762776 systemd[1]: Started session-13.scope - Session 13 of User core.
Sep 12 17:47:04.193949 sshd[10442]: Connection closed by 10.200.16.10 port 46516
Sep 12 17:47:04.194542 sshd-session[10305]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:04.198252 systemd-logind[1904]: Session 13 logged out. Waiting for processes to exit.
Sep 12 17:47:04.201526 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:46516.service: Deactivated successfully.
Sep 12 17:47:04.204355 systemd[1]: session-13.scope: Deactivated successfully.
Sep 12 17:47:04.206696 systemd-logind[1904]: Removed session 13.
Sep 12 17:47:14.309696 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:39262.service - OpenSSH per-connection server daemon (10.200.16.10:39262).
Sep 12 17:47:14.967084 sshd[11970]: Accepted publickey for core from 10.200.16.10 port 39262 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:14.968512 sshd-session[11970]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:14.976616 systemd-logind[1904]: New session 14 of user core.
Sep 12 17:47:14.981237 systemd[1]: Started session-14.scope - Session 14 of User core.
Sep 12 17:47:15.411420 sshd[11995]: Connection closed by 10.200.16.10 port 39262
Sep 12 17:47:15.411743 sshd-session[11970]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:15.418264 systemd-logind[1904]: Session 14 logged out. Waiting for processes to exit.
Sep 12 17:47:15.421426 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:39262.service: Deactivated successfully.
Sep 12 17:47:15.423005 systemd[1]: session-14.scope: Deactivated successfully.
Sep 12 17:47:15.425959 systemd-logind[1904]: Removed session 14.
Sep 12 17:47:15.543369 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:39278.service - OpenSSH per-connection server daemon (10.200.16.10:39278).
Sep 12 17:47:16.193010 sshd[12033]: Accepted publickey for core from 10.200.16.10 port 39278 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:16.194523 sshd-session[12033]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:16.203018 systemd-logind[1904]: New session 15 of user core.
Sep 12 17:47:16.211247 systemd[1]: Started session-15.scope - Session 15 of User core.
Sep 12 17:47:16.641263 sshd[12054]: Connection closed by 10.200.16.10 port 39278
Sep 12 17:47:16.642029 sshd-session[12033]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:16.646669 systemd-logind[1904]: Session 15 logged out. Waiting for processes to exit.
Sep 12 17:47:16.648068 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:39278.service: Deactivated successfully.
Sep 12 17:47:16.652692 systemd[1]: session-15.scope: Deactivated successfully.
Sep 12 17:47:16.654830 systemd-logind[1904]: Removed session 15.
Sep 12 17:47:26.758317 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:36506.service - OpenSSH per-connection server daemon (10.200.16.10:36506).
Sep 12 17:47:27.414150 sshd[12378]: Accepted publickey for core from 10.200.16.10 port 36506 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:27.416016 sshd-session[12378]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:27.425842 systemd-logind[1904]: New session 16 of user core.
Sep 12 17:47:27.432727 systemd[1]: Started session-16.scope - Session 16 of User core.
Sep 12 17:47:27.873309 sshd[12393]: Connection closed by 10.200.16.10 port 36506
Sep 12 17:47:27.873633 sshd-session[12378]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:27.876884 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:36506.service: Deactivated successfully.
Sep 12 17:47:27.878744 systemd[1]: session-16.scope: Deactivated successfully.
Sep 12 17:47:27.879911 systemd-logind[1904]: Session 16 logged out. Waiting for processes to exit.
Sep 12 17:47:27.883425 systemd-logind[1904]: Removed session 16.
Sep 12 17:47:27.990823 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:36508.service - OpenSSH per-connection server daemon (10.200.16.10:36508).
Sep 12 17:47:28.646123 sshd[12429]: Accepted publickey for core from 10.200.16.10 port 36508 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:28.647511 sshd-session[12429]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:28.652660 systemd-logind[1904]: New session 17 of user core.
Sep 12 17:47:28.665276 systemd[1]: Started session-17.scope - Session 17 of User core.
Sep 12 17:47:29.093980 sshd[12462]: Connection closed by 10.200.16.10 port 36508
Sep 12 17:47:29.092949 sshd-session[12429]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:29.098070 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:36508.service: Deactivated successfully.
Sep 12 17:47:29.098650 systemd-logind[1904]: Session 17 logged out. Waiting for processes to exit.
Sep 12 17:47:29.100192 systemd[1]: session-17.scope: Deactivated successfully.
Sep 12 17:47:29.104951 systemd-logind[1904]: Removed session 17.
Sep 12 17:47:39.213395 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:36462.service - OpenSSH per-connection server daemon (10.200.16.10:36462).
Sep 12 17:47:39.869386 sshd[12774]: Accepted publickey for core from 10.200.16.10 port 36462 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:39.871181 sshd-session[12774]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:39.878265 systemd-logind[1904]: New session 18 of user core.
Sep 12 17:47:39.884477 systemd[1]: Started session-18.scope - Session 18 of User core.
Sep 12 17:47:40.316085 sshd[12818]: Connection closed by 10.200.16.10 port 36462
Sep 12 17:47:40.316647 sshd-session[12774]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:40.321968 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:36462.service: Deactivated successfully.
Sep 12 17:47:40.323813 systemd-logind[1904]: Session 18 logged out. Waiting for processes to exit.
Sep 12 17:47:40.325271 systemd[1]: session-18.scope: Deactivated successfully.
Sep 12 17:47:40.327014 systemd-logind[1904]: Removed session 18.
Sep 12 17:47:40.437611 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:43748.service - OpenSSH per-connection server daemon (10.200.16.10:43748).
Sep 12 17:47:41.066394 sshd[12853]: Accepted publickey for core from 10.200.16.10 port 43748 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:41.067664 sshd-session[12853]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:41.071796 systemd-logind[1904]: New session 19 of user core.
Sep 12 17:47:41.088245 systemd[1]: Started session-19.scope - Session 19 of User core.
Sep 12 17:47:41.509841 sshd[12861]: Connection closed by 10.200.16.10 port 43748
Sep 12 17:47:41.510281 sshd-session[12853]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:41.513828 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:43748.service: Deactivated successfully.
Sep 12 17:47:41.515433 systemd[1]: session-19.scope: Deactivated successfully.
Sep 12 17:47:41.516171 systemd-logind[1904]: Session 19 logged out. Waiting for processes to exit.
Sep 12 17:47:41.517354 systemd-logind[1904]: Removed session 19.
Sep 12 17:47:49.760809 setup-nvidia[2403]: \u000d
Sep 12 17:47:49.760809 setup-nvidia[2403]: ERROR: Unable to load the kernel module 'nvidia.ko'.  This happens most
Sep 12 17:47:49.760809 setup-nvidia[2403]:        frequently when this kernel module was built against the wrong or
Sep 12 17:47:49.760809 setup-nvidia[2403]:        improperly configured kernel sources, with a version of gcc that differs
Sep 12 17:47:49.760809 setup-nvidia[2403]:        from the one used to build the target kernel, or if another driver, such
Sep 12 17:47:49.760809 setup-nvidia[2403]:        as nouveau, is present and prevents the NVIDIA kernel module from
Sep 12 17:47:49.760809 setup-nvidia[2403]:        obtaining ownership of the NVIDIA device(s), or no NVIDIA device
Sep 12 17:47:49.760809 setup-nvidia[2403]:        installed in this system is supported by this NVIDIA Linux graphics
Sep 12 17:47:49.760809 setup-nvidia[2403]:        driver release.
Sep 12 17:47:49.760809 setup-nvidia[2403]:        \u000d
Sep 12 17:47:49.760809 setup-nvidia[2403]:        Please see the log entries 'Kernel module load error' and 'Kernel
Sep 12 17:47:49.760809 setup-nvidia[2403]:        messages' at the end of the file
Sep 12 17:47:49.760809 setup-nvidia[2403]:        '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more
Sep 12 17:47:49.760809 setup-nvidia[2403]:        information.
Sep 12 17:47:49.760809 setup-nvidia[2403]: \u000d
Sep 12 17:47:49.775348 setup-nvidia[2403]: dmesg: read kernel buffer failed: Operation not permitted
Sep 12 17:47:49.794420 setup-nvidia[2403]: \u000d
Sep 12 17:47:49.794420 setup-nvidia[2403]: ERROR: Installation has failed.  Please see the file
Sep 12 17:47:49.794420 setup-nvidia[2403]:        '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for
Sep 12 17:47:49.794420 setup-nvidia[2403]:        details.  You may find suggestions on fixing installation problems in
Sep 12 17:47:49.794420 setup-nvidia[2403]:        the README available on the Linux driver download page at
Sep 12 17:47:49.794420 setup-nvidia[2403]:        www.nvidia.com.
Sep 12 17:47:49.794420 setup-nvidia[2403]: \u000d
Sep 12 17:47:49.795008 setup-nvidia[2403]: Last 50 lines of nvidia-installer.log:
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039045rm+0x53: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039042rm+0x90: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039035rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039091rm+0x93: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039087rm+0x36: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039086rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039088rm+0x77: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039032rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030603rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030602rm+0x27: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030606rm+0x37: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030605rm+0x24: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030604rm+0xca: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030607rm+0x193: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030609rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:    /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039399rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build
Sep 12 17:47:49.795817 setup-nvidia[2403]:      MODPOST /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/Module.symvers
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.mod.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/.module-common.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.mod.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.mod.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.mod.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      CC [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.mod.o
Sep 12 17:47:49.795817 setup-nvidia[2403]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:      LD [M]  /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko due to unavailability of vmlinux
Sep 12 17:47:49.795817 setup-nvidia[2403]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko due to unavailability of vmlinux
Sep 12 17:47:49.795817 setup-nvidia[2403]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko due to unavailability of vmlinux
Sep 12 17:47:49.795817 setup-nvidia[2403]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko due to unavailability of vmlinux
Sep 12 17:47:49.795817 setup-nvidia[2403]:      BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko
Sep 12 17:47:49.795817 setup-nvidia[2403]:    Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko due to unavailability of vmlinux
Sep 12 17:47:49.795817 setup-nvidia[2403]:    make[2]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/build'
Sep 12 17:47:49.795817 setup-nvidia[2403]:    make[1]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/source'
Sep 12 17:47:49.795817 setup-nvidia[2403]: -> done.
Sep 12 17:47:49.795817 setup-nvidia[2403]: -> Kernel module compilation complete.
Sep 12 17:47:49.795817 setup-nvidia[2403]: -> Unable to determine if Secure Boot is enabled: No such file or directory
Sep 12 17:47:49.795817 setup-nvidia[2403]: 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.
Sep 12 17:47:49.796978 setup-nvidia[2403]: \u000d
Sep 12 17:47:49.796978 setup-nvidia[2403]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more information.
Sep 12 17:47:49.796978 setup-nvidia[2403]: -> Kernel module load error: Operation not permitted
Sep 12 17:47:49.796978 setup-nvidia[2403]: -> Kernel messages:
Sep 12 17:47:49.796978 setup-nvidia[2403]: ERROR: Installation has failed.  Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/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.
Sep 12 17:47:51.632122 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:55172.service - OpenSSH per-connection server daemon (10.200.16.10:55172).
Sep 12 17:47:52.260678 sshd[13047]: Accepted publickey for core from 10.200.16.10 port 55172 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:52.261944 sshd-session[13047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:52.266083 systemd-logind[1904]: New session 20 of user core.
Sep 12 17:47:52.286182 systemd[1]: Started session-20.scope - Session 20 of User core.
Sep 12 17:47:52.704268 sshd[13050]: Connection closed by 10.200.16.10 port 55172
Sep 12 17:47:52.704636 sshd-session[13047]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:52.708097 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:55172.service: Deactivated successfully.
Sep 12 17:47:52.709831 systemd[1]: session-20.scope: Deactivated successfully.
Sep 12 17:47:52.710610 systemd-logind[1904]: Session 20 logged out. Waiting for processes to exit.
Sep 12 17:47:52.711536 systemd-logind[1904]: Removed session 20.
Sep 12 17:47:52.819505 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:55176.service - OpenSSH per-connection server daemon (10.200.16.10:55176).
Sep 12 17:47:53.473993 sshd[13056]: Accepted publickey for core from 10.200.16.10 port 55176 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:47:53.475360 sshd-session[13056]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:47:53.479760 systemd-logind[1904]: New session 21 of user core.
Sep 12 17:47:53.489179 systemd[1]: Started session-21.scope - Session 21 of User core.
Sep 12 17:47:53.917019 sshd[13059]: Connection closed by 10.200.16.10 port 55176
Sep 12 17:47:53.917428 sshd-session[13056]: pam_unix(sshd:session): session closed for user core
Sep 12 17:47:53.921242 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:55176.service: Deactivated successfully.
Sep 12 17:47:53.922784 systemd[1]: session-21.scope: Deactivated successfully.
Sep 12 17:47:53.923596 systemd-logind[1904]: Session 21 logged out. Waiting for processes to exit.
Sep 12 17:47:53.924504 systemd-logind[1904]: Removed session 21.
Sep 12 17:48:04.039323 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:48124.service - OpenSSH per-connection server daemon (10.200.16.10:48124).
Sep 12 17:48:04.665683 sshd[13065]: Accepted publickey for core from 10.200.16.10 port 48124 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:04.666957 sshd-session[13065]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:04.671263 systemd-logind[1904]: New session 22 of user core.
Sep 12 17:48:04.680452 systemd[1]: Started session-22.scope - Session 22 of User core.
Sep 12 17:48:05.109454 sshd[13068]: Connection closed by 10.200.16.10 port 48124
Sep 12 17:48:05.109738 sshd-session[13065]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:05.113351 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:48124.service: Deactivated successfully.
Sep 12 17:48:05.114956 systemd[1]: session-22.scope: Deactivated successfully.
Sep 12 17:48:05.115636 systemd-logind[1904]: Session 22 logged out. Waiting for processes to exit.
Sep 12 17:48:05.116520 systemd-logind[1904]: Removed session 22.
Sep 12 17:48:05.224122 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:48138.service - OpenSSH per-connection server daemon (10.200.16.10:48138).
Sep 12 17:48:05.852705 sshd[13074]: Accepted publickey for core from 10.200.16.10 port 48138 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:05.853958 sshd-session[13074]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:05.858749 systemd-logind[1904]: New session 23 of user core.
Sep 12 17:48:05.870181 systemd[1]: Started session-23.scope - Session 23 of User core.
Sep 12 17:48:06.295557 sshd[13077]: Connection closed by 10.200.16.10 port 48138
Sep 12 17:48:06.295915 sshd-session[13074]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:06.299388 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:48138.service: Deactivated successfully.
Sep 12 17:48:06.300943 systemd[1]: session-23.scope: Deactivated successfully.
Sep 12 17:48:06.301611 systemd-logind[1904]: Session 23 logged out. Waiting for processes to exit.
Sep 12 17:48:06.302682 systemd-logind[1904]: Removed session 23.
Sep 12 17:48:14.614097 kernel: EXT4-fs (loop6p9): unmounting filesystem 551f0cf8-e67b-4b0c-a816-719b45050a1c.
Sep 12 17:48:14.614516 setup-nvidia[2403]: Container flatcardevelopercontainer-4426.1.0.bin exited successfully.
Sep 12 17:48:14.616956 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4426.1.0.bin.mount: Deactivated successfully.
Sep 12 17:48:14.667893 sudo[2391]: pam_unix(sudo:session): session closed for user root
Sep 12 17:48:14.669900 systemd[1]: session-c2.scope: Deactivated successfully.
Sep 12 17:48:14.670216 systemd[1]: session-c2.scope: Consumed 5min 16.909s CPU time, 888.4M memory peak.
Sep 12 17:48:15.106688 setup-nvidia[1897]: /opt/nvidia /
Sep 12 17:48:15.107741 setup-nvidia[1897]: /
Sep 12 17:48:15.117080 kernel: IPMI message handler: version 39.2
Sep 12 17:48:15.121087 kernel: ipmi device interface
Sep 12 17:48:15.133023 setup-nvidia[1897]: /opt/nvidia/current/usr/lib/modules/6.12.47-flatcar/video /
Sep 12 17:48:15.184625 kernel: nvidia: loading out-of-tree module taints kernel.
Sep 12 17:48:15.184768 kernel: nvidia: module license 'NVIDIA' taints kernel.
Sep 12 17:48:15.184791 kernel: Disabling lock debugging due to kernel taint
Sep 12 17:48:15.192229 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel
Sep 12 17:48:15.192271 kernel: nvidia: module license taints kernel.
Sep 12 17:48:15.584094 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243
Sep 12 17:48:15.990659 kernel: 
Sep 12 17:48:15.990800 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002)
Sep 12 17:48:16.198083 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module  535.247.01  Wed Mar 26 11:50:32 UTC 2025
Sep 12 17:48:16.220090 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms  535.247.01  Wed Mar 26 06:12:31 UTC 2025
Sep 12 17:48:16.240899 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint.
Sep 12 17:48:16.291080 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241.
Sep 12 17:48:16.291146 setup-nvidia[1897]: /
Sep 12 17:48:16.308369 setup-nvidia[13117]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Sep 12 17:48:16.418689 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:36768.service - OpenSSH per-connection server daemon (10.200.16.10:36768).
Sep 12 17:48:17.064965 sshd[13139]: Accepted publickey for core from 10.200.16.10 port 36768 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:17.066244 sshd-session[13139]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:17.071777 systemd-logind[1904]: New session 24 of user core.
Sep 12 17:48:17.080244 systemd[1]: Started session-24.scope - Session 24 of User core.
Sep 12 17:48:17.511554 sshd[13142]: Connection closed by 10.200.16.10 port 36768
Sep 12 17:48:17.511916 sshd-session[13139]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:17.516206 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:36768.service: Deactivated successfully.
Sep 12 17:48:17.517863 systemd[1]: session-24.scope: Deactivated successfully.
Sep 12 17:48:17.519018 systemd-logind[1904]: Session 24 logged out. Waiting for processes to exit.
Sep 12 17:48:17.520254 systemd-logind[1904]: Removed session 24.
Sep 12 17:48:17.626213 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:36770.service - OpenSSH per-connection server daemon (10.200.16.10:36770).
Sep 12 17:48:18.120603 setup-nvidia[13134]: Fri Sep 12 17:48:18 2025
Sep 12 17:48:18.120603 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+
Sep 12 17:48:18.120603 setup-nvidia[13134]: | NVIDIA-SMI 535.247.01             Driver Version: 535.247.01   CUDA Version: 12.2     |
Sep 12 17:48:18.120603 setup-nvidia[13134]: |-----------------------------------------+----------------------+----------------------+
Sep 12 17:48:18.120603 setup-nvidia[13134]: | GPU  Name                 Persistence-M | Bus-Id        Disp.A | Volatile Uncorr. ECC |
Sep 12 17:48:18.120603 setup-nvidia[13134]: | Fan  Temp   Perf          Pwr:Usage/Cap |         Memory-Usage | GPU-Util  Compute M. |
Sep 12 17:48:18.120603 setup-nvidia[13134]: |                                         |                      |               MIG M. |
Sep 12 17:48:18.120603 setup-nvidia[13134]: |=========================================+======================+======================|
Sep 12 17:48:18.124055 setup-nvidia[13134]: |   0  Tesla V100-PCIE-16GB           Off | 00000001:00:00.0 Off |                  Off |
Sep 12 17:48:18.124055 setup-nvidia[13134]: | N/A   26C    P0              37W / 250W |      0MiB / 16384MiB |      0%      Default |
Sep 12 17:48:18.124055 setup-nvidia[13134]: |                                         |                      |                  N/A |
Sep 12 17:48:18.124055 setup-nvidia[13134]: +-----------------------------------------+----------------------+----------------------+
Sep 12 17:48:18.124169 setup-nvidia[13134]:                                                                                          
Sep 12 17:48:18.124169 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+
Sep 12 17:48:18.124169 setup-nvidia[13134]: | Processes:                                                                            |
Sep 12 17:48:18.124169 setup-nvidia[13134]: |  GPU   GI   CI        PID   Type   Process name                            GPU Memory |
Sep 12 17:48:18.124169 setup-nvidia[13134]: |        ID   ID                                                             Usage      |
Sep 12 17:48:18.124169 setup-nvidia[13134]: |=======================================================================================|
Sep 12 17:48:18.124567 setup-nvidia[13134]: |  No running processes found                                                           |
Sep 12 17:48:18.124567 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+
Sep 12 17:48:18.255014 sshd[13148]: Accepted publickey for core from 10.200.16.10 port 36770 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:18.256277 sshd-session[13148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:18.260434 systemd-logind[1904]: New session 25 of user core.
Sep 12 17:48:18.274186 systemd[1]: Started session-25.scope - Session 25 of User core.
Sep 12 17:48:18.508944 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Sep 12 17:48:18.511013 systemd[1]: Reached target multi-user.target - Multi-User System.
Sep 12 17:48:18.511488 systemd[1]: Startup finished in 1.233s (firmware) + 30.077s (loader) + 3.886s (kernel) + 14.915s (initrd) + 3min 12.371s (userspace) = 4min 2.485s.
Sep 12 17:48:18.835885 sshd[13151]: Connection closed by 10.200.16.10 port 36770
Sep 12 17:48:18.836163 sshd-session[13148]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:18.839600 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:36770.service: Deactivated successfully.
Sep 12 17:48:18.841247 systemd[1]: session-25.scope: Deactivated successfully.
Sep 12 17:48:18.841959 systemd-logind[1904]: Session 25 logged out. Waiting for processes to exit.
Sep 12 17:48:18.843031 systemd-logind[1904]: Removed session 25.
Sep 12 17:48:24.881958 systemd[1]: Stopping user@0.service - User Manager for UID 0...
Sep 12 17:48:24.882748 systemd[2394]: Activating special unit exit.target...
Sep 12 17:48:24.882833 systemd[2394]: Stopped target default.target - Main User Target.
Sep 12 17:48:24.882873 systemd[2394]: Stopped target basic.target - Basic System.
Sep 12 17:48:24.882900 systemd[2394]: Stopped target paths.target - Paths.
Sep 12 17:48:24.882925 systemd[2394]: Stopped target sockets.target - Sockets.
Sep 12 17:48:24.882950 systemd[2394]: Stopped target timers.target - Timers.
Sep 12 17:48:24.883307 systemd[2394]: Closed dbus.socket - D-Bus User Message Bus Socket.
Sep 12 17:48:24.883514 systemd[2394]: Removed slice app.slice - User Application Slice.
Sep 12 17:48:24.883545 systemd[2394]: Reached target shutdown.target - Shutdown.
Sep 12 17:48:24.883678 systemd[2394]: Finished systemd-exit.service - Exit the Session.
Sep 12 17:48:24.883713 systemd[2394]: Reached target exit.target - Exit the Session.
Sep 12 17:48:24.899780 (sd-pam)[2395]: pam_unix(systemd-user:session): session closed for user root
Sep 12 17:48:24.901021 systemd[1]: user@0.service: Deactivated successfully.
Sep 12 17:48:24.901442 systemd[1]: Stopped user@0.service - User Manager for UID 0.
Sep 12 17:48:24.903666 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0...
Sep 12 17:48:24.931523 systemd[1]: run-user-0.mount: Deactivated successfully.
Sep 12 17:48:24.932417 systemd[1]: user-runtime-dir@0.service: Deactivated successfully.
Sep 12 17:48:24.932608 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0.
Sep 12 17:48:24.934154 systemd[1]: Removed slice user-0.slice - User Slice of UID 0.
Sep 12 17:48:24.934247 systemd[1]: user-0.slice: Consumed 5min 17.211s CPU time, 894.6M memory peak.
Sep 12 17:48:24.934637 systemd-logind[1904]: Removed session c3.
Sep 12 17:48:28.954274 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:50520.service - OpenSSH per-connection server daemon (10.200.16.10:50520).
Sep 12 17:48:29.585986 sshd[13163]: Accepted publickey for core from 10.200.16.10 port 50520 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:29.587280 sshd-session[13163]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:29.591461 systemd-logind[1904]: New session 26 of user core.
Sep 12 17:48:29.614177 systemd[1]: Started session-26.scope - Session 26 of User core.
Sep 12 17:48:30.029207 sshd[13166]: Connection closed by 10.200.16.10 port 50520
Sep 12 17:48:30.033457 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:50520.service: Deactivated successfully.
Sep 12 17:48:30.029593 sshd-session[13163]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:30.035399 systemd[1]: session-26.scope: Deactivated successfully.
Sep 12 17:48:30.036153 systemd-logind[1904]: Session 26 logged out. Waiting for processes to exit.
Sep 12 17:48:30.037083 systemd-logind[1904]: Removed session 26.
Sep 12 17:48:30.139463 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:55768.service - OpenSSH per-connection server daemon (10.200.16.10:55768).
Sep 12 17:48:30.783541 sshd[13172]: Accepted publickey for core from 10.200.16.10 port 55768 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:30.784803 sshd-session[13172]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:30.788969 systemd-logind[1904]: New session 27 of user core.
Sep 12 17:48:30.804188 systemd[1]: Started session-27.scope - Session 27 of User core.
Sep 12 17:48:31.223075 sshd[13175]: Connection closed by 10.200.16.10 port 55768
Sep 12 17:48:31.223409 sshd-session[13172]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:31.226938 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:55768.service: Deactivated successfully.
Sep 12 17:48:31.228553 systemd[1]: session-27.scope: Deactivated successfully.
Sep 12 17:48:31.229332 systemd-logind[1904]: Session 27 logged out. Waiting for processes to exit.
Sep 12 17:48:31.230307 systemd-logind[1904]: Removed session 27.
Sep 12 17:48:31.338354 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:55780.service - OpenSSH per-connection server daemon (10.200.16.10:55780).
Sep 12 17:48:31.964776 sshd[13181]: Accepted publickey for core from 10.200.16.10 port 55780 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:31.966100 sshd-session[13181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:31.970321 systemd-logind[1904]: New session 28 of user core.
Sep 12 17:48:31.980233 systemd[1]: Started session-28.scope - Session 28 of User core.
Sep 12 17:48:32.407436 sshd[13184]: Connection closed by 10.200.16.10 port 55780
Sep 12 17:48:32.407811 sshd-session[13181]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:32.411394 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:55780.service: Deactivated successfully.
Sep 12 17:48:32.413004 systemd[1]: session-28.scope: Deactivated successfully.
Sep 12 17:48:32.413692 systemd-logind[1904]: Session 28 logged out. Waiting for processes to exit.
Sep 12 17:48:32.414597 systemd-logind[1904]: Removed session 28.
Sep 12 17:48:32.522342 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:55796.service - OpenSSH per-connection server daemon (10.200.16.10:55796).
Sep 12 17:48:33.148597 sshd[13190]: Accepted publickey for core from 10.200.16.10 port 55796 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:33.149883 sshd-session[13190]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:33.154137 systemd-logind[1904]: New session 29 of user core.
Sep 12 17:48:33.170205 systemd[1]: Started session-29.scope - Session 29 of User core.
Sep 12 17:48:33.597148 sshd[13195]: Connection closed by 10.200.16.10 port 55796
Sep 12 17:48:33.597455 sshd-session[13190]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:33.601154 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:55796.service: Deactivated successfully.
Sep 12 17:48:33.602841 systemd[1]: session-29.scope: Deactivated successfully.
Sep 12 17:48:33.603524 systemd-logind[1904]: Session 29 logged out. Waiting for processes to exit.
Sep 12 17:48:33.604480 systemd-logind[1904]: Removed session 29.
Sep 12 17:48:33.720404 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:55800.service - OpenSSH per-connection server daemon (10.200.16.10:55800).
Sep 12 17:48:34.366089 sshd[13202]: Accepted publickey for core from 10.200.16.10 port 55800 ssh2: RSA SHA256:N57c16sACXEH7T/hPh2WdUB1bqX8lYhBLQ19XJC/uD8
Sep 12 17:48:34.367375 sshd-session[13202]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Sep 12 17:48:34.372017 systemd-logind[1904]: New session 30 of user core.
Sep 12 17:48:34.381191 systemd[1]: Started session-30.scope - Session 30 of User core.
Sep 12 17:48:36.343133 sshd[13205]: Connection closed by 10.200.16.10 port 55800
Sep 12 17:48:36.343541 sshd-session[13202]: pam_unix(sshd:session): session closed for user core
Sep 12 17:48:36.347209 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:55800.service: Deactivated successfully.
Sep 12 17:48:36.348800 systemd[1]: session-30.scope: Deactivated successfully.
Sep 12 17:48:36.349010 systemd[1]: session-30.scope: Consumed 1.460s CPU time, 14.2M memory peak.
Sep 12 17:48:36.349509 systemd-logind[1904]: Session 30 logged out. Waiting for processes to exit.
Sep 12 17:48:36.350427 systemd-logind[1904]: Removed session 30.