Oct 13 05:34:22.780086 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Mon Oct 13 03:31:29 -00 2025 Oct 13 05:34:22.780115 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d Oct 13 05:34:22.780125 kernel: BIOS-provided physical RAM map: Oct 13 05:34:22.780133 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 13 05:34:22.780140 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 13 05:34:22.780147 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 13 05:34:22.780158 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 13 05:34:22.780165 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 13 05:34:22.780173 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 13 05:34:22.780180 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 13 05:34:22.780187 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 13 05:34:22.780194 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 13 05:34:22.780202 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 13 05:34:22.780209 kernel: NX (Execute Disable) protection: active Oct 13 05:34:22.780220 kernel: APIC: Static calls initialized Oct 13 05:34:22.780229 kernel: efi: EFI v2.7 by Microsoft Oct 13 05:34:22.780237 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 13 05:34:22.780245 kernel: random: crng init done Oct 13 05:34:22.780253 kernel: secureboot: Secure boot disabled Oct 13 05:34:22.780261 kernel: SMBIOS 3.1.0 present. Oct 13 05:34:22.780270 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 13 05:34:22.780280 kernel: DMI: Memory slots populated: 3/3 Oct 13 05:34:22.780288 kernel: Hypervisor detected: Microsoft Hyper-V Oct 13 05:34:22.780296 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 13 05:34:22.780304 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 13 05:34:22.780312 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 13 05:34:22.780320 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:34:22.780329 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:34:22.780338 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 13 05:34:22.780347 kernel: tsc: Detected 2593.993 MHz processor Oct 13 05:34:22.780358 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 13 05:34:22.780367 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 13 05:34:22.780375 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 13 05:34:22.780383 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 13 05:34:22.780392 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 13 05:34:22.780400 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 13 05:34:22.780409 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 13 05:34:22.780419 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 13 05:34:22.780428 kernel: Using GB pages for direct mapping Oct 13 05:34:22.780436 kernel: ACPI: Early table checksum verification disabled Oct 13 05:34:22.780448 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 13 05:34:22.780457 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780466 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780478 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 13 05:34:22.780486 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 13 05:34:22.780495 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780504 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780529 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780540 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780552 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780561 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780570 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:34:22.780579 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 13 05:34:22.780588 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 13 05:34:22.780597 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 13 05:34:22.780608 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 13 05:34:22.780617 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 13 05:34:22.780625 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 13 05:34:22.780634 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 13 05:34:22.780643 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 13 05:34:22.780652 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 13 05:34:22.780661 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 13 05:34:22.780672 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 05:34:22.780681 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 13 05:34:22.780690 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 13 05:34:22.780698 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 13 05:34:22.780708 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 13 05:34:22.780716 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 13 05:34:22.780725 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 13 05:34:22.780737 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 13 05:34:22.780746 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 13 05:34:22.780755 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 13 05:34:22.780763 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 13 05:34:22.780772 kernel: Zone ranges: Oct 13 05:34:22.780781 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 13 05:34:22.780790 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 13 05:34:22.780801 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 13 05:34:22.780810 kernel: Device empty Oct 13 05:34:22.780819 kernel: Movable zone start for each node Oct 13 05:34:22.780828 kernel: Early memory node ranges Oct 13 05:34:22.780837 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 13 05:34:22.780845 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 13 05:34:22.780854 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 13 05:34:22.780863 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 13 05:34:22.780874 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 13 05:34:22.780883 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 13 05:34:22.780892 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 13 05:34:22.780900 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 13 05:34:22.780909 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 13 05:34:22.780918 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 13 05:34:22.780927 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 13 05:34:22.780942 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 13 05:34:22.780950 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 13 05:34:22.780959 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 13 05:34:22.780968 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 13 05:34:22.780977 kernel: CPU topo: Max. logical packages: 1 Oct 13 05:34:22.780985 kernel: CPU topo: Max. logical dies: 1 Oct 13 05:34:22.780994 kernel: CPU topo: Max. dies per package: 1 Oct 13 05:34:22.781005 kernel: CPU topo: Max. threads per core: 1 Oct 13 05:34:22.781014 kernel: CPU topo: Num. cores per package: 6 Oct 13 05:34:22.781023 kernel: CPU topo: Num. threads per package: 6 Oct 13 05:34:22.781032 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 13 05:34:22.781041 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 13 05:34:22.781049 kernel: Booting paravirtualized kernel on Hyper-V Oct 13 05:34:22.781058 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 13 05:34:22.781070 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 13 05:34:22.781079 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 13 05:34:22.781087 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 13 05:34:22.781096 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 13 05:34:22.781105 kernel: Hyper-V: PV spinlocks enabled Oct 13 05:34:22.781114 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 13 05:34:22.781124 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d Oct 13 05:34:22.781136 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 05:34:22.781145 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 13 05:34:22.781153 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 13 05:34:22.781162 kernel: Fallback order for Node 0: 0 Oct 13 05:34:22.781171 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 13 05:34:22.781180 kernel: Policy zone: Normal Oct 13 05:34:22.781199 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 05:34:22.781208 kernel: software IO TLB: area num 8. Oct 13 05:34:22.781217 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 13 05:34:22.781231 kernel: Kernel/User page tables isolation: enabled Oct 13 05:34:22.781241 kernel: ftrace: allocating 40210 entries in 158 pages Oct 13 05:34:22.781251 kernel: ftrace: allocated 158 pages with 5 groups Oct 13 05:34:22.781260 kernel: Dynamic Preempt: voluntary Oct 13 05:34:22.781269 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 05:34:22.781279 kernel: rcu: RCU event tracing is enabled. Oct 13 05:34:22.781289 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 13 05:34:22.781301 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 05:34:22.781310 kernel: Rude variant of Tasks RCU enabled. Oct 13 05:34:22.781320 kernel: Tracing variant of Tasks RCU enabled. Oct 13 05:34:22.781329 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 05:34:22.781341 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 13 05:34:22.781351 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:34:22.781360 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:34:22.781369 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:34:22.781379 kernel: Using NULL legacy PIC Oct 13 05:34:22.781388 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 13 05:34:22.781397 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 05:34:22.781409 kernel: Console: colour dummy device 80x25 Oct 13 05:34:22.781418 kernel: printk: legacy console [tty1] enabled Oct 13 05:34:22.781427 kernel: printk: legacy console [ttyS0] enabled Oct 13 05:34:22.781437 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 13 05:34:22.781446 kernel: ACPI: Core revision 20240827 Oct 13 05:34:22.781455 kernel: Failed to register legacy timer interrupt Oct 13 05:34:22.781464 kernel: APIC: Switch to symmetric I/O mode setup Oct 13 05:34:22.781476 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 13 05:34:22.781486 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 05:34:22.781495 kernel: Hyper-V: Using IPI hypercalls Oct 13 05:34:22.781504 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 13 05:34:22.781520 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 13 05:34:22.781531 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 13 05:34:22.781540 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 13 05:34:22.781552 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 13 05:34:22.781562 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 13 05:34:22.781571 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 13 05:34:22.781580 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 13 05:34:22.781590 kernel: APIC: read() replaced with hv_apic_read() Oct 13 05:34:22.781599 kernel: APIC: write() replaced with hv_apic_write() Oct 13 05:34:22.781608 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 13 05:34:22.781620 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 13 05:34:22.781629 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 13 05:34:22.781639 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 13 05:34:22.781648 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 13 05:34:22.781657 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 13 05:34:22.781666 kernel: Spectre V2 : Mitigation: Retpolines Oct 13 05:34:22.781675 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 13 05:34:22.781683 kernel: Speculative Store Bypass: Vulnerable Oct 13 05:34:22.781693 kernel: MDS: Mitigation: Clear CPU buffers Oct 13 05:34:22.781704 kernel: TAA: Mitigation: Clear CPU buffers Oct 13 05:34:22.781713 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 13 05:34:22.781721 kernel: active return thunk: its_return_thunk Oct 13 05:34:22.781730 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 13 05:34:22.781739 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 13 05:34:22.781748 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 13 05:34:22.781757 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 13 05:34:22.781766 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 13 05:34:22.781775 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 13 05:34:22.781786 kernel: Freeing SMP alternatives memory: 32K Oct 13 05:34:22.781851 kernel: pid_max: default: 32768 minimum: 301 Oct 13 05:34:22.781861 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 13 05:34:22.781870 kernel: landlock: Up and running. Oct 13 05:34:22.781879 kernel: SELinux: Initializing. Oct 13 05:34:22.781888 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:34:22.781897 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:34:22.781906 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 13 05:34:22.781916 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 13 05:34:22.781925 kernel: signal: max sigframe size: 1776 Oct 13 05:34:22.781939 kernel: rcu: Hierarchical SRCU implementation. Oct 13 05:34:22.781949 kernel: rcu: Max phase no-delay instances is 400. Oct 13 05:34:22.781958 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 13 05:34:22.781967 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 13 05:34:22.781977 kernel: smp: Bringing up secondary CPUs ... Oct 13 05:34:22.781986 kernel: smpboot: x86: Booting SMP configuration: Oct 13 05:34:22.781995 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 13 05:34:22.782007 kernel: smp: Brought up 1 node, 6 CPUs Oct 13 05:34:22.782016 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 13 05:34:22.782026 kernel: Memory: 115369800K/117439364K available (14336K kernel code, 2450K rwdata, 10012K rodata, 24532K init, 1684K bss, 2059284K reserved, 0K cma-reserved) Oct 13 05:34:22.782036 kernel: devtmpfs: initialized Oct 13 05:34:22.782045 kernel: x86/mm: Memory block size: 1024MB Oct 13 05:34:22.782054 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 13 05:34:22.782064 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 05:34:22.782075 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 13 05:34:22.782085 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 05:34:22.782094 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 05:34:22.782103 kernel: audit: initializing netlink subsys (disabled) Oct 13 05:34:22.782113 kernel: audit: type=2000 audit(1760333656.028:1): state=initialized audit_enabled=0 res=1 Oct 13 05:34:22.782122 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 05:34:22.782131 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 13 05:34:22.782142 kernel: cpuidle: using governor menu Oct 13 05:34:22.782152 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 05:34:22.782161 kernel: dca service started, version 1.12.1 Oct 13 05:34:22.782170 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 13 05:34:22.782179 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 05:34:22.782189 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 05:34:22.782198 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 05:34:22.782208 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 05:34:22.782219 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 05:34:22.782229 kernel: ACPI: Added _OSI(Module Device) Oct 13 05:34:22.782238 kernel: ACPI: Added _OSI(Processor Device) Oct 13 05:34:22.782247 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 05:34:22.782256 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 05:34:22.782266 kernel: ACPI: Interpreter enabled Oct 13 05:34:22.782275 kernel: ACPI: PM: (supports S0 S5) Oct 13 05:34:22.782286 kernel: ACPI: Using IOAPIC for interrupt routing Oct 13 05:34:22.782296 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 13 05:34:22.782305 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 13 05:34:22.782314 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 13 05:34:22.782323 kernel: iommu: Default domain type: Translated Oct 13 05:34:22.782333 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 13 05:34:22.782342 kernel: efivars: Registered efivars operations Oct 13 05:34:22.782353 kernel: PCI: Using ACPI for IRQ routing Oct 13 05:34:22.782363 kernel: PCI: System does not support PCI Oct 13 05:34:22.782372 kernel: vgaarb: loaded Oct 13 05:34:22.782381 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 13 05:34:22.782391 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 05:34:22.782400 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 05:34:22.782409 kernel: pnp: PnP ACPI init Oct 13 05:34:22.782421 kernel: pnp: PnP ACPI: found 3 devices Oct 13 05:34:22.782431 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 13 05:34:22.782440 kernel: NET: Registered PF_INET protocol family Oct 13 05:34:22.782449 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 05:34:22.782458 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 13 05:34:22.782468 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 05:34:22.782477 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 05:34:22.782489 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:34:22.782498 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 13 05:34:22.782507 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:34:22.782528 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:34:22.782538 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 05:34:22.782547 kernel: NET: Registered PF_XDP protocol family Oct 13 05:34:22.782557 kernel: PCI: CLS 0 bytes, default 64 Oct 13 05:34:22.782569 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 13 05:34:22.782578 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 13 05:34:22.782587 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 13 05:34:22.782597 kernel: Initialise system trusted keyrings Oct 13 05:34:22.782606 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 13 05:34:22.782615 kernel: Key type asymmetric registered Oct 13 05:34:22.782624 kernel: Asymmetric key parser 'x509' registered Oct 13 05:34:22.782636 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 13 05:34:22.782645 kernel: io scheduler mq-deadline registered Oct 13 05:34:22.782654 kernel: io scheduler kyber registered Oct 13 05:34:22.782663 kernel: io scheduler bfq registered Oct 13 05:34:22.782672 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 13 05:34:22.782682 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 05:34:22.782691 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 13 05:34:22.782703 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 13 05:34:22.782712 kernel: i8042: PNP: No PS/2 controller found. Oct 13 05:34:22.782904 kernel: rtc_cmos 00:02: registered as rtc0 Oct 13 05:34:22.783045 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T05:34:17 UTC (1760333657) Oct 13 05:34:22.783178 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 13 05:34:22.783192 kernel: intel_pstate: CPU model not supported Oct 13 05:34:22.783206 kernel: efifb: probing for efifb Oct 13 05:34:22.783216 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 05:34:22.783225 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 05:34:22.783234 kernel: efifb: scrolling: redraw Oct 13 05:34:22.783244 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 05:34:22.783253 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:34:22.783262 kernel: fb0: EFI VGA frame buffer device Oct 13 05:34:22.783274 kernel: pstore: Using crash dump compression: deflate Oct 13 05:34:22.783284 kernel: pstore: Registered efi_pstore as persistent store backend Oct 13 05:34:22.783293 kernel: NET: Registered PF_INET6 protocol family Oct 13 05:34:22.783302 kernel: Segment Routing with IPv6 Oct 13 05:34:22.783312 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 05:34:22.783321 kernel: NET: Registered PF_PACKET protocol family Oct 13 05:34:22.783330 kernel: Key type dns_resolver registered Oct 13 05:34:22.783340 kernel: IPI shorthand broadcast: enabled Oct 13 05:34:22.783352 kernel: sched_clock: Marking stable (2167004300, 117370900)->(2643408800, -359033600) Oct 13 05:34:22.783361 kernel: registered taskstats version 1 Oct 13 05:34:22.783370 kernel: Loading compiled-in X.509 certificates Oct 13 05:34:22.783380 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: 9f1258ccc510afd4f2a37f4774c4b2e958d823b7' Oct 13 05:34:22.783389 kernel: Demotion targets for Node 0: null Oct 13 05:34:22.783398 kernel: Key type .fscrypt registered Oct 13 05:34:22.783408 kernel: Key type fscrypt-provisioning registered Oct 13 05:34:22.783419 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 05:34:22.783429 kernel: ima: Allocated hash algorithm: sha1 Oct 13 05:34:22.783438 kernel: ima: No architecture policies found Oct 13 05:34:22.783447 kernel: clk: Disabling unused clocks Oct 13 05:34:22.783457 kernel: Freeing unused kernel image (initmem) memory: 24532K Oct 13 05:34:22.783466 kernel: Write protecting the kernel read-only data: 24576k Oct 13 05:34:22.783475 kernel: Freeing unused kernel image (rodata/data gap) memory: 228K Oct 13 05:34:22.783487 kernel: Run /init as init process Oct 13 05:34:22.783497 kernel: with arguments: Oct 13 05:34:22.783506 kernel: /init Oct 13 05:34:22.783527 kernel: with environment: Oct 13 05:34:22.783537 kernel: HOME=/ Oct 13 05:34:22.783546 kernel: TERM=linux Oct 13 05:34:22.783555 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 05:34:22.783567 kernel: hv_vmbus: Vmbus version:4.0 Oct 13 05:34:22.783577 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.783587 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.783596 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 05:34:22.783605 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 05:34:22.783614 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Oct 13 05:34:22.783776 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 05:34:22.783795 kernel: SCSI subsystem initialized Oct 13 05:34:22.783805 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.783814 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 05:34:22.783858 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Oct 13 05:34:22.783871 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 05:34:22.783880 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 05:34:22.783890 kernel: PTP clock support registered Oct 13 05:34:22.783904 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 05:34:22.783913 kernel: hv_vmbus: registering driver hv_utils Oct 13 05:34:22.783922 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 05:34:22.783931 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 05:34:22.783941 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 05:34:22.783950 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 05:34:22.784143 kernel: scsi host0: storvsc_host_t Oct 13 05:34:22.784311 kernel: scsi host1: storvsc_host_t Oct 13 05:34:22.784482 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:34:22.784681 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:34:22.784858 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 13 05:34:22.785018 kernel: sd 0:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Oct 13 05:34:22.785182 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 13 05:34:22.785338 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 13 05:34:22.785494 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Oct 13 05:34:22.785673 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 05:34:22.785833 kernel: sd 0:0:0:1: [sdb] Write Protect is off Oct 13 05:34:22.785989 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 13 05:34:22.786748 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 13 05:34:22.786914 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:34:22.787072 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:34:22.787236 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#197 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:22.787389 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#324 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:22.787552 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#331 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:22.787699 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#204 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:22.787713 kernel: sdb: sdb1 Oct 13 05:34:22.787868 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Oct 13 05:34:22.787882 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:34:22.788040 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 05:34:22.788059 kernel: hv_vmbus: registering driver hv_pci Oct 13 05:34:22.788233 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 13 05:34:22.788401 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 13 05:34:22.788583 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 13 05:34:22.788733 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 13 05:34:22.788885 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 05:34:22.789066 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 13 05:34:22.789225 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 13 05:34:22.789382 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 05:34:22.789549 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 05:34:22.789714 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 13 05:34:22.789729 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 05:34:22.789740 kernel: device-mapper: uevent: version 1.0.3 Oct 13 05:34:22.789750 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 13 05:34:22.789892 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 05:34:22.790047 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 13 05:34:22.790202 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 13 05:34:22.790411 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 13 05:34:22.790430 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Oct 13 05:34:22.790441 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790451 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790634 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 05:34:22.790651 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 05:34:22.790808 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 05:34:22.790827 kernel: raid6: avx2x4 gen() 30218 MB/s Oct 13 05:34:22.790837 kernel: raid6: avx2x2 gen() 31255 MB/s Oct 13 05:34:22.790847 kernel: raid6: avx2x1 gen() 27854 MB/s Oct 13 05:34:22.790865 kernel: raid6: using algorithm avx2x2 gen() 31255 MB/s Oct 13 05:34:22.790876 kernel: raid6: .... xor() 18511 MB/s, rmw enabled Oct 13 05:34:22.790885 kernel: raid6: using avx2x2 recovery algorithm Oct 13 05:34:22.790896 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790914 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790924 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790942 kernel: xor: automatically using best checksumming function avx Oct 13 05:34:22.790953 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.790962 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 05:34:22.790972 kernel: BTRFS: device fsid e87b15e9-127c-40e2-bae7-d0ea05b4f2e3 devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (481) Oct 13 05:34:22.790982 kernel: BTRFS info (device dm-0): first mount of filesystem e87b15e9-127c-40e2-bae7-d0ea05b4f2e3 Oct 13 05:34:22.790995 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:34:22.791005 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 05:34:22.791015 kernel: BTRFS info (device dm-0): enabling free space tree Oct 13 05:34:22.791025 kernel: Invalid ELF header magic: != \u007fELF Oct 13 05:34:22.791040 kernel: loop: module loaded Oct 13 05:34:22.791050 kernel: loop0: detected capacity change from 0 to 100048 Oct 13 05:34:22.791060 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 05:34:22.791071 systemd[1]: Successfully made /usr/ read-only. Oct 13 05:34:22.791087 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 05:34:22.791098 systemd[1]: Detected virtualization microsoft. Oct 13 05:34:22.791108 systemd[1]: Detected architecture x86-64. Oct 13 05:34:22.791118 systemd[1]: Running in initrd. Oct 13 05:34:22.791128 systemd[1]: No hostname configured, using default hostname. Oct 13 05:34:22.791141 systemd[1]: Hostname set to . Oct 13 05:34:22.791153 systemd[1]: Initializing machine ID from random generator. Oct 13 05:34:22.791163 systemd[1]: Queued start job for default target initrd.target. Oct 13 05:34:22.791174 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 13 05:34:22.791184 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:34:22.791194 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:34:22.791204 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 05:34:22.791217 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:34:22.791228 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 05:34:22.791239 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 05:34:22.791249 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:34:22.791262 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:34:22.791272 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:34:22.791283 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:34:22.791293 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:34:22.791303 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:34:22.791313 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:34:22.791326 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:34:22.791336 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:34:22.791346 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 05:34:22.791357 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 05:34:22.791367 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:34:22.791377 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:34:22.791390 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:34:22.791400 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:34:22.791411 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 05:34:22.791421 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 05:34:22.791431 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:34:22.791441 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 05:34:22.791452 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). Oct 13 05:34:22.791465 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 05:34:22.791475 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:34:22.791486 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:34:22.791496 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:34:22.791540 systemd-journald[618]: Collecting audit messages is disabled. Oct 13 05:34:22.791569 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 05:34:22.791580 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:34:22.791594 systemd-journald[618]: Journal started Oct 13 05:34:22.791614 systemd-journald[618]: Runtime Journal (/run/log/journal/d0be9e58a9e1420aaa97724dfee2af49) is 8M, max 2.2G, 2.1G free. Oct 13 05:34:22.803542 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:34:22.803676 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 05:34:22.805681 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 05:34:22.806885 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:34:22.895537 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 13 05:34:22.905309 systemd-modules-load[621]: Inserted module 'br_netfilter' Oct 13 05:34:22.907112 kernel: Bridge firewalling registered Oct 13 05:34:22.906332 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:34:22.908018 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:34:22.928047 systemd-tmpfiles[629]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 13 05:34:22.942817 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 05:34:22.945530 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:34:22.968356 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:34:22.978659 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:34:22.987830 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:34:22.996191 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:34:22.999643 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 05:34:23.009120 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:34:23.046757 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:34:23.049558 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 05:34:23.083339 systemd-resolved[647]: Positive Trust Anchors: Oct 13 05:34:23.083355 systemd-resolved[647]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:34:23.083360 systemd-resolved[647]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 13 05:34:23.098196 dracut-cmdline[660]: 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d Oct 13 05:34:23.083391 systemd-resolved[647]: 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 Oct 13 05:34:23.184854 systemd-resolved[647]: Defaulting to hostname 'linux'. Oct 13 05:34:23.185965 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:34:23.192895 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:34:23.319546 kernel: Loading iSCSI transport class v2.0-870. Oct 13 05:34:23.376544 kernel: iscsi: registered transport (tcp) Oct 13 05:34:23.428347 kernel: iscsi: registered transport (qla4xxx) Oct 13 05:34:23.428378 kernel: QLogic iSCSI HBA Driver Oct 13 05:34:23.477973 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:34:23.510278 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:34:23.512810 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:34:23.579432 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 05:34:23.582207 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 05:34:23.591314 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 05:34:23.634090 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:34:23.636985 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:34:23.675625 systemd-udevd[900]: Using default interface naming scheme 'v257'. Oct 13 05:34:23.687092 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:34:23.698405 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 05:34:23.724016 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:34:23.727029 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:34:23.742280 dracut-pre-trigger[987]: rd.md=0: removing MD RAID activation Oct 13 05:34:23.762168 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:34:23.764857 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:34:23.787936 systemd-networkd[998]: lo: Link UP Oct 13 05:34:23.787944 systemd-networkd[998]: lo: Gained carrier Oct 13 05:34:23.788455 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:34:23.790346 systemd[1]: Reached target network.target - Network. Oct 13 05:34:23.854611 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:34:23.857353 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 05:34:23.967537 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 05:34:23.984159 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:34:23.984231 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:34:23.993803 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:34:24.003741 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:34:24.022532 kernel: cryptd: max_cpu_qlen set to 1000 Oct 13 05:34:24.058535 kernel: AES CTR mode by8 optimization enabled Oct 13 05:34:24.062869 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:34:24.375777 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 05:34:24.385972 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 05:34:24.474432 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 05:34:24.500655 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 05:34:24.519381 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:34:25.640204 disk-uuid[1184]: Warning: The kernel is still using the old partition table. Oct 13 05:34:25.640204 disk-uuid[1184]: The new table will be used at the next reboot or after you Oct 13 05:34:25.640204 disk-uuid[1184]: run partprobe(8) or kpartx(8) Oct 13 05:34:25.640204 disk-uuid[1184]: The operation has completed successfully. Oct 13 05:34:27.870144 systemd-networkd[998]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:34:27.870157 systemd-networkd[998]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:34:27.871044 systemd-networkd[998]: eth0: Link UP Oct 13 05:34:27.871248 systemd-networkd[998]: eth0: Gained carrier Oct 13 05:34:27.871258 systemd-networkd[998]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:34:27.900871 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 05:34:27.910153 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 05:34:27.913758 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 05:34:27.925207 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:34:27.930554 systemd-networkd[998]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 05:34:27.935398 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:34:27.950656 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:34:27.960183 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 05:34:27.961598 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 05:34:28.024951 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:34:28.042534 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1337) Oct 13 05:34:28.051703 kernel: BTRFS info (device sda6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8 Oct 13 05:34:28.051722 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:34:28.100429 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:34:28.100476 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:34:28.110538 kernel: BTRFS info (device sda6): last unmount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8 Oct 13 05:34:28.111582 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 05:34:28.120947 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 05:34:29.175281 ignition[1357]: Ignition 2.22.0 Oct 13 05:34:29.175297 ignition[1357]: Stage: fetch-offline Oct 13 05:34:29.175586 ignition[1357]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:29.175600 ignition[1357]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:29.175706 ignition[1357]: parsed url from cmdline: "" Oct 13 05:34:29.175711 ignition[1357]: no config URL provided Oct 13 05:34:29.175716 ignition[1357]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:34:29.175727 ignition[1357]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:34:29.175733 ignition[1357]: failed to fetch config: resource requires networking Oct 13 05:34:29.175886 ignition[1357]: Ignition finished successfully Oct 13 05:34:29.212762 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:34:29.222912 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 05:34:29.266576 ignition[1367]: Ignition 2.22.0 Oct 13 05:34:29.266589 ignition[1367]: Stage: fetch Oct 13 05:34:29.266819 ignition[1367]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:29.266830 ignition[1367]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:29.266921 ignition[1367]: parsed url from cmdline: "" Oct 13 05:34:29.266925 ignition[1367]: no config URL provided Oct 13 05:34:29.266931 ignition[1367]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:34:29.266938 ignition[1367]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:34:29.266956 ignition[1367]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 05:34:29.353256 ignition[1367]: GET result: OK Oct 13 05:34:29.353308 ignition[1367]: config has been read from IMDS userdata Oct 13 05:34:29.353320 ignition[1367]: parsing config with SHA512: 776af03dd4c3fbbbd0a27f3cbae4e692852c8c3bea8870d668c140c0ce379e5cdcf2c5b17e0dd275e68eb5afde03b2234ec2609c18d1586bc3c44ff3c13f44b4 Oct 13 05:34:29.360814 unknown[1367]: fetched base config from "system" Oct 13 05:34:29.361122 ignition[1367]: fetch: fetch complete Oct 13 05:34:29.360840 unknown[1367]: fetched base config from "system" Oct 13 05:34:29.361128 ignition[1367]: fetch: fetch passed Oct 13 05:34:29.360875 unknown[1367]: fetched user config from "azure" Oct 13 05:34:29.361178 ignition[1367]: Ignition finished successfully Oct 13 05:34:29.366989 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 05:34:29.370945 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 05:34:29.414049 ignition[1377]: Ignition 2.22.0 Oct 13 05:34:29.414066 ignition[1377]: Stage: kargs Oct 13 05:34:29.414315 ignition[1377]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:29.414329 ignition[1377]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:29.420623 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 05:34:29.414841 ignition[1377]: kargs: kargs passed Oct 13 05:34:29.432305 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 05:34:29.414893 ignition[1377]: Ignition finished successfully Oct 13 05:34:29.477868 ignition[1387]: Ignition 2.22.0 Oct 13 05:34:29.477883 ignition[1387]: Stage: disks Oct 13 05:34:29.478102 ignition[1387]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:29.478112 ignition[1387]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:29.478564 ignition[1387]: disks: disks passed Oct 13 05:34:29.487351 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 05:34:29.478608 ignition[1387]: Ignition finished successfully Oct 13 05:34:29.494846 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 05:34:29.501983 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 05:34:29.511317 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:34:29.518686 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:34:29.520125 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:34:29.533355 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 05:34:29.616595 systemd-networkd[998]: eth0: Gained IPv6LL Oct 13 05:34:29.670525 systemd-fsck[1399]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Oct 13 05:34:29.676180 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 05:34:29.678814 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 05:34:31.350531 kernel: EXT4-fs (sda9): mounted filesystem c7d6ef00-6dd1-40b4-91f2-c4c5965e3cac r/w with ordered data mode. Quota mode: none. Oct 13 05:34:31.351202 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 05:34:31.358003 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 05:34:31.398201 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:34:31.424069 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 05:34:31.426669 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 05:34:31.432508 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 05:34:31.446564 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1414) Oct 13 05:34:31.446591 kernel: BTRFS info (device sda6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8 Oct 13 05:34:31.432552 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:34:31.472910 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:34:31.457460 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 05:34:31.489116 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:34:31.489140 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:34:31.475230 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 05:34:31.491761 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:34:32.231205 coreos-metadata[1416]: Oct 13 05:34:32.231 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:34:32.238888 coreos-metadata[1416]: Oct 13 05:34:32.238 INFO Fetch successful Oct 13 05:34:32.242884 coreos-metadata[1416]: Oct 13 05:34:32.238 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:34:32.258032 coreos-metadata[1416]: Oct 13 05:34:32.258 INFO Fetch successful Oct 13 05:34:32.269667 coreos-metadata[1416]: Oct 13 05:34:32.269 INFO wrote hostname ci-4487.0.0-a-6b7b6b9b13 to /sysroot/etc/hostname Oct 13 05:34:32.271841 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:34:32.374440 initrd-setup-root[1444]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 05:34:32.417382 initrd-setup-root[1451]: cut: /sysroot/etc/group: No such file or directory Oct 13 05:34:32.423630 initrd-setup-root[1458]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 05:34:32.441633 initrd-setup-root[1465]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 05:34:33.452839 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 05:34:33.458950 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 05:34:33.464387 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 05:34:33.505592 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 05:34:33.515430 kernel: BTRFS info (device sda6): last unmount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8 Oct 13 05:34:33.537623 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 05:34:33.561952 ignition[1534]: INFO : Ignition 2.22.0 Oct 13 05:34:33.561952 ignition[1534]: INFO : Stage: mount Oct 13 05:34:33.568074 ignition[1534]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:33.568074 ignition[1534]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:33.568074 ignition[1534]: INFO : mount: mount passed Oct 13 05:34:33.568074 ignition[1534]: INFO : Ignition finished successfully Oct 13 05:34:33.573678 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 05:34:33.579601 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 05:34:33.607265 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:34:33.651571 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1547) Oct 13 05:34:33.660328 kernel: BTRFS info (device sda6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8 Oct 13 05:34:33.660366 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:34:33.670734 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:34:33.670790 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:34:33.672997 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:34:33.709218 ignition[1564]: INFO : Ignition 2.22.0 Oct 13 05:34:33.709218 ignition[1564]: INFO : Stage: files Oct 13 05:34:33.715177 ignition[1564]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:33.715177 ignition[1564]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:33.715177 ignition[1564]: DEBUG : files: compiled without relabeling support, skipping Oct 13 05:34:33.785019 ignition[1564]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 05:34:33.785019 ignition[1564]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 05:34:33.841885 ignition[1564]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 05:34:33.847548 ignition[1564]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 05:34:33.852993 unknown[1564]: wrote ssh authorized keys file for user: core Oct 13 05:34:33.856921 ignition[1564]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 05:34:33.898689 ignition[1564]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:34:33.905938 ignition[1564]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:34:33.905938 ignition[1564]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:34:33.905938 ignition[1564]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:34:33.905938 ignition[1564]: INFO : files: files passed Oct 13 05:34:33.905938 ignition[1564]: INFO : Ignition finished successfully Oct 13 05:34:33.916248 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 05:34:33.923081 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 05:34:33.954206 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 05:34:33.967083 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 05:34:33.967199 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 05:34:34.016248 initrd-setup-root-after-ignition[1597]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:34:34.016248 initrd-setup-root-after-ignition[1597]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:34:34.033683 initrd-setup-root-after-ignition[1601]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:34:34.018456 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:34:34.024119 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 05:34:34.045410 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 05:34:34.110672 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 05:34:34.110839 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 05:34:34.120240 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 05:34:34.129509 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 05:34:34.137896 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 05:34:34.142095 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 05:34:34.193879 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:34:34.196653 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 05:34:34.227664 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 13 05:34:34.227894 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:34:34.237828 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:34:34.247483 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 05:34:34.256124 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 05:34:34.256259 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:34:34.268610 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 05:34:34.277273 systemd[1]: Stopped target basic.target - Basic System. Oct 13 05:34:34.284793 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 05:34:34.292452 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:34:34.301318 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 05:34:34.310438 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:34:34.319383 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 05:34:34.327985 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:34:34.336905 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 05:34:34.345975 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 05:34:34.347479 systemd[1]: Stopped target swap.target - Swaps. Oct 13 05:34:34.364306 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 05:34:34.368177 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:34:34.376772 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:34:34.378380 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:34:34.386735 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 05:34:34.386866 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:34:34.396091 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 05:34:34.396221 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 05:34:34.413245 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 05:34:34.413382 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:34:34.422155 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 05:34:34.422278 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 05:34:34.430190 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 05:34:34.430318 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:34:34.444575 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 05:34:34.451617 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 05:34:34.451750 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:34:34.477930 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 05:34:34.485656 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 05:34:34.485824 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:34:34.494418 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 05:34:34.498750 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:34:34.508133 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 05:34:34.524643 ignition[1621]: INFO : Ignition 2.22.0 Oct 13 05:34:34.524643 ignition[1621]: INFO : Stage: umount Oct 13 05:34:34.524643 ignition[1621]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:34:34.524643 ignition[1621]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:34:34.524643 ignition[1621]: INFO : umount: umount passed Oct 13 05:34:34.524643 ignition[1621]: INFO : Ignition finished successfully Oct 13 05:34:34.508256 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:34:34.522753 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 05:34:34.522852 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 05:34:34.529374 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 05:34:34.529471 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 05:34:34.533372 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 05:34:34.533444 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 05:34:34.540582 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 05:34:34.540631 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 05:34:34.548386 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 05:34:34.548437 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 05:34:34.556989 systemd[1]: Stopped target network.target - Network. Oct 13 05:34:34.563322 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 05:34:34.563377 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:34:34.574372 systemd[1]: Stopped target paths.target - Path Units. Oct 13 05:34:34.582270 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 05:34:34.585591 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:34:34.587528 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 05:34:34.595895 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 05:34:34.602541 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 05:34:34.602586 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:34:34.614367 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 05:34:34.614411 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:34:34.623629 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 05:34:34.623689 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 05:34:34.631511 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 05:34:34.631580 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 05:34:34.639571 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 05:34:34.647327 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 05:34:34.656576 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 05:34:34.657201 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 05:34:34.657309 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 05:34:34.662391 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 05:34:34.662488 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 05:34:34.668593 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 05:34:34.668692 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 05:34:34.686785 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 13 05:34:34.688363 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 05:34:34.688416 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:34:34.696489 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 05:34:34.696560 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 05:34:34.715078 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 05:34:34.721983 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 05:34:34.722042 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:34:34.730357 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 05:34:34.730404 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:34:34.732541 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 05:34:34.732586 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 05:34:34.742760 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:34:34.781909 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 05:34:34.782105 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:34:34.790153 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 05:34:34.790206 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 05:34:34.798710 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 05:34:34.798745 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:34:34.806431 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 05:34:34.810074 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:34:34.821872 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 05:34:34.821929 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 05:34:34.833804 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 05:34:34.833856 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:34:34.855729 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 05:34:34.864362 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 13 05:34:34.864423 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:34:34.874707 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 05:34:34.874761 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:34:34.883225 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:34:34.883273 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:34:34.896239 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 05:34:34.896328 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 05:34:34.925262 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 05:34:34.925381 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 05:34:34.929703 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 05:34:34.944548 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 05:34:35.009366 systemd[1]: Switching root. Oct 13 05:34:35.104339 systemd-journald[618]: Journal stopped Oct 13 05:34:42.092087 systemd-journald[618]: Received SIGTERM from PID 1 (systemd). Oct 13 05:34:42.092121 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 05:34:42.092134 kernel: SELinux: policy capability open_perms=1 Oct 13 05:34:42.092144 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 05:34:42.092154 kernel: SELinux: policy capability always_check_network=0 Oct 13 05:34:42.092164 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 05:34:42.092178 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 05:34:42.092189 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 05:34:42.092198 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 05:34:42.092208 kernel: SELinux: policy capability userspace_initial_context=0 Oct 13 05:34:42.092218 kernel: audit: type=1403 audit(1760333675.876:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 05:34:42.092230 systemd[1]: Successfully loaded SELinux policy in 197.636ms. Oct 13 05:34:42.092244 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.107ms. Oct 13 05:34:42.092257 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 05:34:42.092269 systemd[1]: Detected virtualization microsoft. Oct 13 05:34:42.092280 systemd[1]: Detected architecture x86-64. Oct 13 05:34:42.092294 systemd[1]: Detected first boot. Oct 13 05:34:42.092307 systemd[1]: Hostname set to . Oct 13 05:34:42.092318 systemd[1]: Initializing machine ID from random generator. Oct 13 05:34:42.092329 zram_generator::config[1668]: No configuration found. Oct 13 05:34:42.092341 kernel: Guest personality initialized and is inactive Oct 13 05:34:42.092354 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 13 05:34:42.092365 kernel: Initialized host personality Oct 13 05:34:42.092375 kernel: NET: Registered PF_VSOCK protocol family Oct 13 05:34:42.092386 systemd[1]: Populated /etc with preset unit settings. Oct 13 05:34:42.092397 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 05:34:42.092408 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 05:34:42.092420 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 05:34:42.092435 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 05:34:42.092447 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 05:34:42.092485 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 05:34:42.092500 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 05:34:42.092523 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 05:34:42.092543 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 05:34:42.092556 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 05:34:42.092568 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 05:34:42.092583 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:34:42.092595 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:34:42.092607 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 05:34:42.092619 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 05:34:42.092635 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 05:34:42.092651 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:34:42.092663 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 13 05:34:42.092675 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:34:42.092687 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:34:42.092699 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 05:34:42.092714 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 05:34:42.092726 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 05:34:42.092738 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 05:34:42.092749 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:34:42.092761 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:34:42.092772 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:34:42.092784 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:34:42.092798 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 05:34:42.092810 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 05:34:42.092821 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 05:34:42.092833 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:34:42.092852 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:34:42.092865 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:34:42.092877 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 05:34:42.092888 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 05:34:42.092900 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 05:34:42.092912 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 05:34:42.092927 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:34:42.092939 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 05:34:42.092951 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 05:34:42.092963 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 05:34:42.092975 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). Oct 13 05:34:42.092987 systemd[1]: Reached target machines.target - Containers. Oct 13 05:34:42.092999 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 05:34:42.093014 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:34:42.093026 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:34:42.093037 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 05:34:42.093049 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:34:42.093061 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:34:42.093072 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:34:42.093087 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 05:34:42.093099 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:34:42.093115 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 05:34:42.093127 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 05:34:42.093139 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 05:34:42.093151 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 05:34:42.093162 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 05:34:42.093178 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). Oct 13 05:34:42.093190 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:34:42.093202 kernel: fuse: init (API version 7.41) Oct 13 05:34:42.093213 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:34:42.093225 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:34:42.093237 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 05:34:42.093275 systemd-journald[1765]: Collecting audit messages is disabled. Oct 13 05:34:42.093306 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 05:34:42.093319 systemd-journald[1765]: Journal started Oct 13 05:34:42.093344 systemd-journald[1765]: Runtime Journal (/run/log/journal/d3f14ba617bc4b71954f6a277ca29e71) is 8M, max 2.2G, 2.1G free. Oct 13 05:34:41.282749 systemd[1]: Queued start job for default target multi-user.target. Oct 13 05:34:41.293883 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 13 05:34:41.294372 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 05:34:42.108939 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:34:42.117533 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:34:42.125105 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:34:42.129157 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 05:34:42.133569 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 05:34:42.138197 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 05:34:42.142199 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 05:34:42.146766 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 05:34:42.151312 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 05:34:42.155616 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 05:34:42.160667 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:34:42.166128 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 05:34:42.166316 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 05:34:42.171483 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:34:42.171683 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:34:42.176757 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:34:42.176953 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:34:42.182271 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 05:34:42.182466 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 05:34:42.187069 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:34:42.187253 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:34:42.191863 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:34:42.200307 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:34:42.206564 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 05:34:42.210528 kernel: ACPI: bus type drm_connector registered Oct 13 05:34:42.216013 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 05:34:42.222099 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:34:42.222288 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:34:42.236465 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:34:42.254193 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 13 05:34:42.260413 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 05:34:42.267586 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 05:34:42.278264 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 05:34:42.278303 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:34:42.283527 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 05:34:42.288779 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:34:42.304383 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 05:34:42.312281 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 05:34:42.316990 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:34:42.317971 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 05:34:42.322351 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:34:42.323327 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:34:42.329066 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 05:34:42.334925 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 05:34:42.341322 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:34:42.346671 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 05:34:42.349274 systemd-journald[1765]: Time spent on flushing to /var/log/journal/d3f14ba617bc4b71954f6a277ca29e71 is 14.770ms for 939 entries. Oct 13 05:34:42.349274 systemd-journald[1765]: System Journal (/var/log/journal/d3f14ba617bc4b71954f6a277ca29e71) is 8M, max 2.6G, 2.6G free. Oct 13 05:34:42.404586 systemd-journald[1765]: Received client request to flush runtime journal. Oct 13 05:34:42.404668 kernel: loop1: detected capacity change from 0 to 128048 Oct 13 05:34:42.356479 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 05:34:42.361665 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 05:34:42.369066 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 05:34:42.375157 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 05:34:42.406039 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 05:34:42.477595 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:34:42.532582 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 05:34:42.533299 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 05:34:42.892541 kernel: loop2: detected capacity change from 0 to 27752 Oct 13 05:34:43.029809 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 05:34:43.037413 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:34:43.042607 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:34:43.137081 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 05:34:43.197845 systemd-tmpfiles[1828]: ACLs are not supported, ignoring. Oct 13 05:34:43.197867 systemd-tmpfiles[1828]: ACLs are not supported, ignoring. Oct 13 05:34:43.199983 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 05:34:43.205423 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:34:43.225729 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 05:34:43.232248 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:34:43.271498 systemd-udevd[1839]: Using default interface naming scheme 'v257'. Oct 13 05:34:43.335164 systemd-resolved[1827]: Positive Trust Anchors: Oct 13 05:34:43.335182 systemd-resolved[1827]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:34:43.335187 systemd-resolved[1827]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 13 05:34:43.335219 systemd-resolved[1827]: 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 Oct 13 05:34:43.392542 kernel: loop3: detected capacity change from 0 to 110984 Oct 13 05:34:43.489016 systemd-resolved[1827]: Using system hostname 'ci-4487.0.0-a-6b7b6b9b13'. Oct 13 05:34:43.490409 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:34:43.495106 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:34:43.853551 kernel: loop4: detected capacity change from 0 to 128048 Oct 13 05:34:43.876544 kernel: loop5: detected capacity change from 0 to 27752 Oct 13 05:34:43.893538 kernel: loop6: detected capacity change from 0 to 110984 Oct 13 05:34:43.905192 (sd-merge)[1843]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Oct 13 05:34:43.908842 (sd-merge)[1843]: Merged extensions into '/usr'. Oct 13 05:34:43.912537 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 05:34:43.919231 systemd[1]: Starting ensure-sysext.service... Oct 13 05:34:43.923109 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:34:43.957705 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:34:43.964740 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:34:43.978690 systemd[1]: Reload requested from client PID 1845 ('systemctl') (unit ensure-sysext.service)... Oct 13 05:34:43.978703 systemd[1]: Reloading... Oct 13 05:34:44.011299 systemd-tmpfiles[1846]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 13 05:34:44.011346 systemd-tmpfiles[1846]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 13 05:34:44.011687 systemd-tmpfiles[1846]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 05:34:44.011972 systemd-tmpfiles[1846]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 05:34:44.013656 systemd-tmpfiles[1846]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 05:34:44.014415 systemd-tmpfiles[1846]: ACLs are not supported, ignoring. Oct 13 05:34:44.014642 systemd-tmpfiles[1846]: ACLs are not supported, ignoring. Oct 13 05:34:44.034574 zram_generator::config[1914]: No configuration found. Oct 13 05:34:44.066869 kernel: hv_vmbus: registering driver hv_balloon Oct 13 05:34:44.066954 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 05:34:44.066981 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 05:34:44.080257 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 05:34:44.080330 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 05:34:44.084582 kernel: Console: switching to colour dummy device 80x25 Oct 13 05:34:44.091839 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 05:34:44.091902 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:34:44.123249 systemd-tmpfiles[1846]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:34:44.123265 systemd-tmpfiles[1846]: Skipping /boot Oct 13 05:34:44.129673 systemd-networkd[1853]: lo: Link UP Oct 13 05:34:44.129683 systemd-networkd[1853]: lo: Gained carrier Oct 13 05:34:44.132591 systemd-networkd[1853]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:34:44.132600 systemd-networkd[1853]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:34:44.133617 systemd-networkd[1853]: eth0: Link UP Oct 13 05:34:44.133726 systemd-networkd[1853]: eth0: Gained carrier Oct 13 05:34:44.133799 systemd-networkd[1853]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:34:44.143828 systemd-tmpfiles[1846]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:34:44.143842 systemd-tmpfiles[1846]: Skipping /boot Oct 13 05:34:44.152535 kernel: EDAC MC: Ver: 3.0.0 Oct 13 05:34:44.175630 systemd-networkd[1853]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 05:34:44.294507 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 13 05:34:44.295105 systemd[1]: Reloading finished in 316 ms. Oct 13 05:34:44.316801 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:34:44.343891 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:34:44.381065 systemd[1]: Finished ensure-sysext.service. Oct 13 05:34:44.416727 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:34:44.421611 systemd[1]: Reached target network.target - Network. Oct 13 05:34:44.425289 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:34:44.426507 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 05:34:44.451589 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 05:34:44.456673 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:34:44.457797 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 05:34:44.462859 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:34:44.468056 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:34:44.473159 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:34:44.481600 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:34:44.485918 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:34:44.486982 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 05:34:44.491893 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). Oct 13 05:34:44.493016 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 05:34:44.500793 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 05:34:44.506932 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 05:34:44.511710 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 05:34:44.516918 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 05:34:44.522788 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:34:44.527211 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:34:44.528659 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:34:44.528863 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:34:44.533946 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:34:44.534130 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:34:44.540225 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:34:44.540411 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:34:44.545841 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:34:44.546070 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:34:44.555278 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:34:44.555449 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:34:44.560777 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 05:34:44.574041 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 05:34:44.609804 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 05:34:44.772222 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 05:34:44.979838 augenrules[2110]: No rules Oct 13 05:34:44.981069 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 05:34:44.981318 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 05:34:45.808650 systemd-networkd[1853]: eth0: Gained IPv6LL Oct 13 05:34:45.811249 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 05:34:45.813168 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 05:34:45.931098 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:34:47.216448 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 05:34:47.222581 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). Oct 13 05:34:53.284420 ldconfig[2068]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:34:53.307416 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 05:34:53.313973 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 05:34:53.348879 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 05:34:53.353809 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:34:53.358391 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 05:34:53.363650 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 13 05:34:53.368881 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 13 05:34:53.373764 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 05:34:53.378350 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 05:34:53.383726 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 05:34:53.389073 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 05:34:53.389111 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:34:53.392643 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:34:53.432307 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 05:34:53.437942 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 05:34:53.443904 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 05:34:53.449190 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 05:34:53.454348 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 05:34:53.460533 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 05:34:53.464984 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 05:34:53.470714 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 05:34:53.475996 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:34:53.480084 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:34:53.483979 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:34:53.484016 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:34:53.501125 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 05:34:53.505753 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 05:34:53.528547 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 05:34:53.533797 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 05:34:53.538539 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 05:34:53.544229 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 05:34:53.549735 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 05:34:53.553878 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). Oct 13 05:34:53.555017 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 13 05:34:53.559199 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). Oct 13 05:34:53.560212 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 05:34:53.565276 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 13 05:34:53.566277 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 05:34:53.567127 jq[2134]: false Oct 13 05:34:53.571189 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 05:34:53.575890 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 05:34:53.581838 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 05:34:53.584700 chronyd[2126]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 13 05:34:53.587679 KVP[2137]: KVP starting; pid is:2137 Oct 13 05:34:53.588773 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 05:34:53.589759 KVP[2137]: KVP LIC Version: 3.1 Oct 13 05:34:53.592532 kernel: hv_utils: KVP IC version 4.0 Oct 13 05:34:53.593053 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 05:34:53.593478 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 05:34:53.594163 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 05:34:53.600650 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 05:34:53.608127 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 05:34:53.608870 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Refreshing passwd entry cache Oct 13 05:34:53.608877 oslogin_cache_refresh[2136]: Refreshing passwd entry cache Oct 13 05:34:53.613910 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 05:34:53.614134 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 05:34:53.614459 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 05:34:53.614677 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 05:34:53.615155 jq[2150]: true Oct 13 05:34:53.616680 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Failure getting users, quitting Oct 13 05:34:53.616672 oslogin_cache_refresh[2136]: Failure getting users, quitting Oct 13 05:34:53.616787 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:34:53.616787 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Refreshing group entry cache Oct 13 05:34:53.616691 oslogin_cache_refresh[2136]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:34:53.616736 oslogin_cache_refresh[2136]: Refreshing group entry cache Oct 13 05:34:53.621097 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Failure getting groups, quitting Oct 13 05:34:53.621097 google_oslogin_nss_cache[2136]: oslogin_cache_refresh[2136]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:34:53.621087 oslogin_cache_refresh[2136]: Failure getting groups, quitting Oct 13 05:34:53.621099 oslogin_cache_refresh[2136]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:34:53.626084 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 13 05:34:53.627669 chronyd[2126]: Timezone right/UTC failed leap second check, ignoring Oct 13 05:34:53.627851 chronyd[2126]: Loaded seccomp filter (level 2) Oct 13 05:34:53.629218 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 13 05:34:53.631324 extend-filesystems[2135]: Found /dev/sda6 Oct 13 05:34:53.640228 jq[2160]: true Oct 13 05:34:53.634656 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 05:34:53.639134 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 05:34:53.639350 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 05:34:53.648453 extend-filesystems[2135]: Found /dev/sda9 Oct 13 05:34:53.651980 extend-filesystems[2135]: Checking size of /dev/sda9 Oct 13 05:34:53.660150 (ntainerd)[2167]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 05:34:53.669412 update_engine[2148]: I20251013 05:34:53.669327 2148 main.cc:92] Flatcar Update Engine starting Oct 13 05:34:53.672000 extend-filesystems[2135]: Resized partition /dev/sda9 Oct 13 05:34:53.723491 systemd-logind[2146]: New seat seat0. Oct 13 05:34:53.724034 sshd_keygen[2158]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 05:34:53.726475 systemd-logind[2146]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Oct 13 05:34:53.726874 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 05:34:53.749526 extend-filesystems[2219]: resize2fs 1.47.3 (8-Jul-2025) Oct 13 05:34:53.761533 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Oct 13 05:34:53.775367 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 05:34:53.776550 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Oct 13 05:34:53.804225 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 05:34:53.806898 setup-nvidia[2139]: Downloading Flatcar Container Linux Developer Container for version: 4487.0.0 Oct 13 05:34:53.818198 extend-filesystems[2219]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Oct 13 05:34:53.818198 extend-filesystems[2219]: old_desc_blocks = 4, new_desc_blocks = 4 Oct 13 05:34:53.818198 extend-filesystems[2219]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Oct 13 05:34:53.838100 extend-filesystems[2135]: Resized filesystem in /dev/sda9 Oct 13 05:34:53.840331 bash[2199]: Updated "/home/core/.ssh/authorized_keys" Oct 13 05:34:53.827813 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 05:34:53.831057 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 05:34:53.831285 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 05:34:53.846435 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 05:34:53.851926 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 05:34:53.852169 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 05:34:53.859977 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 05:34:53.861185 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 05:34:53.867172 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 05:34:53.884164 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 05:34:53.892490 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 05:34:53.897510 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 13 05:34:53.902176 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 05:34:54.035826 dbus-daemon[2129]: [system] SELinux support is enabled Oct 13 05:34:54.036110 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 05:34:54.038678 update_engine[2148]: I20251013 05:34:54.038621 2148 update_check_scheduler.cc:74] Next update check in 9m1s Oct 13 05:34:54.042874 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). Oct 13 05:34:54.042908 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 05:34:54.043891 dbus-daemon[2129]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 05:34:54.048247 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). Oct 13 05:34:54.048273 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 05:34:54.053408 systemd[1]: Started update-engine.service - Update Engine. Oct 13 05:34:54.059632 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 05:34:54.069670 coreos-metadata[2128]: Oct 13 05:34:54.069 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:34:54.071660 coreos-metadata[2128]: Oct 13 05:34:54.071 INFO Fetch successful Oct 13 05:34:54.071902 coreos-metadata[2128]: Oct 13 05:34:54.071 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 05:34:54.076108 coreos-metadata[2128]: Oct 13 05:34:54.076 INFO Fetch successful Oct 13 05:34:54.076419 coreos-metadata[2128]: Oct 13 05:34:54.076 INFO Fetching http://168.63.129.16/machine/44236c6d-b71f-474e-83f4-1e8e7211b0b8/cb4fa3da%2D7b10%2D4302%2D91a3%2Dac8723bda4e2.%5Fci%2D4487.0.0%2Da%2D6b7b6b9b13?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 05:34:54.078035 coreos-metadata[2128]: Oct 13 05:34:54.078 INFO Fetch successful Oct 13 05:34:54.078177 coreos-metadata[2128]: Oct 13 05:34:54.078 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:34:54.085389 coreos-metadata[2128]: Oct 13 05:34:54.085 INFO Fetch successful Oct 13 05:34:54.104404 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 05:34:54.109647 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 05:34:54.296033 locksmithd[2305]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 05:34:54.513707 setup-nvidia[2320]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:34:54.514092 setup-nvidia[2320]: Dload Upload Total Spent Left Speed Oct 13 05:34:54.979112 containerd[2167]: time="2025-10-13T05:34:54Z" 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 Oct 13 05:34:54.979765 containerd[2167]: time="2025-10-13T05:34:54.979710200Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 13 05:34:54.989236 containerd[2167]: time="2025-10-13T05:34:54.989200400Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.1µs" Oct 13 05:34:54.989236 containerd[2167]: time="2025-10-13T05:34:54.989228500Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 13 05:34:54.989329 containerd[2167]: time="2025-10-13T05:34:54.989251500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 13 05:34:54.989411 containerd[2167]: time="2025-10-13T05:34:54.989387500Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 13 05:34:54.989411 containerd[2167]: time="2025-10-13T05:34:54.989408900Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 13 05:34:54.989463 containerd[2167]: time="2025-10-13T05:34:54.989432300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989529 containerd[2167]: time="2025-10-13T05:34:54.989490300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989529 containerd[2167]: time="2025-10-13T05:34:54.989508400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989742 containerd[2167]: time="2025-10-13T05:34:54.989715000Z" 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 Oct 13 05:34:54.989742 containerd[2167]: time="2025-10-13T05:34:54.989735300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989805 containerd[2167]: time="2025-10-13T05:34:54.989746100Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989805 containerd[2167]: time="2025-10-13T05:34:54.989754400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 13 05:34:54.989861 containerd[2167]: time="2025-10-13T05:34:54.989834400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 13 05:34:54.990118 containerd[2167]: time="2025-10-13T05:34:54.990092900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:34:54.990156 containerd[2167]: time="2025-10-13T05:34:54.990130100Z" 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 Oct 13 05:34:54.990156 containerd[2167]: time="2025-10-13T05:34:54.990140300Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 13 05:34:54.990209 containerd[2167]: time="2025-10-13T05:34:54.990183200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 13 05:34:54.990398 containerd[2167]: time="2025-10-13T05:34:54.990374400Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 13 05:34:54.990466 containerd[2167]: time="2025-10-13T05:34:54.990445600Z" level=info msg="metadata content store policy set" policy=shared Oct 13 05:34:55.008733 containerd[2167]: time="2025-10-13T05:34:55.008697100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 13 05:34:55.008815 containerd[2167]: time="2025-10-13T05:34:55.008756800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 13 05:34:55.008815 containerd[2167]: time="2025-10-13T05:34:55.008776100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 13 05:34:55.008815 containerd[2167]: time="2025-10-13T05:34:55.008790200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 13 05:34:55.008815 containerd[2167]: time="2025-10-13T05:34:55.008804500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 13 05:34:55.008815 containerd[2167]: time="2025-10-13T05:34:55.008815900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008829900Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008841700Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008852700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008863000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008872200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 13 05:34:55.008939 containerd[2167]: time="2025-10-13T05:34:55.008899500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 13 05:34:55.009069 containerd[2167]: time="2025-10-13T05:34:55.009017300Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 13 05:34:55.009069 containerd[2167]: time="2025-10-13T05:34:55.009038100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 13 05:34:55.009069 containerd[2167]: time="2025-10-13T05:34:55.009053900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 13 05:34:55.009069 containerd[2167]: time="2025-10-13T05:34:55.009066200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009077200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009087900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009099100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009109000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009121200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009131700Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 13 05:34:55.009166 containerd[2167]: time="2025-10-13T05:34:55.009141800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 13 05:34:55.009322 containerd[2167]: time="2025-10-13T05:34:55.009199100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 13 05:34:55.009322 containerd[2167]: time="2025-10-13T05:34:55.009228800Z" level=info msg="Start snapshots syncer" Oct 13 05:34:55.009322 containerd[2167]: time="2025-10-13T05:34:55.009262000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 13 05:34:55.009538 containerd[2167]: time="2025-10-13T05:34:55.009476500Z" 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\"}" Oct 13 05:34:55.009659 containerd[2167]: time="2025-10-13T05:34:55.009559200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 13 05:34:55.009659 containerd[2167]: time="2025-10-13T05:34:55.009625500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 13 05:34:55.009768 containerd[2167]: time="2025-10-13T05:34:55.009738200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 13 05:34:55.009810 containerd[2167]: time="2025-10-13T05:34:55.009769400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 13 05:34:55.009810 containerd[2167]: time="2025-10-13T05:34:55.009781200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 13 05:34:55.009810 containerd[2167]: time="2025-10-13T05:34:55.009793900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 13 05:34:55.009810 containerd[2167]: time="2025-10-13T05:34:55.009807000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 13 05:34:55.009907 containerd[2167]: time="2025-10-13T05:34:55.009818000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 13 05:34:55.009907 containerd[2167]: time="2025-10-13T05:34:55.009828500Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 13 05:34:55.009907 containerd[2167]: time="2025-10-13T05:34:55.009849700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 13 05:34:55.009907 containerd[2167]: time="2025-10-13T05:34:55.009861500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 13 05:34:55.009907 containerd[2167]: time="2025-10-13T05:34:55.009871400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009908900Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009925100Z" 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 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009933800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009942900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009950900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009960000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009970000Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009986200Z" level=info msg="runtime interface created" Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009991600Z" level=info msg="created NRI interface" Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.009999300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 13 05:34:55.010019 containerd[2167]: time="2025-10-13T05:34:55.010009600Z" level=info msg="Connect containerd service" Oct 13 05:34:55.010276 containerd[2167]: time="2025-10-13T05:34:55.010035400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 05:34:55.010753 containerd[2167]: time="2025-10-13T05:34:55.010722000Z" 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" Oct 13 05:34:55.503488 containerd[2167]: time="2025-10-13T05:34:55.503440500Z" level=info msg="Start subscribing containerd event" Oct 13 05:34:55.503640 containerd[2167]: time="2025-10-13T05:34:55.503503600Z" level=info msg="Start recovering state" Oct 13 05:34:55.503690 containerd[2167]: time="2025-10-13T05:34:55.503664400Z" level=info msg="Start event monitor" Oct 13 05:34:55.503719 containerd[2167]: time="2025-10-13T05:34:55.503697800Z" level=info msg="Start cni network conf syncer for default" Oct 13 05:34:55.503719 containerd[2167]: time="2025-10-13T05:34:55.503712600Z" level=info msg="Start streaming server" Oct 13 05:34:55.503760 containerd[2167]: time="2025-10-13T05:34:55.503727600Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 13 05:34:55.503760 containerd[2167]: time="2025-10-13T05:34:55.503735300Z" level=info msg="runtime interface starting up..." Oct 13 05:34:55.503760 containerd[2167]: time="2025-10-13T05:34:55.503741200Z" level=info msg="starting plugins..." Oct 13 05:34:55.503760 containerd[2167]: time="2025-10-13T05:34:55.503758100Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 13 05:34:55.503852 containerd[2167]: time="2025-10-13T05:34:55.503665600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 05:34:55.503876 containerd[2167]: time="2025-10-13T05:34:55.503849300Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 05:34:55.503951 containerd[2167]: time="2025-10-13T05:34:55.503933000Z" level=info msg="containerd successfully booted in 0.525281s" Oct 13 05:34:55.504173 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 05:34:56.311890 waagent[2299]: 2025-10-13T05:34:56.311787Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 13 05:34:56.316213 waagent[2299]: 2025-10-13T05:34:56.316165Z INFO Daemon Daemon OS: flatcar 4487.0.0 Oct 13 05:34:56.321116 waagent[2299]: 2025-10-13T05:34:56.319703Z INFO Daemon Daemon Python: 3.11.13 Oct 13 05:34:56.327294 waagent[2299]: 2025-10-13T05:34:56.324499Z INFO Daemon Daemon Run daemon Oct 13 05:34:56.327682 waagent[2299]: 2025-10-13T05:34:56.327635Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0' Oct 13 05:34:56.334532 waagent[2299]: 2025-10-13T05:34:56.334461Z INFO Daemon Daemon Using waagent for provisioning Oct 13 05:34:56.338465 waagent[2299]: 2025-10-13T05:34:56.338413Z INFO Daemon Daemon Activate resource disk Oct 13 05:34:56.342081 waagent[2299]: 2025-10-13T05:34:56.342028Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 05:34:56.349770 waagent[2299]: 2025-10-13T05:34:56.349724Z INFO Daemon Daemon Found device: sdb Oct 13 05:34:56.359300 waagent[2299]: 2025-10-13T05:34:56.359238Z INFO Daemon Daemon Examining partition table Oct 13 05:34:56.428441 waagent[2299]: 2025-10-13T05:34:56.428347Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 13 05:34:56.483969 waagent[2299]: 2025-10-13T05:34:56.483884Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 13 05:34:56.488648 waagent[2299]: 2025-10-13T05:34:56.488595Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 13 05:34:56.934739 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#345 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:56.942531 kernel: sdb: sdb1 Oct 13 05:34:56.950907 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#482 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:56.964195 waagent[2299]: 2025-10-13T05:34:56.964115Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 13 05:34:56.972531 kernel: sdb: sdb1 Oct 13 05:34:56.987540 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#357 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:34:56.994539 kernel: sdb: sdb1 Oct 13 05:34:56.994594 waagent[2299]: 2025-10-13T05:34:56.994508Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 13 05:35:02.074930 login[2302]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 13 05:35:02.075219 login[2301]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:35:02.082404 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 05:35:02.083656 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 05:35:02.089534 systemd-logind[2146]: New session 1 of user core. Oct 13 05:35:02.131623 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 05:35:02.134699 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 05:35:02.168711 (systemd)[2410]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 05:35:02.174576 systemd-logind[2146]: New session c1 of user core. Oct 13 05:35:02.749311 waagent[2299]: 2025-10-13T05:35:02.749221Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 13 05:35:02.860330 waagent[2299]: 2025-10-13T05:35:02.860232Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 13 05:35:02.898044 kernel: EXT4-fs (sdb1): mounted filesystem 5535c6bc-6213-4ff3-9173-6628e7b1ec94 r/w with ordered data mode. Quota mode: none. Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.862560Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.862765Z INFO Daemon Daemon Running default provisioning handler Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.870712Z 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. Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.871233Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.871637Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 05:35:02.898138 waagent[2299]: 2025-10-13T05:35:02.872304Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 05:35:02.979094 waagent[2299]: 2025-10-13T05:35:02.979008Z INFO Daemon Daemon Successfully mounted dvd Oct 13 05:35:02.998886 systemd[2410]: Queued start job for default target default.target. Oct 13 05:35:03.006020 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 05:35:03.006401 waagent[2299]: 2025-10-13T05:35:03.006333Z INFO Daemon Daemon Detect protocol endpoint Oct 13 05:35:03.010370 waagent[2299]: 2025-10-13T05:35:03.010327Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:35:03.014968 waagent[2299]: 2025-10-13T05:35:03.014928Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 05:35:03.018406 systemd[2410]: Created slice app.slice - User Application Slice. Oct 13 05:35:03.018438 systemd[2410]: Reached target paths.target - Paths. Oct 13 05:35:03.018485 systemd[2410]: Reached target timers.target - Timers. Oct 13 05:35:03.019969 systemd[2410]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:35:03.020324 waagent[2299]: 2025-10-13T05:35:03.020283Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 05:35:03.024501 waagent[2299]: 2025-10-13T05:35:03.024453Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 05:35:03.028592 waagent[2299]: 2025-10-13T05:35:03.028550Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 05:35:03.030877 systemd[2410]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:35:03.030995 systemd[2410]: Reached target sockets.target - Sockets. Oct 13 05:35:03.031045 systemd[2410]: Reached target basic.target - Basic System. Oct 13 05:35:03.031090 systemd[2410]: Reached target default.target - Main User Target. Oct 13 05:35:03.031123 systemd[2410]: Startup finished in 849ms. Oct 13 05:35:03.031218 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 05:35:03.032570 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 05:35:03.065849 waagent[2299]: 2025-10-13T05:35:03.065788Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 05:35:03.078493 waagent[2299]: 2025-10-13T05:35:03.067682Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 05:35:03.078493 waagent[2299]: 2025-10-13T05:35:03.068306Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 05:35:03.076718 login[2302]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:35:03.082784 systemd-logind[2146]: New session 2 of user core. Oct 13 05:35:03.097655 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 05:35:03.217820 waagent[2299]: 2025-10-13T05:35:03.217740Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 05:35:03.219492 waagent[2299]: 2025-10-13T05:35:03.219447Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 05:35:03.225163 waagent[2299]: 2025-10-13T05:35:03.225119Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:35:03.255559 waagent[2299]: 2025-10-13T05:35:03.255504Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 05:35:03.257630 waagent[2299]: 2025-10-13T05:35:03.257558Z INFO Daemon Oct 13 05:35:03.257979 waagent[2299]: 2025-10-13T05:35:03.257943Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: d04d8fd5-302c-4a8c-b221-9415e6358de7 eTag: 863655961003665300 source: Fabric] Oct 13 05:35:03.259685 waagent[2299]: 2025-10-13T05:35:03.259635Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 05:35:03.260467 waagent[2299]: 2025-10-13T05:35:03.260419Z INFO Daemon Oct 13 05:35:03.261686 waagent[2299]: 2025-10-13T05:35:03.261652Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:35:03.323633 waagent[2299]: 2025-10-13T05:35:03.323565Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 05:35:03.462036 waagent[2299]: 2025-10-13T05:35:03.461960Z INFO Daemon Downloaded certificate {'thumbprint': '727AFD228634DFB137372AADFD796CACB52DD43A', 'hasPrivateKey': True} Oct 13 05:35:03.464127 waagent[2299]: 2025-10-13T05:35:03.464081Z INFO Daemon Fetch goal state completed Oct 13 05:35:03.471435 waagent[2299]: 2025-10-13T05:35:03.471376Z INFO Daemon Daemon Starting provisioning Oct 13 05:35:03.474460 waagent[2299]: 2025-10-13T05:35:03.474395Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 05:35:03.476130 waagent[2299]: 2025-10-13T05:35:03.475130Z INFO Daemon Daemon Set hostname [ci-4487.0.0-a-6b7b6b9b13] Oct 13 05:35:03.504161 waagent[2299]: 2025-10-13T05:35:03.504113Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-a-6b7b6b9b13] Oct 13 05:35:03.505850 waagent[2299]: 2025-10-13T05:35:03.505799Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 05:35:03.507329 waagent[2299]: 2025-10-13T05:35:03.507281Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 05:35:03.517110 systemd-networkd[1853]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 13 05:35:03.517121 systemd-networkd[1853]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:35:03.517185 systemd-networkd[1853]: eth0: DHCP lease lost Oct 13 05:35:03.539431 waagent[2299]: 2025-10-13T05:35:03.539373Z INFO Daemon Daemon Create user account if not exists Oct 13 05:35:03.540944 waagent[2299]: 2025-10-13T05:35:03.540901Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 05:35:03.542359 waagent[2299]: 2025-10-13T05:35:03.542315Z INFO Daemon Daemon Configure sudoer Oct 13 05:35:03.548914 waagent[2299]: 2025-10-13T05:35:03.548861Z INFO Daemon Daemon Configure sshd Oct 13 05:35:03.555924 waagent[2299]: 2025-10-13T05:35:03.555873Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 13 05:35:03.557507 waagent[2299]: 2025-10-13T05:35:03.557463Z INFO Daemon Daemon Deploy ssh public key. Oct 13 05:35:03.576558 systemd-networkd[1853]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 05:35:04.693317 waagent[2299]: 2025-10-13T05:35:04.693245Z INFO Daemon Daemon Provisioning complete Oct 13 05:35:04.703356 waagent[2299]: 2025-10-13T05:35:04.703313Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 05:35:04.704951 waagent[2299]: 2025-10-13T05:35:04.704908Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 05:35:04.706398 waagent[2299]: 2025-10-13T05:35:04.706361Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 13 05:35:04.873183 waagent[2473]: 2025-10-13T05:35:04.873063Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 13 05:35:04.873495 waagent[2473]: 2025-10-13T05:35:04.873237Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0 Oct 13 05:35:04.873495 waagent[2473]: 2025-10-13T05:35:04.873307Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 13 05:35:04.873788 waagent[2473]: 2025-10-13T05:35:04.873373Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 13 05:35:04.920312 waagent[2473]: 2025-10-13T05:35:04.920227Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 13 05:35:04.920497 waagent[2473]: 2025-10-13T05:35:04.920460Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:35:04.920639 waagent[2473]: 2025-10-13T05:35:04.920582Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:35:04.927373 waagent[2473]: 2025-10-13T05:35:04.927319Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:35:04.932011 waagent[2473]: 2025-10-13T05:35:04.931968Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 05:35:04.932447 waagent[2473]: 2025-10-13T05:35:04.932405Z INFO ExtHandler Oct 13 05:35:04.932525 waagent[2473]: 2025-10-13T05:35:04.932491Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 39ef4578-b503-4dd3-9d60-e7b397412530 eTag: 863655961003665300 source: Fabric] Oct 13 05:35:04.932874 waagent[2473]: 2025-10-13T05:35:04.932834Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 05:35:04.933491 waagent[2473]: 2025-10-13T05:35:04.933452Z INFO ExtHandler Oct 13 05:35:04.933608 waagent[2473]: 2025-10-13T05:35:04.933574Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:35:04.937109 waagent[2473]: 2025-10-13T05:35:04.937070Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 05:35:04.997569 waagent[2473]: 2025-10-13T05:35:04.997487Z INFO ExtHandler Downloaded certificate {'thumbprint': '727AFD228634DFB137372AADFD796CACB52DD43A', 'hasPrivateKey': True} Oct 13 05:35:04.998051 waagent[2473]: 2025-10-13T05:35:04.998003Z INFO ExtHandler Fetch goal state completed Oct 13 05:35:05.009349 waagent[2473]: 2025-10-13T05:35:05.009281Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 13 05:35:05.015508 waagent[2473]: 2025-10-13T05:35:05.015449Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2473 Oct 13 05:35:05.015665 waagent[2473]: 2025-10-13T05:35:05.015627Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 05:35:05.016013 waagent[2473]: 2025-10-13T05:35:05.015976Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 13 05:35:05.017625 waagent[2473]: 2025-10-13T05:35:05.017585Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 05:35:05.018056 waagent[2473]: 2025-10-13T05:35:05.018018Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 13 05:35:05.018204 waagent[2473]: 2025-10-13T05:35:05.018170Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 13 05:35:05.018837 waagent[2473]: 2025-10-13T05:35:05.018798Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 05:35:05.127173 waagent[2473]: 2025-10-13T05:35:05.127106Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 05:35:05.127360 waagent[2473]: 2025-10-13T05:35:05.127322Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 05:35:05.134123 waagent[2473]: 2025-10-13T05:35:05.134084Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 05:35:05.141016 systemd[1]: Reload requested from client PID 2488 ('systemctl') (unit waagent.service)... Oct 13 05:35:05.141034 systemd[1]: Reloading... Oct 13 05:35:05.200591 zram_generator::config[2532]: No configuration found. Oct 13 05:35:05.479782 systemd[1]: Reloading finished in 338 ms. Oct 13 05:35:05.506944 waagent[2473]: 2025-10-13T05:35:05.506879Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 05:35:05.507056 waagent[2473]: 2025-10-13T05:35:05.507020Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 05:35:05.865067 waagent[2473]: 2025-10-13T05:35:05.864952Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 05:35:05.865325 waagent[2473]: 2025-10-13T05:35:05.865286Z 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] Oct 13 05:35:05.866250 waagent[2473]: 2025-10-13T05:35:05.866197Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 05:35:05.866628 waagent[2473]: 2025-10-13T05:35:05.866580Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:35:05.866833 waagent[2473]: 2025-10-13T05:35:05.866777Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 05:35:05.867309 waagent[2473]: 2025-10-13T05:35:05.867241Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 05:35:05.867380 waagent[2473]: 2025-10-13T05:35:05.867327Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:35:05.867523 waagent[2473]: 2025-10-13T05:35:05.867434Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 05:35:05.867523 waagent[2473]: 2025-10-13T05:35:05.867482Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:35:05.868132 waagent[2473]: 2025-10-13T05:35:05.868092Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:35:05.868332 waagent[2473]: 2025-10-13T05:35:05.868280Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 05:35:05.868471 waagent[2473]: 2025-10-13T05:35:05.868390Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 05:35:05.868623 waagent[2473]: 2025-10-13T05:35:05.868575Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 05:35:05.868760 waagent[2473]: 2025-10-13T05:35:05.868714Z INFO EnvHandler ExtHandler Configure routes Oct 13 05:35:05.869222 waagent[2473]: 2025-10-13T05:35:05.869166Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 05:35:05.869323 waagent[2473]: 2025-10-13T05:35:05.869237Z INFO EnvHandler ExtHandler Gateway:None Oct 13 05:35:05.869897 waagent[2473]: 2025-10-13T05:35:05.869849Z INFO EnvHandler ExtHandler Routes:None Oct 13 05:35:05.870414 waagent[2473]: 2025-10-13T05:35:05.870363Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 05:35:05.870414 waagent[2473]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 05:35:05.870414 waagent[2473]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 05:35:05.870414 waagent[2473]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 05:35:05.870414 waagent[2473]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:35:05.870414 waagent[2473]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:35:05.870414 waagent[2473]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:35:05.876976 waagent[2473]: 2025-10-13T05:35:05.876927Z INFO ExtHandler ExtHandler Oct 13 05:35:05.877330 waagent[2473]: 2025-10-13T05:35:05.877285Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: a5d73c19-b0a4-49cd-97b5-8ce13837b059 correlation ec4b74d6-14c0-4a09-9f7e-0a35a3aae3a5 created: 2025-10-13T05:33:25.276031Z] Oct 13 05:35:05.879445 waagent[2473]: 2025-10-13T05:35:05.879389Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 05:35:05.881320 waagent[2473]: 2025-10-13T05:35:05.881281Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Oct 13 05:35:05.906721 waagent[2473]: 2025-10-13T05:35:05.906679Z 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 Oct 13 05:35:05.906721 waagent[2473]: Try `iptables -h' or 'iptables --help' for more information.) Oct 13 05:35:05.907146 waagent[2473]: 2025-10-13T05:35:05.907105Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C30D4680-3D98-47CF-8C2C-7EBE6F9C839B;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 13 05:35:06.003926 waagent[2473]: 2025-10-13T05:35:06.003859Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 13 05:35:06.003926 waagent[2473]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.003926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.003926 waagent[2473]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.003926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.003926 waagent[2473]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.003926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.003926 waagent[2473]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:35:06.003926 waagent[2473]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:35:06.003926 waagent[2473]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:35:06.007926 waagent[2473]: 2025-10-13T05:35:06.007878Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 05:35:06.007926 waagent[2473]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.007926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.007926 waagent[2473]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.007926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.007926 waagent[2473]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:35:06.007926 waagent[2473]: pkts bytes target prot opt in out source destination Oct 13 05:35:06.007926 waagent[2473]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:35:06.007926 waagent[2473]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:35:06.007926 waagent[2473]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:35:06.008189 waagent[2473]: 2025-10-13T05:35:06.008137Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 13 05:35:06.008313 waagent[2473]: 2025-10-13T05:35:06.008279Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 05:35:06.020048 waagent[2473]: 2025-10-13T05:35:06.019994Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 05:35:06.020048 waagent[2473]: Executing ['ip', '-a', '-o', 'link']: Oct 13 05:35:06.020048 waagent[2473]: 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 Oct 13 05:35:06.020048 waagent[2473]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:40:cd:da brd ff:ff:ff:ff:ff:ff\ altname enx7ced8d40cdda Oct 13 05:35:06.020048 waagent[2473]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 05:35:06.020048 waagent[2473]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 05:35:06.020048 waagent[2473]: 2: eth0 inet 10.200.8.36/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 05:35:06.020048 waagent[2473]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 05:35:06.020048 waagent[2473]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 05:35:06.020048 waagent[2473]: 2: eth0 inet6 fe80::7eed:8dff:fe40:cdda/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 05:35:11.522342 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 05:35:11.528215 systemd[1]: Started sshd@0-10.200.8.36:22-10.200.16.10:34684.service - OpenSSH per-connection server daemon (10.200.16.10:34684). Oct 13 05:35:12.595885 sshd[2620]: Accepted publickey for core from 10.200.16.10 port 34684 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:12.597096 sshd-session[2620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:12.601357 systemd-logind[2146]: New session 3 of user core. Oct 13 05:35:12.615650 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 05:35:13.157591 systemd[1]: Started sshd@1-10.200.8.36:22-10.200.16.10:34696.service - OpenSSH per-connection server daemon (10.200.16.10:34696). Oct 13 05:35:13.802186 sshd[2626]: Accepted publickey for core from 10.200.16.10 port 34696 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:13.803703 sshd-session[2626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:13.808184 systemd-logind[2146]: New session 4 of user core. Oct 13 05:35:13.819676 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 05:35:14.255462 sshd[2629]: Connection closed by 10.200.16.10 port 34696 Oct 13 05:35:14.255815 sshd-session[2626]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:14.259470 systemd[1]: sshd@1-10.200.8.36:22-10.200.16.10:34696.service: Deactivated successfully. Oct 13 05:35:14.261221 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 05:35:14.261909 systemd-logind[2146]: Session 4 logged out. Waiting for processes to exit. Oct 13 05:35:14.262796 systemd-logind[2146]: Removed session 4. Oct 13 05:35:14.386191 systemd[1]: Started sshd@2-10.200.8.36:22-10.200.16.10:34708.service - OpenSSH per-connection server daemon (10.200.16.10:34708). Oct 13 05:35:15.058370 sshd[2635]: Accepted publickey for core from 10.200.16.10 port 34708 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:15.059644 sshd-session[2635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:15.063900 systemd-logind[2146]: New session 5 of user core. Oct 13 05:35:15.073748 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 05:35:15.512199 sshd[2638]: Connection closed by 10.200.16.10 port 34708 Oct 13 05:35:15.512596 sshd-session[2635]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:15.515765 systemd[1]: sshd@2-10.200.8.36:22-10.200.16.10:34708.service: Deactivated successfully. Oct 13 05:35:15.517631 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 05:35:15.518968 systemd-logind[2146]: Session 5 logged out. Waiting for processes to exit. Oct 13 05:35:15.519907 systemd-logind[2146]: Removed session 5. Oct 13 05:35:17.408742 chronyd[2126]: Selected source PHC0 Oct 13 05:35:17.781938 setup-nvidia[2320]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 495M 0 752k 0 0 504k 0 0:16:44 0:00:01 0:16:43 504k\u000d 3 495M 3 19.7M 0 0 8128k 0 0:01:02 0:00:02 0:01:00 8128k\u000d 8 495M 8 43.3M 0 0 12.4M 0 0:00:39 0:00:03 0:00:36 12.4M\u000d 13 495M 13 68.4M 0 0 15.3M 0 0:00:32 0:00:04 0:00:28 15.3M\u000d 18 495M 18 92.9M 0 0 16.9M 0 0:00:29 0:00:05 0:00:24 18.7M\u000d 23 495M 23 115M 0 0 17.9M 0 0:00:27 0:00:06 0:00:21 23.0M\u000d 28 495M 28 141M 0 0 18.9M 0 0:00:26 0:00:07 0:00:19 24.3M\u000d 33 495M 33 165M 0 0 19.5M 0 0:00:25 0:00:08 0:00:17 24.4M\u000d 36 495M 36 179M 0 0 18.8M 0 0:00:26 0:00:09 0:00:17 21.9M\u000d 36 495M 36 179M 0 0 17.1M 0 0:00:28 0:00:10 0:00:18 17.3M\u000d 40 495M 40 200M 0 0 17.4M 0 0:00:28 0:00:11 0:00:17 16.9M\u000d 45 495M 45 225M 0 0 18.0M 0 0:00:27 0:00:12 0:00:15 16.8M\u000d 50 495M 50 250M 0 0 18.5M 0 0:00:26 0:00:13 0:00:13 16.9M\u000d 55 495M 55 273M 0 0 18.9M 0 0:00:26 0:00:14 0:00:12 19.1M\u000d 60 495M 60 299M 0 0 19.3M 0 0:00:25 0:00:15 0:00:10 24.0M\u000d 65 495M 65 322M 0 0 19.5M 0 0:00:25 0:00:16 0:00:09 24.4M\u000d 70 495M 70 346M 0 0 19.9M 0 0:00:24 0:00:17 0:00:07 24.4M\u000d 75 495M 75 372M 0 0 20.2M 0 0:00:24 0:00:18 0:00:06 24.6M\u000d 80 495M 80 397M 0 0 20.4M 0 0:00:24 0:00:19 0:00:05 24.9M\u000d 85 495M 85 422M 0 0 20.6M 0 0:00:23 0:00:20 0:00:03 24.7M\u000d 90 495M 90 449M 0 0 20.9M 0 0:00:23 0:00:21 0:00:02 25.5M\u000d 96 495M 96 475M 0 0 21.2M 0 0:00:23 0:00:22 0:00:01 25.8M\u000d100 495M 100 495M 0 0 21.2M 0 0:00:23 0:00:23 --:--:-- 25.4M Oct 13 05:35:25.629104 systemd[1]: Started sshd@3-10.200.8.36:22-10.200.16.10:40024.service - OpenSSH per-connection server daemon (10.200.16.10:40024). Oct 13 05:35:26.289166 sshd[2654]: Accepted publickey for core from 10.200.16.10 port 40024 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:26.290433 sshd-session[2654]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:26.297632 systemd-logind[2146]: New session 6 of user core. Oct 13 05:35:26.303681 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 05:35:26.759545 sshd[2657]: Connection closed by 10.200.16.10 port 40024 Oct 13 05:35:26.757067 sshd-session[2654]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:26.760725 systemd[1]: sshd@3-10.200.8.36:22-10.200.16.10:40024.service: Deactivated successfully. Oct 13 05:35:26.762424 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 05:35:26.766371 systemd-logind[2146]: Session 6 logged out. Waiting for processes to exit. Oct 13 05:35:26.767352 systemd-logind[2146]: Removed session 6. Oct 13 05:35:26.877861 systemd[1]: Started sshd@4-10.200.8.36:22-10.200.16.10:40032.service - OpenSSH per-connection server daemon (10.200.16.10:40032). Oct 13 05:35:27.544532 sshd[2663]: Accepted publickey for core from 10.200.16.10 port 40032 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:27.545913 sshd-session[2663]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:27.553652 systemd-logind[2146]: New session 7 of user core. Oct 13 05:35:27.559674 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 05:35:28.004538 sshd[2666]: Connection closed by 10.200.16.10 port 40032 Oct 13 05:35:28.006553 sshd-session[2663]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:28.012319 systemd[1]: sshd@4-10.200.8.36:22-10.200.16.10:40032.service: Deactivated successfully. Oct 13 05:35:28.017279 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 05:35:28.018748 systemd-logind[2146]: Session 7 logged out. Waiting for processes to exit. Oct 13 05:35:28.020417 systemd-logind[2146]: Removed session 7. Oct 13 05:35:28.282497 setup-nvidia[2139]: Downloading NVIDIA 535.261.03 Driver Oct 13 05:35:28.286725 setup-nvidia[2673]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:35:28.286955 setup-nvidia[2673]: Dload Upload Total Spent Left Speed Oct 13 05:35:32.193616 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 13 05:35:38.121543 systemd[1]: Started sshd@5-10.200.8.36:22-10.200.16.10:45172.service - OpenSSH per-connection server daemon (10.200.16.10:45172). Oct 13 05:35:38.764032 sshd[2675]: Accepted publickey for core from 10.200.16.10 port 45172 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:38.765310 sshd-session[2675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:38.769598 systemd-logind[2146]: New session 8 of user core. Oct 13 05:35:38.780636 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 13 05:35:39.219652 sshd[2678]: Connection closed by 10.200.16.10 port 45172 Oct 13 05:35:39.219874 sshd-session[2675]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:39.223584 systemd[1]: sshd@5-10.200.8.36:22-10.200.16.10:45172.service: Deactivated successfully. Oct 13 05:35:39.225342 systemd[1]: session-8.scope: Deactivated successfully. Oct 13 05:35:39.226157 systemd-logind[2146]: Session 8 logged out. Waiting for processes to exit. Oct 13 05:35:39.227029 systemd-logind[2146]: Removed session 8. Oct 13 05:35:39.337187 systemd[1]: Started sshd@6-10.200.8.36:22-10.200.16.10:45178.service - OpenSSH per-connection server daemon (10.200.16.10:45178). Oct 13 05:35:39.725123 update_engine[2148]: I20251013 05:35:39.725059 2148 update_attempter.cc:509] Updating boot flags... Oct 13 05:35:39.988627 sshd[2684]: Accepted publickey for core from 10.200.16.10 port 45178 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:39.989759 sshd-session[2684]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:39.993877 systemd-logind[2146]: New session 9 of user core. Oct 13 05:35:40.003644 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 13 05:35:40.442317 sshd[2803]: Connection closed by 10.200.16.10 port 45178 Oct 13 05:35:40.442695 sshd-session[2684]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:40.446502 systemd[1]: sshd@6-10.200.8.36:22-10.200.16.10:45178.service: Deactivated successfully. Oct 13 05:35:40.448067 systemd[1]: session-9.scope: Deactivated successfully. Oct 13 05:35:40.448784 systemd-logind[2146]: Session 9 logged out. Waiting for processes to exit. Oct 13 05:35:40.449843 systemd-logind[2146]: Removed session 9. Oct 13 05:35:50.561853 systemd[1]: Started sshd@7-10.200.8.36:22-10.200.16.10:34140.service - OpenSSH per-connection server daemon (10.200.16.10:34140). Oct 13 05:35:51.201959 sshd[2809]: Accepted publickey for core from 10.200.16.10 port 34140 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:51.203230 sshd-session[2809]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:51.207461 systemd-logind[2146]: New session 10 of user core. Oct 13 05:35:51.215637 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 13 05:35:51.656706 sshd[2812]: Connection closed by 10.200.16.10 port 34140 Oct 13 05:35:51.656958 sshd-session[2809]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:51.660548 systemd[1]: sshd@7-10.200.8.36:22-10.200.16.10:34140.service: Deactivated successfully. Oct 13 05:35:51.662117 systemd[1]: session-10.scope: Deactivated successfully. Oct 13 05:35:51.662813 systemd-logind[2146]: Session 10 logged out. Waiting for processes to exit. Oct 13 05:35:51.663680 systemd-logind[2146]: Removed session 10. Oct 13 05:35:51.769700 systemd[1]: Started sshd@8-10.200.8.36:22-10.200.16.10:34146.service - OpenSSH per-connection server daemon (10.200.16.10:34146). Oct 13 05:35:52.436732 sshd[2818]: Accepted publickey for core from 10.200.16.10 port 34146 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:35:52.438005 sshd-session[2818]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:35:52.442245 systemd-logind[2146]: New session 11 of user core. Oct 13 05:35:52.450664 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 13 05:35:52.889872 sshd[2821]: Connection closed by 10.200.16.10 port 34146 Oct 13 05:35:52.890113 sshd-session[2818]: pam_unix(sshd:session): session closed for user core Oct 13 05:35:52.893758 systemd[1]: sshd@8-10.200.8.36:22-10.200.16.10:34146.service: Deactivated successfully. Oct 13 05:35:52.895319 systemd[1]: session-11.scope: Deactivated successfully. Oct 13 05:35:52.896026 systemd-logind[2146]: Session 11 logged out. Waiting for processes to exit. Oct 13 05:35:52.896925 systemd-logind[2146]: Removed session 11. Oct 13 05:36:03.007564 systemd[1]: Started sshd@9-10.200.8.36:22-10.200.16.10:54628.service - OpenSSH per-connection server daemon (10.200.16.10:54628). Oct 13 05:36:03.648763 sshd[2827]: Accepted publickey for core from 10.200.16.10 port 54628 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:03.650001 sshd-session[2827]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:03.654386 systemd-logind[2146]: New session 12 of user core. Oct 13 05:36:03.675655 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 13 05:36:04.101851 sshd[2830]: Connection closed by 10.200.16.10 port 54628 Oct 13 05:36:04.102204 sshd-session[2827]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:04.105976 systemd[1]: sshd@9-10.200.8.36:22-10.200.16.10:54628.service: Deactivated successfully. Oct 13 05:36:04.107989 systemd[1]: session-12.scope: Deactivated successfully. Oct 13 05:36:04.108928 systemd-logind[2146]: Session 12 logged out. Waiting for processes to exit. Oct 13 05:36:04.109833 systemd-logind[2146]: Removed session 12. Oct 13 05:36:04.217194 systemd[1]: Started sshd@10-10.200.8.36:22-10.200.16.10:54640.service - OpenSSH per-connection server daemon (10.200.16.10:54640). Oct 13 05:36:04.862547 sshd[2837]: Accepted publickey for core from 10.200.16.10 port 54640 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:05.805387 sshd-session[2837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:05.811228 systemd-logind[2146]: New session 13 of user core. Oct 13 05:36:05.831701 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 13 05:36:06.190337 sshd[2840]: Connection closed by 10.200.16.10 port 54640 Oct 13 05:36:06.190709 sshd-session[2837]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:06.194335 systemd[1]: sshd@10-10.200.8.36:22-10.200.16.10:54640.service: Deactivated successfully. Oct 13 05:36:06.195914 systemd[1]: session-13.scope: Deactivated successfully. Oct 13 05:36:06.196637 systemd-logind[2146]: Session 13 logged out. Waiting for processes to exit. Oct 13 05:36:06.197582 systemd-logind[2146]: Removed session 13. Oct 13 05:36:16.315692 systemd[1]: Started sshd@11-10.200.8.36:22-10.200.16.10:34786.service - OpenSSH per-connection server daemon (10.200.16.10:34786). Oct 13 05:36:16.958541 sshd[2846]: Accepted publickey for core from 10.200.16.10 port 34786 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:16.959886 sshd-session[2846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:16.964157 systemd-logind[2146]: New session 14 of user core. Oct 13 05:36:16.972628 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 13 05:36:17.420763 sshd[2849]: Connection closed by 10.200.16.10 port 34786 Oct 13 05:36:17.421031 sshd-session[2846]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:17.424753 systemd[1]: sshd@11-10.200.8.36:22-10.200.16.10:34786.service: Deactivated successfully. Oct 13 05:36:17.426555 systemd[1]: session-14.scope: Deactivated successfully. Oct 13 05:36:17.427403 systemd-logind[2146]: Session 14 logged out. Waiting for processes to exit. Oct 13 05:36:17.428264 systemd-logind[2146]: Removed session 14. Oct 13 05:36:17.538436 systemd[1]: Started sshd@12-10.200.8.36:22-10.200.16.10:34802.service - OpenSSH per-connection server daemon (10.200.16.10:34802). Oct 13 05:36:18.178351 sshd[2855]: Accepted publickey for core from 10.200.16.10 port 34802 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:18.179532 sshd-session[2855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:18.183768 systemd-logind[2146]: New session 15 of user core. Oct 13 05:36:18.194635 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 13 05:36:18.634355 sshd[2858]: Connection closed by 10.200.16.10 port 34802 Oct 13 05:36:18.634731 sshd-session[2855]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:18.638417 systemd[1]: sshd@12-10.200.8.36:22-10.200.16.10:34802.service: Deactivated successfully. Oct 13 05:36:18.640105 systemd[1]: session-15.scope: Deactivated successfully. Oct 13 05:36:18.640838 systemd-logind[2146]: Session 15 logged out. Waiting for processes to exit. Oct 13 05:36:18.641733 systemd-logind[2146]: Removed session 15. Oct 13 05:36:28.749032 systemd[1]: Started sshd@13-10.200.8.36:22-10.200.16.10:49682.service - OpenSSH per-connection server daemon (10.200.16.10:49682). Oct 13 05:36:28.894713 setup-nvidia[2673]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 2049k 0 0 2460k 0 0:02:15 --:--:-- 0:02:15 2460k\u000d 1 325M 1 6222k 0 0 3583k 0 0:01:33 0:00:01 0:01:32 3582k\u000d 3 325M 3 12.0M 0 0 4621k 0 0:01:12 0:00:02 0:01:10 4621k\u000d 4 325M 4 16.0M 0 0 4127k 0 0:01:20 0:00:03 0:01:17 4127k\u000d 6 325M 6 22.0M 0 0 4463k 0 0:01:14 0:00:05 0:01:09 4463k\u000d 7 325M 7 26.0M 0 0 4545k 0 0:01:13 0:00:05 0:01:08 4891k\u000d 9 325M 9 30.2M 0 0 4648k 0 0:01:11 0:00:06 0:01:05 5022k\u000d 11 325M 11 38.0M 0 0 4916k 0 0:01:07 0:00:07 0:01:00 5065k\u000d 13 325M 13 44.0M 0 0 5031k 0 0:01:06 0:00:08 0:00:58 5750k\u000d 14 325M 14 48.0M 0 0 5082k 0 0:01:05 0:00:09 0:00:56 5759k\u000d 16 325M 16 54.1M 0 0 5137k 0 0:01:04 0:00:10 0:00:54 5839k\u000d 18 325M 18 60.0M 0 0 5267k 0 0:01:03 0:00:11 0:00:52 6093k\u000d 20 325M 20 66.1M 0 0 5268k 0 0:01:03 0:00:12 0:00:51 5832k\u000d 22 325M 22 72.0M 0 0 5371k 0 0:01:02 0:00:13 0:00:49 6008k\u000d 23 325M 23 78.0M 0 0 5353k 0 0:01:02 0:00:14 0:00:48 5852k\u000d 25 325M 25 82.0M 0 0 5361k 0 0:01:02 0:00:15 0:00:47 5857k\u000d 26 325M 26 88.0M 0 0 5379k 0 0:01:02 0:00:16 0:00:46 5635k\u000d 28 325M 28 94.0M 0 0 5363k 0 0:01:02 0:00:17 0:00:45 5604k\u000d 30 325M 30 98.0M 0 0 5347k 0 0:01:02 0:00:18 0:00:44 5285k\u000d 31 325M 31 104M 0 0 5346k 0 0:01:02 0:00:19 0:00:43 5325k\u000d 33 325M 33 108M 0 0 5339k 0 0:01:02 0:00:20 0:00:42 5272k\u000d 34 325M 34 114M 0 0 5309k 0 0:01:02 0:00:21 0:00:41 5086k\u000d 36 325M 36 118M 0 0 5304k 0 0:01:02 0:00:22 0:00:40 5085k\u000d 38 325M 38 124M 0 0 5363k 0 0:01:02 0:00:23 0:00:39 5420k\u000d 39 325M 39 130M 0 0 5361k 0 0:01:02 0:00:24 0:00:38 5420k\u000d 41 325M 41 136M 0 0 5420k 0 0:01:01 0:00:25 0:00:36 5754k\u000d 43 325M 43 141M 0 0 5448k 0 0:01:01 0:00:26 0:00:35 6102k\u000d 44 325M 44 146M 0 0 5374k 0 0:01:02 0:00:27 0:00:35 5694k\u000d 46 325M 46 152M 0 0 5430k 0 0:01:01 0:00:28 0:00:33 5750k\u000d 47 325M 47 156M 0 0 5373k 0 0:01:02 0:00:29 0:00:33 5434k\u000d 50 325M 50 164M 0 0 5475k 0 0:01:00 0:00:30 0:00:30 5758k\u000d 51 325M 51 168M 0 0 5420k 0 0:01:01 0:00:31 0:00:30 5274k\u000d 53 325M 53 176M 0 0 5477k 0 0:01:00 0:00:32 0:00:28 6037k\u000d 55 325M 55 180M 0 0 5479k 0 0:01:00 0:00:33 0:00:27 5761k\u000d 57 325M 57 186M 0 0 5468k 0 0:01:01 0:00:34 0:00:27 6022k\u000d 58 325M 58 192M 0 0 5466k 0 0:01:01 0:00:35 0:00:26 5413k\u000d 59 325M 59 194M 0 0 5297k 0 0:01:03 0:00:37 0:00:26 4621k\u000d 61 325M 61 200M 0 0 5410k 0 0:01:01 0:00:37 0:00:24 4965k\u000d 63 325M 63 205M 0 0 5456k 0 0:01:01 0:00:38 0:00:23 5299k\u000d 64 325M 64 210M 0 0 5413k 0 0:01:01 0:00:39 0:00:22 5021k\u000d 66 325M 66 216M 0 0 5418k 0 0:01:01 0:00:40 0:00:21 5065k\u000d 67 325M 67 220M 0 0 5401k 0 0:01:01 0:00:41 0:00:20 6332k\u000d 69 325M 69 228M 0 0 5439k 0 0:01:01 0:00:42 0:00:19 5659k\u000d 71 325M 71 232M 0 0 5441k 0 0:01:01 0:00:43 0:00:18 5326k\u000d 73 325M 73 238M 0 0 5422k 0 0:01:01 0:00:44 0:00:17 5496k\u000d 74 325M 74 242M 0 0 5423k 0 0:01:01 0:00:45 0:00:16 5466k\u000d 76 325M 76 248M 0 0 5422k 0 0:01:01 0:00:46 0:00:15 5592k\u000d 77 325M 77 254M 0 0 5439k 0 0:01:01 0:00:47 0:00:14 5431k\u000d 79 325M 79 260M 0 0 5469k 0 0:01:01 0:00:48 0:00:13 5713k\u000d 80 325M 80 264M 0 0 5429k 0 0:01:01 0:00:49 0:00:12 5492k\u000d 83 325M 83 272M 0 0 5495k 0 0:01:00 0:00:50 0:00:10 6147k\u000d 84 325M 84 276M 0 0 5455k 0 0:01:01 0:00:51 0:00:10 5765k\u000d 86 325M 86 282M 0 0 5481k 0 0:01:00 0:00:52 0:00:08 5893k\u000d 88 325M 88 288M 0 0 5483k 0 0:01:00 0:00:53 0:00:07 5620k\u000d 90 325M 90 294M 0 0 5477k 0 0:01:00 0:00:54 0:00:06 5941k\u000d 91 325M 91 298M 0 0 5475k 0 0:01:00 0:00:55 0:00:05 5281k\u000d 93 325M 93 304M 0 0 5484k 0 0:01:00 0:00:56 0:00:04 5789k\u000d 95 325M 95 309M 0 0 5504k 0 0:01:00 0:00:57 0:00:03 5750k\u000d 96 325M 96 314M 0 0 5474k 0 0:01:00 0:00:58 0:00:02 5370k\u000d 98 325M 98 320M 0 0 5465k 0 0:01:01 0:00:59 0:00:02 5324k\u000d100 325M 100 325M 0 0 5506k 0 0:01:00 0:01:00 --:--:-- 5860k Oct 13 05:36:28.984601 setup-nvidia[2139]: Extract the NVIDIA Driver Installer 535.261.03 Oct 13 05:36:28.984601 setup-nvidia[2139]: /opt/nvidia/workdir/nvidia-workdir / Oct 13 05:36:29.003048 setup-nvidia[2869]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 13 05:36:29.282680 setup-nvidia[2869]: Verifying archive integrity... OK Oct 13 05:36:29.401637 sshd[2864]: Accepted publickey for core from 10.200.16.10 port 49682 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:29.402929 sshd-session[2864]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:29.407240 systemd-logind[2146]: New session 16 of user core. Oct 13 05:36:29.416658 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 13 05:36:29.858978 sshd[2892]: Connection closed by 10.200.16.10 port 49682 Oct 13 05:36:29.859324 sshd-session[2864]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:29.862538 systemd[1]: sshd@13-10.200.8.36:22-10.200.16.10:49682.service: Deactivated successfully. Oct 13 05:36:29.864165 systemd[1]: session-16.scope: Deactivated successfully. Oct 13 05:36:29.865368 systemd-logind[2146]: Session 16 logged out. Waiting for processes to exit. Oct 13 05:36:29.866333 systemd-logind[2146]: Removed session 16. Oct 13 05:36:29.979119 systemd[1]: Started sshd@14-10.200.8.36:22-10.200.16.10:49696.service - OpenSSH per-connection server daemon (10.200.16.10:49696). Oct 13 05:36:29.985207 setup-nvidia[2869]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 13 05:36:30.623878 sshd[2905]: Accepted publickey for core from 10.200.16.10 port 49696 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:30.625208 sshd-session[2905]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:30.630673 systemd-logind[2146]: New session 17 of user core. Oct 13 05:36:30.637729 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 13 05:36:31.081534 sshd[2908]: Connection closed by 10.200.16.10 port 49696 Oct 13 05:36:31.081926 sshd-session[2905]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:31.085913 systemd[1]: sshd@14-10.200.8.36:22-10.200.16.10:49696.service: Deactivated successfully. Oct 13 05:36:31.087570 systemd[1]: session-17.scope: Deactivated successfully. Oct 13 05:36:31.088361 systemd-logind[2146]: Session 17 logged out. Waiting for processes to exit. Oct 13 05:36:31.089763 systemd-logind[2146]: Removed session 17. Oct 13 05:36:31.818293 setup-nvidia[2900]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 13 05:36:31.868127 setup-nvidia[2139]: / Oct 13 05:36:31.868456 setup-nvidia[2139]: Spawn system-nspawn container to install the NVIDIA drivers Oct 13 05:36:32.166333 sudo[2916]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4487.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 Oct 13 05:36:32.168087 sudo[2916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:36:32.174027 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 13 05:36:32.178602 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:36:32.213643 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:36:32.217821 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 13 05:36:32.241882 (systemd)[2919]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:36:32.244248 systemd-logind[2146]: New session c3 of user root. Oct 13 05:36:32.434294 systemd[2919]: Queued start job for default target default.target. Oct 13 05:36:32.453765 systemd[2919]: Created slice app.slice - User Application Slice. Oct 13 05:36:32.453799 systemd[2919]: Reached target paths.target - Paths. Oct 13 05:36:32.453857 systemd[2919]: Reached target timers.target - Timers. Oct 13 05:36:32.455180 systemd[2919]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:36:32.466160 systemd[2919]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:36:32.466298 systemd[2919]: Reached target sockets.target - Sockets. Oct 13 05:36:32.466358 systemd[2919]: Reached target basic.target - Basic System. Oct 13 05:36:32.466444 systemd[2919]: Reached target default.target - Main User Target. Oct 13 05:36:32.466486 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 13 05:36:32.466491 systemd[2919]: Startup finished in 214ms. Oct 13 05:36:32.469447 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 13 05:36:32.559697 systemd-udevd[1839]: Received message with invalid length, ignoring Oct 13 05:36:32.564532 kernel: loop7: detected capacity change from 0 to 12644352 Oct 13 05:36:32.566529 kernel: loop7: p9 Oct 13 05:36:32.581371 setup-nvidia[2928]: ░ Spawning container flatcardevelopercontainer-4487.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4487.0.0.bin. Oct 13 05:36:32.699817 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Oct 13 05:36:32.699932 kernel: EXT4-fs (loop7p9): mounted filesystem d2a26a63-211a-4b1f-b156-979c5f5fad00 ro without journal. Quota mode: none. Oct 13 05:36:41.199531 systemd[1]: Started sshd@15-10.200.8.36:22-10.200.16.10:40886.service - OpenSSH per-connection server daemon (10.200.16.10:40886). Oct 13 05:36:41.843077 sshd[3769]: Accepted publickey for core from 10.200.16.10 port 40886 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:41.844419 sshd-session[3769]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:41.850373 systemd-logind[2146]: New session 18 of user core. Oct 13 05:36:41.861639 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 13 05:36:42.296311 sshd[3831]: Connection closed by 10.200.16.10 port 40886 Oct 13 05:36:42.296656 sshd-session[3769]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:42.300363 systemd[1]: sshd@15-10.200.8.36:22-10.200.16.10:40886.service: Deactivated successfully. Oct 13 05:36:42.302627 systemd[1]: session-18.scope: Deactivated successfully. Oct 13 05:36:42.303453 systemd-logind[2146]: Session 18 logged out. Waiting for processes to exit. Oct 13 05:36:42.304369 systemd-logind[2146]: Removed session 18. Oct 13 05:36:42.414142 systemd[1]: Started sshd@16-10.200.8.36:22-10.200.16.10:40888.service - OpenSSH per-connection server daemon (10.200.16.10:40888). Oct 13 05:36:43.055950 sshd[3899]: Accepted publickey for core from 10.200.16.10 port 40888 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:43.057178 sshd-session[3899]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:43.064607 systemd-logind[2146]: New session 19 of user core. Oct 13 05:36:43.078648 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 13 05:36:43.515028 sshd[3902]: Connection closed by 10.200.16.10 port 40888 Oct 13 05:36:43.515370 sshd-session[3899]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:43.518917 systemd[1]: sshd@16-10.200.8.36:22-10.200.16.10:40888.service: Deactivated successfully. Oct 13 05:36:43.520550 systemd[1]: session-19.scope: Deactivated successfully. Oct 13 05:36:43.521249 systemd-logind[2146]: Session 19 logged out. Waiting for processes to exit. Oct 13 05:36:43.522455 systemd-logind[2146]: Removed session 19. Oct 13 05:36:53.635183 systemd[1]: Started sshd@17-10.200.8.36:22-10.200.16.10:40234.service - OpenSSH per-connection server daemon (10.200.16.10:40234). Oct 13 05:36:54.295352 sshd[8160]: Accepted publickey for core from 10.200.16.10 port 40234 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:54.296683 sshd-session[8160]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:54.302132 systemd-logind[2146]: New session 20 of user core. Oct 13 05:36:54.310707 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 13 05:36:54.753648 sshd[8426]: Connection closed by 10.200.16.10 port 40234 Oct 13 05:36:54.754011 sshd-session[8160]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:54.758604 systemd[1]: sshd@17-10.200.8.36:22-10.200.16.10:40234.service: Deactivated successfully. Oct 13 05:36:54.760460 systemd[1]: session-20.scope: Deactivated successfully. Oct 13 05:36:54.760953 systemd-logind[2146]: Session 20 logged out. Waiting for processes to exit. Oct 13 05:36:54.765313 systemd-logind[2146]: Removed session 20. Oct 13 05:36:54.869924 systemd[1]: Started sshd@18-10.200.8.36:22-10.200.16.10:40250.service - OpenSSH per-connection server daemon (10.200.16.10:40250). Oct 13 05:36:55.542029 sshd[8613]: Accepted publickey for core from 10.200.16.10 port 40250 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:36:55.543345 sshd-session[8613]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:36:55.550587 systemd-logind[2146]: New session 21 of user core. Oct 13 05:36:55.556004 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 13 05:36:55.997241 sshd[8886]: Connection closed by 10.200.16.10 port 40250 Oct 13 05:36:55.997799 sshd-session[8613]: pam_unix(sshd:session): session closed for user core Oct 13 05:36:56.002872 systemd[1]: sshd@18-10.200.8.36:22-10.200.16.10:40250.service: Deactivated successfully. Oct 13 05:36:56.004493 systemd[1]: session-21.scope: Deactivated successfully. Oct 13 05:36:56.007937 systemd-logind[2146]: Session 21 logged out. Waiting for processes to exit. Oct 13 05:36:56.009306 systemd-logind[2146]: Removed session 21. Oct 13 05:37:06.121301 systemd[1]: Started sshd@19-10.200.8.36:22-10.200.16.10:35736.service - OpenSSH per-connection server daemon (10.200.16.10:35736). Oct 13 05:37:06.780096 sshd[12723]: Accepted publickey for core from 10.200.16.10 port 35736 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:06.781921 sshd-session[12723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:06.786831 systemd-logind[2146]: New session 22 of user core. Oct 13 05:37:06.796652 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 13 05:37:07.239023 sshd[12863]: Connection closed by 10.200.16.10 port 35736 Oct 13 05:37:07.239368 sshd-session[12723]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:07.243087 systemd-logind[2146]: Session 22 logged out. Waiting for processes to exit. Oct 13 05:37:07.245157 systemd[1]: sshd@19-10.200.8.36:22-10.200.16.10:35736.service: Deactivated successfully. Oct 13 05:37:07.248793 systemd[1]: session-22.scope: Deactivated successfully. Oct 13 05:37:07.251229 systemd-logind[2146]: Removed session 22. Oct 13 05:37:07.361825 systemd[1]: Started sshd@20-10.200.8.36:22-10.200.16.10:35746.service - OpenSSH per-connection server daemon (10.200.16.10:35746). Oct 13 05:37:08.028958 sshd[13068]: Accepted publickey for core from 10.200.16.10 port 35746 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:08.030649 sshd-session[13068]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:08.035708 systemd-logind[2146]: New session 23 of user core. Oct 13 05:37:08.043919 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 13 05:37:08.488542 sshd[13310]: Connection closed by 10.200.16.10 port 35746 Oct 13 05:37:08.488449 sshd-session[13068]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:08.492297 systemd[1]: sshd@20-10.200.8.36:22-10.200.16.10:35746.service: Deactivated successfully. Oct 13 05:37:08.494676 systemd[1]: session-23.scope: Deactivated successfully. Oct 13 05:37:08.496113 systemd-logind[2146]: Session 23 logged out. Waiting for processes to exit. Oct 13 05:37:08.497757 systemd-logind[2146]: Removed session 23. Oct 13 05:37:18.608938 systemd[1]: Started sshd@21-10.200.8.36:22-10.200.16.10:47236.service - OpenSSH per-connection server daemon (10.200.16.10:47236). Oct 13 05:37:19.291189 sshd[14731]: Accepted publickey for core from 10.200.16.10 port 47236 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:19.294142 sshd-session[14731]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:19.304458 systemd-logind[2146]: New session 24 of user core. Oct 13 05:37:19.310339 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 13 05:37:19.748867 sshd[14746]: Connection closed by 10.200.16.10 port 47236 Oct 13 05:37:19.753681 sshd-session[14731]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:19.757637 systemd[1]: sshd@21-10.200.8.36:22-10.200.16.10:47236.service: Deactivated successfully. Oct 13 05:37:19.760097 systemd[1]: session-24.scope: Deactivated successfully. Oct 13 05:37:19.761821 systemd-logind[2146]: Session 24 logged out. Waiting for processes to exit. Oct 13 05:37:19.765909 systemd-logind[2146]: Removed session 24. Oct 13 05:37:19.874921 systemd[1]: Started sshd@22-10.200.8.36:22-10.200.16.10:47238.service - OpenSSH per-connection server daemon (10.200.16.10:47238). Oct 13 05:37:20.532438 sshd[14765]: Accepted publickey for core from 10.200.16.10 port 47238 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:20.534136 sshd-session[14765]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:20.543670 systemd-logind[2146]: New session 25 of user core. Oct 13 05:37:20.551617 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 13 05:37:20.992085 sshd[14785]: Connection closed by 10.200.16.10 port 47238 Oct 13 05:37:20.993701 sshd-session[14765]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:20.997614 systemd-logind[2146]: Session 25 logged out. Waiting for processes to exit. Oct 13 05:37:21.000136 systemd[1]: sshd@22-10.200.8.36:22-10.200.16.10:47238.service: Deactivated successfully. Oct 13 05:37:21.003800 systemd[1]: session-25.scope: Deactivated successfully. Oct 13 05:37:21.006909 systemd-logind[2146]: Removed session 25. Oct 13 05:37:31.110794 systemd[1]: Started sshd@23-10.200.8.36:22-10.200.16.10:44428.service - OpenSSH per-connection server daemon (10.200.16.10:44428). Oct 13 05:37:31.782541 sshd[15127]: Accepted publickey for core from 10.200.16.10 port 44428 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:31.783413 sshd-session[15127]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:31.792447 systemd-logind[2146]: New session 26 of user core. Oct 13 05:37:31.798661 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 13 05:37:32.240017 sshd[15160]: Connection closed by 10.200.16.10 port 44428 Oct 13 05:37:32.240614 sshd-session[15127]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:32.244943 systemd[1]: sshd@23-10.200.8.36:22-10.200.16.10:44428.service: Deactivated successfully. Oct 13 05:37:32.245181 systemd-logind[2146]: Session 26 logged out. Waiting for processes to exit. Oct 13 05:37:32.247365 systemd[1]: session-26.scope: Deactivated successfully. Oct 13 05:37:32.251902 systemd-logind[2146]: Removed session 26. Oct 13 05:37:32.356765 systemd[1]: Started sshd@24-10.200.8.36:22-10.200.16.10:44432.service - OpenSSH per-connection server daemon (10.200.16.10:44432). Oct 13 05:37:33.022177 sshd[15178]: Accepted publickey for core from 10.200.16.10 port 44432 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:33.023151 sshd-session[15178]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:33.029290 systemd-logind[2146]: New session 27 of user core. Oct 13 05:37:33.041747 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 13 05:37:33.477896 sshd[15194]: Connection closed by 10.200.16.10 port 44432 Oct 13 05:37:33.478230 sshd-session[15178]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:33.481570 systemd[1]: sshd@24-10.200.8.36:22-10.200.16.10:44432.service: Deactivated successfully. Oct 13 05:37:33.483247 systemd[1]: session-27.scope: Deactivated successfully. Oct 13 05:37:33.485102 systemd-logind[2146]: Session 27 logged out. Waiting for processes to exit. Oct 13 05:37:33.486562 systemd-logind[2146]: Removed session 27. Oct 13 05:37:43.605406 systemd[1]: Started sshd@25-10.200.8.36:22-10.200.16.10:42806.service - OpenSSH per-connection server daemon (10.200.16.10:42806). Oct 13 05:37:44.247869 sshd[15556]: Accepted publickey for core from 10.200.16.10 port 42806 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:44.249211 sshd-session[15556]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:44.253570 systemd-logind[2146]: New session 28 of user core. Oct 13 05:37:44.263715 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 13 05:37:44.700534 sshd[15559]: Connection closed by 10.200.16.10 port 42806 Oct 13 05:37:44.701144 sshd-session[15556]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:44.704950 systemd[1]: sshd@25-10.200.8.36:22-10.200.16.10:42806.service: Deactivated successfully. Oct 13 05:37:44.706538 systemd[1]: session-28.scope: Deactivated successfully. Oct 13 05:37:44.707253 systemd-logind[2146]: Session 28 logged out. Waiting for processes to exit. Oct 13 05:37:44.708152 systemd-logind[2146]: Removed session 28. Oct 13 05:37:44.819915 systemd[1]: Started sshd@26-10.200.8.36:22-10.200.16.10:42808.service - OpenSSH per-connection server daemon (10.200.16.10:42808). Oct 13 05:37:45.480005 sshd[15565]: Accepted publickey for core from 10.200.16.10 port 42808 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:45.481224 sshd-session[15565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:45.485652 systemd-logind[2146]: New session 29 of user core. Oct 13 05:37:45.497639 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 13 05:37:45.932226 sshd[15569]: Connection closed by 10.200.16.10 port 42808 Oct 13 05:37:45.932531 sshd-session[15565]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:45.936319 systemd[1]: sshd@26-10.200.8.36:22-10.200.16.10:42808.service: Deactivated successfully. Oct 13 05:37:45.937879 systemd[1]: session-29.scope: Deactivated successfully. Oct 13 05:37:45.938582 systemd-logind[2146]: Session 29 logged out. Waiting for processes to exit. Oct 13 05:37:45.939612 systemd-logind[2146]: Removed session 29. Oct 13 05:37:52.765765 setup-nvidia[2928]: \u000d Oct 13 05:37:52.765765 setup-nvidia[2928]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 13 05:37:52.765765 setup-nvidia[2928]: frequently when this kernel module was built against the wrong or Oct 13 05:37:52.765765 setup-nvidia[2928]: improperly configured kernel sources, with a version of gcc that differs Oct 13 05:37:52.765765 setup-nvidia[2928]: from the one used to build the target kernel, or if another driver, such Oct 13 05:37:52.765765 setup-nvidia[2928]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 13 05:37:52.765765 setup-nvidia[2928]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 13 05:37:52.765765 setup-nvidia[2928]: installed in this system is supported by this NVIDIA Linux graphics Oct 13 05:37:52.765765 setup-nvidia[2928]: driver release. Oct 13 05:37:52.765765 setup-nvidia[2928]: \u000d Oct 13 05:37:52.765765 setup-nvidia[2928]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 13 05:37:52.765765 setup-nvidia[2928]: messages' at the end of the file Oct 13 05:37:52.765765 setup-nvidia[2928]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 13 05:37:52.765765 setup-nvidia[2928]: information. Oct 13 05:37:52.765765 setup-nvidia[2928]: \u000d Oct 13 05:37:52.780451 setup-nvidia[2928]: dmesg: read kernel buffer failed: Operation not permitted Oct 13 05:37:52.798996 setup-nvidia[2928]: \u000d Oct 13 05:37:52.798996 setup-nvidia[2928]: ERROR: Installation has failed. Please see the file Oct 13 05:37:52.798996 setup-nvidia[2928]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 13 05:37:52.798996 setup-nvidia[2928]: details. You may find suggestions on fixing installation problems in Oct 13 05:37:52.798996 setup-nvidia[2928]: the README available on the Linux driver download page at Oct 13 05:37:52.798996 setup-nvidia[2928]: www.nvidia.com. Oct 13 05:37:52.798996 setup-nvidia[2928]: \u000d Oct 13 05:37:52.799489 setup-nvidia[2928]: Last 50 lines of nvidia-installer.log: Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:37:52.800593 setup-nvidia[2928]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 13 05:37:52.800593 setup-nvidia[2928]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 13 05:37:52.800593 setup-nvidia[2928]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 13 05:37:52.800593 setup-nvidia[2928]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 13 05:37:52.800593 setup-nvidia[2928]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 13 05:37:52.800593 setup-nvidia[2928]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 13 05:37:52.800593 setup-nvidia[2928]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:37:52.800593 setup-nvidia[2928]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 13 05:37:52.800593 setup-nvidia[2928]: make[2]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/build' Oct 13 05:37:52.800593 setup-nvidia[2928]: make[1]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/source' Oct 13 05:37:52.800593 setup-nvidia[2928]: -> done. Oct 13 05:37:52.800593 setup-nvidia[2928]: -> Kernel module compilation complete. Oct 13 05:37:52.800593 setup-nvidia[2928]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 13 05:37:52.800593 setup-nvidia[2928]: 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. Oct 13 05:37:52.800593 setup-nvidia[2928]: \u000d Oct 13 05:37:52.800593 setup-nvidia[2928]: 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. Oct 13 05:37:52.800593 setup-nvidia[2928]: -> Kernel module load error: Operation not permitted Oct 13 05:37:52.800593 setup-nvidia[2928]: -> Kernel messages: Oct 13 05:37:52.800593 setup-nvidia[2928]: 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. Oct 13 05:37:56.050873 systemd[1]: Started sshd@27-10.200.8.36:22-10.200.16.10:38042.service - OpenSSH per-connection server daemon (10.200.16.10:38042). Oct 13 05:37:56.694723 sshd[15755]: Accepted publickey for core from 10.200.16.10 port 38042 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:56.695975 sshd-session[15755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:56.700537 systemd-logind[2146]: New session 30 of user core. Oct 13 05:37:56.721635 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 13 05:37:57.148669 sshd[15760]: Connection closed by 10.200.16.10 port 38042 Oct 13 05:37:57.148867 sshd-session[15755]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:57.152501 systemd[1]: sshd@27-10.200.8.36:22-10.200.16.10:38042.service: Deactivated successfully. Oct 13 05:37:57.154110 systemd[1]: session-30.scope: Deactivated successfully. Oct 13 05:37:57.154817 systemd-logind[2146]: Session 30 logged out. Waiting for processes to exit. Oct 13 05:37:57.155677 systemd-logind[2146]: Removed session 30. Oct 13 05:37:57.261219 systemd[1]: Started sshd@28-10.200.8.36:22-10.200.16.10:38056.service - OpenSSH per-connection server daemon (10.200.16.10:38056). Oct 13 05:37:57.900628 sshd[15766]: Accepted publickey for core from 10.200.16.10 port 38056 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:37:57.901885 sshd-session[15766]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:37:57.906731 systemd-logind[2146]: New session 31 of user core. Oct 13 05:37:57.927657 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 13 05:37:58.355430 sshd[15769]: Connection closed by 10.200.16.10 port 38056 Oct 13 05:37:58.355829 sshd-session[15766]: pam_unix(sshd:session): session closed for user core Oct 13 05:37:58.359762 systemd[1]: sshd@28-10.200.8.36:22-10.200.16.10:38056.service: Deactivated successfully. Oct 13 05:37:58.361365 systemd[1]: session-31.scope: Deactivated successfully. Oct 13 05:37:58.362103 systemd-logind[2146]: Session 31 logged out. Waiting for processes to exit. Oct 13 05:37:58.363005 systemd-logind[2146]: Removed session 31. Oct 13 05:38:08.480617 systemd[1]: Started sshd@29-10.200.8.36:22-10.200.16.10:44684.service - OpenSSH per-connection server daemon (10.200.16.10:44684). Oct 13 05:38:09.135280 sshd[15775]: Accepted publickey for core from 10.200.16.10 port 44684 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:09.136539 sshd-session[15775]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:09.140674 systemd-logind[2146]: New session 32 of user core. Oct 13 05:38:09.150635 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 13 05:38:09.590931 sshd[15778]: Connection closed by 10.200.16.10 port 44684 Oct 13 05:38:09.591278 sshd-session[15775]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:09.595007 systemd[1]: sshd@29-10.200.8.36:22-10.200.16.10:44684.service: Deactivated successfully. Oct 13 05:38:09.596897 systemd[1]: session-32.scope: Deactivated successfully. Oct 13 05:38:09.597710 systemd-logind[2146]: Session 32 logged out. Waiting for processes to exit. Oct 13 05:38:09.598603 systemd-logind[2146]: Removed session 32. Oct 13 05:38:09.709187 systemd[1]: Started sshd@30-10.200.8.36:22-10.200.16.10:44690.service - OpenSSH per-connection server daemon (10.200.16.10:44690). Oct 13 05:38:10.352981 sshd[15784]: Accepted publickey for core from 10.200.16.10 port 44690 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:10.354210 sshd-session[15784]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:10.358619 systemd-logind[2146]: New session 33 of user core. Oct 13 05:38:10.371633 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 13 05:38:11.222111 sshd[15787]: Connection closed by 10.200.16.10 port 44690 Oct 13 05:38:11.222473 sshd-session[15784]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:11.226299 systemd[1]: sshd@30-10.200.8.36:22-10.200.16.10:44690.service: Deactivated successfully. Oct 13 05:38:11.228026 systemd[1]: session-33.scope: Deactivated successfully. Oct 13 05:38:11.228814 systemd-logind[2146]: Session 33 logged out. Waiting for processes to exit. Oct 13 05:38:11.229778 systemd-logind[2146]: Removed session 33. Oct 13 05:38:20.932215 systemd[1]: Started sshd@31-10.200.8.36:22-10.200.16.10:45382.service - OpenSSH per-connection server daemon (10.200.16.10:45382). Oct 13 05:38:21.578460 sshd[15794]: Accepted publickey for core from 10.200.16.10 port 45382 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:21.579749 sshd-session[15794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:21.584081 systemd-logind[2146]: New session 34 of user core. Oct 13 05:38:21.604674 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 13 05:38:22.032218 sshd[15797]: Connection closed by 10.200.16.10 port 45382 Oct 13 05:38:22.032607 sshd-session[15794]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:22.036346 systemd[1]: sshd@31-10.200.8.36:22-10.200.16.10:45382.service: Deactivated successfully. Oct 13 05:38:22.037946 systemd[1]: session-34.scope: Deactivated successfully. Oct 13 05:38:22.038713 systemd-logind[2146]: Session 34 logged out. Waiting for processes to exit. Oct 13 05:38:22.039609 systemd-logind[2146]: Removed session 34. Oct 13 05:38:22.157037 systemd[1]: Started sshd@32-10.200.8.36:22-10.200.16.10:45398.service - OpenSSH per-connection server daemon (10.200.16.10:45398). Oct 13 05:38:22.800821 sshd[15803]: Accepted publickey for core from 10.200.16.10 port 45398 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:22.802218 sshd-session[15803]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:22.806528 systemd-logind[2146]: New session 35 of user core. Oct 13 05:38:22.823651 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 13 05:38:23.255324 sshd[15806]: Connection closed by 10.200.16.10 port 45398 Oct 13 05:38:23.255702 sshd-session[15803]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:23.260626 systemd[1]: sshd@32-10.200.8.36:22-10.200.16.10:45398.service: Deactivated successfully. Oct 13 05:38:23.262229 systemd[1]: session-35.scope: Deactivated successfully. Oct 13 05:38:23.262941 systemd-logind[2146]: Session 35 logged out. Waiting for processes to exit. Oct 13 05:38:23.263822 systemd-logind[2146]: Removed session 35. Oct 13 05:38:23.371531 kernel: EXT4-fs (loop7p9): unmounting filesystem d2a26a63-211a-4b1f-b156-979c5f5fad00. Oct 13 05:38:23.371650 setup-nvidia[2928]: Container flatcardevelopercontainer-4487.0.0.bin exited successfully. Oct 13 05:38:23.373814 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4487.0.0.bin.mount: Deactivated successfully. Oct 13 05:38:23.424318 sudo[2916]: pam_unix(sudo:session): session closed for user root Oct 13 05:38:23.426389 systemd[1]: session-c2.scope: Deactivated successfully. Oct 13 05:38:23.426663 systemd[1]: session-c2.scope: Consumed 5min 23.573s CPU time, 892.1M memory peak. Oct 13 05:38:23.855865 setup-nvidia[2139]: /opt/nvidia / Oct 13 05:38:23.856860 setup-nvidia[2139]: / Oct 13 05:38:23.866532 kernel: IPMI message handler: version 39.2 Oct 13 05:38:23.869534 kernel: ipmi device interface Oct 13 05:38:23.882126 setup-nvidia[2139]: /opt/nvidia/current/usr/lib/modules/6.12.51-flatcar/video / Oct 13 05:38:23.935416 kernel: nvidia: loading out-of-tree module taints kernel. Oct 13 05:38:23.935572 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 13 05:38:23.935615 kernel: Disabling lock debugging due to kernel taint Oct 13 05:38:23.942860 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 13 05:38:23.942912 kernel: nvidia: module license taints kernel. Oct 13 05:38:24.338554 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 13 05:38:24.742536 kernel: Oct 13 05:38:24.742687 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 13 05:38:24.950537 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 13 05:38:24.972538 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 13 05:38:24.993546 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 13 05:38:25.038538 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 13 05:38:25.038606 setup-nvidia[2139]: / Oct 13 05:38:25.047898 setup-nvidia[15845]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:38:26.875053 setup-nvidia[15862]: Mon Oct 13 05:38:26 2025 Oct 13 05:38:26.875053 setup-nvidia[15862]: +---------------------------------------------------------------------------------------+ Oct 13 05:38:26.875053 setup-nvidia[15862]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 13 05:38:26.875053 setup-nvidia[15862]: |-----------------------------------------+----------------------+----------------------+ Oct 13 05:38:26.875053 setup-nvidia[15862]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 13 05:38:26.875053 setup-nvidia[15862]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 13 05:38:26.875053 setup-nvidia[15862]: | | | MIG M. | Oct 13 05:38:26.875053 setup-nvidia[15862]: |=========================================+======================+======================| Oct 13 05:38:26.880581 setup-nvidia[15862]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 13 05:38:26.880581 setup-nvidia[15862]: | N/A 23C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Oct 13 05:38:26.880581 setup-nvidia[15862]: | | | N/A | Oct 13 05:38:26.880581 setup-nvidia[15862]: +-----------------------------------------+----------------------+----------------------+ Oct 13 05:38:26.880581 setup-nvidia[15862]: Oct 13 05:38:26.880581 setup-nvidia[15862]: +---------------------------------------------------------------------------------------+ Oct 13 05:38:26.880581 setup-nvidia[15862]: | Processes: | Oct 13 05:38:26.880581 setup-nvidia[15862]: | GPU GI CI PID Type Process name GPU Memory | Oct 13 05:38:26.880581 setup-nvidia[15862]: | ID ID Usage | Oct 13 05:38:26.880581 setup-nvidia[15862]: |=======================================================================================| Oct 13 05:38:26.880581 setup-nvidia[15862]: | No running processes found | Oct 13 05:38:26.880581 setup-nvidia[15862]: +---------------------------------------------------------------------------------------+ Oct 13 05:38:27.253177 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 05:38:27.255264 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 05:38:27.255635 systemd[1]: Startup finished in 1.313s (firmware) + 14.822s (loader) + 4.765s (kernel) + 14.190s (initrd) + 3min 51.575s (userspace) = 4min 26.666s. Oct 13 05:38:33.376646 systemd[1]: Started sshd@33-10.200.8.36:22-10.200.16.10:52866.service - OpenSSH per-connection server daemon (10.200.16.10:52866). Oct 13 05:38:33.435665 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 13 05:38:33.436370 systemd[2919]: Activating special unit exit.target... Oct 13 05:38:33.436444 systemd[2919]: Stopped target default.target - Main User Target. Oct 13 05:38:33.436483 systemd[2919]: Stopped target basic.target - Basic System. Oct 13 05:38:33.436511 systemd[2919]: Stopped target paths.target - Paths. Oct 13 05:38:33.436574 systemd[2919]: Stopped target sockets.target - Sockets. Oct 13 05:38:33.436600 systemd[2919]: Stopped target timers.target - Timers. Oct 13 05:38:33.436924 systemd[2919]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:38:33.437131 systemd[2919]: Removed slice app.slice - User Application Slice. Oct 13 05:38:33.437164 systemd[2919]: Reached target shutdown.target - Shutdown. Oct 13 05:38:33.437298 systemd[2919]: Finished systemd-exit.service - Exit the Session. Oct 13 05:38:33.437335 systemd[2919]: Reached target exit.target - Exit the Session. Oct 13 05:38:33.455915 (sd-pam)[2920]: pam_unix(systemd-user:session): session closed for user root Oct 13 05:38:33.455919 systemd-logind[2146]: Removed session c3. Oct 13 05:38:33.457074 systemd[1]: user@0.service: Deactivated successfully. Oct 13 05:38:33.457609 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 13 05:38:33.460339 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:38:33.481825 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 13 05:38:33.482752 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 13 05:38:33.482934 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:38:33.484404 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 13 05:38:33.484494 systemd[1]: user-0.slice: Consumed 5min 23.798s CPU time, 898.4M memory peak. Oct 13 05:38:34.042915 sshd[15869]: Accepted publickey for core from 10.200.16.10 port 52866 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:34.044198 sshd-session[15869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:34.048840 systemd-logind[2146]: New session 36 of user core. Oct 13 05:38:34.058757 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 13 05:38:34.496380 sshd[15876]: Connection closed by 10.200.16.10 port 52866 Oct 13 05:38:34.496754 sshd-session[15869]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:34.500440 systemd[1]: sshd@33-10.200.8.36:22-10.200.16.10:52866.service: Deactivated successfully. Oct 13 05:38:34.502130 systemd[1]: session-36.scope: Deactivated successfully. Oct 13 05:38:34.502839 systemd-logind[2146]: Session 36 logged out. Waiting for processes to exit. Oct 13 05:38:34.503708 systemd-logind[2146]: Removed session 36. Oct 13 05:38:34.615136 systemd[1]: Started sshd@34-10.200.8.36:22-10.200.16.10:52880.service - OpenSSH per-connection server daemon (10.200.16.10:52880). Oct 13 05:38:35.284194 sshd[15882]: Accepted publickey for core from 10.200.16.10 port 52880 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:35.285439 sshd-session[15882]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:35.289715 systemd-logind[2146]: New session 37 of user core. Oct 13 05:38:35.305650 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 13 05:38:35.735160 sshd[15885]: Connection closed by 10.200.16.10 port 52880 Oct 13 05:38:35.735500 sshd-session[15882]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:35.739304 systemd[1]: sshd@34-10.200.8.36:22-10.200.16.10:52880.service: Deactivated successfully. Oct 13 05:38:35.740942 systemd[1]: session-37.scope: Deactivated successfully. Oct 13 05:38:35.741652 systemd-logind[2146]: Session 37 logged out. Waiting for processes to exit. Oct 13 05:38:35.742586 systemd-logind[2146]: Removed session 37. Oct 13 05:38:35.860260 systemd[1]: Started sshd@35-10.200.8.36:22-10.200.16.10:52896.service - OpenSSH per-connection server daemon (10.200.16.10:52896). Oct 13 05:38:36.506023 sshd[15891]: Accepted publickey for core from 10.200.16.10 port 52896 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:36.507341 sshd-session[15891]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:36.511786 systemd-logind[2146]: New session 38 of user core. Oct 13 05:38:36.524663 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 13 05:38:37.711978 sshd[15894]: Connection closed by 10.200.16.10 port 52896 Oct 13 05:38:37.077031 systemd[1]: Started sshd@36-10.200.8.36:22-10.200.16.10:52904.service - OpenSSH per-connection server daemon (10.200.16.10:52904). Oct 13 05:38:37.712065 sshd-session[15891]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:37.716044 systemd[1]: sshd@35-10.200.8.36:22-10.200.16.10:52896.service: Deactivated successfully. Oct 13 05:38:37.717702 systemd[1]: session-38.scope: Deactivated successfully. Oct 13 05:38:37.718422 systemd-logind[2146]: Session 38 logged out. Waiting for processes to exit. Oct 13 05:38:37.719335 systemd-logind[2146]: Removed session 38. Oct 13 05:38:37.721459 sshd[15897]: Accepted publickey for core from 10.200.16.10 port 52904 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:37.722687 sshd-session[15897]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:37.727289 systemd-logind[2146]: New session 39 of user core. Oct 13 05:38:37.740648 systemd[1]: Started session-39.scope - Session 39 of User core. Oct 13 05:38:38.177321 sshd[15903]: Connection closed by 10.200.16.10 port 52904 Oct 13 05:38:38.177607 sshd-session[15897]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:38.181274 systemd[1]: sshd@36-10.200.8.36:22-10.200.16.10:52904.service: Deactivated successfully. Oct 13 05:38:38.182947 systemd[1]: session-39.scope: Deactivated successfully. Oct 13 05:38:38.183699 systemd-logind[2146]: Session 39 logged out. Waiting for processes to exit. Oct 13 05:38:38.184637 systemd-logind[2146]: Removed session 39. Oct 13 05:38:38.295853 systemd[1]: Started sshd@37-10.200.8.36:22-10.200.16.10:52916.service - OpenSSH per-connection server daemon (10.200.16.10:52916). Oct 13 05:38:38.959571 sshd[15909]: Accepted publickey for core from 10.200.16.10 port 52916 ssh2: RSA SHA256:WFYXd6dvSyzcP4kGnXrBllJt9uiqEJ8foqbJDShS6Os Oct 13 05:38:38.960810 sshd-session[15909]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:38:38.965155 systemd-logind[2146]: New session 40 of user core. Oct 13 05:38:38.980788 systemd[1]: Started session-40.scope - Session 40 of User core. Oct 13 05:38:40.884818 sshd[15912]: Connection closed by 10.200.16.10 port 52916 Oct 13 05:38:40.885172 sshd-session[15909]: pam_unix(sshd:session): session closed for user core Oct 13 05:38:40.888252 systemd[1]: sshd@37-10.200.8.36:22-10.200.16.10:52916.service: Deactivated successfully. Oct 13 05:38:40.889997 systemd[1]: session-40.scope: Deactivated successfully. Oct 13 05:38:40.890197 systemd[1]: session-40.scope: Consumed 1.410s CPU time, 14.1M memory peak. Oct 13 05:38:40.891227 systemd-logind[2146]: Session 40 logged out. Waiting for processes to exit. Oct 13 05:38:40.892168 systemd-logind[2146]: Removed session 40.