Sep 12 22:56:33.212045 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 20:38:35 -00 2025 Sep 12 22:56:33.212073 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=8e60d6befc710e967d67e9a1d87ced7416895090c99a765b3a00e66a62f49e40 Sep 12 22:56:33.212083 kernel: BIOS-provided physical RAM map: Sep 12 22:56:33.212090 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 12 22:56:33.212097 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 12 22:56:33.212103 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 12 22:56:33.212111 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 12 22:56:33.212121 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 12 22:56:33.212128 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 12 22:56:33.212135 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 12 22:56:33.212142 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 12 22:56:33.212148 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 12 22:56:33.212155 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 12 22:56:33.212162 kernel: NX (Execute Disable) protection: active Sep 12 22:56:33.212173 kernel: APIC: Static calls initialized Sep 12 22:56:33.212180 kernel: efi: EFI v2.7 by Microsoft Sep 12 22:56:33.212188 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 12 22:56:33.212196 kernel: random: crng init done Sep 12 22:56:33.212203 kernel: secureboot: Secure boot disabled Sep 12 22:56:33.212211 kernel: SMBIOS 3.1.0 present. Sep 12 22:56:33.212218 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 12 22:56:33.212226 kernel: DMI: Memory slots populated: 3/3 Sep 12 22:56:33.212233 kernel: Hypervisor detected: Microsoft Hyper-V Sep 12 22:56:33.212241 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 12 22:56:33.212251 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 12 22:56:33.212258 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 12 22:56:33.212266 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 22:56:33.212273 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 22:56:33.212282 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 12 22:56:33.212290 kernel: tsc: Detected 2593.993 MHz processor Sep 12 22:56:33.212298 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 22:56:33.212306 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 22:56:33.212313 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 12 22:56:33.212323 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 12 22:56:33.212331 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 22:56:33.212339 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 12 22:56:33.212346 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 12 22:56:33.212354 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 12 22:56:33.212361 kernel: Using GB pages for direct mapping Sep 12 22:56:33.212369 kernel: ACPI: Early table checksum verification disabled Sep 12 22:56:33.212381 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 12 22:56:33.212391 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212399 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212407 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 12 22:56:33.212415 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 12 22:56:33.212423 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212432 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212442 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212450 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212459 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212467 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212475 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 22:56:33.212483 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 12 22:56:33.212491 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 12 22:56:33.212499 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 12 22:56:33.212507 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 12 22:56:33.212518 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 12 22:56:33.212526 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 12 22:56:33.212534 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 12 22:56:33.212542 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 12 22:56:33.212550 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 12 22:56:33.212558 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 12 22:56:33.212566 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 12 22:56:33.212574 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 12 22:56:33.212584 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 12 22:56:33.212592 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 12 22:56:33.212600 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 12 22:56:33.212608 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 12 22:56:33.212616 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 12 22:56:33.212625 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 12 22:56:33.212633 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 12 22:56:33.212641 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 12 22:56:33.212649 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 12 22:56:33.212660 kernel: Zone ranges: Sep 12 22:56:33.212668 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 22:56:33.212676 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 12 22:56:33.212684 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 12 22:56:33.212692 kernel: Device empty Sep 12 22:56:33.212712 kernel: Movable zone start for each node Sep 12 22:56:33.212720 kernel: Early memory node ranges Sep 12 22:56:33.212728 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 12 22:56:33.212736 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 12 22:56:33.212747 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 12 22:56:33.212756 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 12 22:56:33.212764 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 12 22:56:33.212772 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 12 22:56:33.212780 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 22:56:33.212788 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 12 22:56:33.212796 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 12 22:56:33.212804 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 12 22:56:33.212812 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 12 22:56:33.212823 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 12 22:56:33.212831 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 22:56:33.212839 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 22:56:33.212847 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 12 22:56:33.212855 kernel: CPU topo: Max. logical packages: 1 Sep 12 22:56:33.212863 kernel: CPU topo: Max. logical dies: 1 Sep 12 22:56:33.212871 kernel: CPU topo: Max. dies per package: 1 Sep 12 22:56:33.212879 kernel: CPU topo: Max. threads per core: 1 Sep 12 22:56:33.212887 kernel: CPU topo: Num. cores per package: 6 Sep 12 22:56:33.212898 kernel: CPU topo: Num. threads per package: 6 Sep 12 22:56:33.212906 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 12 22:56:33.212914 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 12 22:56:33.212922 kernel: Booting paravirtualized kernel on Hyper-V Sep 12 22:56:33.212930 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 22:56:33.212939 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 12 22:56:33.212947 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 12 22:56:33.212955 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 12 22:56:33.212963 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 12 22:56:33.212973 kernel: Hyper-V: PV spinlocks enabled Sep 12 22:56:33.212981 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 22:56:33.212990 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=8e60d6befc710e967d67e9a1d87ced7416895090c99a765b3a00e66a62f49e40 Sep 12 22:56:33.212999 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 22:56:33.213007 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 12 22:56:33.213015 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 12 22:56:33.213024 kernel: Fallback order for Node 0: 0 Sep 12 22:56:33.213032 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 12 22:56:33.213042 kernel: Policy zone: Normal Sep 12 22:56:33.213058 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 22:56:33.213066 kernel: software IO TLB: area num 8. Sep 12 22:56:33.213077 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 12 22:56:33.213086 kernel: Kernel/User page tables isolation: enabled Sep 12 22:56:33.213094 kernel: ftrace: allocating 40125 entries in 157 pages Sep 12 22:56:33.213103 kernel: ftrace: allocated 157 pages with 5 groups Sep 12 22:56:33.213111 kernel: Dynamic Preempt: voluntary Sep 12 22:56:33.213120 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 22:56:33.213129 kernel: rcu: RCU event tracing is enabled. Sep 12 22:56:33.213138 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 12 22:56:33.213147 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 22:56:33.213158 kernel: Rude variant of Tasks RCU enabled. Sep 12 22:56:33.213167 kernel: Tracing variant of Tasks RCU enabled. Sep 12 22:56:33.213175 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 22:56:33.213184 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 12 22:56:33.213192 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 22:56:33.213203 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 22:56:33.213212 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 22:56:33.213221 kernel: Using NULL legacy PIC Sep 12 22:56:33.213229 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 12 22:56:33.213238 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 22:56:33.213246 kernel: Console: colour dummy device 80x25 Sep 12 22:56:33.213255 kernel: printk: legacy console [tty1] enabled Sep 12 22:56:33.213263 kernel: printk: legacy console [ttyS0] enabled Sep 12 22:56:33.213272 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 12 22:56:33.213283 kernel: ACPI: Core revision 20240827 Sep 12 22:56:33.213291 kernel: Failed to register legacy timer interrupt Sep 12 22:56:33.213300 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 22:56:33.213309 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 12 22:56:33.213317 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 12 22:56:33.213326 kernel: Hyper-V: Using IPI hypercalls Sep 12 22:56:33.213334 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 12 22:56:33.213343 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 12 22:56:33.213351 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 12 22:56:33.213365 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 12 22:56:33.213374 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 12 22:56:33.213383 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 12 22:56:33.213391 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 12 22:56:33.213399 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 12 22:56:33.213408 kernel: APIC: read() replaced with hv_apic_read() Sep 12 22:56:33.213417 kernel: APIC: write() replaced with hv_apic_write() Sep 12 22:56:33.213425 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 12 22:56:33.213434 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 12 22:56:33.213445 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Sep 12 22:56:33.213454 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 12 22:56:33.213462 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 12 22:56:33.213471 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 22:56:33.213479 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 22:56:33.213487 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 22:56:33.213496 kernel: Speculative Store Bypass: Vulnerable Sep 12 22:56:33.213504 kernel: MDS: Mitigation: Clear CPU buffers Sep 12 22:56:33.213512 kernel: TAA: Mitigation: Clear CPU buffers Sep 12 22:56:33.213521 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 12 22:56:33.213531 kernel: active return thunk: its_return_thunk Sep 12 22:56:33.213540 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 12 22:56:33.213548 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 22:56:33.213556 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 22:56:33.213565 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 22:56:33.213573 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 22:56:33.213582 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 12 22:56:33.213590 kernel: Freeing SMP alternatives memory: 32K Sep 12 22:56:33.213599 kernel: pid_max: default: 32768 minimum: 301 Sep 12 22:56:33.213607 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 12 22:56:33.213615 kernel: landlock: Up and running. Sep 12 22:56:33.213626 kernel: SELinux: Initializing. Sep 12 22:56:33.213635 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 22:56:33.213643 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 22:56:33.213652 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 12 22:56:33.213660 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 12 22:56:33.213669 kernel: signal: max sigframe size: 1776 Sep 12 22:56:33.213677 kernel: rcu: Hierarchical SRCU implementation. Sep 12 22:56:33.213686 kernel: rcu: Max phase no-delay instances is 400. Sep 12 22:56:33.213703 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 12 22:56:33.213712 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 12 22:56:33.213723 kernel: smp: Bringing up secondary CPUs ... Sep 12 22:56:33.213732 kernel: smpboot: x86: Booting SMP configuration: Sep 12 22:56:33.213741 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 12 22:56:33.213749 kernel: smp: Brought up 1 node, 6 CPUs Sep 12 22:56:33.213758 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Sep 12 22:56:33.213767 kernel: Memory: 115339092K/117439364K available (14336K kernel code, 2432K rwdata, 9992K rodata, 54084K init, 2880K bss, 2090004K reserved, 0K cma-reserved) Sep 12 22:56:33.213780 kernel: devtmpfs: initialized Sep 12 22:56:33.213792 kernel: x86/mm: Memory block size: 1024MB Sep 12 22:56:33.213801 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 12 22:56:33.213813 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 22:56:33.213821 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 12 22:56:33.213830 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 22:56:33.213839 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 22:56:33.213848 kernel: audit: initializing netlink subsys (disabled) Sep 12 22:56:33.213856 kernel: audit: type=2000 audit(1757717789.028:1): state=initialized audit_enabled=0 res=1 Sep 12 22:56:33.213865 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 22:56:33.213873 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 22:56:33.213882 kernel: cpuidle: using governor menu Sep 12 22:56:33.213893 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 22:56:33.213901 kernel: dca service started, version 1.12.1 Sep 12 22:56:33.213910 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 12 22:56:33.213918 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 22:56:33.213927 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 22:56:33.213936 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 22:56:33.213944 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 22:56:33.213953 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 22:56:33.213961 kernel: ACPI: Added _OSI(Module Device) Sep 12 22:56:33.213972 kernel: ACPI: Added _OSI(Processor Device) Sep 12 22:56:33.213981 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 22:56:33.213989 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 22:56:33.213998 kernel: ACPI: Interpreter enabled Sep 12 22:56:33.214006 kernel: ACPI: PM: (supports S0 S5) Sep 12 22:56:33.214015 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 22:56:33.214024 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 22:56:33.214032 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 12 22:56:33.214041 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 12 22:56:33.214052 kernel: iommu: Default domain type: Translated Sep 12 22:56:33.214060 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 22:56:33.214069 kernel: efivars: Registered efivars operations Sep 12 22:56:33.214077 kernel: PCI: Using ACPI for IRQ routing Sep 12 22:56:33.214086 kernel: PCI: System does not support PCI Sep 12 22:56:33.214095 kernel: vgaarb: loaded Sep 12 22:56:33.214104 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 12 22:56:33.214112 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 22:56:33.214121 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 22:56:33.214132 kernel: pnp: PnP ACPI init Sep 12 22:56:33.214140 kernel: pnp: PnP ACPI: found 3 devices Sep 12 22:56:33.214149 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 22:56:33.214158 kernel: NET: Registered PF_INET protocol family Sep 12 22:56:33.214167 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 22:56:33.214175 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 12 22:56:33.214184 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 22:56:33.214193 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 22:56:33.214201 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 22:56:33.214212 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 12 22:56:33.214221 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 22:56:33.214230 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 22:56:33.214238 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 22:56:33.214247 kernel: NET: Registered PF_XDP protocol family Sep 12 22:56:33.214255 kernel: PCI: CLS 0 bytes, default 64 Sep 12 22:56:33.214264 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 12 22:56:33.214272 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 12 22:56:33.214281 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 12 22:56:33.214292 kernel: Initialise system trusted keyrings Sep 12 22:56:33.214300 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 12 22:56:33.214309 kernel: Key type asymmetric registered Sep 12 22:56:33.214317 kernel: Asymmetric key parser 'x509' registered Sep 12 22:56:33.214326 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 12 22:56:33.214334 kernel: io scheduler mq-deadline registered Sep 12 22:56:33.214343 kernel: io scheduler kyber registered Sep 12 22:56:33.214351 kernel: io scheduler bfq registered Sep 12 22:56:33.214360 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 22:56:33.214371 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 22:56:33.214380 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 22:56:33.214388 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 12 22:56:33.214397 kernel: i8042: PNP: No PS/2 controller found. Sep 12 22:56:33.214552 kernel: rtc_cmos 00:02: registered as rtc0 Sep 12 22:56:33.214654 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T22:56:32 UTC (1757717792) Sep 12 22:56:33.214765 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 12 22:56:33.214783 kernel: intel_pstate: CPU model not supported Sep 12 22:56:33.214792 kernel: efifb: probing for efifb Sep 12 22:56:33.214800 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 12 22:56:33.214809 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 12 22:56:33.214818 kernel: efifb: scrolling: redraw Sep 12 22:56:33.214826 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 22:56:33.214835 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 22:56:33.214843 kernel: fb0: EFI VGA frame buffer device Sep 12 22:56:33.214852 kernel: pstore: Using crash dump compression: deflate Sep 12 22:56:33.214863 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 22:56:33.214872 kernel: NET: Registered PF_INET6 protocol family Sep 12 22:56:33.214880 kernel: Segment Routing with IPv6 Sep 12 22:56:33.214889 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 22:56:33.214897 kernel: NET: Registered PF_PACKET protocol family Sep 12 22:56:33.214906 kernel: Key type dns_resolver registered Sep 12 22:56:33.214915 kernel: IPI shorthand broadcast: enabled Sep 12 22:56:33.214923 kernel: sched_clock: Marking stable (3630005100, 106174700)->(4063201500, -327021700) Sep 12 22:56:33.214932 kernel: registered taskstats version 1 Sep 12 22:56:33.214941 kernel: Loading compiled-in X.509 certificates Sep 12 22:56:33.214952 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: c3297a5801573420030c321362a802da1fd49c4e' Sep 12 22:56:33.214960 kernel: Demotion targets for Node 0: null Sep 12 22:56:33.214969 kernel: Key type .fscrypt registered Sep 12 22:56:33.214977 kernel: Key type fscrypt-provisioning registered Sep 12 22:56:33.214986 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 22:56:33.214994 kernel: ima: Allocated hash algorithm: sha1 Sep 12 22:56:33.215003 kernel: ima: No architecture policies found Sep 12 22:56:33.215012 kernel: clk: Disabling unused clocks Sep 12 22:56:33.215022 kernel: Warning: unable to open an initial console. Sep 12 22:56:33.215031 kernel: Freeing unused kernel image (initmem) memory: 54084K Sep 12 22:56:33.215040 kernel: Write protecting the kernel read-only data: 24576k Sep 12 22:56:33.215048 kernel: Freeing unused kernel image (rodata/data gap) memory: 248K Sep 12 22:56:33.215057 kernel: Run /init as init process Sep 12 22:56:33.215065 kernel: with arguments: Sep 12 22:56:33.215074 kernel: /init Sep 12 22:56:33.215082 kernel: with environment: Sep 12 22:56:33.215091 kernel: HOME=/ Sep 12 22:56:33.215101 kernel: TERM=linux Sep 12 22:56:33.215110 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 22:56:33.215120 systemd[1]: Successfully made /usr/ read-only. Sep 12 22:56:33.215131 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 22:56:33.215141 systemd[1]: Detected virtualization microsoft. Sep 12 22:56:33.215150 systemd[1]: Detected architecture x86-64. Sep 12 22:56:33.215158 systemd[1]: Running in initrd. Sep 12 22:56:33.215167 systemd[1]: No hostname configured, using default hostname. Sep 12 22:56:33.215179 systemd[1]: Hostname set to . Sep 12 22:56:33.215188 systemd[1]: Initializing machine ID from random generator. Sep 12 22:56:33.215197 systemd[1]: Queued start job for default target initrd.target. Sep 12 22:56:33.215206 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 22:56:33.215215 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 22:56:33.215225 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 22:56:33.215234 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 22:56:33.215244 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 22:56:33.215256 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 22:56:33.215266 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 22:56:33.215276 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 22:56:33.215285 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 22:56:33.215294 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 22:56:33.215303 systemd[1]: Reached target paths.target - Path Units. Sep 12 22:56:33.215313 systemd[1]: Reached target slices.target - Slice Units. Sep 12 22:56:33.215324 systemd[1]: Reached target swap.target - Swaps. Sep 12 22:56:33.215333 systemd[1]: Reached target timers.target - Timer Units. Sep 12 22:56:33.215342 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 22:56:33.215352 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 22:56:33.215361 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 22:56:33.215370 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 22:56:33.215379 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 22:56:33.215388 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 22:56:33.215400 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 22:56:33.215409 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 22:56:33.215418 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 22:56:33.215427 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 22:56:33.215437 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 22:56:33.215446 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 22:56:33.215455 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 22:56:33.215464 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 22:56:33.215474 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 22:56:33.215531 systemd-journald[232]: Collecting audit messages is disabled. Sep 12 22:56:33.215591 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 22:56:33.215603 systemd-journald[232]: Journal started Sep 12 22:56:33.215628 systemd-journald[232]: Runtime Journal (/run/log/journal/2d5879fa828a4577b418a7470665ddaf) is 8M, max 2.2G, 2.1G free. Sep 12 22:56:33.218071 systemd-modules-load[235]: Inserted module 'overlay' Sep 12 22:56:33.223468 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 22:56:33.223862 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 22:56:33.224281 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 22:56:33.224956 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 22:56:33.227348 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 22:56:33.228218 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 22:56:33.276787 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 12 22:56:33.278666 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 22:56:33.284932 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:33.298266 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 22:56:33.320195 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 22:56:33.301739 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 22:56:33.322408 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 22:56:33.333717 kernel: Bridge firewalling registered Sep 12 22:56:33.341083 systemd-modules-load[235]: Inserted module 'br_netfilter' Sep 12 22:56:33.346851 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 22:56:33.348499 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 22:56:33.360606 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 22:56:33.370768 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 22:56:33.376164 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 22:56:33.381180 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 22:56:33.399114 dracut-cmdline[271]: 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=8e60d6befc710e967d67e9a1d87ced7416895090c99a765b3a00e66a62f49e40 Sep 12 22:56:33.399481 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 22:56:33.455747 systemd-resolved[274]: Positive Trust Anchors: Sep 12 22:56:33.455763 systemd-resolved[274]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 22:56:33.455794 systemd-resolved[274]: 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 22:56:33.458353 systemd-resolved[274]: Defaulting to hostname 'linux'. Sep 12 22:56:33.459390 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 22:56:33.461447 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 22:56:33.532724 kernel: SCSI subsystem initialized Sep 12 22:56:33.543726 kernel: Loading iSCSI transport class v2.0-870. Sep 12 22:56:33.555718 kernel: iscsi: registered transport (tcp) Sep 12 22:56:33.578626 kernel: iscsi: registered transport (qla4xxx) Sep 12 22:56:33.578653 kernel: QLogic iSCSI HBA Driver Sep 12 22:56:33.598428 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 22:56:33.621166 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 22:56:33.623627 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 22:56:33.683973 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 22:56:33.693640 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 22:56:33.755714 kernel: raid6: avx2x4 gen() 30710 MB/s Sep 12 22:56:33.774708 kernel: raid6: avx2x2 gen() 30804 MB/s Sep 12 22:56:33.795663 kernel: raid6: avx2x1 gen() 26809 MB/s Sep 12 22:56:33.795692 kernel: raid6: using algorithm avx2x2 gen() 30804 MB/s Sep 12 22:56:33.818820 kernel: raid6: .... xor() 18199 MB/s, rmw enabled Sep 12 22:56:33.818848 kernel: raid6: using avx2x2 recovery algorithm Sep 12 22:56:33.840715 kernel: xor: automatically using best checksumming function avx Sep 12 22:56:34.012730 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 22:56:34.019338 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 22:56:34.028862 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 22:56:34.071803 systemd-udevd[486]: Using default interface naming scheme 'v255'. Sep 12 22:56:34.077819 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 22:56:34.079365 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 22:56:34.117217 dracut-pre-trigger[492]: rd.md=0: removing MD RAID activation Sep 12 22:56:34.142478 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 22:56:34.145432 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 22:56:34.226214 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 22:56:34.239684 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 22:56:34.263352 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 22:56:34.271717 kernel: AES CTR mode by8 optimization enabled Sep 12 22:56:34.296561 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 22:56:34.298076 kernel: hv_vmbus: Vmbus version:4.0 Sep 12 22:56:34.296754 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:34.309331 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 22:56:34.320708 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 12 22:56:34.320739 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 22:56:34.320752 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 22:56:34.321958 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 22:56:34.343102 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 22:56:34.343125 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 22:56:34.347731 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 22:56:34.394864 kernel: hv_vmbus: registering driver hv_netvsc Sep 12 22:56:34.394896 kernel: hv_vmbus: registering driver hv_pci Sep 12 22:56:34.394909 kernel: hv_vmbus: registering driver hid_hyperv Sep 12 22:56:34.394928 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 12 22:56:34.395121 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 12 22:56:34.395245 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 12 22:56:34.395399 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 12 22:56:34.395521 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 12 22:56:34.395535 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 12 22:56:34.395654 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 12 22:56:34.395804 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 12 22:56:34.396736 kernel: PTP clock support registered Sep 12 22:56:34.398784 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 12 22:56:34.405985 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 22:56:34.406026 kernel: hv_vmbus: registering driver hv_storvsc Sep 12 22:56:34.408370 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 22:56:34.416716 kernel: scsi host1: storvsc_host_t Sep 12 22:56:34.416762 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 12 22:56:34.416784 kernel: scsi host0: storvsc_host_t Sep 12 22:56:34.422046 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:34.458103 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 22:56:34.458317 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 22:56:34.458469 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 12 22:56:34.458608 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 12 22:56:34.458756 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 12 22:56:34.458908 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 12 22:56:34.459036 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 12 22:56:34.459846 kernel: hv_utils: Registering HyperV Utility Driver Sep 12 22:56:34.464531 kernel: hv_vmbus: registering driver hv_utils Sep 12 22:56:34.469127 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 12 22:56:34.469299 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 12 22:56:34.469441 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Sep 12 22:56:34.475192 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Sep 12 22:56:34.480348 kernel: sd 0:0:0:1: [sda] Write Protect is off Sep 12 22:56:34.480500 kernel: sd 0:0:0:0: [sdb] Write Protect is off Sep 12 22:56:34.480629 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Sep 12 22:56:34.487335 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 22:56:34.487485 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Sep 12 22:56:34.487711 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#263 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:34.503075 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 22:56:34.503259 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#270 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:34.504711 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#453 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:34.515387 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#460 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:34.521433 kernel: sda: sda1 Sep 12 22:56:34.521465 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Sep 12 22:56:34.539709 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Sep 12 22:56:34.539737 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Sep 12 22:56:34.757392 kernel: hv_utils: Heartbeat IC version 3.0 Sep 12 22:56:34.757449 kernel: hv_utils: Shutdown IC version 3.2 Sep 12 22:56:34.759869 kernel: hv_utils: TimeSync IC version 4.0 Sep 12 22:56:34.760718 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 12 22:56:34.938143 systemd-resolved[274]: Clock change detected. Flushing caches. Sep 12 22:56:34.946659 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 22:56:34.946733 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 12 22:56:35.405338 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 12 22:56:35.433691 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 12 22:56:35.435187 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 12 22:56:35.457635 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 22:56:35.470550 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 12 22:56:35.481351 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 22:56:35.510857 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#489 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:35.534858 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Sep 12 22:56:36.548250 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#513 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:56:36.565717 disk-uuid[722]: The operation has completed successfully. Sep 12 22:56:36.569384 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Sep 12 22:56:37.142164 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 22:56:37.142284 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 22:56:37.144655 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 22:56:37.171933 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 22:56:37.174212 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 22:56:37.180883 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 22:56:37.194112 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 22:56:37.198617 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 22:56:37.208505 sh[887]: Success Sep 12 22:56:37.235655 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 22:56:37.255637 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 22:56:37.255677 kernel: device-mapper: uevent: version 1.0.3 Sep 12 22:56:37.260358 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 12 22:56:37.271855 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 12 22:56:37.569484 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 22:56:37.578531 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 22:56:37.597568 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 22:56:37.615864 kernel: BTRFS: device fsid 5d2ab445-1154-4e47-9d7e-ff4b81d84474 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (912) Sep 12 22:56:37.624818 kernel: BTRFS info (device dm-0): first mount of filesystem 5d2ab445-1154-4e47-9d7e-ff4b81d84474 Sep 12 22:56:37.624862 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 22:56:37.906370 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 22:56:37.906449 kernel: BTRFS info (device dm-0): enabling free space tree Sep 12 22:56:37.933285 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 22:56:37.935298 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 12 22:56:37.948551 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 22:56:37.949371 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 22:56:37.963928 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 22:56:38.002859 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (943) Sep 12 22:56:38.011857 kernel: BTRFS info (device sdb6): first mount of filesystem fd5cdc72-255e-4ed2-8d25-c5e581a08827 Sep 12 22:56:38.011901 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Sep 12 22:56:38.071958 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 22:56:38.075148 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 22:56:38.097854 kernel: BTRFS info (device sdb6): turning on async discard Sep 12 22:56:38.097895 kernel: BTRFS info (device sdb6): enabling free space tree Sep 12 22:56:38.108890 kernel: BTRFS info (device sdb6): last unmount of filesystem fd5cdc72-255e-4ed2-8d25-c5e581a08827 Sep 12 22:56:38.110628 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 22:56:38.113140 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 22:56:38.153113 systemd-networkd[1075]: lo: Link UP Sep 12 22:56:38.153123 systemd-networkd[1075]: lo: Gained carrier Sep 12 22:56:38.154329 systemd-networkd[1075]: Enumeration completed Sep 12 22:56:38.154424 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 22:56:38.154963 systemd-networkd[1075]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:56:38.154968 systemd-networkd[1075]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 22:56:38.155805 systemd-networkd[1075]: eth0: Link UP Sep 12 22:56:38.155984 systemd-networkd[1075]: eth0: Gained carrier Sep 12 22:56:38.155995 systemd-networkd[1075]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:56:38.159974 systemd[1]: Reached target network.target - Network. Sep 12 22:56:38.219894 systemd-networkd[1075]: eth0: DHCPv4 address 10.200.8.14/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 22:56:39.468601 ignition[1082]: Ignition 2.22.0 Sep 12 22:56:39.468658 ignition[1082]: Stage: fetch-offline Sep 12 22:56:39.469272 ignition[1082]: no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:39.469290 ignition[1082]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:39.469411 ignition[1082]: parsed url from cmdline: "" Sep 12 22:56:39.469417 ignition[1082]: no config URL provided Sep 12 22:56:39.469427 ignition[1082]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 22:56:39.469441 ignition[1082]: no config at "/usr/lib/ignition/user.ign" Sep 12 22:56:39.469447 ignition[1082]: failed to fetch config: resource requires networking Sep 12 22:56:39.469613 ignition[1082]: Ignition finished successfully Sep 12 22:56:39.499191 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 22:56:39.501858 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 12 22:56:39.551940 ignition[1095]: Ignition 2.22.0 Sep 12 22:56:39.551954 ignition[1095]: Stage: fetch Sep 12 22:56:39.552213 ignition[1095]: no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:39.552225 ignition[1095]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:39.552324 ignition[1095]: parsed url from cmdline: "" Sep 12 22:56:39.552329 ignition[1095]: no config URL provided Sep 12 22:56:39.552334 ignition[1095]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 22:56:39.552343 ignition[1095]: no config at "/usr/lib/ignition/user.ign" Sep 12 22:56:39.552362 ignition[1095]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 12 22:56:39.654516 ignition[1095]: GET result: OK Sep 12 22:56:39.654572 ignition[1095]: config has been read from IMDS userdata Sep 12 22:56:39.654589 ignition[1095]: parsing config with SHA512: fbee7641b48c59ba2ebd282624ad5c448e4030fb4ba62ff121dc395d694bf53a94a32fd79e19a234a139961623aa21122de399170aac278f8e4f2d0ce8f941b6 Sep 12 22:56:39.660208 unknown[1095]: fetched base config from "system" Sep 12 22:56:39.660399 ignition[1095]: fetch: fetch complete Sep 12 22:56:39.660215 unknown[1095]: fetched base config from "system" Sep 12 22:56:39.660404 ignition[1095]: fetch: fetch passed Sep 12 22:56:39.660220 unknown[1095]: fetched user config from "azure" Sep 12 22:56:39.660447 ignition[1095]: Ignition finished successfully Sep 12 22:56:39.665981 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 12 22:56:39.669007 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 22:56:39.734290 ignition[1105]: Ignition 2.22.0 Sep 12 22:56:39.734305 ignition[1105]: Stage: kargs Sep 12 22:56:39.734528 ignition[1105]: no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:39.734540 ignition[1105]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:39.739649 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 22:56:39.735030 ignition[1105]: kargs: kargs passed Sep 12 22:56:39.747125 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 22:56:39.735077 ignition[1105]: Ignition finished successfully Sep 12 22:56:39.799418 ignition[1115]: Ignition 2.22.0 Sep 12 22:56:39.799431 ignition[1115]: Stage: disks Sep 12 22:56:39.799655 ignition[1115]: no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:39.799667 ignition[1115]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:39.805433 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 22:56:39.800125 ignition[1115]: disks: disks passed Sep 12 22:56:39.811501 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 22:56:39.800170 ignition[1115]: Ignition finished successfully Sep 12 22:56:39.819778 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 22:56:39.828250 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 22:56:39.834669 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 22:56:39.842862 systemd[1]: Reached target basic.target - Basic System. Sep 12 22:56:39.849982 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 22:56:39.925935 systemd-networkd[1075]: eth0: Gained IPv6LL Sep 12 22:56:39.942621 systemd-fsck[1126]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 12 22:56:39.950006 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 22:56:39.952442 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 22:56:41.574871 kernel: EXT4-fs (sdb9): mounted filesystem d027afc5-396a-49bf-a5be-60ddd42cb089 r/w with ordered data mode. Quota mode: none. Sep 12 22:56:41.575486 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 22:56:41.578650 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 22:56:41.608915 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 22:56:41.635402 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 22:56:41.637783 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 12 22:56:41.643311 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 22:56:41.656706 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1140) Sep 12 22:56:41.643344 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 22:56:41.669853 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 22:56:41.687554 kernel: BTRFS info (device sdb6): first mount of filesystem fd5cdc72-255e-4ed2-8d25-c5e581a08827 Sep 12 22:56:41.687575 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Sep 12 22:56:41.687704 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 22:56:41.703562 kernel: BTRFS info (device sdb6): turning on async discard Sep 12 22:56:41.703595 kernel: BTRFS info (device sdb6): enabling free space tree Sep 12 22:56:41.705506 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 22:56:42.905921 coreos-metadata[1142]: Sep 12 22:56:42.905 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 22:56:42.912735 coreos-metadata[1142]: Sep 12 22:56:42.908 INFO Fetch successful Sep 12 22:56:42.912735 coreos-metadata[1142]: Sep 12 22:56:42.908 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 12 22:56:42.930243 coreos-metadata[1142]: Sep 12 22:56:42.915 INFO Fetch successful Sep 12 22:56:42.930243 coreos-metadata[1142]: Sep 12 22:56:42.916 INFO wrote hostname ci-4459.0.0-a-f23c8f3bc8 to /sysroot/etc/hostname Sep 12 22:56:42.918146 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 22:56:43.122766 initrd-setup-root[1170]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 22:56:43.147770 initrd-setup-root[1177]: cut: /sysroot/etc/group: No such file or directory Sep 12 22:56:43.184863 initrd-setup-root[1184]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 22:56:43.215420 initrd-setup-root[1191]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 22:56:44.072932 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 22:56:44.075684 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 22:56:44.093433 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 22:56:44.105200 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 22:56:44.113499 kernel: BTRFS info (device sdb6): last unmount of filesystem fd5cdc72-255e-4ed2-8d25-c5e581a08827 Sep 12 22:56:44.134016 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 22:56:44.145595 ignition[1258]: INFO : Ignition 2.22.0 Sep 12 22:56:44.145595 ignition[1258]: INFO : Stage: mount Sep 12 22:56:44.151261 ignition[1258]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:44.151261 ignition[1258]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:44.151261 ignition[1258]: INFO : mount: mount passed Sep 12 22:56:44.151261 ignition[1258]: INFO : Ignition finished successfully Sep 12 22:56:44.152997 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 22:56:44.166764 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 22:56:44.200962 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 22:56:44.222858 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1274) Sep 12 22:56:44.231792 kernel: BTRFS info (device sdb6): first mount of filesystem fd5cdc72-255e-4ed2-8d25-c5e581a08827 Sep 12 22:56:44.231828 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Sep 12 22:56:44.242335 kernel: BTRFS info (device sdb6): turning on async discard Sep 12 22:56:44.242360 kernel: BTRFS info (device sdb6): enabling free space tree Sep 12 22:56:44.244589 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 22:56:44.281361 ignition[1291]: INFO : Ignition 2.22.0 Sep 12 22:56:44.281361 ignition[1291]: INFO : Stage: files Sep 12 22:56:44.286924 ignition[1291]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:44.286924 ignition[1291]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:44.286924 ignition[1291]: DEBUG : files: compiled without relabeling support, skipping Sep 12 22:56:44.308982 ignition[1291]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 22:56:44.308982 ignition[1291]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 22:56:44.349736 ignition[1291]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 22:56:44.354999 ignition[1291]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 22:56:44.354999 ignition[1291]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 22:56:44.350809 unknown[1291]: wrote ssh authorized keys file for user: core Sep 12 22:56:44.411429 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 22:56:44.418338 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 22:56:44.418338 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 22:56:44.418338 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 22:56:44.418338 ignition[1291]: INFO : files: files passed Sep 12 22:56:44.418338 ignition[1291]: INFO : Ignition finished successfully Sep 12 22:56:44.420224 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 22:56:44.430713 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 22:56:44.459065 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 22:56:44.462200 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 22:56:44.462293 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 22:56:44.503932 initrd-setup-root-after-ignition[1324]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 22:56:44.503932 initrd-setup-root-after-ignition[1324]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 22:56:44.521060 initrd-setup-root-after-ignition[1328]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 22:56:44.511186 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 22:56:44.511565 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 22:56:44.523109 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 22:56:44.576538 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 22:56:44.576652 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 22:56:44.579106 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 22:56:44.593942 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 22:56:44.601298 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 22:56:44.602052 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 22:56:44.622268 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 22:56:44.624891 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 22:56:44.643540 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 22:56:44.652489 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 22:56:44.654312 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 22:56:44.663266 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 22:56:44.663390 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 22:56:44.677430 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 22:56:44.685883 systemd[1]: Stopped target basic.target - Basic System. Sep 12 22:56:44.693099 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 22:56:44.700710 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 22:56:44.709327 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 22:56:44.717987 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 12 22:56:44.726395 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 22:56:44.734585 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 22:56:44.743756 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 22:56:44.752382 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 22:56:44.760099 systemd[1]: Stopped target swap.target - Swaps. Sep 12 22:56:44.766803 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 22:56:44.766957 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 22:56:44.777282 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 22:56:44.785467 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 22:56:44.787712 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 22:56:44.787909 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 22:56:44.799969 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 22:56:44.800095 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 22:56:44.816362 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 22:56:44.816495 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 22:56:44.824470 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 22:56:44.824594 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 22:56:44.832067 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 12 22:56:44.832186 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 22:56:44.841883 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 22:56:44.848941 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 22:56:44.849066 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 22:56:44.868435 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 22:56:44.872057 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 22:56:44.896727 ignition[1348]: INFO : Ignition 2.22.0 Sep 12 22:56:44.896727 ignition[1348]: INFO : Stage: umount Sep 12 22:56:44.896727 ignition[1348]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 22:56:44.896727 ignition[1348]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 22:56:44.896727 ignition[1348]: INFO : umount: umount passed Sep 12 22:56:44.896727 ignition[1348]: INFO : Ignition finished successfully Sep 12 22:56:44.872188 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 22:56:44.886213 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 22:56:44.886341 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 22:56:44.903517 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 22:56:44.903624 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 22:56:44.908013 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 22:56:44.908354 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 22:56:44.922993 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 22:56:44.923051 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 22:56:44.925118 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 12 22:56:44.925162 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 12 22:56:44.935621 systemd[1]: Stopped target network.target - Network. Sep 12 22:56:44.942804 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 22:56:44.942874 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 22:56:44.949991 systemd[1]: Stopped target paths.target - Path Units. Sep 12 22:56:44.961473 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 22:56:44.965926 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 22:56:44.975661 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 22:56:44.982596 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 22:56:44.989644 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 22:56:44.989697 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 22:56:44.991598 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 22:56:44.991635 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 22:56:45.056813 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 22:56:45.056981 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 22:56:45.064962 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 22:56:45.065024 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 22:56:45.067989 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 22:56:45.080211 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 22:56:45.081980 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 22:56:45.082693 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 22:56:45.082791 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 22:56:45.099476 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 22:56:45.099586 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 22:56:45.112535 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 22:56:45.112857 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 22:56:45.112960 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 22:56:45.126107 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 22:56:45.126369 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 22:56:45.126466 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 22:56:45.132740 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 12 22:56:45.137679 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 22:56:45.137721 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 22:56:45.146729 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 22:56:45.146784 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 22:56:45.155715 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 22:56:45.163015 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 22:56:45.163068 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 22:56:45.171295 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 22:56:45.171341 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 22:56:45.185415 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 22:56:45.185461 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 22:56:45.187526 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 22:56:45.187582 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 22:56:45.205897 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 22:56:45.208972 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 22:56:45.209039 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 22:56:45.243454 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 22:56:45.243606 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 22:56:45.245053 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 22:56:45.245098 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 22:56:45.256625 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 22:56:45.256660 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 22:56:45.266175 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 22:56:45.266228 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 22:56:45.283256 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 22:56:45.283316 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 22:56:45.299609 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 22:56:45.299661 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 22:56:45.313516 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 22:56:45.320846 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 12 22:56:45.320901 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 22:56:45.329950 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 22:56:45.330001 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 22:56:45.338479 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 22:56:45.338525 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:45.347612 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 12 22:56:45.347670 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 22:56:45.347714 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 22:56:45.348068 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 22:56:45.348163 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 22:56:45.351055 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 22:56:45.351141 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 22:56:45.358084 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 22:56:45.369790 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 22:56:45.413631 systemd[1]: Switching root. Sep 12 22:56:45.525890 systemd-journald[232]: Journal stopped Sep 12 22:56:52.130542 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Sep 12 22:56:52.130573 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 22:56:52.130586 kernel: SELinux: policy capability open_perms=1 Sep 12 22:56:52.130595 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 22:56:52.130605 kernel: SELinux: policy capability always_check_network=0 Sep 12 22:56:52.130614 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 22:56:52.130624 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 22:56:52.130637 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 22:56:52.130646 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 22:56:52.130655 kernel: SELinux: policy capability userspace_initial_context=0 Sep 12 22:56:52.130665 kernel: audit: type=1403 audit(1757717806.284:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 22:56:52.130676 systemd[1]: Successfully loaded SELinux policy in 188.189ms. Sep 12 22:56:52.130687 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.547ms. Sep 12 22:56:52.130699 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 22:56:52.130713 systemd[1]: Detected virtualization microsoft. Sep 12 22:56:52.130724 systemd[1]: Detected architecture x86-64. Sep 12 22:56:52.130734 systemd[1]: Detected first boot. Sep 12 22:56:52.130746 systemd[1]: Hostname set to . Sep 12 22:56:52.130756 systemd[1]: Initializing machine ID from random generator. Sep 12 22:56:52.130770 zram_generator::config[1394]: No configuration found. Sep 12 22:56:52.130781 kernel: Guest personality initialized and is inactive Sep 12 22:56:52.130795 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 12 22:56:52.130804 kernel: Initialized host personality Sep 12 22:56:52.130814 kernel: NET: Registered PF_VSOCK protocol family Sep 12 22:56:52.130825 systemd[1]: Populated /etc with preset unit settings. Sep 12 22:56:52.130849 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 22:56:52.130866 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 22:56:52.130877 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 22:56:52.130887 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 22:56:52.130898 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 22:56:52.130909 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 22:56:52.130920 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 22:56:52.130931 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 22:56:52.130944 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 22:56:52.130955 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 22:56:52.130966 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 22:56:52.130978 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 22:56:52.130988 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 22:56:52.130999 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 22:56:52.131010 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 22:56:52.131025 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 22:56:52.131036 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 22:56:52.131063 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 22:56:52.131076 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 22:56:52.131094 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 22:56:52.131106 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 22:56:52.131118 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 22:56:52.131129 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 22:56:52.131140 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 22:56:52.131154 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 22:56:52.131166 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 22:56:52.131177 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 22:56:52.131188 systemd[1]: Reached target slices.target - Slice Units. Sep 12 22:56:52.131199 systemd[1]: Reached target swap.target - Swaps. Sep 12 22:56:52.131210 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 22:56:52.131221 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 22:56:52.131235 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 22:56:52.131247 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 22:56:52.131258 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 22:56:52.131270 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 22:56:52.131281 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 22:56:52.131292 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 22:56:52.131306 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 22:56:52.131317 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 22:56:52.131332 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 22:56:52.131343 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 22:56:52.131354 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 22:56:52.131365 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 22:56:52.131377 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 22:56:52.131389 systemd[1]: Reached target machines.target - Containers. Sep 12 22:56:52.131403 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 22:56:52.131415 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 22:56:52.131426 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 22:56:52.131438 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 22:56:52.131449 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 22:56:52.131460 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 22:56:52.131471 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 22:56:52.131484 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 22:56:52.131500 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 22:56:52.131512 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 22:56:52.131523 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 22:56:52.131535 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 22:56:52.131549 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 22:56:52.131561 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 22:56:52.131572 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 22:56:52.131584 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 22:56:52.131595 kernel: loop: module loaded Sep 12 22:56:52.131608 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 22:56:52.131620 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 22:56:52.131631 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 22:56:52.131642 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 22:56:52.131653 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 22:56:52.131665 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 22:56:52.131676 systemd[1]: Stopped verity-setup.service. Sep 12 22:56:52.131687 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 22:56:52.131701 kernel: fuse: init (API version 7.41) Sep 12 22:56:52.131712 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 22:56:52.131723 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 22:56:52.131734 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 22:56:52.131746 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 22:56:52.131757 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 22:56:52.131768 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 22:56:52.131780 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 22:56:52.131817 systemd-journald[1501]: Collecting audit messages is disabled. Sep 12 22:56:52.131856 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 22:56:52.131870 systemd-journald[1501]: Journal started Sep 12 22:56:52.131897 systemd-journald[1501]: Runtime Journal (/run/log/journal/cd2def1f1e90416bb64a726a56718345) is 8M, max 2.2G, 2.1G free. Sep 12 22:56:51.216113 systemd[1]: Queued start job for default target multi-user.target. Sep 12 22:56:51.243207 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Sep 12 22:56:51.243711 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 22:56:52.145410 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 22:56:52.149787 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 22:56:52.150058 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 22:56:52.154613 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 22:56:52.154805 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 22:56:52.161083 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 22:56:52.161284 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 22:56:52.167791 kernel: ACPI: bus type drm_connector registered Sep 12 22:56:52.168232 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 22:56:52.168422 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 22:56:52.172873 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 22:56:52.173070 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 22:56:52.177205 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 22:56:52.177438 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 22:56:52.182129 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 22:56:52.186496 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 22:56:52.191368 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 22:56:52.196455 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 22:56:52.201477 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 22:56:52.218077 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 22:56:52.223572 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 22:56:52.241924 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 22:56:52.246825 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 22:56:52.246902 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 22:56:52.253385 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 22:56:52.259111 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 22:56:52.262892 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 22:56:52.279705 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 22:56:52.284869 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 22:56:52.289715 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 22:56:52.290746 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 22:56:52.294651 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 22:56:52.295819 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 22:56:52.300437 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 22:56:52.305527 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 22:56:52.311020 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 22:56:52.316758 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 22:56:52.321362 systemd-journald[1501]: Time spent on flushing to /var/log/journal/cd2def1f1e90416bb64a726a56718345 is 15.941ms for 950 entries. Sep 12 22:56:52.321362 systemd-journald[1501]: System Journal (/var/log/journal/cd2def1f1e90416bb64a726a56718345) is 8M, max 2.6G, 2.6G free. Sep 12 22:56:52.403522 systemd-journald[1501]: Received client request to flush runtime journal. Sep 12 22:56:52.403582 kernel: loop0: detected capacity change from 0 to 128016 Sep 12 22:56:52.385719 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 22:56:52.390460 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 22:56:52.396307 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 22:56:52.414068 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 22:56:52.448708 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 22:56:52.457554 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 22:56:52.458242 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 22:56:52.818880 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 22:56:52.824601 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 22:56:52.954872 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 22:56:52.985956 systemd-tmpfiles[1550]: ACLs are not supported, ignoring. Sep 12 22:56:52.985977 systemd-tmpfiles[1550]: ACLs are not supported, ignoring. Sep 12 22:56:52.990069 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 22:56:53.017868 kernel: loop1: detected capacity change from 0 to 110984 Sep 12 22:56:53.523866 kernel: loop2: detected capacity change from 0 to 27936 Sep 12 22:56:54.011876 kernel: loop3: detected capacity change from 0 to 128016 Sep 12 22:56:54.028859 kernel: loop4: detected capacity change from 0 to 110984 Sep 12 22:56:54.051865 kernel: loop5: detected capacity change from 0 to 27936 Sep 12 22:56:54.137272 (sd-merge)[1557]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 12 22:56:54.137777 (sd-merge)[1557]: Merged extensions into '/usr'. Sep 12 22:56:54.141228 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 22:56:54.147545 systemd[1]: Starting ensure-sysext.service... Sep 12 22:56:54.151120 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 22:56:54.173030 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 22:56:54.179392 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 22:56:54.211365 systemd-udevd[1563]: Using default interface naming scheme 'v255'. Sep 12 22:56:54.215228 systemd[1]: Reload requested from client PID 1559 ('systemctl') (unit ensure-sysext.service)... Sep 12 22:56:54.215245 systemd[1]: Reloading... Sep 12 22:56:54.245378 systemd-tmpfiles[1560]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 12 22:56:54.254908 zram_generator::config[1589]: No configuration found. Sep 12 22:56:54.257196 systemd-tmpfiles[1560]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 12 22:56:54.257540 systemd-tmpfiles[1560]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 22:56:54.257846 systemd-tmpfiles[1560]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 22:56:54.258720 systemd-tmpfiles[1560]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 22:56:54.259018 systemd-tmpfiles[1560]: ACLs are not supported, ignoring. Sep 12 22:56:54.259082 systemd-tmpfiles[1560]: ACLs are not supported, ignoring. Sep 12 22:56:54.329407 systemd-tmpfiles[1560]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 22:56:54.329424 systemd-tmpfiles[1560]: Skipping /boot Sep 12 22:56:54.338322 systemd-tmpfiles[1560]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 22:56:54.338337 systemd-tmpfiles[1560]: Skipping /boot Sep 12 22:56:54.502202 systemd[1]: Reloading finished in 286 ms. Sep 12 22:56:54.548610 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 22:56:54.559676 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 22:56:54.590692 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 22:56:54.620232 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 22:56:54.626546 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 22:56:54.631508 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 22:56:54.638332 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 22:56:54.638533 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 22:56:54.639626 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 22:56:54.644621 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 22:56:54.650044 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 22:56:54.654008 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 22:56:54.654267 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 22:56:54.654387 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 22:56:54.655986 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 22:56:54.656171 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 22:56:54.661203 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 22:56:54.661389 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 22:56:54.666573 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 22:56:54.666741 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 22:56:54.675225 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 22:56:54.675431 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 22:56:54.676632 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 22:56:54.684574 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 22:56:54.689943 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 22:56:54.693853 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 22:56:54.693975 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 22:56:54.694084 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 22:56:54.695323 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 22:56:54.695499 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 22:56:54.700380 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 22:56:54.700548 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 22:56:54.705704 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 22:56:54.705887 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 22:56:54.720712 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 22:56:54.726572 systemd[1]: Finished ensure-sysext.service. Sep 12 22:56:54.732922 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 12 22:56:54.737052 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 22:56:54.737282 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 22:56:54.738321 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 22:56:54.756076 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 22:56:54.760966 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 22:56:54.766821 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 22:56:54.770999 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 22:56:54.771078 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 22:56:54.771172 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 22:56:54.776536 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 22:56:54.780506 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 22:56:54.781219 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 22:56:54.781406 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 22:56:54.786220 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 22:56:54.786426 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 22:56:54.791054 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 22:56:54.791233 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 22:56:54.796150 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 22:56:54.796349 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 22:56:54.802690 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 22:56:54.802757 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 22:56:54.842534 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 22:56:54.849353 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 22:56:54.857116 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 22:56:54.915565 augenrules[1743]: No rules Sep 12 22:56:54.914443 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 22:56:54.914709 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 22:56:54.930186 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 22:56:54.996124 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 12 22:56:55.004355 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 22:56:55.005882 kernel: hv_vmbus: registering driver hyperv_fb Sep 12 22:56:55.006105 systemd-resolved[1654]: Positive Trust Anchors: Sep 12 22:56:55.006118 systemd-resolved[1654]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 22:56:55.006151 systemd-resolved[1654]: 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 22:56:55.021599 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 12 22:56:55.021658 kernel: hv_vmbus: registering driver hv_balloon Sep 12 22:56:55.021682 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 12 22:56:55.019956 systemd-resolved[1654]: Using system hostname 'ci-4459.0.0-a-f23c8f3bc8'. Sep 12 22:56:55.021617 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 22:56:55.029425 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 12 22:56:55.033353 kernel: Console: switching to colour dummy device 80x25 Sep 12 22:56:55.038241 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 22:56:55.041914 systemd-networkd[1723]: lo: Link UP Sep 12 22:56:55.041928 systemd-networkd[1723]: lo: Gained carrier Sep 12 22:56:55.043609 systemd-networkd[1723]: Enumeration completed Sep 12 22:56:55.043859 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 22:56:55.044085 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 22:56:55.044475 systemd-networkd[1723]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:56:55.044487 systemd-networkd[1723]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 22:56:55.045487 systemd-networkd[1723]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:56:55.045554 systemd-networkd[1723]: eth0: Link UP Sep 12 22:56:55.045558 systemd-networkd[1723]: eth0: Gained carrier Sep 12 22:56:55.045571 systemd-networkd[1723]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:56:55.053830 systemd[1]: Reached target network.target - Network. Sep 12 22:56:55.057462 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 22:56:55.063541 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 22:56:55.069002 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 22:56:55.086174 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 22:56:55.092893 systemd-networkd[1723]: eth0: DHCPv4 address 10.200.8.14/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 22:56:55.095371 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 22:56:55.095595 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:55.104615 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 22:56:55.163868 kernel: EDAC MC: Ver: 3.0.0 Sep 12 22:56:55.206618 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 22:56:55.265958 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 22:56:55.268520 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 22:56:55.414184 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 22:56:56.351385 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 22:56:56.374952 systemd-networkd[1723]: eth0: Gained IPv6LL Sep 12 22:56:56.376895 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 22:56:56.382273 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 22:56:57.223491 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 22:56:57.228848 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 22:57:00.665941 ldconfig[1531]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 22:57:00.678640 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 22:57:00.685229 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 22:57:00.728209 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 22:57:00.732871 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 22:57:00.737237 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 22:57:00.742314 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 22:57:00.747434 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 12 22:57:00.752213 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 22:57:00.756642 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 22:57:00.761793 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 22:57:00.766829 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 22:57:00.766888 systemd[1]: Reached target paths.target - Path Units. Sep 12 22:57:00.770683 systemd[1]: Reached target timers.target - Timer Units. Sep 12 22:57:00.787408 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 22:57:00.793217 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 22:57:00.799332 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 22:57:00.805034 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 22:57:00.810230 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 22:57:00.816377 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 22:57:00.820671 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 22:57:00.825867 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 22:57:00.830870 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 22:57:00.834622 systemd[1]: Reached target basic.target - Basic System. Sep 12 22:57:00.838112 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 22:57:00.838145 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 22:57:00.852023 systemd[1]: Starting chronyd.service - NTP client/server... Sep 12 22:57:00.871576 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 22:57:00.876738 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 12 22:57:00.881628 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 22:57:00.886062 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 22:57:00.891400 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 22:57:00.896182 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 22:57:00.900058 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 22:57:00.900979 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 12 22:57:00.904901 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 22:57:00.905827 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 12 22:57:00.909983 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 22:57:00.911710 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 22:57:00.916922 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 22:57:00.920851 jq[1881]: false Sep 12 22:57:00.921913 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 22:57:00.927372 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 22:57:00.933663 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 22:57:00.937537 chronyd[1873]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Sep 12 22:57:00.938423 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 22:57:00.938872 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 22:57:00.939299 KVP[1884]: KVP starting; pid is:1884 Sep 12 22:57:00.939661 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 22:57:00.944213 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 22:57:00.951170 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 22:57:00.952415 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Refreshing passwd entry cache Sep 12 22:57:00.952794 oslogin_cache_refresh[1883]: Refreshing passwd entry cache Sep 12 22:57:00.956239 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 22:57:00.956464 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 22:57:00.956865 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 22:57:00.957078 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 22:57:00.962516 extend-filesystems[1882]: Found /dev/sdb6 Sep 12 22:57:00.968600 kernel: hv_utils: KVP IC version 4.0 Sep 12 22:57:00.959642 KVP[1884]: KVP LIC Version: 3.1 Sep 12 22:57:00.968707 jq[1895]: true Sep 12 22:57:00.974882 jq[1906]: true Sep 12 22:57:00.977951 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Failure getting users, quitting Sep 12 22:57:00.977945 oslogin_cache_refresh[1883]: Failure getting users, quitting Sep 12 22:57:00.978065 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 22:57:00.978065 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Refreshing group entry cache Sep 12 22:57:00.977966 oslogin_cache_refresh[1883]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 22:57:00.978011 oslogin_cache_refresh[1883]: Refreshing group entry cache Sep 12 22:57:00.982534 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Failure getting groups, quitting Sep 12 22:57:00.982534 google_oslogin_nss_cache[1883]: oslogin_cache_refresh[1883]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 22:57:00.982526 oslogin_cache_refresh[1883]: Failure getting groups, quitting Sep 12 22:57:00.982537 oslogin_cache_refresh[1883]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 22:57:00.984218 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 12 22:57:00.984801 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 12 22:57:00.989113 chronyd[1873]: Timezone right/UTC failed leap second check, ignoring Sep 12 22:57:00.989318 chronyd[1873]: Loaded seccomp filter (level 2) Sep 12 22:57:00.989396 systemd[1]: Started chronyd.service - NTP client/server. Sep 12 22:57:00.998928 extend-filesystems[1882]: Found /dev/sdb9 Sep 12 22:57:01.001727 extend-filesystems[1882]: Checking size of /dev/sdb9 Sep 12 22:57:00.998963 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 22:57:01.012072 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 22:57:01.012264 (ntainerd)[1926]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 22:57:01.013172 update_engine[1894]: I20250912 22:57:01.013094 1894 main.cc:92] Flatcar Update Engine starting Sep 12 22:57:01.037972 extend-filesystems[1882]: Old size kept for /dev/sdb9 Sep 12 22:57:01.038650 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 22:57:01.038957 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 22:57:01.045711 systemd-logind[1892]: New seat seat0. Sep 12 22:57:01.048180 systemd-logind[1892]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 22:57:01.048535 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 22:57:01.102946 bash[1928]: Updated "/home/core/.ssh/authorized_keys" Sep 12 22:57:01.104170 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 22:57:01.122014 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 22:57:01.128373 setup-nvidia[1886]: Downloading Flatcar Container Linux Developer Container for version: 4459.0.0 Sep 12 22:57:01.226780 sshd_keygen[1933]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 22:57:01.248881 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 22:57:01.254338 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 22:57:01.274617 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 12 22:57:01.280359 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 22:57:01.280587 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 22:57:01.286888 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 22:57:01.300799 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 12 22:57:01.311964 dbus-daemon[1876]: [system] SELinux support is enabled Sep 12 22:57:01.312134 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 22:57:01.317087 update_engine[1894]: I20250912 22:57:01.317032 1894 update_check_scheduler.cc:74] Next update check in 5m48s Sep 12 22:57:01.318673 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 22:57:01.318705 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 22:57:01.319567 dbus-daemon[1876]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 12 22:57:01.323723 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 22:57:01.323745 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 22:57:01.328902 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 22:57:01.334186 systemd[1]: Started update-engine.service - Update Engine. Sep 12 22:57:01.340099 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 22:57:01.344975 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 22:57:01.349771 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 22:57:01.354654 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 22:57:01.396787 coreos-metadata[1875]: Sep 12 22:57:01.396 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 22:57:01.399107 coreos-metadata[1875]: Sep 12 22:57:01.399 INFO Fetch successful Sep 12 22:57:01.399297 coreos-metadata[1875]: Sep 12 22:57:01.399 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 12 22:57:01.403241 coreos-metadata[1875]: Sep 12 22:57:01.403 INFO Fetch successful Sep 12 22:57:01.403505 coreos-metadata[1875]: Sep 12 22:57:01.403 INFO Fetching http://168.63.129.16/machine/6061b559-4e76-4315-8777-c6ef11b8bb5d/c311d149%2D3020%2D46b9%2Dbcff%2D852dcfec6fd9.%5Fci%2D4459.0.0%2Da%2Df23c8f3bc8?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 12 22:57:01.405087 coreos-metadata[1875]: Sep 12 22:57:01.405 INFO Fetch successful Sep 12 22:57:01.405238 coreos-metadata[1875]: Sep 12 22:57:01.405 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 12 22:57:01.412316 coreos-metadata[1875]: Sep 12 22:57:01.412 INFO Fetch successful Sep 12 22:57:01.444701 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 12 22:57:01.449350 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 22:57:01.573351 locksmithd[2034]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 22:57:01.878007 setup-nvidia[2050]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 22:57:01.878007 setup-nvidia[2050]: Dload Upload Total Spent Left Speed Sep 12 22:57:01.913278 containerd[1926]: time="2025-09-12T22:57:01Z" 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 22:57:01.913973 containerd[1926]: time="2025-09-12T22:57:01.913932000Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 22:57:01.926198 containerd[1926]: time="2025-09-12T22:57:01.926154700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Sep 12 22:57:01.926198 containerd[1926]: time="2025-09-12T22:57:01.926184100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 22:57:01.926198 containerd[1926]: time="2025-09-12T22:57:01.926202200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 22:57:01.926362 containerd[1926]: time="2025-09-12T22:57:01.926338800Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 22:57:01.926404 containerd[1926]: time="2025-09-12T22:57:01.926361400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 22:57:01.926404 containerd[1926]: time="2025-09-12T22:57:01.926386700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 22:57:01.926469 containerd[1926]: time="2025-09-12T22:57:01.926446000Z" 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 22:57:01.926469 containerd[1926]: time="2025-09-12T22:57:01.926464500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 22:57:01.926711 containerd[1926]: time="2025-09-12T22:57:01.926683800Z" 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 22:57:01.926711 containerd[1926]: time="2025-09-12T22:57:01.926706600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 22:57:01.926770 containerd[1926]: time="2025-09-12T22:57:01.926718500Z" 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 22:57:01.926770 containerd[1926]: time="2025-09-12T22:57:01.926726400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 22:57:01.926839 containerd[1926]: time="2025-09-12T22:57:01.926816200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 22:57:01.927087 containerd[1926]: time="2025-09-12T22:57:01.927064600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 22:57:01.927118 containerd[1926]: time="2025-09-12T22:57:01.927099800Z" 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 22:57:01.927141 containerd[1926]: time="2025-09-12T22:57:01.927123600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 22:57:01.927179 containerd[1926]: time="2025-09-12T22:57:01.927160400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 22:57:01.927403 containerd[1926]: time="2025-09-12T22:57:01.927380300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 22:57:01.927468 containerd[1926]: time="2025-09-12T22:57:01.927448400Z" level=info msg="metadata content store policy set" policy=shared Sep 12 22:57:01.943330 containerd[1926]: time="2025-09-12T22:57:01.943299700Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 22:57:01.943396 containerd[1926]: time="2025-09-12T22:57:01.943353900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 22:57:01.943396 containerd[1926]: time="2025-09-12T22:57:01.943371500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 22:57:01.943396 containerd[1926]: time="2025-09-12T22:57:01.943383500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 22:57:01.943396 containerd[1926]: time="2025-09-12T22:57:01.943395900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943406700Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943417600Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943428600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943439000Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943448900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943458000Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 22:57:01.943502 containerd[1926]: time="2025-09-12T22:57:01.943469100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 22:57:01.943643 containerd[1926]: time="2025-09-12T22:57:01.943583000Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 22:57:01.943643 containerd[1926]: time="2025-09-12T22:57:01.943603000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 22:57:01.943643 containerd[1926]: time="2025-09-12T22:57:01.943617400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 22:57:01.943643 containerd[1926]: time="2025-09-12T22:57:01.943627400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 22:57:01.943643 containerd[1926]: time="2025-09-12T22:57:01.943637200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943646800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943658300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943668400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943679900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943690300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 22:57:01.943744 containerd[1926]: time="2025-09-12T22:57:01.943699700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 22:57:01.943948 containerd[1926]: time="2025-09-12T22:57:01.943881300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 22:57:01.943981 containerd[1926]: time="2025-09-12T22:57:01.943950500Z" level=info msg="Start snapshots syncer" Sep 12 22:57:01.944229 containerd[1926]: time="2025-09-12T22:57:01.944157400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 22:57:01.944679 containerd[1926]: time="2025-09-12T22:57:01.944637600Z" 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 22:57:01.944801 containerd[1926]: time="2025-09-12T22:57:01.944690300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 22:57:01.944801 containerd[1926]: time="2025-09-12T22:57:01.944759800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 22:57:01.944906 containerd[1926]: time="2025-09-12T22:57:01.944879100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 22:57:01.944906 containerd[1926]: time="2025-09-12T22:57:01.944908500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944920600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944931100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944941100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944950600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944960400Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944985100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.944998000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 22:57:01.945006 containerd[1926]: time="2025-09-12T22:57:01.945007500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945041400Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945055500Z" 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 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945063900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945073900Z" 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 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945081500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945090700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945100700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945115400Z" level=info msg="runtime interface created" Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945121100Z" level=info msg="created NRI interface" Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945128900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945140900Z" level=info msg="Connect containerd service" Sep 12 22:57:01.945169 containerd[1926]: time="2025-09-12T22:57:01.945163700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 22:57:01.945801 containerd[1926]: time="2025-09-12T22:57:01.945772500Z" 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 22:57:02.386625 containerd[1926]: time="2025-09-12T22:57:02.386579600Z" level=info msg="Start subscribing containerd event" Sep 12 22:57:02.386749 containerd[1926]: time="2025-09-12T22:57:02.386632500Z" level=info msg="Start recovering state" Sep 12 22:57:02.386749 containerd[1926]: time="2025-09-12T22:57:02.386725100Z" level=info msg="Start event monitor" Sep 12 22:57:02.386749 containerd[1926]: time="2025-09-12T22:57:02.386745200Z" level=info msg="Start cni network conf syncer for default" Sep 12 22:57:02.386825 containerd[1926]: time="2025-09-12T22:57:02.386755500Z" level=info msg="Start streaming server" Sep 12 22:57:02.386825 containerd[1926]: time="2025-09-12T22:57:02.386765000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 22:57:02.386825 containerd[1926]: time="2025-09-12T22:57:02.386771800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 22:57:02.386919 containerd[1926]: time="2025-09-12T22:57:02.386782100Z" level=info msg="runtime interface starting up..." Sep 12 22:57:02.386919 containerd[1926]: time="2025-09-12T22:57:02.386870400Z" level=info msg="starting plugins..." Sep 12 22:57:02.386919 containerd[1926]: time="2025-09-12T22:57:02.386893900Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 22:57:02.386980 containerd[1926]: time="2025-09-12T22:57:02.386848300Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 22:57:02.387068 containerd[1926]: time="2025-09-12T22:57:02.387044800Z" level=info msg="containerd successfully booted in 0.474136s" Sep 12 22:57:02.387145 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 22:57:03.191258 waagent[2028]: 2025-09-12T22:57:03.191104Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 12 22:57:03.195548 waagent[2028]: 2025-09-12T22:57:03.195446Z INFO Daemon Daemon OS: flatcar 4459.0.0 Sep 12 22:57:03.198903 waagent[2028]: 2025-09-12T22:57:03.198853Z INFO Daemon Daemon Python: 3.11.13 Sep 12 22:57:03.202194 waagent[2028]: 2025-09-12T22:57:03.202142Z INFO Daemon Daemon Run daemon Sep 12 22:57:03.205036 waagent[2028]: 2025-09-12T22:57:03.204992Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.0.0' Sep 12 22:57:03.211462 waagent[2028]: 2025-09-12T22:57:03.211412Z INFO Daemon Daemon Using waagent for provisioning Sep 12 22:57:03.215267 waagent[2028]: 2025-09-12T22:57:03.215226Z INFO Daemon Daemon Activate resource disk Sep 12 22:57:03.218649 waagent[2028]: 2025-09-12T22:57:03.218599Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 12 22:57:03.225840 waagent[2028]: 2025-09-12T22:57:03.225798Z INFO Daemon Daemon Found device: sda Sep 12 22:57:03.234797 waagent[2028]: 2025-09-12T22:57:03.234756Z INFO Daemon Daemon Examining partition table Sep 12 22:57:03.300881 waagent[2028]: 2025-09-12T22:57:03.300726Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 12 22:57:03.349955 waagent[2028]: 2025-09-12T22:57:03.349880Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Sep 12 22:57:03.354282 waagent[2028]: 2025-09-12T22:57:03.354222Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 12 22:57:03.711674 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#478 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:57:03.723867 kernel: sda: sda1 Sep 12 22:57:03.748524 waagent[2028]: 2025-09-12T22:57:03.748448Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Sep 12 22:57:03.766856 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#561 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 12 22:57:03.782856 kernel: sda: sda1 Sep 12 22:57:03.782916 waagent[2028]: 2025-09-12T22:57:03.782876Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Sep 12 22:57:09.596031 waagent[2028]: 2025-09-12T22:57:09.595945Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Sep 12 22:57:09.596754 login[2033]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 12 22:57:09.597052 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 22:57:09.604569 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 22:57:09.605773 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 22:57:09.613286 systemd-logind[1892]: New session 1 of user core. Sep 12 22:57:09.640171 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 22:57:09.642658 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 22:57:09.676439 (systemd)[2141]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 22:57:09.679317 systemd-logind[1892]: New session c1 of user core. Sep 12 22:57:09.709864 kernel: EXT4-fs (sda1): mounted filesystem c6547e05-0c74-4bbf-b7b7-fedaf8032c11 r/w with ordered data mode. Quota mode: none. Sep 12 22:57:09.709946 waagent[2028]: 2025-09-12T22:57:09.709779Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Sep 12 22:57:09.715713 waagent[2028]: 2025-09-12T22:57:09.715664Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 22:57:09.720028 waagent[2028]: 2025-09-12T22:57:09.719986Z INFO Daemon Daemon Running default provisioning handler Sep 12 22:57:09.730474 waagent[2028]: 2025-09-12T22:57:09.730421Z 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 22:57:09.740426 waagent[2028]: 2025-09-12T22:57:09.740375Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 12 22:57:09.742054 waagent[2028]: 2025-09-12T22:57:09.742002Z INFO Daemon Daemon cloud-init is enabled: False Sep 12 22:57:09.754062 waagent[2028]: 2025-09-12T22:57:09.743411Z INFO Daemon Daemon Copying ovf-env.xml Sep 12 22:57:09.879235 waagent[2028]: 2025-09-12T22:57:09.879116Z INFO Daemon Daemon Successfully mounted dvd Sep 12 22:57:09.928392 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 12 22:57:09.928690 waagent[2028]: 2025-09-12T22:57:09.928629Z INFO Daemon Daemon Detect protocol endpoint Sep 12 22:57:09.930238 waagent[2028]: 2025-09-12T22:57:09.930181Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 22:57:09.931652 waagent[2028]: 2025-09-12T22:57:09.931614Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 12 22:57:09.933046 waagent[2028]: 2025-09-12T22:57:09.933012Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 12 22:57:09.934624 waagent[2028]: 2025-09-12T22:57:09.934585Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 12 22:57:09.935911 waagent[2028]: 2025-09-12T22:57:09.935876Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 12 22:57:09.954059 waagent[2028]: 2025-09-12T22:57:09.954018Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 12 22:57:09.955709 waagent[2028]: 2025-09-12T22:57:09.955685Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 12 22:57:09.956968 waagent[2028]: 2025-09-12T22:57:09.956936Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 12 22:57:10.083262 systemd[2141]: Queued start job for default target default.target. Sep 12 22:57:10.094406 systemd[2141]: Created slice app.slice - User Application Slice. Sep 12 22:57:10.094437 systemd[2141]: Reached target paths.target - Paths. Sep 12 22:57:10.094490 systemd[2141]: Reached target timers.target - Timers. Sep 12 22:57:10.095751 systemd[2141]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 22:57:10.096534 waagent[2028]: 2025-09-12T22:57:10.096460Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 12 22:57:10.098206 waagent[2028]: 2025-09-12T22:57:10.098164Z INFO Daemon Daemon Forcing an update of the goal state. Sep 12 22:57:10.102802 waagent[2028]: 2025-09-12T22:57:10.102754Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 22:57:10.120230 systemd[2141]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 22:57:10.120356 systemd[2141]: Reached target sockets.target - Sockets. Sep 12 22:57:10.120413 systemd[2141]: Reached target basic.target - Basic System. Sep 12 22:57:10.120460 systemd[2141]: Reached target default.target - Main User Target. Sep 12 22:57:10.120501 systemd[2141]: Startup finished in 433ms. Sep 12 22:57:10.120521 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 22:57:10.121884 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 22:57:10.150974 waagent[2028]: 2025-09-12T22:57:10.150899Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 12 22:57:10.152953 waagent[2028]: 2025-09-12T22:57:10.152912Z INFO Daemon Sep 12 22:57:10.153216 waagent[2028]: 2025-09-12T22:57:10.153182Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b53181fc-8b06-4b29-8a3a-7dd0bbe874b2 eTag: 11355381032253918673 source: Fabric] Sep 12 22:57:10.154857 waagent[2028]: 2025-09-12T22:57:10.154810Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 12 22:57:10.155566 waagent[2028]: 2025-09-12T22:57:10.155528Z INFO Daemon Sep 12 22:57:10.156679 waagent[2028]: 2025-09-12T22:57:10.156645Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 12 22:57:10.161687 waagent[2028]: 2025-09-12T22:57:10.161655Z INFO Daemon Daemon Downloading artifacts profile blob Sep 12 22:57:10.300320 waagent[2028]: 2025-09-12T22:57:10.300255Z INFO Daemon Downloaded certificate {'thumbprint': '0F7B914ED393701FB0E460EA7F766D9E3217B736', 'hasPrivateKey': True} Sep 12 22:57:10.302264 waagent[2028]: 2025-09-12T22:57:10.302224Z INFO Daemon Fetch goal state completed Sep 12 22:57:10.339897 waagent[2028]: 2025-09-12T22:57:10.339822Z INFO Daemon Daemon Starting provisioning Sep 12 22:57:10.341551 waagent[2028]: 2025-09-12T22:57:10.341490Z INFO Daemon Daemon Handle ovf-env.xml. Sep 12 22:57:10.342928 waagent[2028]: 2025-09-12T22:57:10.342880Z INFO Daemon Daemon Set hostname [ci-4459.0.0-a-f23c8f3bc8] Sep 12 22:57:10.379120 waagent[2028]: 2025-09-12T22:57:10.379061Z INFO Daemon Daemon Publish hostname [ci-4459.0.0-a-f23c8f3bc8] Sep 12 22:57:10.380827 waagent[2028]: 2025-09-12T22:57:10.380778Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 12 22:57:10.381513 waagent[2028]: 2025-09-12T22:57:10.381472Z INFO Daemon Daemon Primary interface is [eth0] Sep 12 22:57:10.398504 systemd-networkd[1723]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 22:57:10.398513 systemd-networkd[1723]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 22:57:10.399041 waagent[2028]: 2025-09-12T22:57:10.398926Z INFO Daemon Daemon Create user account if not exists Sep 12 22:57:10.398543 systemd-networkd[1723]: eth0: DHCP lease lost Sep 12 22:57:10.400562 waagent[2028]: 2025-09-12T22:57:10.400515Z INFO Daemon Daemon User core already exists, skip useradd Sep 12 22:57:10.401904 waagent[2028]: 2025-09-12T22:57:10.401832Z INFO Daemon Daemon Configure sudoer Sep 12 22:57:10.406647 waagent[2028]: 2025-09-12T22:57:10.406604Z INFO Daemon Daemon Configure sshd Sep 12 22:57:10.411796 waagent[2028]: 2025-09-12T22:57:10.411754Z 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 22:57:10.413599 waagent[2028]: 2025-09-12T22:57:10.413560Z INFO Daemon Daemon Deploy ssh public key. Sep 12 22:57:10.494887 systemd-networkd[1723]: eth0: DHCPv4 address 10.200.8.14/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 22:57:10.598674 login[2033]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 22:57:10.603202 systemd-logind[1892]: New session 2 of user core. Sep 12 22:57:10.612955 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 22:57:24.769580 chronyd[1873]: Selected source PHC0 Sep 12 22:57:26.325367 setup-nvidia[2050]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 473M 0 1711k 0 0 1198k 0 0:06:44 0:00:01 0:06:43 1198k\u000d 5 473M 5 27.1M 0 0 10.8M 0 0:00:43 0:00:02 0:00:41 10.8M\u000d 10 473M 10 52.0M 0 0 14.9M 0 0:00:31 0:00:03 0:00:28 14.9M\u000d 16 473M 16 76.4M 0 0 17.2M 0 0:00:27 0:00:04 0:00:23 17.2M\u000d 22 473M 22 105M 0 0 19.0M 0 0:00:24 0:00:05 0:00:19 20.9M\u000d 27 473M 27 130M 0 0 20.1M 0 0:00:23 0:00:06 0:00:17 25.5M\u000d 32 473M 32 155M 0 0 20.7M 0 0:00:22 0:00:07 0:00:15 25.8M\u000d 37 473M 37 178M 0 0 21.1M 0 0:00:22 0:00:08 0:00:14 25.4M\u000d 38 473M 38 181M 0 0 19.0M 0 0:00:24 0:00:09 0:00:15 20.5M\u000d 38 473M 38 181M 0 0 17.3M 0 0:00:27 0:00:10 0:00:17 15.4M\u000d 38 473M 38 181M 0 0 15.8M 0 0:00:29 0:00:11 0:00:18 10.1M\u000d 38 473M 38 183M 0 0 14.7M 0 0:00:32 0:00:12 0:00:20 5827k\u000d 42 473M 42 202M 0 0 14.9M 0 0:00:31 0:00:13 0:00:18 4777k\u000d 47 473M 47 224M 0 0 15.5M 0 0:00:30 0:00:14 0:00:16 9089k\u000d 52 473M 52 250M 0 0 16.1M 0 0:00:29 0:00:15 0:00:14 13.6M\u000d 57 473M 57 274M 0 0 16.6M 0 0:00:28 0:00:16 0:00:12 18.5M\u000d 62 473M 62 297M 0 0 17.0M 0 0:00:27 0:00:17 0:00:10 22.7M\u000d 68 473M 68 323M 0 0 17.4M 0 0:00:27 0:00:18 0:00:09 24.1M\u000d 73 473M 73 347M 0 0 17.8M 0 0:00:26 0:00:19 0:00:07 24.2M\u000d 78 473M 78 371M 0 0 18.1M 0 0:00:26 0:00:20 0:00:06 24.5M\u000d 83 473M 83 397M 0 0 18.5M 0 0:00:25 0:00:21 0:00:04 24.8M\u000d 89 473M 89 425M 0 0 18.8M 0 0:00:25 0:00:22 0:00:03 25.0M\u000d 94 473M 94 448M 0 0 19.1M 0 0:00:24 0:00:23 0:00:01 25.1M\u000d 99 473M 99 472M 0 0 19.3M 0 0:00:24 0:00:24 --:--:-- 25.3M\u000d100 473M 100 473M 0 0 19.3M 0 0:00:24 0:00:24 --:--:-- 25.6M Sep 12 22:57:36.355811 setup-nvidia[1886]: Downloading NVIDIA 535.261.03 Driver Sep 12 22:57:36.360118 setup-nvidia[2200]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 22:57:36.360360 setup-nvidia[2200]: Dload Upload Total Spent Left Speed Sep 12 22:57:40.529674 waagent[2028]: 2025-09-12T22:57:40.529605Z INFO Daemon Daemon Provisioning complete Sep 12 22:57:40.541338 waagent[2028]: 2025-09-12T22:57:40.541295Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 12 22:57:40.543158 waagent[2028]: 2025-09-12T22:57:40.543101Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 12 22:57:40.543845 waagent[2028]: 2025-09-12T22:57:40.543802Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 12 22:57:40.700628 waagent[2201]: 2025-09-12T22:57:40.700544Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 12 22:57:40.700988 waagent[2201]: 2025-09-12T22:57:40.700691Z INFO ExtHandler ExtHandler OS: flatcar 4459.0.0 Sep 12 22:57:40.700988 waagent[2201]: 2025-09-12T22:57:40.700765Z INFO ExtHandler ExtHandler Python: 3.11.13 Sep 12 22:57:40.700988 waagent[2201]: 2025-09-12T22:57:40.700856Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 12 22:57:40.804461 waagent[2201]: 2025-09-12T22:57:40.804335Z INFO ExtHandler ExtHandler Distro: flatcar-4459.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 12 22:57:40.804623 waagent[2201]: 2025-09-12T22:57:40.804584Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 22:57:40.804705 waagent[2201]: 2025-09-12T22:57:40.804672Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 22:57:40.816891 waagent[2201]: 2025-09-12T22:57:40.816815Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 22:57:40.827252 waagent[2201]: 2025-09-12T22:57:40.827214Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 12 22:57:40.827756 waagent[2201]: 2025-09-12T22:57:40.827715Z INFO ExtHandler Sep 12 22:57:40.827903 waagent[2201]: 2025-09-12T22:57:40.827811Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ca29a859-dfef-4a1f-8947-be599cc15c18 eTag: 11355381032253918673 source: Fabric] Sep 12 22:57:40.828254 waagent[2201]: 2025-09-12T22:57:40.828214Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 12 22:57:40.829131 waagent[2201]: 2025-09-12T22:57:40.829088Z INFO ExtHandler Sep 12 22:57:40.829206 waagent[2201]: 2025-09-12T22:57:40.829176Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 12 22:57:40.832824 waagent[2201]: 2025-09-12T22:57:40.832781Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 12 22:57:40.911478 waagent[2201]: 2025-09-12T22:57:40.911412Z INFO ExtHandler Downloaded certificate {'thumbprint': '0F7B914ED393701FB0E460EA7F766D9E3217B736', 'hasPrivateKey': True} Sep 12 22:57:40.911985 waagent[2201]: 2025-09-12T22:57:40.911940Z INFO ExtHandler Fetch goal state completed Sep 12 22:57:40.922820 waagent[2201]: 2025-09-12T22:57:40.922774Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Sep 12 22:57:40.928048 waagent[2201]: 2025-09-12T22:57:40.928004Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2201 Sep 12 22:57:40.928189 waagent[2201]: 2025-09-12T22:57:40.928153Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 12 22:57:40.928533 waagent[2201]: 2025-09-12T22:57:40.928494Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 12 22:57:40.930192 waagent[2201]: 2025-09-12T22:57:40.930148Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.0.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 12 22:57:40.930632 waagent[2201]: 2025-09-12T22:57:40.930589Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 12 22:57:40.930788 waagent[2201]: 2025-09-12T22:57:40.930752Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 12 22:57:40.931431 waagent[2201]: 2025-09-12T22:57:40.931393Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 12 22:57:40.992427 waagent[2201]: 2025-09-12T22:57:40.992381Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 12 22:57:40.992631 waagent[2201]: 2025-09-12T22:57:40.992593Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 12 22:57:40.999067 waagent[2201]: 2025-09-12T22:57:40.999041Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 12 22:57:41.005442 systemd[1]: Reload requested from client PID 2216 ('systemctl') (unit waagent.service)... Sep 12 22:57:41.005458 systemd[1]: Reloading... Sep 12 22:57:41.069870 zram_generator::config[2259]: No configuration found. Sep 12 22:57:41.338944 systemd[1]: Reloading finished in 333 ms. Sep 12 22:57:41.365639 waagent[2201]: 2025-09-12T22:57:41.365565Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 12 22:57:41.365751 waagent[2201]: 2025-09-12T22:57:41.365716Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 12 22:57:41.838853 waagent[2201]: 2025-09-12T22:57:41.838753Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 12 22:57:41.839204 waagent[2201]: 2025-09-12T22:57:41.839129Z 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 22:57:41.839948 waagent[2201]: 2025-09-12T22:57:41.839899Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 12 22:57:41.840302 waagent[2201]: 2025-09-12T22:57:41.840257Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 22:57:41.840479 waagent[2201]: 2025-09-12T22:57:41.840438Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 12 22:57:41.840724 waagent[2201]: 2025-09-12T22:57:41.840689Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 22:57:41.840799 waagent[2201]: 2025-09-12T22:57:41.840757Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 22:57:41.840972 waagent[2201]: 2025-09-12T22:57:41.840934Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 22:57:41.841382 waagent[2201]: 2025-09-12T22:57:41.841338Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 12 22:57:41.841515 waagent[2201]: 2025-09-12T22:57:41.841471Z INFO EnvHandler ExtHandler Configure routes Sep 12 22:57:41.841870 waagent[2201]: 2025-09-12T22:57:41.841801Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 12 22:57:41.841956 waagent[2201]: 2025-09-12T22:57:41.841911Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 12 22:57:41.842057 waagent[2201]: 2025-09-12T22:57:41.842008Z INFO EnvHandler ExtHandler Gateway:None Sep 12 22:57:41.842613 waagent[2201]: 2025-09-12T22:57:41.842561Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 12 22:57:41.842779 waagent[2201]: 2025-09-12T22:57:41.842739Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 12 22:57:41.842779 waagent[2201]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 12 22:57:41.842779 waagent[2201]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 12 22:57:41.842779 waagent[2201]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 12 22:57:41.842779 waagent[2201]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 12 22:57:41.842779 waagent[2201]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 22:57:41.842779 waagent[2201]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 22:57:41.842951 waagent[2201]: 2025-09-12T22:57:41.842784Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 12 22:57:41.842951 waagent[2201]: 2025-09-12T22:57:41.842861Z INFO EnvHandler ExtHandler Routes:None Sep 12 22:57:41.843785 waagent[2201]: 2025-09-12T22:57:41.843736Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 12 22:57:41.849844 waagent[2201]: 2025-09-12T22:57:41.849780Z INFO ExtHandler ExtHandler Sep 12 22:57:41.850169 waagent[2201]: 2025-09-12T22:57:41.850128Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: d431934c-8b9d-4fe1-9f83-4bf1ebe9db12 correlation 12ce8e22-0618-4922-82ec-b433c5ad56b0 created: 2025-09-12T22:55:31.368423Z] Sep 12 22:57:41.851410 waagent[2201]: 2025-09-12T22:57:41.851365Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 12 22:57:41.853983 waagent[2201]: 2025-09-12T22:57:41.853893Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Sep 12 22:57:41.910720 waagent[2201]: 2025-09-12T22:57:41.910670Z 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 22:57:41.910720 waagent[2201]: Try `iptables -h' or 'iptables --help' for more information.) Sep 12 22:57:41.911114 waagent[2201]: 2025-09-12T22:57:41.911076Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 79D5BF14-43B7-48F0-A1E4-0CD7254A9EA8;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 12 22:57:41.981244 waagent[2201]: 2025-09-12T22:57:41.981186Z INFO MonitorHandler ExtHandler Network interfaces: Sep 12 22:57:41.981244 waagent[2201]: Executing ['ip', '-a', '-o', 'link']: Sep 12 22:57:41.981244 waagent[2201]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Sep 12 22:57:41.981244 waagent[2201]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:40:a5:fe brd ff:ff:ff:ff:ff:ff Sep 12 22:57:41.981244 waagent[2201]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 12 22:57:41.981244 waagent[2201]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 12 22:57:41.981244 waagent[2201]: 2: eth0 inet 10.200.8.14/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 12 22:57:41.981244 waagent[2201]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 12 22:57:41.981244 waagent[2201]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 12 22:57:41.981244 waagent[2201]: 2: eth0 inet6 fe80::7eed:8dff:fe40:a5fe/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 12 22:57:42.055359 waagent[2201]: 2025-09-12T22:57:42.054752Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 12 22:57:42.055359 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 22:57:42.055359 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.055359 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 22:57:42.055359 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.055359 waagent[2201]: Chain OUTPUT (policy ACCEPT 7 packets, 580 bytes) Sep 12 22:57:42.055359 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.055359 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 22:57:42.055359 waagent[2201]: 9 995 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 22:57:42.055359 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 22:57:42.058796 waagent[2201]: 2025-09-12T22:57:42.058744Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 12 22:57:42.058796 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 22:57:42.058796 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.058796 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 22:57:42.058796 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.058796 waagent[2201]: Chain OUTPUT (policy ACCEPT 9 packets, 869 bytes) Sep 12 22:57:42.058796 waagent[2201]: pkts bytes target prot opt in out source destination Sep 12 22:57:42.058796 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 22:57:42.058796 waagent[2201]: 9 995 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 22:57:42.058796 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 22:57:42.059130 waagent[2201]: 2025-09-12T22:57:42.059094Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 12 22:57:42.059287 waagent[2201]: 2025-09-12T22:57:42.059255Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 12 22:57:43.139114 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 12 22:57:46.161460 update_engine[1894]: I20250912 22:57:46.161370 1894 update_attempter.cc:509] Updating boot flags... Sep 12 22:57:48.300205 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 22:57:48.302915 systemd[1]: Started sshd@0-10.200.8.14:22-10.200.16.10:60272.service - OpenSSH per-connection server daemon (10.200.16.10:60272). Sep 12 22:57:49.153681 sshd[2466]: Accepted publickey for core from 10.200.16.10 port 60272 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:57:49.154902 sshd-session[2466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:57:49.159105 systemd-logind[1892]: New session 3 of user core. Sep 12 22:57:49.172963 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 22:57:49.705131 systemd[1]: Started sshd@1-10.200.8.14:22-10.200.16.10:60278.service - OpenSSH per-connection server daemon (10.200.16.10:60278). Sep 12 22:57:50.349117 sshd[2472]: Accepted publickey for core from 10.200.16.10 port 60278 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:57:50.350347 sshd-session[2472]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:57:50.354747 systemd-logind[1892]: New session 4 of user core. Sep 12 22:57:50.370957 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 22:57:50.792171 sshd[2475]: Connection closed by 10.200.16.10 port 60278 Sep 12 22:57:50.792476 sshd-session[2472]: pam_unix(sshd:session): session closed for user core Sep 12 22:57:50.796077 systemd[1]: sshd@1-10.200.8.14:22-10.200.16.10:60278.service: Deactivated successfully. Sep 12 22:57:50.797684 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 22:57:50.798408 systemd-logind[1892]: Session 4 logged out. Waiting for processes to exit. Sep 12 22:57:50.799507 systemd-logind[1892]: Removed session 4. Sep 12 22:57:50.907262 systemd[1]: Started sshd@2-10.200.8.14:22-10.200.16.10:44532.service - OpenSSH per-connection server daemon (10.200.16.10:44532). Sep 12 22:57:51.558326 sshd[2481]: Accepted publickey for core from 10.200.16.10 port 44532 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:57:51.559670 sshd-session[2481]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:57:51.563950 systemd-logind[1892]: New session 5 of user core. Sep 12 22:57:51.577975 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 22:57:52.001409 sshd[2484]: Connection closed by 10.200.16.10 port 44532 Sep 12 22:57:52.001796 sshd-session[2481]: pam_unix(sshd:session): session closed for user core Sep 12 22:57:52.005533 systemd[1]: sshd@2-10.200.8.14:22-10.200.16.10:44532.service: Deactivated successfully. Sep 12 22:57:52.007188 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 22:57:52.007860 systemd-logind[1892]: Session 5 logged out. Waiting for processes to exit. Sep 12 22:57:52.008738 systemd-logind[1892]: Removed session 5. Sep 12 22:58:02.128047 systemd[1]: Started sshd@3-10.200.8.14:22-10.200.16.10:54994.service - OpenSSH per-connection server daemon (10.200.16.10:54994). Sep 12 22:58:02.755435 sshd[2491]: Accepted publickey for core from 10.200.16.10 port 54994 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:02.756669 sshd-session[2491]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:02.760795 systemd-logind[1892]: New session 6 of user core. Sep 12 22:58:02.769956 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 22:58:03.198798 sshd[2494]: Connection closed by 10.200.16.10 port 54994 Sep 12 22:58:03.199187 sshd-session[2491]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:03.202662 systemd[1]: sshd@3-10.200.8.14:22-10.200.16.10:54994.service: Deactivated successfully. Sep 12 22:58:03.204477 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 22:58:03.205250 systemd-logind[1892]: Session 6 logged out. Waiting for processes to exit. Sep 12 22:58:03.206172 systemd-logind[1892]: Removed session 6. Sep 12 22:58:03.310476 systemd[1]: Started sshd@4-10.200.8.14:22-10.200.16.10:55000.service - OpenSSH per-connection server daemon (10.200.16.10:55000). Sep 12 22:58:03.937581 sshd[2500]: Accepted publickey for core from 10.200.16.10 port 55000 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:03.938883 sshd-session[2500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:03.943208 systemd-logind[1892]: New session 7 of user core. Sep 12 22:58:03.952969 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 22:58:04.382996 sshd[2503]: Connection closed by 10.200.16.10 port 55000 Sep 12 22:58:04.383378 sshd-session[2500]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:04.387079 systemd[1]: sshd@4-10.200.8.14:22-10.200.16.10:55000.service: Deactivated successfully. Sep 12 22:58:04.388722 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 22:58:04.389416 systemd-logind[1892]: Session 7 logged out. Waiting for processes to exit. Sep 12 22:58:04.390353 systemd-logind[1892]: Removed session 7. Sep 12 22:58:14.509338 systemd[1]: Started sshd@5-10.200.8.14:22-10.200.16.10:57206.service - OpenSSH per-connection server daemon (10.200.16.10:57206). Sep 12 22:58:15.136211 sshd[2509]: Accepted publickey for core from 10.200.16.10 port 57206 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:15.137497 sshd-session[2509]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:15.141747 systemd-logind[1892]: New session 8 of user core. Sep 12 22:58:15.162953 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 22:58:15.581697 sshd[2512]: Connection closed by 10.200.16.10 port 57206 Sep 12 22:58:15.582016 sshd-session[2509]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:15.585635 systemd[1]: sshd@5-10.200.8.14:22-10.200.16.10:57206.service: Deactivated successfully. Sep 12 22:58:15.587320 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 22:58:15.588072 systemd-logind[1892]: Session 8 logged out. Waiting for processes to exit. Sep 12 22:58:15.589004 systemd-logind[1892]: Removed session 8. Sep 12 22:58:15.703366 systemd[1]: Started sshd@6-10.200.8.14:22-10.200.16.10:57208.service - OpenSSH per-connection server daemon (10.200.16.10:57208). Sep 12 22:58:16.331977 sshd[2518]: Accepted publickey for core from 10.200.16.10 port 57208 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:16.333205 sshd-session[2518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:16.337465 systemd-logind[1892]: New session 9 of user core. Sep 12 22:58:16.345961 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 22:58:16.781230 sshd[2521]: Connection closed by 10.200.16.10 port 57208 Sep 12 22:58:16.781599 sshd-session[2518]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:16.785068 systemd[1]: sshd@6-10.200.8.14:22-10.200.16.10:57208.service: Deactivated successfully. Sep 12 22:58:16.786713 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 22:58:16.787430 systemd-logind[1892]: Session 9 logged out. Waiting for processes to exit. Sep 12 22:58:16.788436 systemd-logind[1892]: Removed session 9. Sep 12 22:58:26.903789 systemd[1]: Started sshd@7-10.200.8.14:22-10.200.16.10:38276.service - OpenSSH per-connection server daemon (10.200.16.10:38276). Sep 12 22:58:27.551395 sshd[2527]: Accepted publickey for core from 10.200.16.10 port 38276 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:27.552603 sshd-session[2527]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:27.556728 systemd-logind[1892]: New session 10 of user core. Sep 12 22:58:27.565995 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 22:58:27.995015 sshd[2530]: Connection closed by 10.200.16.10 port 38276 Sep 12 22:58:27.995394 sshd-session[2527]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:27.998808 systemd[1]: sshd@7-10.200.8.14:22-10.200.16.10:38276.service: Deactivated successfully. Sep 12 22:58:28.000517 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 22:58:28.001373 systemd-logind[1892]: Session 10 logged out. Waiting for processes to exit. Sep 12 22:58:28.002315 systemd-logind[1892]: Removed session 10. Sep 12 22:58:28.118623 systemd[1]: Started sshd@8-10.200.8.14:22-10.200.16.10:38280.service - OpenSSH per-connection server daemon (10.200.16.10:38280). Sep 12 22:58:28.744684 sshd[2536]: Accepted publickey for core from 10.200.16.10 port 38280 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:28.745940 sshd-session[2536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:28.750219 systemd-logind[1892]: New session 11 of user core. Sep 12 22:58:28.756992 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 22:58:29.189159 sshd[2539]: Connection closed by 10.200.16.10 port 38280 Sep 12 22:58:29.189553 sshd-session[2536]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:29.192997 systemd[1]: sshd@8-10.200.8.14:22-10.200.16.10:38280.service: Deactivated successfully. Sep 12 22:58:29.194614 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 22:58:29.195332 systemd-logind[1892]: Session 11 logged out. Waiting for processes to exit. Sep 12 22:58:29.196220 systemd-logind[1892]: Removed session 11. Sep 12 22:58:39.304543 systemd[1]: Started sshd@9-10.200.8.14:22-10.200.16.10:38030.service - OpenSSH per-connection server daemon (10.200.16.10:38030). Sep 12 22:58:39.946770 sshd[2545]: Accepted publickey for core from 10.200.16.10 port 38030 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:39.948003 sshd-session[2545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:39.954859 systemd-logind[1892]: New session 12 of user core. Sep 12 22:58:39.962957 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 22:58:40.389119 sshd[2548]: Connection closed by 10.200.16.10 port 38030 Sep 12 22:58:40.389499 sshd-session[2545]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:40.393015 systemd[1]: sshd@9-10.200.8.14:22-10.200.16.10:38030.service: Deactivated successfully. Sep 12 22:58:40.394618 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 22:58:40.395314 systemd-logind[1892]: Session 12 logged out. Waiting for processes to exit. Sep 12 22:58:40.396312 systemd-logind[1892]: Removed session 12. Sep 12 22:58:40.511113 systemd[1]: Started sshd@10-10.200.8.14:22-10.200.16.10:50606.service - OpenSSH per-connection server daemon (10.200.16.10:50606). Sep 12 22:58:41.137503 sshd[2554]: Accepted publickey for core from 10.200.16.10 port 50606 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:41.138791 sshd-session[2554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:41.143110 systemd-logind[1892]: New session 13 of user core. Sep 12 22:58:41.151998 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 22:58:41.582941 sshd[2557]: Connection closed by 10.200.16.10 port 50606 Sep 12 22:58:41.583230 sshd-session[2554]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:41.586664 systemd[1]: sshd@10-10.200.8.14:22-10.200.16.10:50606.service: Deactivated successfully. Sep 12 22:58:41.588269 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 22:58:41.588950 systemd-logind[1892]: Session 13 logged out. Waiting for processes to exit. Sep 12 22:58:41.589811 systemd-logind[1892]: Removed session 13. Sep 12 22:58:51.194955 setup-nvidia[2200]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 1 325M 1 6145k 0 0 5278k 0 0:01:03 0:00:01 0:01:02 5283k\u000d 3 325M 3 10.0M 0 0 5116k 0 0:01:05 0:00:02 0:01:03 5117k\u000d 4 325M 4 14.0M 0 0 4547k 0 0:01:13 0:00:03 0:01:10 4548k\u000d 5 325M 5 18.0M 0 0 4409k 0 0:01:15 0:00:04 0:01:11 4410k\u000d 6 325M 6 22.0M 0 0 4359k 0 0:01:16 0:00:05 0:01:11 4360k\u000d 7 325M 7 26.0M 0 0 4261k 0 0:01:18 0:00:06 0:01:12 4028k\u000d 8 325M 8 28.7M 0 0 4243k 0 0:01:18 0:00:06 0:01:12 3889k\u000d 9 325M 9 32.0M 0 0 4077k 0 0:01:21 0:00:08 0:01:13 3773k\u000d 11 325M 11 36.0M 0 0 4102k 0 0:01:21 0:00:08 0:01:13 3835k\u000d 12 325M 12 40.0M 0 0 4124k 0 0:01:20 0:00:09 0:01:11 3869k\u000d 14 325M 14 46.0M 0 0 4219k 0 0:01:19 0:00:11 0:01:08 4165k\u000d 15 325M 15 50.0M 0 0 4279k 0 0:01:17 0:00:11 0:01:06 4330k\u000d 17 325M 17 56.0M 0 0 4349k 0 0:01:16 0:00:13 0:01:03 4775k\u000d 18 325M 18 60.0M 0 0 4371k 0 0:01:16 0:00:14 0:01:02 4846k\u000d 20 325M 20 66.0M 0 0 4428k 0 0:01:15 0:00:15 0:01:00 4996k\u000d 21 325M 21 70.0M 0 0 4433k 0 0:01:15 0:00:16 0:00:59 4911k\u000d 22 325M 22 73.5M 0 0 4436k 0 0:01:15 0:00:16 0:00:59 4809k\u000d 24 325M 24 80.0M 0 0 4516k 0 0:01:13 0:00:18 0:00:55 4957k\u000d 25 325M 25 84.0M 0 0 4540k 0 0:01:13 0:00:18 0:00:55 5027k\u000d 27 325M 27 89.3M 0 0 4576k 0 0:01:12 0:00:19 0:00:53 5053k\u000d 28 325M 28 94.0M 0 0 4539k 0 0:01:13 0:00:21 0:00:52 4881k\u000d 30 325M 30 98.0M 0 0 4556k 0 0:01:13 0:00:22 0:00:51 4961k\u000d 31 325M 31 104M 0 0 4593k 0 0:01:12 0:00:23 0:00:49 4872k\u000d 33 325M 33 108M 0 0 4611k 0 0:01:12 0:00:23 0:00:49 4881k\u000d 34 325M 34 114M 0 0 4653k 0 0:01:11 0:00:25 0:00:46 4955k\u000d 36 325M 36 120M 0 0 4688k 0 0:01:11 0:00:26 0:00:45 5320k\u000d 38 325M 38 124M 0 0 4703k 0 0:01:10 0:00:26 0:00:44 5358k\u000d 39 325M 39 130M 0 0 4727k 0 0:01:10 0:00:28 0:00:42 5353k\u000d 41 325M 41 134M 0 0 4749k 0 0:01:10 0:00:28 0:00:42 5411k\u000d 42 325M 42 140M 0 0 4772k 0 0:01:09 0:00:30 0:00:39 5375k\u000d 44 325M 44 146M 0 0 4785k 0 0:01:09 0:00:31 0:00:38 5289k\u000d 46 325M 46 150M 0 0 4789k 0 0:01:09 0:00:32 0:00:37 5247k\u000d 47 325M 47 154M 0 0 4785k 0 0:01:09 0:00:32 0:00:37 5125k\u000d 49 325M 49 160M 0 0 4800k 0 0:01:09 0:00:34 0:00:35 5087k\u000d 50 325M 50 164M 0 0 4801k 0 0:01:09 0:00:34 0:00:35 4980k\u000d 52 325M 52 170M 0 0 4817k 0 0:01:09 0:00:36 0:00:33 5020k\u000d 53 325M 53 174M 0 0 4816k 0 0:01:09 0:00:36 0:00:33 4990k\u000d 55 325M 55 180M 0 0 4839k 0 0:01:08 0:00:38 0:00:30 5183k\u000d 56 325M 56 185M 0 0 4867k 0 0:01:08 0:00:38 0:00:30 5344k\u000d 58 325M 58 190M 0 0 4841k 0 0:01:08 0:00:40 0:00:28 5106k\u000d 59 325M 59 195M 0 0 4885k 0 0:01:08 0:00:40 0:00:28 5400k\u000d 61 325M 61 200M 0 0 4862k 0 0:01:08 0:00:42 0:00:26 5198k\u000d 62 325M 62 204M 0 0 4844k 0 0:01:08 0:00:43 0:00:25 4885k\u000d 63 325M 63 208M 0 0 4851k 0 0:01:08 0:00:43 0:00:25 4729k\u000d 65 325M 65 214M 0 0 4854k 0 0:01:08 0:00:45 0:00:23 4964k\u000d 66 325M 66 218M 0 0 4855k 0 0:01:08 0:00:45 0:00:23 4610k\u000d 68 325M 68 224M 0 0 4853k 0 0:01:08 0:00:47 0:00:21 4779k\u000d 69 325M 69 228M 0 0 4864k 0 0:01:08 0:00:47 0:00:21 5043k\u000d 70 325M 70 229M 0 0 4781k 0 0:01:09 0:00:49 0:00:20 4185k\u000d 70 325M 70 230M 0 0 4667k 0 0:01:11 0:00:50 0:00:21 3078k\u000d 70 325M 70 230M 0 0 4595k 0 0:01:12 0:00:51 0:00:21 2329k\u000d 70 325M 70 230M 0 0 4541k 0 0:01:13 0:00:51 0:00:22 1393k\u000d 71 325M 71 231M 0 0 4444k 0 0:01:15 0:00:53 0:00:22 683k\u000d 71 325M 71 232M 0 0 4405k 0 0:01:15 0:00:53 0:00:22 591k\u000d 71 325M 71 233M 0 0 4343k 0 0:01:16 0:00:55 0:00:21 753k\u000d 71 325M 71 234M 0 0 4280k 0 0:01:17 0:00:56 0:00:21 909k\u000d 72 325M 72 235M 0 0 4239k 0 0:01:18 0:00:56 0:00:22 1087k\u000d 73 325M 73 240M 0 0 4226k 0 0:01:18 0:00:58 0:00:20 1801k\u000d 75 325M 75 246M 0 0 4249k 0 0:01:18 0:00:59 0:00:19 2657k\u000d 76 325M 76 250M 0 0 4261k 0 0:01:18 0:01:00 0:00:18 3373k\u000d 78 325M 78 256M 0 0 4279k 0 0:01:17 0:01:01 0:00:16 4261k\u000d 79 325M 79 260M 0 0 4292k 0 0:01:17 0:01:02 0:00:15 4889k\u000d 81 325M 81 266M 0 0 4310k 0 0:01:17 0:01:03 0:00:14 5268k\u000d 82 325M 82 270M 0 0 4317k 0 0:01:17 0:01:04 0:00:13 5162k\u000d 84 325M 84 275M 0 0 4342k 0 0:01:16 0:01:04 0:00:12 5341k\u000d 85 325M 85 280M 0 0 4340k 0 0:01:16 0:01:06 0:00:10 5117k\u000d 87 325M 87 286M 0 0 4358k 0 0:01:16 0:01:07 0:00:09 5147k\u000d 88 325M 88 290M 0 0 4371k 0 0:01:16 0:01:07 0:00:09 5178k\u000d 90 325M 90 296M 0 0 4380k 0 0:01:16 0:01:09 0:00:07 5154k\u000d 92 325M 92 300M 0 0 4384k 0 0:01:16 0:01:10 0:00:06 4915k\u000d 93 325M 93 305M 0 0 4415k 0 0:01:15 0:01:10 0:00:05 5431k\u000d 95 325M 95 310M 0 0 4403k 0 0:01:15 0:01:12 0:00:03 5018k\u000d 96 325M 96 315M 0 0 4424k 0 0:01:15 0:01:12 0:00:03 5154k\u000d 98 325M 98 320M 0 0 4426k 0 0:01:15 0:01:14 0:00:01 5087k\u000d100 325M 100 325M 0 0 4459k 0 0:01:14 0:01:14 --:--:-- 5567k Sep 12 22:58:51.285318 setup-nvidia[1886]: Extract the NVIDIA Driver Installer 535.261.03 Sep 12 22:58:51.285318 setup-nvidia[1886]: /opt/nvidia/workdir/nvidia-workdir / Sep 12 22:58:51.330476 setup-nvidia[2564]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Sep 12 22:58:51.615428 setup-nvidia[2564]: Verifying archive integrity... OK Sep 12 22:58:51.696090 systemd[1]: Started sshd@11-10.200.8.14:22-10.200.16.10:43728.service - OpenSSH per-connection server daemon (10.200.16.10:43728). Sep 12 22:58:52.341456 setup-nvidia[2564]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Sep 12 22:58:52.342134 sshd[2588]: Accepted publickey for core from 10.200.16.10 port 43728 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:52.343375 sshd-session[2588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:52.348907 systemd-logind[1892]: New session 14 of user core. Sep 12 22:58:52.360058 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 22:58:52.785623 sshd[2598]: Connection closed by 10.200.16.10 port 43728 Sep 12 22:58:52.786025 sshd-session[2588]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:52.789776 systemd[1]: sshd@11-10.200.8.14:22-10.200.16.10:43728.service: Deactivated successfully. Sep 12 22:58:52.791379 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 22:58:52.792071 systemd-logind[1892]: Session 14 logged out. Waiting for processes to exit. Sep 12 22:58:52.793226 systemd-logind[1892]: Removed session 14. Sep 12 22:58:52.896280 systemd[1]: Started sshd@12-10.200.8.14:22-10.200.16.10:43744.service - OpenSSH per-connection server daemon (10.200.16.10:43744). Sep 12 22:58:53.522144 sshd[2604]: Accepted publickey for core from 10.200.16.10 port 43744 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:58:53.523909 sshd-session[2604]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:58:53.528543 systemd-logind[1892]: New session 15 of user core. Sep 12 22:58:53.536041 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 22:58:53.967401 sshd[2607]: Connection closed by 10.200.16.10 port 43744 Sep 12 22:58:53.966044 sshd-session[2604]: pam_unix(sshd:session): session closed for user core Sep 12 22:58:53.969809 systemd[1]: sshd@12-10.200.8.14:22-10.200.16.10:43744.service: Deactivated successfully. Sep 12 22:58:53.973849 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 22:58:53.974645 systemd-logind[1892]: Session 15 logged out. Waiting for processes to exit. Sep 12 22:58:53.975815 systemd-logind[1892]: Removed session 15. Sep 12 22:58:54.139481 setup-nvidia[2594]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 12 22:58:54.233103 setup-nvidia[1886]: / Sep 12 22:58:54.233103 setup-nvidia[1886]: Spawn system-nspawn container to install the NVIDIA drivers Sep 12 22:58:54.469137 sudo[2615]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.0.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.261.03 Sep 12 22:58:54.470870 sudo[2615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 22:58:54.482100 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 12 22:58:54.485256 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 22:58:54.509074 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 22:58:54.512779 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 12 22:58:54.519328 (systemd)[2618]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 12 22:58:54.521499 systemd-logind[1892]: New session c3 of user root. Sep 12 22:58:54.696677 systemd[2618]: Queued start job for default target default.target. Sep 12 22:58:54.713952 systemd[2618]: Created slice app.slice - User Application Slice. Sep 12 22:58:54.713981 systemd[2618]: Reached target paths.target - Paths. Sep 12 22:58:54.714029 systemd[2618]: Reached target timers.target - Timers. Sep 12 22:58:54.715211 systemd[2618]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 22:58:54.726016 systemd[2618]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 22:58:54.726132 systemd[2618]: Reached target sockets.target - Sockets. Sep 12 22:58:54.726187 systemd[2618]: Reached target basic.target - Basic System. Sep 12 22:58:54.726231 systemd[2618]: Reached target default.target - Main User Target. Sep 12 22:58:54.726262 systemd[2618]: Startup finished in 198ms. Sep 12 22:58:54.726307 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 12 22:58:54.730280 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 12 22:58:54.827871 kernel: loop6: detected capacity change from 0 to 12644352 Sep 12 22:58:54.830862 kernel: loop6: p9 Sep 12 22:58:54.842622 setup-nvidia[2627]: ░ Spawning container flatcardevelopercontainer-4459.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.0.0.bin. Sep 12 22:58:54.954191 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 12 22:58:54.954289 kernel: EXT4-fs (loop6p9): mounted filesystem 57ec9fcf-b174-433d-9de4-0c1e47a24cd6 ro without journal. Quota mode: none. Sep 12 22:59:04.081075 systemd[1]: Started sshd@13-10.200.8.14:22-10.200.16.10:44724.service - OpenSSH per-connection server daemon (10.200.16.10:44724). Sep 12 22:59:04.708035 sshd[3467]: Accepted publickey for core from 10.200.16.10 port 44724 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:04.709254 sshd-session[3467]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:04.713545 systemd-logind[1892]: New session 16 of user core. Sep 12 22:59:04.722967 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 22:59:05.150996 sshd[3470]: Connection closed by 10.200.16.10 port 44724 Sep 12 22:59:05.151359 sshd-session[3467]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:05.154397 systemd[1]: sshd@13-10.200.8.14:22-10.200.16.10:44724.service: Deactivated successfully. Sep 12 22:59:05.156471 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 22:59:05.157941 systemd-logind[1892]: Session 16 logged out. Waiting for processes to exit. Sep 12 22:59:05.159051 systemd-logind[1892]: Removed session 16. Sep 12 22:59:05.275388 systemd[1]: Started sshd@14-10.200.8.14:22-10.200.16.10:44728.service - OpenSSH per-connection server daemon (10.200.16.10:44728). Sep 12 22:59:05.900588 sshd[3476]: Accepted publickey for core from 10.200.16.10 port 44728 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:05.901850 sshd-session[3476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:05.906135 systemd-logind[1892]: New session 17 of user core. Sep 12 22:59:05.920986 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 22:59:06.343786 sshd[3479]: Connection closed by 10.200.16.10 port 44728 Sep 12 22:59:06.344176 sshd-session[3476]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:06.347807 systemd[1]: sshd@14-10.200.8.14:22-10.200.16.10:44728.service: Deactivated successfully. Sep 12 22:59:06.349448 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 22:59:06.350162 systemd-logind[1892]: Session 17 logged out. Waiting for processes to exit. Sep 12 22:59:06.351098 systemd-logind[1892]: Removed session 17. Sep 12 22:59:16.458487 systemd[1]: Started sshd@15-10.200.8.14:22-10.200.16.10:45270.service - OpenSSH per-connection server daemon (10.200.16.10:45270). Sep 12 22:59:17.113827 sshd[6645]: Accepted publickey for core from 10.200.16.10 port 45270 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:17.115301 sshd-session[6645]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:17.123143 systemd-logind[1892]: New session 18 of user core. Sep 12 22:59:17.129920 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 22:59:17.559376 sshd[6834]: Connection closed by 10.200.16.10 port 45270 Sep 12 22:59:17.559996 sshd-session[6645]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:17.563766 systemd-logind[1892]: Session 18 logged out. Waiting for processes to exit. Sep 12 22:59:17.565121 systemd[1]: sshd@15-10.200.8.14:22-10.200.16.10:45270.service: Deactivated successfully. Sep 12 22:59:17.567418 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 22:59:17.570002 systemd-logind[1892]: Removed session 18. Sep 12 22:59:17.672687 systemd[1]: Started sshd@16-10.200.8.14:22-10.200.16.10:45274.service - OpenSSH per-connection server daemon (10.200.16.10:45274). Sep 12 22:59:18.314513 sshd[7038]: Accepted publickey for core from 10.200.16.10 port 45274 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:18.315905 sshd-session[7038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:18.321882 systemd-logind[1892]: New session 19 of user core. Sep 12 22:59:18.329039 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 22:59:18.758029 sshd[7299]: Connection closed by 10.200.16.10 port 45274 Sep 12 22:59:18.758390 sshd-session[7038]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:18.762043 systemd[1]: sshd@16-10.200.8.14:22-10.200.16.10:45274.service: Deactivated successfully. Sep 12 22:59:18.763792 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 22:59:18.764531 systemd-logind[1892]: Session 19 logged out. Waiting for processes to exit. Sep 12 22:59:18.766546 systemd-logind[1892]: Removed session 19. Sep 12 22:59:28.873549 systemd[1]: Started sshd@17-10.200.8.14:22-10.200.16.10:58338.service - OpenSSH per-connection server daemon (10.200.16.10:58338). Sep 12 22:59:29.501400 sshd[10794]: Accepted publickey for core from 10.200.16.10 port 58338 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:29.502878 sshd-session[10794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:29.507591 systemd-logind[1892]: New session 20 of user core. Sep 12 22:59:29.521977 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 22:59:29.949155 sshd[10965]: Connection closed by 10.200.16.10 port 58338 Sep 12 22:59:29.949925 sshd-session[10794]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:29.954078 systemd-logind[1892]: Session 20 logged out. Waiting for processes to exit. Sep 12 22:59:29.954674 systemd[1]: sshd@17-10.200.8.14:22-10.200.16.10:58338.service: Deactivated successfully. Sep 12 22:59:29.957495 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 22:59:29.960689 systemd-logind[1892]: Removed session 20. Sep 12 22:59:30.068552 systemd[1]: Started sshd@18-10.200.8.14:22-10.200.16.10:44692.service - OpenSSH per-connection server daemon (10.200.16.10:44692). Sep 12 22:59:30.713464 sshd[11090]: Accepted publickey for core from 10.200.16.10 port 44692 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:30.714822 sshd-session[11090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:30.723676 systemd-logind[1892]: New session 21 of user core. Sep 12 22:59:30.737062 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 22:59:31.160990 sshd[11321]: Connection closed by 10.200.16.10 port 44692 Sep 12 22:59:31.163363 sshd-session[11090]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:31.176049 systemd-logind[1892]: Session 21 logged out. Waiting for processes to exit. Sep 12 22:59:31.178298 systemd[1]: sshd@18-10.200.8.14:22-10.200.16.10:44692.service: Deactivated successfully. Sep 12 22:59:31.181230 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 22:59:31.183919 systemd-logind[1892]: Removed session 21. Sep 12 22:59:41.279213 systemd[1]: Started sshd@19-10.200.8.14:22-10.200.16.10:58186.service - OpenSSH per-connection server daemon (10.200.16.10:58186). Sep 12 22:59:41.921317 sshd[14159]: Accepted publickey for core from 10.200.16.10 port 58186 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:41.922717 sshd-session[14159]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:41.932482 systemd-logind[1892]: New session 22 of user core. Sep 12 22:59:41.938993 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 22:59:42.366758 sshd[14192]: Connection closed by 10.200.16.10 port 58186 Sep 12 22:59:42.367136 sshd-session[14159]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:42.370545 systemd-logind[1892]: Session 22 logged out. Waiting for processes to exit. Sep 12 22:59:42.373257 systemd[1]: sshd@19-10.200.8.14:22-10.200.16.10:58186.service: Deactivated successfully. Sep 12 22:59:42.375478 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 22:59:42.381426 systemd-logind[1892]: Removed session 22. Sep 12 22:59:42.483192 systemd[1]: Started sshd@20-10.200.8.14:22-10.200.16.10:58188.service - OpenSSH per-connection server daemon (10.200.16.10:58188). Sep 12 22:59:43.123968 sshd[14229]: Accepted publickey for core from 10.200.16.10 port 58188 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:43.125762 sshd-session[14229]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:43.133072 systemd-logind[1892]: New session 23 of user core. Sep 12 22:59:43.143059 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 22:59:43.569582 sshd[14262]: Connection closed by 10.200.16.10 port 58188 Sep 12 22:59:43.575390 sshd-session[14229]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:43.580156 systemd[1]: sshd@20-10.200.8.14:22-10.200.16.10:58188.service: Deactivated successfully. Sep 12 22:59:43.582598 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 22:59:43.583914 systemd-logind[1892]: Session 23 logged out. Waiting for processes to exit. Sep 12 22:59:43.586260 systemd-logind[1892]: Removed session 23. Sep 12 22:59:53.683322 systemd[1]: Started sshd@21-10.200.8.14:22-10.200.16.10:34318.service - OpenSSH per-connection server daemon (10.200.16.10:34318). Sep 12 22:59:54.335388 sshd[14645]: Accepted publickey for core from 10.200.16.10 port 34318 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:54.337181 sshd-session[14645]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:54.343060 systemd-logind[1892]: New session 24 of user core. Sep 12 22:59:54.349924 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 22:59:54.780911 sshd[14673]: Connection closed by 10.200.16.10 port 34318 Sep 12 22:59:54.781281 sshd-session[14645]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:54.785670 systemd-logind[1892]: Session 24 logged out. Waiting for processes to exit. Sep 12 22:59:54.786556 systemd[1]: sshd@21-10.200.8.14:22-10.200.16.10:34318.service: Deactivated successfully. Sep 12 22:59:54.789087 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 22:59:54.792183 systemd-logind[1892]: Removed session 24. Sep 12 22:59:54.894210 systemd[1]: Started sshd@22-10.200.8.14:22-10.200.16.10:34330.service - OpenSSH per-connection server daemon (10.200.16.10:34330). Sep 12 22:59:55.545605 sshd[14697]: Accepted publickey for core from 10.200.16.10 port 34330 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 22:59:55.548050 sshd-session[14697]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 22:59:55.554686 systemd-logind[1892]: New session 25 of user core. Sep 12 22:59:55.565035 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 22:59:55.996248 sshd[14724]: Connection closed by 10.200.16.10 port 34330 Sep 12 22:59:55.995933 sshd-session[14697]: pam_unix(sshd:session): session closed for user core Sep 12 22:59:55.999432 systemd-logind[1892]: Session 25 logged out. Waiting for processes to exit. Sep 12 22:59:56.002224 systemd[1]: sshd@22-10.200.8.14:22-10.200.16.10:34330.service: Deactivated successfully. Sep 12 22:59:56.005618 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 22:59:56.010162 systemd-logind[1892]: Removed session 25. Sep 12 23:00:06.109595 systemd[1]: Started sshd@23-10.200.8.14:22-10.200.16.10:38298.service - OpenSSH per-connection server daemon (10.200.16.10:38298). Sep 12 23:00:06.768242 sshd[15059]: Accepted publickey for core from 10.200.16.10 port 38298 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:06.771276 sshd-session[15059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:06.780672 systemd-logind[1892]: New session 26 of user core. Sep 12 23:00:06.786877 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 23:00:07.214992 sshd[15075]: Connection closed by 10.200.16.10 port 38298 Sep 12 23:00:07.217043 sshd-session[15059]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:07.221826 systemd[1]: sshd@23-10.200.8.14:22-10.200.16.10:38298.service: Deactivated successfully. Sep 12 23:00:07.223434 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 23:00:07.224010 systemd-logind[1892]: Session 26 logged out. Waiting for processes to exit. Sep 12 23:00:07.228461 systemd-logind[1892]: Removed session 26. Sep 12 23:00:07.334263 systemd[1]: Started sshd@24-10.200.8.14:22-10.200.16.10:38314.service - OpenSSH per-connection server daemon (10.200.16.10:38314). Sep 12 23:00:07.984361 sshd[15098]: Accepted publickey for core from 10.200.16.10 port 38314 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:07.985823 sshd-session[15098]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:07.990896 systemd-logind[1892]: New session 27 of user core. Sep 12 23:00:08.000061 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 23:00:08.431693 sshd[15125]: Connection closed by 10.200.16.10 port 38314 Sep 12 23:00:08.432071 sshd-session[15098]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:08.435544 systemd-logind[1892]: Session 27 logged out. Waiting for processes to exit. Sep 12 23:00:08.436896 systemd[1]: sshd@24-10.200.8.14:22-10.200.16.10:38314.service: Deactivated successfully. Sep 12 23:00:08.439291 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 23:00:08.441449 systemd-logind[1892]: Removed session 27. Sep 12 23:00:18.560641 systemd[1]: Started sshd@25-10.200.8.14:22-10.200.16.10:49864.service - OpenSSH per-connection server daemon (10.200.16.10:49864). Sep 12 23:00:19.231374 sshd[15354]: Accepted publickey for core from 10.200.16.10 port 49864 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:19.232607 sshd-session[15354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:19.237033 systemd-logind[1892]: New session 28 of user core. Sep 12 23:00:19.250943 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 23:00:19.252048 setup-nvidia[2627]: \u000d Sep 12 23:00:19.252048 setup-nvidia[2627]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 12 23:00:19.252048 setup-nvidia[2627]: frequently when this kernel module was built against the wrong or Sep 12 23:00:19.252048 setup-nvidia[2627]: improperly configured kernel sources, with a version of gcc that differs Sep 12 23:00:19.252048 setup-nvidia[2627]: from the one used to build the target kernel, or if another driver, such Sep 12 23:00:19.252048 setup-nvidia[2627]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 12 23:00:19.252048 setup-nvidia[2627]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 12 23:00:19.252048 setup-nvidia[2627]: installed in this system is supported by this NVIDIA Linux graphics Sep 12 23:00:19.252048 setup-nvidia[2627]: driver release. Sep 12 23:00:19.252048 setup-nvidia[2627]: \u000d Sep 12 23:00:19.252048 setup-nvidia[2627]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 12 23:00:19.252048 setup-nvidia[2627]: messages' at the end of the file Sep 12 23:00:19.252048 setup-nvidia[2627]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Sep 12 23:00:19.252048 setup-nvidia[2627]: information. Sep 12 23:00:19.252048 setup-nvidia[2627]: \u000d Sep 12 23:00:19.267884 setup-nvidia[2627]: dmesg: read kernel buffer failed: Operation not permitted Sep 12 23:00:19.286639 setup-nvidia[2627]: \u000d Sep 12 23:00:19.286639 setup-nvidia[2627]: ERROR: Installation has failed. Please see the file Sep 12 23:00:19.286639 setup-nvidia[2627]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Sep 12 23:00:19.286639 setup-nvidia[2627]: details. You may find suggestions on fixing installation problems in Sep 12 23:00:19.286639 setup-nvidia[2627]: the README available on the Linux driver download page at Sep 12 23:00:19.286639 setup-nvidia[2627]: www.nvidia.com. Sep 12 23:00:19.286639 setup-nvidia[2627]: \u000d Sep 12 23:00:19.287198 setup-nvidia[2627]: Last 50 lines of nvidia-installer.log: Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 12 23:00:19.288045 setup-nvidia[2627]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Sep 12 23:00:19.288045 setup-nvidia[2627]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Sep 12 23:00:19.288045 setup-nvidia[2627]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 12 23:00:19.288045 setup-nvidia[2627]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 12 23:00:19.288045 setup-nvidia[2627]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 12 23:00:19.288045 setup-nvidia[2627]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 12 23:00:19.288045 setup-nvidia[2627]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 12 23:00:19.288045 setup-nvidia[2627]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Sep 12 23:00:19.288045 setup-nvidia[2627]: make[2]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/build' Sep 12 23:00:19.288045 setup-nvidia[2627]: make[1]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/source' Sep 12 23:00:19.288045 setup-nvidia[2627]: -> done. Sep 12 23:00:19.288045 setup-nvidia[2627]: -> Kernel module compilation complete. Sep 12 23:00:19.288045 setup-nvidia[2627]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 12 23:00:19.288045 setup-nvidia[2627]: 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 23:00:19.288045 setup-nvidia[2627]: \u000d Sep 12 23:00:19.288045 setup-nvidia[2627]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Sep 12 23:00:19.288045 setup-nvidia[2627]: -> Kernel module load error: Operation not permitted Sep 12 23:00:19.288045 setup-nvidia[2627]: -> Kernel messages: Sep 12 23:00:19.289246 setup-nvidia[2627]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/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 23:00:19.674042 sshd[15431]: Connection closed by 10.200.16.10 port 49864 Sep 12 23:00:19.674410 sshd-session[15354]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:19.677989 systemd[1]: sshd@25-10.200.8.14:22-10.200.16.10:49864.service: Deactivated successfully. Sep 12 23:00:19.679906 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 23:00:19.680697 systemd-logind[1892]: Session 28 logged out. Waiting for processes to exit. Sep 12 23:00:19.681681 systemd-logind[1892]: Removed session 28. Sep 12 23:00:19.784915 systemd[1]: Started sshd@26-10.200.8.14:22-10.200.16.10:49880.service - OpenSSH per-connection server daemon (10.200.16.10:49880). Sep 12 23:00:20.434944 sshd[15461]: Accepted publickey for core from 10.200.16.10 port 49880 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:20.436235 sshd-session[15461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:20.440502 systemd-logind[1892]: New session 29 of user core. Sep 12 23:00:20.451966 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 12 23:00:20.877889 sshd[15464]: Connection closed by 10.200.16.10 port 49880 Sep 12 23:00:20.878253 sshd-session[15461]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:20.881767 systemd[1]: sshd@26-10.200.8.14:22-10.200.16.10:49880.service: Deactivated successfully. Sep 12 23:00:20.883349 systemd[1]: session-29.scope: Deactivated successfully. Sep 12 23:00:20.884033 systemd-logind[1892]: Session 29 logged out. Waiting for processes to exit. Sep 12 23:00:20.884919 systemd-logind[1892]: Removed session 29. Sep 12 23:00:30.995484 systemd[1]: Started sshd@27-10.200.8.14:22-10.200.16.10:40548.service - OpenSSH per-connection server daemon (10.200.16.10:40548). Sep 12 23:00:31.621579 sshd[15470]: Accepted publickey for core from 10.200.16.10 port 40548 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:31.622911 sshd-session[15470]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:31.627259 systemd-logind[1892]: New session 30 of user core. Sep 12 23:00:31.636970 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 12 23:00:32.066820 sshd[15473]: Connection closed by 10.200.16.10 port 40548 Sep 12 23:00:32.067133 sshd-session[15470]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:32.070822 systemd[1]: sshd@27-10.200.8.14:22-10.200.16.10:40548.service: Deactivated successfully. Sep 12 23:00:32.072446 systemd[1]: session-30.scope: Deactivated successfully. Sep 12 23:00:32.073144 systemd-logind[1892]: Session 30 logged out. Waiting for processes to exit. Sep 12 23:00:32.074034 systemd-logind[1892]: Removed session 30. Sep 12 23:00:32.181646 systemd[1]: Started sshd@28-10.200.8.14:22-10.200.16.10:40564.service - OpenSSH per-connection server daemon (10.200.16.10:40564). Sep 12 23:00:32.811714 sshd[15479]: Accepted publickey for core from 10.200.16.10 port 40564 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:32.814305 sshd-session[15479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:32.818585 systemd-logind[1892]: New session 31 of user core. Sep 12 23:00:32.833964 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 12 23:00:33.253909 sshd[15482]: Connection closed by 10.200.16.10 port 40564 Sep 12 23:00:33.254295 sshd-session[15479]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:33.257859 systemd[1]: sshd@28-10.200.8.14:22-10.200.16.10:40564.service: Deactivated successfully. Sep 12 23:00:33.259469 systemd[1]: session-31.scope: Deactivated successfully. Sep 12 23:00:33.260141 systemd-logind[1892]: Session 31 logged out. Waiting for processes to exit. Sep 12 23:00:33.261032 systemd-logind[1892]: Removed session 31. Sep 12 23:00:43.369351 systemd[1]: Started sshd@29-10.200.8.14:22-10.200.16.10:35556.service - OpenSSH per-connection server daemon (10.200.16.10:35556). Sep 12 23:00:43.997282 sshd[15489]: Accepted publickey for core from 10.200.16.10 port 35556 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:43.998580 sshd-session[15489]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:44.002966 systemd-logind[1892]: New session 32 of user core. Sep 12 23:00:44.015960 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 12 23:00:44.441250 sshd[15492]: Connection closed by 10.200.16.10 port 35556 Sep 12 23:00:44.441641 sshd-session[15489]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:44.445198 systemd[1]: sshd@29-10.200.8.14:22-10.200.16.10:35556.service: Deactivated successfully. Sep 12 23:00:44.446780 systemd[1]: session-32.scope: Deactivated successfully. Sep 12 23:00:44.447471 systemd-logind[1892]: Session 32 logged out. Waiting for processes to exit. Sep 12 23:00:44.448584 systemd-logind[1892]: Removed session 32. Sep 12 23:00:44.507861 kernel: EXT4-fs (loop6p9): unmounting filesystem 57ec9fcf-b174-433d-9de4-0c1e47a24cd6. Sep 12 23:00:44.508394 setup-nvidia[2627]: Container flatcardevelopercontainer-4459.0.0.bin exited successfully. Sep 12 23:00:44.510687 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.0.0.bin.mount: Deactivated successfully. Sep 12 23:00:44.555567 systemd[1]: Started sshd@30-10.200.8.14:22-10.200.16.10:35558.service - OpenSSH per-connection server daemon (10.200.16.10:35558). Sep 12 23:00:44.555714 sudo[2615]: pam_unix(sudo:session): session closed for user root Sep 12 23:00:44.558134 systemd[1]: session-c2.scope: Deactivated successfully. Sep 12 23:00:44.558392 systemd[1]: session-c2.scope: Consumed 5min 20.179s CPU time, 889.2M memory peak. Sep 12 23:00:44.980611 setup-nvidia[1886]: /opt/nvidia / Sep 12 23:00:44.981595 setup-nvidia[1886]: / Sep 12 23:00:44.990860 kernel: IPMI message handler: version 39.2 Sep 12 23:00:44.994860 kernel: ipmi device interface Sep 12 23:00:45.007153 setup-nvidia[1886]: /opt/nvidia/current/usr/lib/modules/6.12.47-flatcar/video / Sep 12 23:00:45.065488 kernel: nvidia: loading out-of-tree module taints kernel. Sep 12 23:00:45.065776 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 12 23:00:45.065813 kernel: Disabling lock debugging due to kernel taint Sep 12 23:00:45.072690 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 12 23:00:45.072761 kernel: nvidia: module license taints kernel. Sep 12 23:00:45.203712 sshd[15500]: Accepted publickey for core from 10.200.16.10 port 35558 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:45.205014 sshd-session[15500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:45.209452 systemd-logind[1892]: New session 33 of user core. Sep 12 23:00:45.220966 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 12 23:00:45.470868 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 12 23:00:45.743359 sshd[15526]: Connection closed by 10.200.16.10 port 35558 Sep 12 23:00:45.743639 sshd-session[15500]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:45.747240 systemd[1]: sshd@30-10.200.8.14:22-10.200.16.10:35558.service: Deactivated successfully. Sep 12 23:00:45.748820 systemd[1]: session-33.scope: Deactivated successfully. Sep 12 23:00:45.749542 systemd-logind[1892]: Session 33 logged out. Waiting for processes to exit. Sep 12 23:00:45.750468 systemd-logind[1892]: Removed session 33. Sep 12 23:00:45.869883 kernel: Sep 12 23:00:45.870014 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 12 23:00:46.079867 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Sep 12 23:00:46.100862 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Sep 12 23:00:46.120881 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 12 23:00:46.169860 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 12 23:00:46.170255 setup-nvidia[1886]: / Sep 12 23:00:46.185602 setup-nvidia[15540]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 23:00:47.992640 setup-nvidia[15557]: Fri Sep 12 23:00:47 2025 Sep 12 23:00:47.992640 setup-nvidia[15557]: +---------------------------------------------------------------------------------------+ Sep 12 23:00:47.992640 setup-nvidia[15557]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Sep 12 23:00:47.992640 setup-nvidia[15557]: |-----------------------------------------+----------------------+----------------------+ Sep 12 23:00:47.992640 setup-nvidia[15557]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 12 23:00:47.992640 setup-nvidia[15557]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 12 23:00:47.992640 setup-nvidia[15557]: | | | MIG M. | Sep 12 23:00:47.992640 setup-nvidia[15557]: |=========================================+======================+======================| Sep 12 23:00:47.997771 setup-nvidia[15557]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 12 23:00:47.997771 setup-nvidia[15557]: | N/A 25C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Sep 12 23:00:47.997771 setup-nvidia[15557]: | | | N/A | Sep 12 23:00:47.997771 setup-nvidia[15557]: +-----------------------------------------+----------------------+----------------------+ Sep 12 23:00:47.997771 setup-nvidia[15557]: Sep 12 23:00:47.997771 setup-nvidia[15557]: +---------------------------------------------------------------------------------------+ Sep 12 23:00:47.997771 setup-nvidia[15557]: | Processes: | Sep 12 23:00:47.997771 setup-nvidia[15557]: | GPU GI CI PID Type Process name GPU Memory | Sep 12 23:00:47.997771 setup-nvidia[15557]: | ID ID Usage | Sep 12 23:00:47.997771 setup-nvidia[15557]: |=======================================================================================| Sep 12 23:00:47.998026 setup-nvidia[15557]: | No running processes found | Sep 12 23:00:47.998026 setup-nvidia[15557]: +---------------------------------------------------------------------------------------+ Sep 12 23:00:48.367160 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 23:00:48.371979 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 23:00:48.376505 systemd[1]: Startup finished in 1.130s (firmware) + 25.159s (loader) + 3.760s (kernel) + 13.211s (initrd) + 4min 2.279s (userspace) = 4min 45.540s. Sep 12 23:00:54.593728 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 12 23:00:54.594526 systemd[2618]: Activating special unit exit.target... Sep 12 23:00:54.594610 systemd[2618]: Stopped target default.target - Main User Target. Sep 12 23:00:54.594650 systemd[2618]: Stopped target basic.target - Basic System. Sep 12 23:00:54.594679 systemd[2618]: Stopped target paths.target - Paths. Sep 12 23:00:54.594706 systemd[2618]: Stopped target sockets.target - Sockets. Sep 12 23:00:54.594732 systemd[2618]: Stopped target timers.target - Timers. Sep 12 23:00:54.595092 systemd[2618]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 12 23:00:54.595301 systemd[2618]: Removed slice app.slice - User Application Slice. Sep 12 23:00:54.595333 systemd[2618]: Reached target shutdown.target - Shutdown. Sep 12 23:00:54.595470 systemd[2618]: Finished systemd-exit.service - Exit the Session. Sep 12 23:00:54.595506 systemd[2618]: Reached target exit.target - Exit the Session. Sep 12 23:00:54.608985 (sd-pam)[2619]: pam_unix(systemd-user:session): session closed for user root Sep 12 23:00:54.610132 systemd[1]: user@0.service: Deactivated successfully. Sep 12 23:00:54.610571 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 12 23:00:54.613053 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 23:00:54.642234 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 12 23:00:54.643108 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 12 23:00:54.643286 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 23:00:54.644720 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 12 23:00:54.644808 systemd[1]: user-0.slice: Consumed 5min 20.400s CPU time, 895.2M memory peak. Sep 12 23:00:54.645267 systemd-logind[1892]: Removed session c3. Sep 12 23:00:55.858319 systemd[1]: Started sshd@31-10.200.8.14:22-10.200.16.10:54366.service - OpenSSH per-connection server daemon (10.200.16.10:54366). Sep 12 23:00:56.506317 sshd[15568]: Accepted publickey for core from 10.200.16.10 port 54366 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:56.507744 sshd-session[15568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:56.512161 systemd-logind[1892]: New session 34 of user core. Sep 12 23:00:56.520983 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 12 23:00:56.952360 sshd[15571]: Connection closed by 10.200.16.10 port 54366 Sep 12 23:00:56.952755 sshd-session[15568]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:56.956294 systemd[1]: sshd@31-10.200.8.14:22-10.200.16.10:54366.service: Deactivated successfully. Sep 12 23:00:56.957887 systemd[1]: session-34.scope: Deactivated successfully. Sep 12 23:00:56.958557 systemd-logind[1892]: Session 34 logged out. Waiting for processes to exit. Sep 12 23:00:56.959456 systemd-logind[1892]: Removed session 34. Sep 12 23:00:57.076941 systemd[1]: Started sshd@32-10.200.8.14:22-10.200.16.10:54370.service - OpenSSH per-connection server daemon (10.200.16.10:54370). Sep 12 23:00:57.709133 sshd[15577]: Accepted publickey for core from 10.200.16.10 port 54370 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:57.710391 sshd-session[15577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:57.714616 systemd-logind[1892]: New session 35 of user core. Sep 12 23:00:57.724968 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 12 23:00:58.149402 sshd[15580]: Connection closed by 10.200.16.10 port 54370 Sep 12 23:00:58.149795 sshd-session[15577]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:58.153413 systemd[1]: sshd@32-10.200.8.14:22-10.200.16.10:54370.service: Deactivated successfully. Sep 12 23:00:58.155052 systemd[1]: session-35.scope: Deactivated successfully. Sep 12 23:00:58.155718 systemd-logind[1892]: Session 35 logged out. Waiting for processes to exit. Sep 12 23:00:58.156767 systemd-logind[1892]: Removed session 35. Sep 12 23:00:58.264200 systemd[1]: Started sshd@33-10.200.8.14:22-10.200.16.10:54382.service - OpenSSH per-connection server daemon (10.200.16.10:54382). Sep 12 23:00:58.892395 sshd[15586]: Accepted publickey for core from 10.200.16.10 port 54382 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:00:58.893661 sshd-session[15586]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:00:58.897971 systemd-logind[1892]: New session 36 of user core. Sep 12 23:00:58.913980 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 12 23:00:59.337689 sshd[15589]: Connection closed by 10.200.16.10 port 54382 Sep 12 23:00:59.338005 sshd-session[15586]: pam_unix(sshd:session): session closed for user core Sep 12 23:00:59.341560 systemd[1]: sshd@33-10.200.8.14:22-10.200.16.10:54382.service: Deactivated successfully. Sep 12 23:00:59.343128 systemd[1]: session-36.scope: Deactivated successfully. Sep 12 23:00:59.343776 systemd-logind[1892]: Session 36 logged out. Waiting for processes to exit. Sep 12 23:00:59.344690 systemd-logind[1892]: Removed session 36. Sep 12 23:00:59.452298 systemd[1]: Started sshd@34-10.200.8.14:22-10.200.16.10:54386.service - OpenSSH per-connection server daemon (10.200.16.10:54386). Sep 12 23:01:00.078950 sshd[15595]: Accepted publickey for core from 10.200.16.10 port 54386 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:01:00.081881 sshd-session[15595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:01:00.086226 systemd-logind[1892]: New session 37 of user core. Sep 12 23:01:00.096987 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 12 23:01:00.525312 sshd[15598]: Connection closed by 10.200.16.10 port 54386 Sep 12 23:01:00.525700 sshd-session[15595]: pam_unix(sshd:session): session closed for user core Sep 12 23:01:00.529504 systemd[1]: sshd@34-10.200.8.14:22-10.200.16.10:54386.service: Deactivated successfully. Sep 12 23:01:00.531124 systemd[1]: session-37.scope: Deactivated successfully. Sep 12 23:01:00.531793 systemd-logind[1892]: Session 37 logged out. Waiting for processes to exit. Sep 12 23:01:00.532669 systemd-logind[1892]: Removed session 37. Sep 12 23:01:00.640182 systemd[1]: Started sshd@35-10.200.8.14:22-10.200.16.10:38542.service - OpenSSH per-connection server daemon (10.200.16.10:38542). Sep 12 23:01:01.265787 sshd[15604]: Accepted publickey for core from 10.200.16.10 port 38542 ssh2: RSA SHA256:ByZITjcT+ScCWP5udD9H496+eYaxds6ili5aJi1e52Q Sep 12 23:01:01.267090 sshd-session[15604]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 23:01:01.271283 systemd-logind[1892]: New session 38 of user core. Sep 12 23:01:01.286974 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 12 23:01:03.223337 sshd[15607]: Connection closed by 10.200.16.10 port 38542 Sep 12 23:01:03.223746 sshd-session[15604]: pam_unix(sshd:session): session closed for user core Sep 12 23:01:03.227388 systemd[1]: sshd@35-10.200.8.14:22-10.200.16.10:38542.service: Deactivated successfully. Sep 12 23:01:03.229065 systemd[1]: session-38.scope: Deactivated successfully. Sep 12 23:01:03.229269 systemd[1]: session-38.scope: Consumed 1.454s CPU time, 14M memory peak. Sep 12 23:01:03.229786 systemd-logind[1892]: Session 38 logged out. Waiting for processes to exit. Sep 12 23:01:03.230710 systemd-logind[1892]: Removed session 38.