Oct 28 05:17:21.093239 kernel: Linux version 6.12.54-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 Tue Oct 28 03:19:40 -00 2025 Oct 28 05:17:21.093271 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=449db75fd0bf4f00a7b0da93783dc37f82f4a66df937e11c006397de0369495c Oct 28 05:17:21.093281 kernel: BIOS-provided physical RAM map: Oct 28 05:17:21.093289 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 28 05:17:21.093296 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 28 05:17:21.093303 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 28 05:17:21.093314 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 28 05:17:21.093321 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 28 05:17:21.093329 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 28 05:17:21.093336 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 28 05:17:21.093343 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 28 05:17:21.093351 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 28 05:17:21.093358 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 28 05:17:21.093365 kernel: NX (Execute Disable) protection: active Oct 28 05:17:21.093377 kernel: APIC: Static calls initialized Oct 28 05:17:21.093385 kernel: efi: EFI v2.7 by Microsoft Oct 28 05:17:21.093394 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee6da98 RNG=0x3ffd1018 Oct 28 05:17:21.093402 kernel: random: crng init done Oct 28 05:17:21.093410 kernel: secureboot: Secure boot disabled Oct 28 05:17:21.093418 kernel: SMBIOS 3.1.0 present. Oct 28 05:17:21.093426 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 28 05:17:21.093437 kernel: DMI: Memory slots populated: 3/3 Oct 28 05:17:21.093445 kernel: Hypervisor detected: Microsoft Hyper-V Oct 28 05:17:21.093453 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 28 05:17:21.093461 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 28 05:17:21.093468 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 28 05:17:21.093477 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 28 05:17:21.093485 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 28 05:17:21.093494 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 28 05:17:21.093503 kernel: tsc: Detected 2593.993 MHz processor Oct 28 05:17:21.093514 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 28 05:17:21.093523 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 28 05:17:21.093532 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 28 05:17:21.093540 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 28 05:17:21.093548 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 28 05:17:21.093557 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 28 05:17:21.093565 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 28 05:17:21.093576 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 28 05:17:21.093584 kernel: Using GB pages for direct mapping Oct 28 05:17:21.093593 kernel: ACPI: Early table checksum verification disabled Oct 28 05:17:21.093605 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 28 05:17:21.093614 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093623 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093634 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 28 05:17:21.093643 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 28 05:17:21.093652 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093661 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093670 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093681 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093690 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093699 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093708 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 28 05:17:21.093716 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 28 05:17:21.093725 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 28 05:17:21.093734 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 28 05:17:21.093746 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 28 05:17:21.093755 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 28 05:17:21.093763 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 28 05:17:21.093772 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 28 05:17:21.093781 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 28 05:17:21.093790 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 28 05:17:21.093798 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 28 05:17:21.093810 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 28 05:17:21.093819 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 28 05:17:21.093827 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 28 05:17:21.093836 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 28 05:17:21.093845 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 28 05:17:21.093854 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 28 05:17:21.093862 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 28 05:17:21.093874 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 28 05:17:21.093883 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 28 05:17:21.093892 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 28 05:17:21.093901 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 28 05:17:21.093910 kernel: Zone ranges: Oct 28 05:17:21.093918 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 28 05:17:21.093927 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 28 05:17:21.093949 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 28 05:17:21.093960 kernel: Device empty Oct 28 05:17:21.093969 kernel: Movable zone start for each node Oct 28 05:17:21.093977 kernel: Early memory node ranges Oct 28 05:17:21.093986 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 28 05:17:21.093995 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 28 05:17:21.094004 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 28 05:17:21.094012 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 28 05:17:21.094025 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 28 05:17:21.094033 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 28 05:17:21.094042 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 28 05:17:21.094051 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 28 05:17:21.094060 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 28 05:17:21.094069 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 28 05:17:21.094078 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 28 05:17:21.094089 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 28 05:17:21.094098 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 28 05:17:21.094107 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 28 05:17:21.094116 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 28 05:17:21.094125 kernel: CPU topo: Max. logical packages: 1 Oct 28 05:17:21.094134 kernel: CPU topo: Max. logical dies: 1 Oct 28 05:17:21.094142 kernel: CPU topo: Max. dies per package: 1 Oct 28 05:17:21.094154 kernel: CPU topo: Max. threads per core: 1 Oct 28 05:17:21.094162 kernel: CPU topo: Num. cores per package: 6 Oct 28 05:17:21.094171 kernel: CPU topo: Num. threads per package: 6 Oct 28 05:17:21.094180 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 28 05:17:21.094189 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 28 05:17:21.094197 kernel: Booting paravirtualized kernel on Hyper-V Oct 28 05:17:21.094206 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 28 05:17:21.094218 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 28 05:17:21.094227 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 28 05:17:21.094236 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 28 05:17:21.094244 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 28 05:17:21.094253 kernel: Hyper-V: PV spinlocks enabled Oct 28 05:17:21.094262 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 28 05:17:21.094272 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=449db75fd0bf4f00a7b0da93783dc37f82f4a66df937e11c006397de0369495c Oct 28 05:17:21.094284 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 28 05:17:21.094293 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 28 05:17:21.094302 kernel: Fallback order for Node 0: 0 Oct 28 05:17:21.094311 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 28 05:17:21.094319 kernel: Policy zone: Normal Oct 28 05:17:21.094329 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 05:17:21.094347 kernel: software IO TLB: area num 8. Oct 28 05:17:21.094357 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 28 05:17:21.094368 kernel: Kernel/User page tables isolation: enabled Oct 28 05:17:21.094378 kernel: ftrace: allocating 40092 entries in 157 pages Oct 28 05:17:21.094387 kernel: ftrace: allocated 157 pages with 5 groups Oct 28 05:17:21.094396 kernel: Dynamic Preempt: voluntary Oct 28 05:17:21.094405 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 05:17:21.094415 kernel: rcu: RCU event tracing is enabled. Oct 28 05:17:21.094425 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 28 05:17:21.094434 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 05:17:21.094446 kernel: Rude variant of Tasks RCU enabled. Oct 28 05:17:21.094456 kernel: Tracing variant of Tasks RCU enabled. Oct 28 05:17:21.094465 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 05:17:21.094474 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 28 05:17:21.094486 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 28 05:17:21.094495 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 28 05:17:21.094505 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 28 05:17:21.094514 kernel: Using NULL legacy PIC Oct 28 05:17:21.094523 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 28 05:17:21.094533 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 28 05:17:21.094542 kernel: Console: colour dummy device 80x25 Oct 28 05:17:21.094554 kernel: printk: legacy console [tty1] enabled Oct 28 05:17:21.094563 kernel: printk: legacy console [ttyS0] enabled Oct 28 05:17:21.094572 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 28 05:17:21.094581 kernel: ACPI: Core revision 20240827 Oct 28 05:17:21.094590 kernel: Failed to register legacy timer interrupt Oct 28 05:17:21.094600 kernel: APIC: Switch to symmetric I/O mode setup Oct 28 05:17:21.094609 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 28 05:17:21.094621 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 28 05:17:21.094630 kernel: Hyper-V: Using IPI hypercalls Oct 28 05:17:21.094639 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 28 05:17:21.094648 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 28 05:17:21.094658 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 28 05:17:21.094667 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 28 05:17:21.094676 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 28 05:17:21.094688 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 28 05:17:21.094697 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 28 05:17:21.094706 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 28 05:17:21.094715 kernel: APIC: read() replaced with hv_apic_read() Oct 28 05:17:21.094725 kernel: APIC: write() replaced with hv_apic_write() Oct 28 05:17:21.094734 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 28 05:17:21.094743 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 28 05:17:21.094755 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 28 05:17:21.094765 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 28 05:17:21.094774 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 28 05:17:21.094783 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 28 05:17:21.094792 kernel: Spectre V2 : Mitigation: Retpolines Oct 28 05:17:21.094801 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 28 05:17:21.094809 kernel: Speculative Store Bypass: Vulnerable Oct 28 05:17:21.094819 kernel: MDS: Mitigation: Clear CPU buffers Oct 28 05:17:21.094827 kernel: TAA: Mitigation: Clear CPU buffers Oct 28 05:17:21.094839 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 28 05:17:21.094848 kernel: active return thunk: its_return_thunk Oct 28 05:17:21.094856 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 28 05:17:21.094865 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 28 05:17:21.094874 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 28 05:17:21.094883 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 28 05:17:21.094892 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 28 05:17:21.094901 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 28 05:17:21.094910 kernel: Freeing SMP alternatives memory: 32K Oct 28 05:17:21.094919 kernel: pid_max: default: 32768 minimum: 301 Oct 28 05:17:21.094930 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 28 05:17:21.094946 kernel: landlock: Up and running. Oct 28 05:17:21.094955 kernel: SELinux: Initializing. Oct 28 05:17:21.094964 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 28 05:17:21.094973 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 28 05:17:21.094983 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 28 05:17:21.094992 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 28 05:17:21.095001 kernel: signal: max sigframe size: 1776 Oct 28 05:17:21.095010 kernel: rcu: Hierarchical SRCU implementation. Oct 28 05:17:21.095023 kernel: rcu: Max phase no-delay instances is 400. Oct 28 05:17:21.095032 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 28 05:17:21.095042 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 28 05:17:21.095051 kernel: smp: Bringing up secondary CPUs ... Oct 28 05:17:21.095060 kernel: smpboot: x86: Booting SMP configuration: Oct 28 05:17:21.095069 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 28 05:17:21.095079 kernel: smp: Brought up 1 node, 6 CPUs Oct 28 05:17:21.095088 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 28 05:17:21.095100 kernel: Memory: 115357524K/117439364K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15960K init, 2084K bss, 2071564K reserved, 0K cma-reserved) Oct 28 05:17:21.095110 kernel: devtmpfs: initialized Oct 28 05:17:21.095119 kernel: x86/mm: Memory block size: 1024MB Oct 28 05:17:21.095128 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 28 05:17:21.095138 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 05:17:21.095147 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 28 05:17:21.095159 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 05:17:21.095168 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 28 05:17:21.095178 kernel: audit: initializing netlink subsys (disabled) Oct 28 05:17:21.095187 kernel: audit: type=2000 audit(1761628634.029:1): state=initialized audit_enabled=0 res=1 Oct 28 05:17:21.095196 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 05:17:21.095205 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 28 05:17:21.095215 kernel: cpuidle: using governor menu Oct 28 05:17:21.095224 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 05:17:21.095236 kernel: dca service started, version 1.12.1 Oct 28 05:17:21.095245 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 28 05:17:21.095254 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 28 05:17:21.095264 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 05:17:21.095273 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 28 05:17:21.095282 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 05:17:21.095291 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 28 05:17:21.095303 kernel: ACPI: Added _OSI(Module Device) Oct 28 05:17:21.095313 kernel: ACPI: Added _OSI(Processor Device) Oct 28 05:17:21.095322 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 05:17:21.095331 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 05:17:21.095340 kernel: ACPI: Interpreter enabled Oct 28 05:17:21.095350 kernel: ACPI: PM: (supports S0 S5) Oct 28 05:17:21.095359 kernel: ACPI: Using IOAPIC for interrupt routing Oct 28 05:17:21.095371 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 28 05:17:21.095380 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 28 05:17:21.095389 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 28 05:17:21.095398 kernel: iommu: Default domain type: Translated Oct 28 05:17:21.095408 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 28 05:17:21.095417 kernel: efivars: Registered efivars operations Oct 28 05:17:21.095426 kernel: PCI: Using ACPI for IRQ routing Oct 28 05:17:21.095438 kernel: PCI: System does not support PCI Oct 28 05:17:21.095447 kernel: vgaarb: loaded Oct 28 05:17:21.095457 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 28 05:17:21.095466 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 05:17:21.095475 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 05:17:21.095484 kernel: pnp: PnP ACPI init Oct 28 05:17:21.095494 kernel: pnp: PnP ACPI: found 3 devices Oct 28 05:17:21.095506 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 28 05:17:21.095515 kernel: NET: Registered PF_INET protocol family Oct 28 05:17:21.095525 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 05:17:21.095534 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 28 05:17:21.095543 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 28 05:17:21.095553 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 05:17:21.095562 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 28 05:17:21.095574 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 28 05:17:21.095583 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 28 05:17:21.095592 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 28 05:17:21.095602 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 28 05:17:21.095611 kernel: NET: Registered PF_XDP protocol family Oct 28 05:17:21.095620 kernel: PCI: CLS 0 bytes, default 64 Oct 28 05:17:21.095629 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 28 05:17:21.095641 kernel: software IO TLB: mapped [mem 0x000000003ad6b000-0x000000003ed6b000] (64MB) Oct 28 05:17:21.095650 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 28 05:17:21.095660 kernel: Initialise system trusted keyrings Oct 28 05:17:21.095669 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 28 05:17:21.095678 kernel: Key type asymmetric registered Oct 28 05:17:21.095687 kernel: Asymmetric key parser 'x509' registered Oct 28 05:17:21.095696 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 28 05:17:21.095708 kernel: io scheduler mq-deadline registered Oct 28 05:17:21.095717 kernel: io scheduler kyber registered Oct 28 05:17:21.095726 kernel: io scheduler bfq registered Oct 28 05:17:21.095735 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 28 05:17:21.095745 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 05:17:21.095754 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 28 05:17:21.095763 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 28 05:17:21.095775 kernel: i8042: PNP: No PS/2 controller found. Oct 28 05:17:21.095972 kernel: rtc_cmos 00:02: registered as rtc0 Oct 28 05:17:21.096120 kernel: rtc_cmos 00:02: setting system clock to 2025-10-28T05:17:16 UTC (1761628636) Oct 28 05:17:21.096255 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 28 05:17:21.096269 kernel: intel_pstate: CPU model not supported Oct 28 05:17:21.096278 kernel: efifb: probing for efifb Oct 28 05:17:21.096293 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 28 05:17:21.096302 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 28 05:17:21.096311 kernel: efifb: scrolling: redraw Oct 28 05:17:21.096321 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 28 05:17:21.096330 kernel: Console: switching to colour frame buffer device 128x48 Oct 28 05:17:21.096339 kernel: fb0: EFI VGA frame buffer device Oct 28 05:17:21.096349 kernel: pstore: Using crash dump compression: deflate Oct 28 05:17:21.096361 kernel: pstore: Registered efi_pstore as persistent store backend Oct 28 05:17:21.096370 kernel: NET: Registered PF_INET6 protocol family Oct 28 05:17:21.096379 kernel: Segment Routing with IPv6 Oct 28 05:17:21.096389 kernel: In-situ OAM (IOAM) with IPv6 Oct 28 05:17:21.096398 kernel: NET: Registered PF_PACKET protocol family Oct 28 05:17:21.096407 kernel: Key type dns_resolver registered Oct 28 05:17:21.096416 kernel: IPI shorthand broadcast: enabled Oct 28 05:17:21.096426 kernel: sched_clock: Marking stable (2109006100, 113296000)->(2818851000, -596548900) Oct 28 05:17:21.096438 kernel: registered taskstats version 1 Oct 28 05:17:21.096447 kernel: Loading compiled-in X.509 certificates Oct 28 05:17:21.096457 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: a9d98af1927e389c63ed03bf44a9f2758bf88a8e' Oct 28 05:17:21.096466 kernel: Demotion targets for Node 0: null Oct 28 05:17:21.096476 kernel: Key type .fscrypt registered Oct 28 05:17:21.096485 kernel: Key type fscrypt-provisioning registered Oct 28 05:17:21.096495 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 05:17:21.096507 kernel: ima: Allocated hash algorithm: sha1 Oct 28 05:17:21.096516 kernel: ima: No architecture policies found Oct 28 05:17:21.096525 kernel: clk: Disabling unused clocks Oct 28 05:17:21.096534 kernel: Freeing unused kernel image (initmem) memory: 15960K Oct 28 05:17:21.096544 kernel: Write protecting the kernel read-only data: 45056k Oct 28 05:17:21.096553 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Oct 28 05:17:21.096562 kernel: Run /init as init process Oct 28 05:17:21.096574 kernel: with arguments: Oct 28 05:17:21.096583 kernel: /init Oct 28 05:17:21.096593 kernel: with environment: Oct 28 05:17:21.096602 kernel: HOME=/ Oct 28 05:17:21.096611 kernel: TERM=linux Oct 28 05:17:21.096620 kernel: hv_vmbus: Vmbus version:4.0 Oct 28 05:17:21.096630 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.096639 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.096651 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 05:17:21.096660 kernel: hv_vmbus: registering driver hid_hyperv Oct 28 05:17:21.096670 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Oct 28 05:17:21.096833 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 28 05:17:21.096848 kernel: SCSI subsystem initialized Oct 28 05:17:21.096858 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.096871 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 28 05:17:21.096881 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 28 05:17:21.096891 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 28 05:17:21.096900 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 28 05:17:21.096909 kernel: PTP clock support registered Oct 28 05:17:21.096919 kernel: hv_utils: Registering HyperV Utility Driver Oct 28 05:17:21.096928 kernel: hv_vmbus: registering driver hv_utils Oct 28 05:17:21.096950 kernel: hv_utils: Heartbeat IC version 3.0 Oct 28 05:17:21.096960 kernel: hv_utils: Shutdown IC version 3.2 Oct 28 05:17:21.096969 kernel: hv_utils: TimeSync IC version 4.0 Oct 28 05:17:21.096979 kernel: hv_vmbus: registering driver hv_storvsc Oct 28 05:17:21.097157 kernel: scsi host0: storvsc_host_t Oct 28 05:17:21.097321 kernel: scsi host1: storvsc_host_t Oct 28 05:17:21.097497 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 28 05:17:21.097666 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 28 05:17:21.097838 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 28 05:17:21.098013 kernel: sd 0:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Oct 28 05:17:21.098173 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 28 05:17:21.098338 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 28 05:17:21.098495 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Oct 28 05:17:21.098652 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 28 05:17:21.098808 kernel: sd 0:0:0:1: [sdb] Write Protect is off Oct 28 05:17:21.099021 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 28 05:17:21.099227 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 28 05:17:21.099390 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 28 05:17:21.099561 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#134 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 28 05:17:21.099721 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 28 05:17:21.099868 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#259 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 28 05:17:21.100028 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#141 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 28 05:17:21.100180 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#266 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 28 05:17:21.100194 kernel: sdb: sdb1 Oct 28 05:17:21.100349 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Oct 28 05:17:21.100363 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 28 05:17:21.100517 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 28 05:17:21.100531 kernel: hv_vmbus: registering driver hv_pci Oct 28 05:17:21.100687 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 28 05:17:21.100851 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 28 05:17:21.101057 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 28 05:17:21.101274 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 28 05:17:21.101427 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 28 05:17:21.101611 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 28 05:17:21.101824 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 28 05:17:21.102002 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 28 05:17:21.102162 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 28 05:17:21.102322 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 28 05:17:21.102337 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 28 05:17:21.102352 kernel: device-mapper: uevent: version 1.0.3 Oct 28 05:17:21.102498 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 28 05:17:21.102512 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 28 05:17:21.102665 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 28 05:17:21.102821 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 28 05:17:21.102990 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 28 05:17:21.103009 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Oct 28 05:17:21.103169 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 28 05:17:21.103184 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 28 05:17:21.103195 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103348 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 28 05:17:21.103363 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103372 kernel: raid6: avx2x4 gen() 31007 MB/s Oct 28 05:17:21.103382 kernel: raid6: avx2x2 gen() 31182 MB/s Oct 28 05:17:21.103395 kernel: raid6: avx2x1 gen() 27099 MB/s Oct 28 05:17:21.103405 kernel: raid6: using algorithm avx2x2 gen() 31182 MB/s Oct 28 05:17:21.103415 kernel: raid6: .... xor() 18438 MB/s, rmw enabled Oct 28 05:17:21.103425 kernel: raid6: using avx2x2 recovery algorithm Oct 28 05:17:21.103435 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103444 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103454 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103466 kernel: xor: automatically using best checksumming function avx Oct 28 05:17:21.103476 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103485 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 28 05:17:21.103495 kernel: BTRFS: device fsid 98ad3ab2-0171-42ae-a5fc-7be2369f5a89 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (412) Oct 28 05:17:21.103505 kernel: BTRFS info (device dm-0): first mount of filesystem 98ad3ab2-0171-42ae-a5fc-7be2369f5a89 Oct 28 05:17:21.103515 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 28 05:17:21.103525 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 28 05:17:21.103537 kernel: BTRFS info (device dm-0): enabling free space tree Oct 28 05:17:21.103547 kernel: Invalid ELF header magic: != \u007fELF Oct 28 05:17:21.103556 kernel: loop: module loaded Oct 28 05:17:21.103566 kernel: loop0: detected capacity change from 0 to 100136 Oct 28 05:17:21.103576 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 28 05:17:21.103587 systemd[1]: Successfully made /usr/ read-only. Oct 28 05:17:21.103600 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 28 05:17:21.103613 systemd[1]: Detected virtualization microsoft. Oct 28 05:17:21.103623 systemd[1]: Detected architecture x86-64. Oct 28 05:17:21.103633 systemd[1]: Running in initrd. Oct 28 05:17:21.103642 systemd[1]: No hostname configured, using default hostname. Oct 28 05:17:21.103653 systemd[1]: Hostname set to . Oct 28 05:17:21.103663 systemd[1]: Initializing machine ID from random generator. Oct 28 05:17:21.103675 systemd[1]: Queued start job for default target initrd.target. Oct 28 05:17:21.103685 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 05:17:21.103695 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 05:17:21.103706 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 05:17:21.103716 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 28 05:17:21.103726 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 05:17:21.103740 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 28 05:17:21.103750 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 28 05:17:21.103761 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 05:17:21.103773 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 05:17:21.103786 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 28 05:17:21.103796 systemd[1]: Reached target paths.target - Path Units. Oct 28 05:17:21.103806 systemd[1]: Reached target slices.target - Slice Units. Oct 28 05:17:21.103817 systemd[1]: Reached target swap.target - Swaps. Oct 28 05:17:21.103827 systemd[1]: Reached target timers.target - Timer Units. Oct 28 05:17:21.103837 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 05:17:21.103850 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 05:17:21.103862 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 28 05:17:21.103873 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 28 05:17:21.103883 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 05:17:21.103893 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 05:17:21.103904 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 05:17:21.103914 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 05:17:21.103927 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 28 05:17:21.103937 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 28 05:17:21.103968 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 05:17:21.103979 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 28 05:17:21.103993 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 28 05:17:21.104003 systemd[1]: Starting systemd-fsck-usr.service... Oct 28 05:17:21.104013 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 05:17:21.104027 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 05:17:21.104059 systemd-journald[549]: Collecting audit messages is disabled. Oct 28 05:17:21.104083 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:21.104098 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 28 05:17:21.104109 systemd-journald[549]: Journal started Oct 28 05:17:21.104132 systemd-journald[549]: Runtime Journal (/run/log/journal/5440d907684e48ba8a34458ffd21f1ad) is 8M, max 2.2G, 2.1G free. Oct 28 05:17:21.115421 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 05:17:21.118787 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 05:17:21.120485 systemd[1]: Finished systemd-fsck-usr.service. Oct 28 05:17:21.131997 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 28 05:17:21.133053 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 05:17:21.286973 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 28 05:17:21.293571 systemd-modules-load[552]: Inserted module 'br_netfilter' Oct 28 05:17:21.295271 kernel: Bridge firewalling registered Oct 28 05:17:21.294638 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 05:17:21.305913 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 05:17:21.429241 systemd-tmpfiles[560]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 28 05:17:21.435137 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 05:17:21.448950 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 05:17:21.464664 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 05:17:21.474863 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 05:17:21.478599 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 05:17:21.495594 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:21.505153 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 05:17:21.507414 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 28 05:17:21.606847 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 05:17:21.626262 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 28 05:17:21.676538 systemd-resolved[574]: Positive Trust Anchors: Oct 28 05:17:21.676554 systemd-resolved[574]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 05:17:21.676559 systemd-resolved[574]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 05:17:21.676590 systemd-resolved[574]: 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 28 05:17:21.752934 dracut-cmdline[593]: 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=449db75fd0bf4f00a7b0da93783dc37f82f4a66df937e11c006397de0369495c Oct 28 05:17:21.700236 systemd-resolved[574]: Defaulting to hostname 'linux'. Oct 28 05:17:21.701320 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 05:17:21.725012 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 05:17:21.942971 kernel: Loading iSCSI transport class v2.0-870. Oct 28 05:17:22.048968 kernel: iscsi: registered transport (tcp) Oct 28 05:17:22.174848 kernel: iscsi: registered transport (qla4xxx) Oct 28 05:17:22.174912 kernel: QLogic iSCSI HBA Driver Oct 28 05:17:22.284847 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 05:17:22.319977 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 05:17:22.332037 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 05:17:22.383151 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 28 05:17:22.385932 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 28 05:17:22.393357 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 28 05:17:22.448912 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 28 05:17:22.451223 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 05:17:22.489319 systemd-udevd[831]: Using default interface naming scheme 'v257'. Oct 28 05:17:22.500631 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 05:17:22.503256 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 28 05:17:22.540453 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 05:17:22.543550 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 05:17:22.559139 dracut-pre-trigger[889]: rd.md=0: removing MD RAID activation Oct 28 05:17:22.577568 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 05:17:22.580200 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 05:17:22.604171 systemd-networkd[943]: lo: Link UP Oct 28 05:17:22.604180 systemd-networkd[943]: lo: Gained carrier Oct 28 05:17:22.604699 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 05:17:22.610859 systemd[1]: Reached target network.target - Network. Oct 28 05:17:22.670969 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 05:17:22.673672 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 28 05:17:22.752284 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 05:17:22.752422 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:22.761333 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:22.770898 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:22.816961 kernel: hv_vmbus: registering driver hv_netvsc Oct 28 05:17:22.830680 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 05:17:22.830790 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:22.848439 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:22.857227 kernel: cryptd: max_cpu_qlen set to 1000 Oct 28 05:17:22.941963 kernel: AES CTR mode by8 optimization enabled Oct 28 05:17:22.946012 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:23.159980 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 28 05:17:23.170208 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 28 05:17:23.323929 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 28 05:17:23.353428 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 28 05:17:23.375727 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 28 05:17:24.692397 disk-uuid[1114]: Warning: The kernel is still using the old partition table. Oct 28 05:17:24.692397 disk-uuid[1114]: The new table will be used at the next reboot or after you Oct 28 05:17:24.692397 disk-uuid[1114]: run partprobe(8) or kpartx(8) Oct 28 05:17:24.692397 disk-uuid[1114]: The operation has completed successfully. Oct 28 05:17:26.112021 systemd-networkd[943]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 05:17:26.112034 systemd-networkd[943]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 05:17:26.112851 systemd-networkd[943]: eth0: Link UP Oct 28 05:17:26.113041 systemd-networkd[943]: eth0: Gained carrier Oct 28 05:17:26.113054 systemd-networkd[943]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 05:17:26.130306 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 05:17:26.130418 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 28 05:17:26.140128 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 28 05:17:26.165994 systemd-networkd[943]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 28 05:17:26.166118 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 28 05:17:26.168892 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 05:17:26.181561 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 05:17:26.191567 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 05:17:26.202338 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 28 05:17:26.244907 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 28 05:17:26.260635 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1267) Oct 28 05:17:26.270096 kernel: BTRFS info (device sda6): first mount of filesystem 7acd037c-32ce-4796-90d6-101869832417 Oct 28 05:17:26.270137 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 28 05:17:26.310288 kernel: BTRFS info (device sda6): turning on async discard Oct 28 05:17:26.310333 kernel: BTRFS info (device sda6): enabling free space tree Oct 28 05:17:26.321963 kernel: BTRFS info (device sda6): last unmount of filesystem 7acd037c-32ce-4796-90d6-101869832417 Oct 28 05:17:26.322109 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 28 05:17:26.324727 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 28 05:17:27.277085 systemd-networkd[943]: eth0: Gained IPv6LL Oct 28 05:17:27.643531 ignition[1293]: Ignition 2.22.0 Oct 28 05:17:27.643547 ignition[1293]: Stage: fetch-offline Oct 28 05:17:27.643834 ignition[1293]: no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:27.643848 ignition[1293]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:27.646103 ignition[1293]: parsed url from cmdline: "" Oct 28 05:17:27.646108 ignition[1293]: no config URL provided Oct 28 05:17:27.646114 ignition[1293]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 05:17:27.658116 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 05:17:27.646128 ignition[1293]: no config at "/usr/lib/ignition/user.ign" Oct 28 05:17:27.669308 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 28 05:17:27.646134 ignition[1293]: failed to fetch config: resource requires networking Oct 28 05:17:27.652243 ignition[1293]: Ignition finished successfully Oct 28 05:17:27.712653 ignition[1303]: Ignition 2.22.0 Oct 28 05:17:27.712667 ignition[1303]: Stage: fetch Oct 28 05:17:27.712921 ignition[1303]: no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:27.712932 ignition[1303]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:27.713063 ignition[1303]: parsed url from cmdline: "" Oct 28 05:17:27.713067 ignition[1303]: no config URL provided Oct 28 05:17:27.713073 ignition[1303]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 05:17:27.713082 ignition[1303]: no config at "/usr/lib/ignition/user.ign" Oct 28 05:17:27.713099 ignition[1303]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 28 05:17:27.838814 ignition[1303]: GET result: OK Oct 28 05:17:27.838921 ignition[1303]: config has been read from IMDS userdata Oct 28 05:17:27.838963 ignition[1303]: parsing config with SHA512: d7158cf651c843b7bd53bb514eef6c80c6e8d43f9656f1694130fc54de3ad43aa19c49d467003390d1ec26862553728c4d6f10010a0a816afa2e27ae599b82ff Oct 28 05:17:27.847719 unknown[1303]: fetched base config from "system" Oct 28 05:17:27.847730 unknown[1303]: fetched base config from "system" Oct 28 05:17:27.847737 unknown[1303]: fetched user config from "azure" Oct 28 05:17:27.851086 ignition[1303]: fetch: fetch complete Oct 28 05:17:27.851092 ignition[1303]: fetch: fetch passed Oct 28 05:17:27.854431 ignition[1303]: Ignition finished successfully Oct 28 05:17:27.868150 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 28 05:17:27.870686 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 28 05:17:27.926269 ignition[1313]: Ignition 2.22.0 Oct 28 05:17:27.926284 ignition[1313]: Stage: kargs Oct 28 05:17:27.926565 ignition[1313]: no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:27.926577 ignition[1313]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:27.931796 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 28 05:17:27.927280 ignition[1313]: kargs: kargs passed Oct 28 05:17:27.939455 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 28 05:17:27.927328 ignition[1313]: Ignition finished successfully Oct 28 05:17:27.988738 ignition[1322]: Ignition 2.22.0 Oct 28 05:17:27.988752 ignition[1322]: Stage: disks Oct 28 05:17:27.989032 ignition[1322]: no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:27.989043 ignition[1322]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:27.989745 ignition[1322]: disks: disks passed Oct 28 05:17:27.998407 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 28 05:17:27.989792 ignition[1322]: Ignition finished successfully Oct 28 05:17:28.005465 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 28 05:17:28.012359 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 28 05:17:28.021068 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 05:17:28.022514 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 05:17:28.033748 systemd[1]: Reached target basic.target - Basic System. Oct 28 05:17:28.043927 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 28 05:17:28.290589 systemd-fsck[1333]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Oct 28 05:17:28.297295 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 28 05:17:28.309479 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 28 05:17:29.734958 kernel: EXT4-fs (sda9): mounted filesystem 0ce42fa0-8451-4928-b788-6e54ab295d7a r/w with ordered data mode. Quota mode: none. Oct 28 05:17:29.735555 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 28 05:17:29.737709 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 28 05:17:29.781561 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 05:17:29.812564 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 28 05:17:29.820550 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 28 05:17:29.844982 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1348) Oct 28 05:17:29.845010 kernel: BTRFS info (device sda6): first mount of filesystem 7acd037c-32ce-4796-90d6-101869832417 Oct 28 05:17:29.845024 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 28 05:17:29.822100 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 28 05:17:29.864913 kernel: BTRFS info (device sda6): turning on async discard Oct 28 05:17:29.864952 kernel: BTRFS info (device sda6): enabling free space tree Oct 28 05:17:29.822138 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 05:17:29.847814 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 28 05:17:29.867643 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 05:17:29.880261 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 28 05:17:30.721094 coreos-metadata[1350]: Oct 28 05:17:30.721 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 28 05:17:30.727026 coreos-metadata[1350]: Oct 28 05:17:30.726 INFO Fetch successful Oct 28 05:17:30.727026 coreos-metadata[1350]: Oct 28 05:17:30.727 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 28 05:17:30.739148 coreos-metadata[1350]: Oct 28 05:17:30.739 INFO Fetch successful Oct 28 05:17:30.744099 coreos-metadata[1350]: Oct 28 05:17:30.744 INFO wrote hostname ci-4501.0.0-n-a027052b46 to /sysroot/etc/hostname Oct 28 05:17:30.751290 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 28 05:17:31.155764 initrd-setup-root[1378]: cut: /sysroot/etc/passwd: No such file or directory Oct 28 05:17:31.250060 initrd-setup-root[1385]: cut: /sysroot/etc/group: No such file or directory Oct 28 05:17:31.293736 initrd-setup-root[1392]: cut: /sysroot/etc/shadow: No such file or directory Oct 28 05:17:31.301458 initrd-setup-root[1399]: cut: /sysroot/etc/gshadow: No such file or directory Oct 28 05:17:32.917873 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 28 05:17:32.924135 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 28 05:17:32.929437 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 28 05:17:33.011713 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 28 05:17:33.021139 kernel: BTRFS info (device sda6): last unmount of filesystem 7acd037c-32ce-4796-90d6-101869832417 Oct 28 05:17:33.042489 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 28 05:17:33.060117 ignition[1468]: INFO : Ignition 2.22.0 Oct 28 05:17:33.060117 ignition[1468]: INFO : Stage: mount Oct 28 05:17:33.073003 ignition[1468]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:33.073003 ignition[1468]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:33.073003 ignition[1468]: INFO : mount: mount passed Oct 28 05:17:33.073003 ignition[1468]: INFO : Ignition finished successfully Oct 28 05:17:33.063555 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 28 05:17:33.073159 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 28 05:17:33.111107 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 05:17:33.133960 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1484) Oct 28 05:17:33.143491 kernel: BTRFS info (device sda6): first mount of filesystem 7acd037c-32ce-4796-90d6-101869832417 Oct 28 05:17:33.143518 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 28 05:17:33.153707 kernel: BTRFS info (device sda6): turning on async discard Oct 28 05:17:33.153732 kernel: BTRFS info (device sda6): enabling free space tree Oct 28 05:17:33.155819 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 05:17:33.204114 ignition[1501]: INFO : Ignition 2.22.0 Oct 28 05:17:33.204114 ignition[1501]: INFO : Stage: files Oct 28 05:17:33.210200 ignition[1501]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:33.210200 ignition[1501]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:33.210200 ignition[1501]: DEBUG : files: compiled without relabeling support, skipping Oct 28 05:17:33.226849 ignition[1501]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 28 05:17:33.226849 ignition[1501]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 28 05:17:33.337423 ignition[1501]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 28 05:17:33.343059 ignition[1501]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 28 05:17:33.348419 ignition[1501]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 05:17:33.343192 unknown[1501]: wrote ssh authorized keys file for user: core Oct 28 05:17:33.375462 ignition[1501]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 05:17:33.383464 ignition[1501]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 05:17:33.383464 ignition[1501]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 28 05:17:33.383464 ignition[1501]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 28 05:17:33.383464 ignition[1501]: INFO : files: files passed Oct 28 05:17:33.383464 ignition[1501]: INFO : Ignition finished successfully Oct 28 05:17:33.392601 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 28 05:17:33.395103 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 28 05:17:33.430611 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 28 05:17:33.434196 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 05:17:33.434303 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 28 05:17:33.510992 initrd-setup-root-after-ignition[1536]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 05:17:33.510992 initrd-setup-root-after-ignition[1536]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 28 05:17:33.519139 initrd-setup-root-after-ignition[1540]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 05:17:33.518443 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 05:17:33.520080 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 28 05:17:33.537331 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 28 05:17:33.585456 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 05:17:33.585573 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 28 05:17:33.600107 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 28 05:17:33.600937 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 28 05:17:33.618382 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 28 05:17:33.619401 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 28 05:17:33.651243 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 05:17:33.654366 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 28 05:17:33.690536 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 05:17:33.690705 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 28 05:17:33.700403 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 05:17:33.710131 systemd[1]: Stopped target timers.target - Timer Units. Oct 28 05:17:33.711659 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 05:17:33.711827 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 05:17:33.735442 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 28 05:17:33.737017 systemd[1]: Stopped target basic.target - Basic System. Oct 28 05:17:33.745362 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 28 05:17:33.755654 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 05:17:33.787983 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 28 05:17:33.799834 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 28 05:17:33.808825 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 28 05:17:33.817982 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 05:17:33.827071 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 28 05:17:33.838070 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 28 05:17:33.846919 systemd[1]: Stopped target swap.target - Swaps. Oct 28 05:17:33.854824 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 05:17:33.855005 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 28 05:17:33.866558 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 28 05:17:33.875695 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 05:17:33.885992 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 28 05:17:33.886328 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 05:17:33.896700 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 05:17:33.896843 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 28 05:17:33.911009 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 28 05:17:33.911150 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 05:17:33.921091 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 05:17:33.921210 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 28 05:17:33.929484 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 28 05:17:33.929607 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 28 05:17:33.940170 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 28 05:17:33.948655 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 28 05:17:33.955387 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 05:17:33.955530 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 05:17:33.957710 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 05:17:33.957825 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 05:17:33.972024 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 05:17:34.020809 ignition[1560]: INFO : Ignition 2.22.0 Oct 28 05:17:34.020809 ignition[1560]: INFO : Stage: umount Oct 28 05:17:34.020809 ignition[1560]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 05:17:34.020809 ignition[1560]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 28 05:17:34.020809 ignition[1560]: INFO : umount: umount passed Oct 28 05:17:34.020809 ignition[1560]: INFO : Ignition finished successfully Oct 28 05:17:33.972144 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 05:17:33.992283 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 05:17:33.994080 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 28 05:17:34.028200 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 28 05:17:34.028322 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 28 05:17:34.035506 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 05:17:34.035559 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 28 05:17:34.045655 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 28 05:17:34.045721 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 28 05:17:34.052092 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 28 05:17:34.052147 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 28 05:17:34.054574 systemd[1]: Stopped target network.target - Network. Oct 28 05:17:34.065763 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 28 05:17:34.065828 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 05:17:34.073084 systemd[1]: Stopped target paths.target - Path Units. Oct 28 05:17:34.082576 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 05:17:34.086000 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 05:17:34.089796 systemd[1]: Stopped target slices.target - Slice Units. Oct 28 05:17:34.097957 systemd[1]: Stopped target sockets.target - Socket Units. Oct 28 05:17:34.109618 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 05:17:34.109669 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 05:17:34.124414 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 05:17:34.124460 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 05:17:34.133602 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 05:17:34.133669 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 28 05:17:34.141467 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 28 05:17:34.141522 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 28 05:17:34.149374 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 28 05:17:34.157034 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 28 05:17:34.166247 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 28 05:17:34.169575 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 05:17:34.169766 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 28 05:17:34.184333 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 05:17:34.184437 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 28 05:17:34.199315 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 05:17:34.199430 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 28 05:17:34.213105 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 28 05:17:34.214933 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 05:17:34.215014 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 28 05:17:34.222923 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 05:17:34.223004 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 28 05:17:34.232247 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 28 05:17:34.240029 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 28 05:17:34.240096 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 05:17:34.255382 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 05:17:34.255436 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 28 05:17:34.264066 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 28 05:17:34.264120 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 28 05:17:34.266225 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 05:17:34.318079 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 05:17:34.318235 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 05:17:34.327222 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 05:17:34.327269 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 28 05:17:34.334884 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 05:17:34.334923 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 05:17:34.343486 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 05:17:34.350458 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 28 05:17:34.358569 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 05:17:34.358636 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 28 05:17:34.371735 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 05:17:34.371796 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 05:17:34.389417 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 28 05:17:34.398931 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 28 05:17:34.399014 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 05:17:34.407889 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 05:17:34.407965 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 05:17:34.410060 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 28 05:17:34.410114 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 05:17:34.423293 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 05:17:34.423348 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 05:17:34.432540 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 05:17:34.432597 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:34.441843 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 05:17:34.442174 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 28 05:17:34.543763 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 05:17:34.543894 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 28 05:17:34.547199 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 28 05:17:34.555283 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 28 05:17:34.609519 systemd[1]: Switching root. Oct 28 05:17:34.765955 systemd-journald[549]: Received SIGTERM from PID 1 (systemd). Oct 28 05:17:34.766021 systemd-journald[549]: Journal stopped Oct 28 05:17:43.720970 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 05:17:43.731959 kernel: SELinux: policy capability open_perms=1 Oct 28 05:17:43.731988 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 05:17:43.732000 kernel: SELinux: policy capability always_check_network=0 Oct 28 05:17:43.732010 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 05:17:43.732020 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 05:17:43.732039 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 05:17:43.732049 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 28 05:17:43.732060 kernel: SELinux: policy capability userspace_initial_context=0 Oct 28 05:17:43.732070 kernel: audit: type=1403 audit(1761628656.274:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 05:17:43.732084 systemd[1]: Successfully loaded SELinux policy in 380.521ms. Oct 28 05:17:43.732098 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.674ms. Oct 28 05:17:43.732114 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 28 05:17:43.732126 systemd[1]: Detected virtualization microsoft. Oct 28 05:17:43.732139 systemd[1]: Detected architecture x86-64. Oct 28 05:17:43.732150 systemd[1]: Detected first boot. Oct 28 05:17:43.732165 systemd[1]: Hostname set to . Oct 28 05:17:43.732177 systemd[1]: Initializing machine ID from random generator. Oct 28 05:17:43.732189 zram_generator::config[1608]: No configuration found. Oct 28 05:17:43.732201 kernel: Guest personality initialized and is inactive Oct 28 05:17:43.732213 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 28 05:17:43.732223 kernel: Initialized host personality Oct 28 05:17:43.732237 kernel: NET: Registered PF_VSOCK protocol family Oct 28 05:17:43.732249 systemd[1]: Populated /etc with preset unit settings. Oct 28 05:17:43.732265 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 05:17:43.732276 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 28 05:17:43.732288 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 05:17:43.732300 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 28 05:17:43.732315 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 28 05:17:43.732327 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 28 05:17:43.732339 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 28 05:17:43.732351 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 28 05:17:43.732362 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 28 05:17:43.732374 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 28 05:17:43.732389 systemd[1]: Created slice user.slice - User and Session Slice. Oct 28 05:17:43.732401 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 05:17:43.732413 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 05:17:43.732424 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 28 05:17:43.732436 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 28 05:17:43.732448 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 28 05:17:43.732464 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 05:17:43.732479 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 28 05:17:43.732491 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 05:17:43.732503 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 05:17:43.732515 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 28 05:17:43.732530 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 28 05:17:43.732545 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 28 05:17:43.732558 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 28 05:17:43.732570 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 05:17:43.732582 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 05:17:43.732594 systemd[1]: Reached target slices.target - Slice Units. Oct 28 05:17:43.732606 systemd[1]: Reached target swap.target - Swaps. Oct 28 05:17:43.732618 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 28 05:17:43.732633 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 28 05:17:43.732645 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 28 05:17:43.732658 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 05:17:43.732670 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 05:17:43.732685 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 05:17:43.732697 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 28 05:17:43.732709 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 28 05:17:43.732721 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 28 05:17:43.732733 systemd[1]: Mounting media.mount - External Media Directory... Oct 28 05:17:43.732745 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:43.732760 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 28 05:17:43.732773 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 28 05:17:43.732785 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 28 05:17:43.732801 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 28 05:17:43.732813 systemd[1]: Reached target machines.target - Containers. Oct 28 05:17:43.732826 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 28 05:17:43.732838 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 05:17:43.732853 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 05:17:43.732866 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 28 05:17:43.732878 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 05:17:43.732890 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 05:17:43.732903 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 05:17:43.732915 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 28 05:17:43.732927 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 05:17:43.732953 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 28 05:17:43.732967 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 05:17:43.732979 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 28 05:17:43.732991 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 05:17:43.733003 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 05:17:43.733016 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 28 05:17:43.733032 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 05:17:43.733044 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 05:17:43.733057 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 05:17:43.733069 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 28 05:17:43.733085 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 28 05:17:43.733097 kernel: fuse: init (API version 7.41) Oct 28 05:17:43.733109 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 05:17:43.733124 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:43.733137 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 28 05:17:43.733176 systemd-journald[1708]: Collecting audit messages is disabled. Oct 28 05:17:43.733208 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 28 05:17:43.733221 systemd[1]: Mounted media.mount - External Media Directory. Oct 28 05:17:43.733233 systemd-journald[1708]: Journal started Oct 28 05:17:43.733257 systemd-journald[1708]: Runtime Journal (/run/log/journal/911d32f54f7d4fd7a30e0eea27826dc7) is 8M, max 2.2G, 2.1G free. Oct 28 05:17:42.830566 systemd[1]: Queued start job for default target multi-user.target. Oct 28 05:17:42.844700 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 28 05:17:42.845239 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 05:17:43.738452 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 05:17:43.744431 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 28 05:17:43.751156 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 28 05:17:43.757095 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 28 05:17:43.762201 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 28 05:17:43.767321 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 05:17:43.772795 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 05:17:43.773072 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 28 05:17:43.778252 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 05:17:43.778434 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 05:17:43.783462 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 05:17:43.783642 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 05:17:43.789829 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 05:17:43.790276 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 28 05:17:43.795101 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 05:17:43.795292 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 05:17:43.800050 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 05:17:43.805769 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 05:17:43.816145 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 28 05:17:43.824415 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 28 05:17:43.831063 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 05:17:43.848924 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 05:17:43.853879 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 28 05:17:43.861057 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 28 05:17:43.891207 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 28 05:17:43.895864 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 28 05:17:43.895905 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 05:17:43.901997 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 28 05:17:43.907106 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 05:17:43.908261 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 28 05:17:43.913530 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 28 05:17:43.918167 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 05:17:43.919103 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 28 05:17:43.923506 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 05:17:43.924630 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 05:17:43.930112 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 28 05:17:43.935698 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 28 05:17:43.946084 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 28 05:17:43.951231 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 28 05:17:43.962627 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 28 05:17:43.967868 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 28 05:17:43.977630 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 28 05:17:44.007964 kernel: loop1: detected capacity change from 0 to 27736 Oct 28 05:17:44.026083 systemd-journald[1708]: Time spent on flushing to /var/log/journal/911d32f54f7d4fd7a30e0eea27826dc7 is 15.130ms for 944 entries. Oct 28 05:17:44.026083 systemd-journald[1708]: System Journal (/var/log/journal/911d32f54f7d4fd7a30e0eea27826dc7) is 8M, max 2.6G, 2.6G free. Oct 28 05:17:44.095746 systemd-journald[1708]: Received client request to flush runtime journal. Oct 28 05:17:44.095902 kernel: ACPI: bus type drm_connector registered Oct 28 05:17:44.089738 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 05:17:44.093627 systemd-tmpfiles[1751]: ACLs are not supported, ignoring. Oct 28 05:17:44.093641 systemd-tmpfiles[1751]: ACLs are not supported, ignoring. Oct 28 05:17:44.097433 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 28 05:17:44.105277 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 05:17:44.111735 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 05:17:44.111920 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 05:17:44.118633 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 28 05:17:44.178447 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 28 05:17:44.829145 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 28 05:17:44.835628 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 05:17:44.840915 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 05:17:44.874566 systemd-tmpfiles[1770]: ACLs are not supported, ignoring. Oct 28 05:17:44.874587 systemd-tmpfiles[1770]: ACLs are not supported, ignoring. Oct 28 05:17:44.879204 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 05:17:44.924083 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 28 05:17:44.942980 kernel: loop2: detected capacity change from 0 to 128912 Oct 28 05:17:44.945082 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 05:17:44.987156 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 28 05:17:45.109909 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 28 05:17:45.116599 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 05:17:45.155877 systemd-resolved[1769]: Positive Trust Anchors: Oct 28 05:17:45.155896 systemd-resolved[1769]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 05:17:45.155900 systemd-resolved[1769]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 05:17:45.155931 systemd-resolved[1769]: 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 28 05:17:45.171349 systemd-udevd[1782]: Using default interface naming scheme 'v257'. Oct 28 05:17:45.361771 systemd-resolved[1769]: Using system hostname 'ci-4501.0.0-n-a027052b46'. Oct 28 05:17:45.380700 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 05:17:45.385429 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 05:17:45.834974 kernel: loop3: detected capacity change from 0 to 111544 Oct 28 05:17:45.910507 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 05:17:45.919483 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 05:17:45.965185 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 28 05:17:46.032969 kernel: hv_vmbus: registering driver hyperv_fb Oct 28 05:17:46.047384 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 28 05:17:46.047466 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 28 05:17:46.050756 kernel: Console: switching to colour dummy device 80x25 Oct 28 05:17:46.054968 kernel: Console: switching to colour frame buffer device 128x48 Oct 28 05:17:46.115736 kernel: hv_vmbus: registering driver hv_balloon Oct 28 05:17:46.115824 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 28 05:17:46.112487 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:46.128590 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 05:17:46.128798 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:46.133550 kernel: mousedev: PS/2 mouse device common for all mice Oct 28 05:17:46.134780 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 05:17:46.157122 systemd-networkd[1796]: lo: Link UP Oct 28 05:17:46.157131 systemd-networkd[1796]: lo: Gained carrier Oct 28 05:17:46.158580 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 05:17:46.158840 systemd-networkd[1796]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 05:17:46.158845 systemd-networkd[1796]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 05:17:46.159670 systemd-networkd[1796]: eth0: Link UP Oct 28 05:17:46.159681 systemd-networkd[1796]: eth0: Gained carrier Oct 28 05:17:46.159696 systemd-networkd[1796]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 05:17:46.163136 systemd[1]: Reached target network.target - Network. Oct 28 05:17:46.167911 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 28 05:17:46.173635 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 28 05:17:46.214984 systemd-networkd[1796]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 28 05:17:46.225044 kernel: EDAC MC: Ver: 3.0.0 Oct 28 05:17:46.258187 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 28 05:17:46.447390 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 28 05:17:46.450119 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 28 05:17:46.531904 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 28 05:17:46.801968 kernel: loop4: detected capacity change from 0 to 27736 Oct 28 05:17:46.816961 kernel: loop5: detected capacity change from 0 to 128912 Oct 28 05:17:46.832966 kernel: loop6: detected capacity change from 0 to 111544 Oct 28 05:17:46.846114 (sd-merge)[1925]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Oct 28 05:17:46.849837 (sd-merge)[1925]: Merged extensions into '/usr'. Oct 28 05:17:46.853660 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 28 05:17:46.856695 systemd[1]: Starting ensure-sysext.service... Oct 28 05:17:46.858298 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 05:17:46.896518 systemd[1]: Reload requested from client PID 1927 ('systemctl') (unit ensure-sysext.service)... Oct 28 05:17:46.896537 systemd[1]: Reloading... Oct 28 05:17:46.917631 systemd-tmpfiles[1928]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 28 05:17:46.917671 systemd-tmpfiles[1928]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 28 05:17:46.917976 systemd-tmpfiles[1928]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 28 05:17:46.918260 systemd-tmpfiles[1928]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 28 05:17:46.919214 systemd-tmpfiles[1928]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 05:17:46.919484 systemd-tmpfiles[1928]: ACLs are not supported, ignoring. Oct 28 05:17:46.919586 systemd-tmpfiles[1928]: ACLs are not supported, ignoring. Oct 28 05:17:46.945964 zram_generator::config[1960]: No configuration found. Oct 28 05:17:47.048807 systemd-tmpfiles[1928]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 05:17:47.048823 systemd-tmpfiles[1928]: Skipping /boot Oct 28 05:17:47.057565 systemd-tmpfiles[1928]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 05:17:47.057580 systemd-tmpfiles[1928]: Skipping /boot Oct 28 05:17:47.213134 systemd[1]: Reloading finished in 316 ms. Oct 28 05:17:47.269707 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 05:17:47.284491 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.285779 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 28 05:17:47.288177 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 28 05:17:47.288762 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 05:17:47.289914 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 28 05:17:47.308921 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 05:17:47.313449 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 05:17:47.315983 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 05:17:47.316609 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 05:17:47.316723 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 28 05:17:47.317910 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 28 05:17:47.319805 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 28 05:17:47.320211 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.322251 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 05:17:47.322434 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 05:17:47.323767 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 05:17:47.323959 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 05:17:47.325213 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 05:17:47.325378 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 05:17:47.328454 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.328655 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 05:17:47.329806 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 05:17:47.330873 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 05:17:47.332370 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 05:17:47.332954 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 05:17:47.333065 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 28 05:17:47.333165 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.336969 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 05:17:47.337145 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 05:17:47.339789 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 05:17:47.339978 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 05:17:47.340589 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 05:17:47.340747 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 05:17:47.342236 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.342479 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 05:17:47.343512 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 05:17:47.344003 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 05:17:47.344042 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 28 05:17:47.344087 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 05:17:47.344130 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 05:17:47.344163 systemd[1]: Reached target time-set.target - System Time Set. Oct 28 05:17:47.344649 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 28 05:17:47.345081 systemd[1]: Finished ensure-sysext.service. Oct 28 05:17:47.345659 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 28 05:17:47.349207 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 05:17:47.349375 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 05:17:47.628183 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 28 05:17:47.797274 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 05:17:48.077070 systemd-networkd[1796]: eth0: Gained IPv6LL Oct 28 05:17:48.079294 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 28 05:17:48.084838 systemd[1]: Reached target network-online.target - Network is Online. Oct 28 05:17:48.103555 augenrules[2067]: No rules Oct 28 05:17:48.104706 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 05:17:48.104967 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 28 05:17:49.985814 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 28 05:17:49.991078 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 28 05:18:00.010472 ldconfig[2023]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 28 05:18:00.029962 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 28 05:18:00.036988 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 28 05:18:00.075056 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 28 05:18:00.081729 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 05:18:00.086318 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 28 05:18:00.091513 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 28 05:18:00.097033 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 28 05:18:00.102086 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 28 05:18:00.106689 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 28 05:18:00.112091 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 28 05:18:00.117524 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 28 05:18:00.117569 systemd[1]: Reached target paths.target - Path Units. Oct 28 05:18:00.121315 systemd[1]: Reached target timers.target - Timer Units. Oct 28 05:18:00.138096 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 28 05:18:00.144279 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 28 05:18:00.150560 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 28 05:18:00.156251 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 28 05:18:00.163031 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 28 05:18:00.169880 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 28 05:18:00.185726 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 28 05:18:00.191521 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 28 05:18:00.197299 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 05:18:00.201223 systemd[1]: Reached target basic.target - Basic System. Oct 28 05:18:00.205110 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 28 05:18:00.205140 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 28 05:18:00.219784 systemd[1]: Starting chronyd.service - NTP client/server... Oct 28 05:18:00.224600 systemd[1]: Starting containerd.service - containerd container runtime... Oct 28 05:18:00.250896 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 28 05:18:00.256619 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 28 05:18:00.262021 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 28 05:18:00.268075 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 28 05:18:00.273504 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 28 05:18:00.278595 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 28 05:18:00.279651 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 28 05:18:00.282843 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 28 05:18:00.283926 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 28 05:18:00.291211 chronyd[2079]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 28 05:18:00.293422 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 28 05:18:00.294456 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 28 05:18:00.301282 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 28 05:18:00.305090 jq[2087]: false Oct 28 05:18:00.306064 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 28 05:18:00.312300 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 28 05:18:00.319295 KVP[2090]: KVP starting; pid is:2090 Oct 28 05:18:00.319527 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 28 05:18:00.326168 kernel: hv_utils: KVP IC version 4.0 Oct 28 05:18:00.326106 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 28 05:18:00.322007 KVP[2090]: KVP LIC Version: 3.1 Oct 28 05:18:00.326552 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 05:18:00.331246 systemd[1]: Starting update-engine.service - Update Engine... Oct 28 05:18:00.336279 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 28 05:18:00.343607 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 28 05:18:00.349226 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 28 05:18:00.349438 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 28 05:18:00.349719 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 05:18:00.349898 extend-filesystems[2088]: Found /dev/sda6 Oct 28 05:18:00.353168 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Refreshing passwd entry cache Oct 28 05:18:00.353074 oslogin_cache_refresh[2089]: Refreshing passwd entry cache Oct 28 05:18:00.356961 chronyd[2079]: Timezone right/UTC failed leap second check, ignoring Oct 28 05:18:00.357143 chronyd[2079]: Loaded seccomp filter (level 2) Oct 28 05:18:00.359525 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 28 05:18:00.362995 jq[2110]: true Oct 28 05:18:00.364085 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Failure getting users, quitting Oct 28 05:18:00.364085 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 28 05:18:00.364075 oslogin_cache_refresh[2089]: Failure getting users, quitting Oct 28 05:18:00.364209 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Refreshing group entry cache Oct 28 05:18:00.364093 oslogin_cache_refresh[2089]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 28 05:18:00.364138 oslogin_cache_refresh[2089]: Refreshing group entry cache Oct 28 05:18:00.364399 systemd[1]: Started chronyd.service - NTP client/server. Oct 28 05:18:00.368788 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 05:18:00.369024 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 28 05:18:00.384699 jq[2119]: true Oct 28 05:18:00.389172 update_engine[2103]: I20251028 05:18:00.389099 2103 main.cc:92] Flatcar Update Engine starting Oct 28 05:18:00.389899 extend-filesystems[2088]: Found /dev/sda9 Oct 28 05:18:00.393114 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Failure getting groups, quitting Oct 28 05:18:00.393114 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 28 05:18:00.393193 extend-filesystems[2088]: Checking size of /dev/sda9 Oct 28 05:18:00.392927 oslogin_cache_refresh[2089]: Failure getting groups, quitting Oct 28 05:18:00.394321 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 28 05:18:00.392959 oslogin_cache_refresh[2089]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 28 05:18:00.394546 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 28 05:18:00.455228 extend-filesystems[2088]: Resized partition /dev/sda9 Oct 28 05:18:00.487863 systemd-logind[2098]: New seat seat0. Oct 28 05:18:00.489109 systemd-logind[2098]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Oct 28 05:18:00.490744 systemd[1]: Started systemd-logind.service - User Login Management. Oct 28 05:18:00.519028 sshd_keygen[2118]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 28 05:18:00.519983 extend-filesystems[2154]: resize2fs 1.47.3 (8-Jul-2025) Oct 28 05:18:00.551029 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 28 05:18:00.553947 setup-nvidia[2092]: Downloading Flatcar Container Linux Developer Container for version: 4501.0.0+nightly-20251027-2100 Oct 28 05:18:00.554960 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Oct 28 05:18:00.559757 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 28 05:18:00.574346 bash[2148]: Updated "/home/core/.ssh/authorized_keys" Oct 28 05:18:00.574966 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Oct 28 05:18:00.576220 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 28 05:18:00.580615 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 28 05:18:00.586818 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 28 05:18:00.587155 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 05:18:00.587375 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 28 05:18:00.593034 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 28 05:18:00.615650 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 28 05:18:00.621767 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 28 05:18:00.626810 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 28 05:18:00.631532 systemd[1]: Reached target getty.target - Login Prompts. Oct 28 05:18:00.648075 extend-filesystems[2154]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Oct 28 05:18:00.648075 extend-filesystems[2154]: old_desc_blocks = 4, new_desc_blocks = 4 Oct 28 05:18:00.648075 extend-filesystems[2154]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Oct 28 05:18:00.659003 extend-filesystems[2088]: Resized filesystem in /dev/sda9 Oct 28 05:18:00.658647 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 05:18:00.658923 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 28 05:18:00.671431 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 28 05:18:00.705114 dbus-daemon[2082]: [system] SELinux support is enabled Oct 28 05:18:00.705368 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 28 05:18:00.710737 update_engine[2103]: I20251028 05:18:00.710685 2103 update_check_scheduler.cc:74] Next update check in 6m0s Oct 28 05:18:00.712342 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 28 05:18:00.712376 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 28 05:18:00.713296 dbus-daemon[2082]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 28 05:18:00.717635 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 28 05:18:00.717666 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 28 05:18:00.722809 systemd[1]: Started update-engine.service - Update Engine. Oct 28 05:18:00.730005 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 28 05:18:00.788466 coreos-metadata[2081]: Oct 28 05:18:00.788 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 28 05:18:00.790984 coreos-metadata[2081]: Oct 28 05:18:00.790 INFO Fetch successful Oct 28 05:18:00.791151 coreos-metadata[2081]: Oct 28 05:18:00.791 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 28 05:18:00.795555 coreos-metadata[2081]: Oct 28 05:18:00.795 INFO Fetch successful Oct 28 05:18:00.795856 coreos-metadata[2081]: Oct 28 05:18:00.795 INFO Fetching http://168.63.129.16/machine/cd314c44-5bc9-42f4-a7e5-750daeee5053/87702b5a%2Dc097%2D42e4%2D9211%2D11ce7fea71aa.%5Fci%2D4501.0.0%2Dn%2Da027052b46?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 28 05:18:00.797577 coreos-metadata[2081]: Oct 28 05:18:00.797 INFO Fetch successful Oct 28 05:18:00.797763 coreos-metadata[2081]: Oct 28 05:18:00.797 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 28 05:18:00.805245 coreos-metadata[2081]: Oct 28 05:18:00.805 INFO Fetch successful Oct 28 05:18:00.856964 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 28 05:18:00.862061 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 28 05:18:00.989711 locksmithd[2252]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 05:18:01.056603 containerd[2120]: time="2025-10-28T05:18:01Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 28 05:18:01.057195 containerd[2120]: time="2025-10-28T05:18:01.057157600Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068211800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9µs" Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068240900Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068260300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068400500Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068417700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068439700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068497000Z" 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 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068510900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068697600Z" 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 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068712700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068723000Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070432 containerd[2120]: time="2025-10-28T05:18:01.068731200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.068809500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069070600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069101100Z" 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 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069112000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069142100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069393700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 28 05:18:01.070736 containerd[2120]: time="2025-10-28T05:18:01.069517300Z" level=info msg="metadata content store policy set" policy=shared Oct 28 05:18:01.098063 containerd[2120]: time="2025-10-28T05:18:01.098013100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098073500Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098091700Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098105000Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098117600Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098128100Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098141800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 28 05:18:01.098153 containerd[2120]: time="2025-10-28T05:18:01.098153800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 28 05:18:01.098307 containerd[2120]: time="2025-10-28T05:18:01.098166500Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 28 05:18:01.098307 containerd[2120]: time="2025-10-28T05:18:01.098177300Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 28 05:18:01.098307 containerd[2120]: time="2025-10-28T05:18:01.098187000Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 28 05:18:01.098307 containerd[2120]: time="2025-10-28T05:18:01.098198200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 28 05:18:01.098393 containerd[2120]: time="2025-10-28T05:18:01.098313400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 28 05:18:01.098393 containerd[2120]: time="2025-10-28T05:18:01.098345800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 28 05:18:01.098393 containerd[2120]: time="2025-10-28T05:18:01.098362100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 28 05:18:01.098393 containerd[2120]: time="2025-10-28T05:18:01.098374700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 28 05:18:01.098393 containerd[2120]: time="2025-10-28T05:18:01.098385000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098394600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098406100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098428700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098443400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098454700Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 28 05:18:01.098492 containerd[2120]: time="2025-10-28T05:18:01.098464400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 28 05:18:01.098620 containerd[2120]: time="2025-10-28T05:18:01.098518900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 28 05:18:01.098620 containerd[2120]: time="2025-10-28T05:18:01.098534000Z" level=info msg="Start snapshots syncer" Oct 28 05:18:01.098620 containerd[2120]: time="2025-10-28T05:18:01.098558600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 28 05:18:01.098858 containerd[2120]: time="2025-10-28T05:18:01.098799400Z" 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 28 05:18:01.099013 containerd[2120]: time="2025-10-28T05:18:01.098872200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 28 05:18:01.100161 containerd[2120]: time="2025-10-28T05:18:01.100123200Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 28 05:18:01.100286 containerd[2120]: time="2025-10-28T05:18:01.100260100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 28 05:18:01.100319 containerd[2120]: time="2025-10-28T05:18:01.100290500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 28 05:18:01.100319 containerd[2120]: time="2025-10-28T05:18:01.100303100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 28 05:18:01.100319 containerd[2120]: time="2025-10-28T05:18:01.100313700Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100327100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100338400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100349100Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100371200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100383100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 28 05:18:01.100399 containerd[2120]: time="2025-10-28T05:18:01.100393800Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100430800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100458800Z" 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 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100469000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100478900Z" 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 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100487500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 28 05:18:01.100518 containerd[2120]: time="2025-10-28T05:18:01.100507000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100520900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100533200Z" level=info msg="runtime interface created" Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100538500Z" level=info msg="created NRI interface" Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100546200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100557000Z" level=info msg="Connect containerd service" Oct 28 05:18:01.100637 containerd[2120]: time="2025-10-28T05:18:01.100581500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 28 05:18:01.101377 containerd[2120]: time="2025-10-28T05:18:01.101347200Z" 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 28 05:18:02.101833 containerd[2120]: time="2025-10-28T05:18:02.101782900Z" level=info msg="Start subscribing containerd event" Oct 28 05:18:02.102245 containerd[2120]: time="2025-10-28T05:18:02.101843900Z" level=info msg="Start recovering state" Oct 28 05:18:02.102278 containerd[2120]: time="2025-10-28T05:18:02.102249400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 28 05:18:02.102345 containerd[2120]: time="2025-10-28T05:18:02.102320600Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 28 05:18:02.102425 containerd[2120]: time="2025-10-28T05:18:02.102385200Z" level=info msg="Start event monitor" Oct 28 05:18:02.102457 containerd[2120]: time="2025-10-28T05:18:02.102445300Z" level=info msg="Start cni network conf syncer for default" Oct 28 05:18:02.102481 containerd[2120]: time="2025-10-28T05:18:02.102459600Z" level=info msg="Start streaming server" Oct 28 05:18:02.102481 containerd[2120]: time="2025-10-28T05:18:02.102469200Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 28 05:18:02.102528 containerd[2120]: time="2025-10-28T05:18:02.102482400Z" level=info msg="runtime interface starting up..." Oct 28 05:18:02.102528 containerd[2120]: time="2025-10-28T05:18:02.102489100Z" level=info msg="starting plugins..." Oct 28 05:18:02.102528 containerd[2120]: time="2025-10-28T05:18:02.102507600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 28 05:18:02.102746 containerd[2120]: time="2025-10-28T05:18:02.102719400Z" level=info msg="containerd successfully booted in 1.046569s" Oct 28 05:18:02.102896 systemd[1]: Started containerd.service - containerd container runtime. Oct 28 05:18:03.472528 waagent[2248]: 2025-10-28T05:18:03.472446Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 28 05:18:03.476950 waagent[2248]: 2025-10-28T05:18:03.476891Z INFO Daemon Daemon OS: flatcar 4501.0.0 Oct 28 05:18:03.480315 waagent[2248]: 2025-10-28T05:18:03.480270Z INFO Daemon Daemon Python: 3.11.13 Oct 28 05:18:03.483701 waagent[2248]: 2025-10-28T05:18:03.483658Z INFO Daemon Daemon Run daemon Oct 28 05:18:03.486788 waagent[2248]: 2025-10-28T05:18:03.486744Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4501.0.0' Oct 28 05:18:03.493512 waagent[2248]: 2025-10-28T05:18:03.493465Z INFO Daemon Daemon Using waagent for provisioning Oct 28 05:18:03.497521 waagent[2248]: 2025-10-28T05:18:03.497474Z INFO Daemon Daemon Activate resource disk Oct 28 05:18:03.501031 waagent[2248]: 2025-10-28T05:18:03.500984Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 28 05:18:03.508363 waagent[2248]: 2025-10-28T05:18:03.508321Z INFO Daemon Daemon Found device: sdb Oct 28 05:18:03.517317 waagent[2248]: 2025-10-28T05:18:03.517268Z INFO Daemon Daemon Examining partition table Oct 28 05:18:03.581929 waagent[2248]: 2025-10-28T05:18:03.581854Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 28 05:18:03.656399 waagent[2248]: 2025-10-28T05:18:03.656323Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 28 05:18:03.661110 waagent[2248]: 2025-10-28T05:18:03.661049Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 28 05:18:04.050292 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#407 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 28 05:18:04.061966 kernel: sdb: sdb1 Oct 28 05:18:04.079315 waagent[2248]: 2025-10-28T05:18:04.079236Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 28 05:18:04.091934 waagent[2248]: 2025-10-28T05:18:04.091877Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 28 05:18:08.010745 login[2240]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 28 05:18:08.061089 login[2241]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 28 05:18:08.068093 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 28 05:18:08.069183 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 28 05:18:08.075036 systemd-logind[2098]: New session 1 of user core. Oct 28 05:18:08.117908 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 28 05:18:08.120403 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 28 05:18:08.155361 (systemd)[2341]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 05:18:08.157608 systemd-logind[2098]: New session c1 of user core. Oct 28 05:18:08.952725 systemd[2341]: Queued start job for default target default.target. Oct 28 05:18:08.971042 systemd[2341]: Created slice app.slice - User Application Slice. Oct 28 05:18:08.971076 systemd[2341]: Reached target paths.target - Paths. Oct 28 05:18:08.971127 systemd[2341]: Reached target timers.target - Timers. Oct 28 05:18:08.972434 systemd[2341]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 28 05:18:08.983369 systemd[2341]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 28 05:18:08.983494 systemd[2341]: Reached target sockets.target - Sockets. Oct 28 05:18:08.983546 systemd[2341]: Reached target basic.target - Basic System. Oct 28 05:18:08.983592 systemd[2341]: Reached target default.target - Main User Target. Oct 28 05:18:08.983626 systemd[2341]: Startup finished in 819ms. Oct 28 05:18:08.983793 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 28 05:18:08.985355 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 28 05:18:09.011095 login[2240]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 28 05:18:09.015376 systemd-logind[2098]: New session 2 of user core. Oct 28 05:18:09.024054 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 28 05:18:09.481177 waagent[2248]: 2025-10-28T05:18:09.481093Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 28 05:18:09.626827 waagent[2248]: 2025-10-28T05:18:09.626741Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 28 05:18:09.626966 kernel: EXT4-fs (sdb1): mounted filesystem 9d76243d-f8cd-46e7-b5d4-f2d8512bcee7 r/w with ordered data mode. Quota mode: none. Oct 28 05:18:09.629514 waagent[2248]: 2025-10-28T05:18:09.629466Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 28 05:18:09.631136 waagent[2248]: 2025-10-28T05:18:09.631082Z INFO Daemon Daemon Running default provisioning handler Oct 28 05:18:09.647903 waagent[2248]: 2025-10-28T05:18:09.647837Z 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 28 05:18:09.649991 waagent[2248]: 2025-10-28T05:18:09.649935Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 28 05:18:09.650306 waagent[2248]: 2025-10-28T05:18:09.650271Z INFO Daemon Daemon cloud-init is enabled: False Oct 28 05:18:09.650896 waagent[2248]: 2025-10-28T05:18:09.650867Z INFO Daemon Daemon Copying ovf-env.xml Oct 28 05:18:09.869232 waagent[2248]: 2025-10-28T05:18:09.868984Z INFO Daemon Daemon Successfully mounted dvd Oct 28 05:18:09.936795 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 28 05:18:09.937158 waagent[2248]: 2025-10-28T05:18:09.937100Z INFO Daemon Daemon Detect protocol endpoint Oct 28 05:18:09.938819 waagent[2248]: 2025-10-28T05:18:09.938760Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 28 05:18:09.939602 waagent[2248]: 2025-10-28T05:18:09.939562Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 28 05:18:09.941082 waagent[2248]: 2025-10-28T05:18:09.941043Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 28 05:18:09.942754 waagent[2248]: 2025-10-28T05:18:09.942715Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 28 05:18:09.943405 waagent[2248]: 2025-10-28T05:18:09.943364Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 28 05:18:09.968887 waagent[2248]: 2025-10-28T05:18:09.968835Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 28 05:18:09.970530 waagent[2248]: 2025-10-28T05:18:09.970503Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 28 05:18:09.972141 waagent[2248]: 2025-10-28T05:18:09.972103Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 28 05:18:10.115498 waagent[2248]: 2025-10-28T05:18:10.115400Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 28 05:18:10.117233 waagent[2248]: 2025-10-28T05:18:10.117185Z INFO Daemon Daemon Forcing an update of the goal state. Oct 28 05:18:10.122147 waagent[2248]: 2025-10-28T05:18:10.122064Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 28 05:18:10.140270 waagent[2248]: 2025-10-28T05:18:10.140219Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 28 05:18:10.142320 waagent[2248]: 2025-10-28T05:18:10.142277Z INFO Daemon Oct 28 05:18:10.142671 waagent[2248]: 2025-10-28T05:18:10.142635Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 4e2a4ae1-dffc-4725-87ea-78324f542df8 eTag: 17279777105761680996 source: Fabric] Oct 28 05:18:10.144399 waagent[2248]: 2025-10-28T05:18:10.144357Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 28 05:18:10.145177 waagent[2248]: 2025-10-28T05:18:10.145130Z INFO Daemon Oct 28 05:18:10.145579 waagent[2248]: 2025-10-28T05:18:10.145545Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 28 05:18:10.176761 waagent[2248]: 2025-10-28T05:18:10.176711Z INFO Daemon Daemon Downloading artifacts profile blob Oct 28 05:18:10.309021 waagent[2248]: 2025-10-28T05:18:10.308953Z INFO Daemon Downloaded certificate {'thumbprint': '5D79360A8313B109258061116988568A67DA69F8', 'hasPrivateKey': True} Oct 28 05:18:10.310924 waagent[2248]: 2025-10-28T05:18:10.310866Z INFO Daemon Fetch goal state completed Oct 28 05:18:10.355240 waagent[2248]: 2025-10-28T05:18:10.355185Z INFO Daemon Daemon Starting provisioning Oct 28 05:18:10.357135 waagent[2248]: 2025-10-28T05:18:10.357072Z INFO Daemon Daemon Handle ovf-env.xml. Oct 28 05:18:10.358776 waagent[2248]: 2025-10-28T05:18:10.358724Z INFO Daemon Daemon Set hostname [ci-4501.0.0-n-a027052b46] Oct 28 05:18:10.385991 waagent[2248]: 2025-10-28T05:18:10.385926Z INFO Daemon Daemon Publish hostname [ci-4501.0.0-n-a027052b46] Oct 28 05:18:10.387691 waagent[2248]: 2025-10-28T05:18:10.387640Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 28 05:18:10.389154 waagent[2248]: 2025-10-28T05:18:10.389115Z INFO Daemon Daemon Primary interface is [eth0] Oct 28 05:18:10.400523 systemd-networkd[1796]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 05:18:10.400534 systemd-networkd[1796]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Oct 28 05:18:10.400604 systemd-networkd[1796]: eth0: DHCP lease lost Oct 28 05:18:10.431916 waagent[2248]: 2025-10-28T05:18:10.431852Z INFO Daemon Daemon Create user account if not exists Oct 28 05:18:10.433687 waagent[2248]: 2025-10-28T05:18:10.433639Z INFO Daemon Daemon User core already exists, skip useradd Oct 28 05:18:10.435396 waagent[2248]: 2025-10-28T05:18:10.435353Z INFO Daemon Daemon Configure sudoer Oct 28 05:18:10.442472 waagent[2248]: 2025-10-28T05:18:10.442430Z INFO Daemon Daemon Configure sshd Oct 28 05:18:10.447496 waagent[2248]: 2025-10-28T05:18:10.447453Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 28 05:18:10.449061 waagent[2248]: 2025-10-28T05:18:10.449020Z INFO Daemon Daemon Deploy ssh public key. Oct 28 05:18:10.479983 systemd-networkd[1796]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 28 05:18:11.625965 waagent[2248]: 2025-10-28T05:18:11.625897Z INFO Daemon Daemon Provisioning complete Oct 28 05:18:11.636590 waagent[2248]: 2025-10-28T05:18:11.636547Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 28 05:18:11.641329 waagent[2248]: 2025-10-28T05:18:11.641280Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 28 05:18:11.648701 waagent[2248]: 2025-10-28T05:18:11.648653Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 28 05:18:11.801141 waagent[2399]: 2025-10-28T05:18:11.801050Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 28 05:18:11.801495 waagent[2399]: 2025-10-28T05:18:11.801204Z INFO ExtHandler ExtHandler OS: flatcar 4501.0.0 Oct 28 05:18:11.801495 waagent[2399]: 2025-10-28T05:18:11.801271Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 28 05:18:11.801495 waagent[2399]: 2025-10-28T05:18:11.801339Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 28 05:18:11.866012 waagent[2399]: 2025-10-28T05:18:11.865915Z INFO ExtHandler ExtHandler Distro: flatcar-4501.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 28 05:18:11.866209 waagent[2399]: 2025-10-28T05:18:11.866170Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 28 05:18:11.866287 waagent[2399]: 2025-10-28T05:18:11.866254Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 28 05:18:11.873428 waagent[2399]: 2025-10-28T05:18:11.873370Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 28 05:18:11.878507 waagent[2399]: 2025-10-28T05:18:11.878428Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 28 05:18:11.878956 waagent[2399]: 2025-10-28T05:18:11.878895Z INFO ExtHandler Oct 28 05:18:11.879048 waagent[2399]: 2025-10-28T05:18:11.879013Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: abb38675-071c-4eb2-87e0-cebfbe0bc5df eTag: 17279777105761680996 source: Fabric] Oct 28 05:18:11.879376 waagent[2399]: 2025-10-28T05:18:11.879337Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 28 05:18:11.879996 waagent[2399]: 2025-10-28T05:18:11.879935Z INFO ExtHandler Oct 28 05:18:11.880071 waagent[2399]: 2025-10-28T05:18:11.880039Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 28 05:18:11.883700 waagent[2399]: 2025-10-28T05:18:11.883658Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 28 05:18:11.943598 waagent[2399]: 2025-10-28T05:18:11.943528Z INFO ExtHandler Downloaded certificate {'thumbprint': '5D79360A8313B109258061116988568A67DA69F8', 'hasPrivateKey': True} Oct 28 05:18:11.944125 waagent[2399]: 2025-10-28T05:18:11.944083Z INFO ExtHandler Fetch goal state completed Oct 28 05:18:11.955766 waagent[2399]: 2025-10-28T05:18:11.955712Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.3 30 Sep 2025 (Library: OpenSSL 3.4.3 30 Sep 2025) Oct 28 05:18:11.963029 waagent[2399]: 2025-10-28T05:18:11.962977Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2399 Oct 28 05:18:11.963167 waagent[2399]: 2025-10-28T05:18:11.963127Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 28 05:18:11.963500 waagent[2399]: 2025-10-28T05:18:11.963462Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 28 05:18:11.965231 waagent[2399]: 2025-10-28T05:18:11.965189Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4501.0.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 28 05:18:11.965676 waagent[2399]: 2025-10-28T05:18:11.965637Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4501.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 28 05:18:11.965828 waagent[2399]: 2025-10-28T05:18:11.965793Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 28 05:18:11.966443 waagent[2399]: 2025-10-28T05:18:11.966404Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 28 05:18:12.034301 waagent[2399]: 2025-10-28T05:18:12.034252Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 28 05:18:12.034498 waagent[2399]: 2025-10-28T05:18:12.034461Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 28 05:18:12.040872 waagent[2399]: 2025-10-28T05:18:12.040826Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 28 05:18:12.048078 systemd[1]: Reload requested from client PID 2414 ('systemctl') (unit waagent.service)... Oct 28 05:18:12.048096 systemd[1]: Reloading... Oct 28 05:18:12.120967 zram_generator::config[2452]: No configuration found. Oct 28 05:18:12.431298 systemd[1]: Reloading finished in 382 ms. Oct 28 05:18:12.452784 waagent[2399]: 2025-10-28T05:18:12.452711Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 28 05:18:12.452903 waagent[2399]: 2025-10-28T05:18:12.452865Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 28 05:18:13.169073 waagent[2399]: 2025-10-28T05:18:13.168989Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 28 05:18:13.169438 waagent[2399]: 2025-10-28T05:18:13.169336Z 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 28 05:18:13.170139 waagent[2399]: 2025-10-28T05:18:13.170086Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 28 05:18:13.170590 waagent[2399]: 2025-10-28T05:18:13.170547Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 28 05:18:13.170654 waagent[2399]: 2025-10-28T05:18:13.170608Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 28 05:18:13.170999 waagent[2399]: 2025-10-28T05:18:13.170963Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 28 05:18:13.171176 waagent[2399]: 2025-10-28T05:18:13.171133Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 28 05:18:13.171274 waagent[2399]: 2025-10-28T05:18:13.171220Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 28 05:18:13.171350 waagent[2399]: 2025-10-28T05:18:13.171309Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 28 05:18:13.171832 waagent[2399]: 2025-10-28T05:18:13.171752Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 28 05:18:13.172103 waagent[2399]: 2025-10-28T05:18:13.172056Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 28 05:18:13.172237 waagent[2399]: 2025-10-28T05:18:13.172189Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 28 05:18:13.172441 waagent[2399]: 2025-10-28T05:18:13.172386Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 28 05:18:13.172559 waagent[2399]: 2025-10-28T05:18:13.172523Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 28 05:18:13.172759 waagent[2399]: 2025-10-28T05:18:13.172715Z INFO EnvHandler ExtHandler Configure routes Oct 28 05:18:13.173116 waagent[2399]: 2025-10-28T05:18:13.173080Z INFO EnvHandler ExtHandler Gateway:None Oct 28 05:18:13.173379 waagent[2399]: 2025-10-28T05:18:13.173338Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 28 05:18:13.173379 waagent[2399]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 28 05:18:13.173379 waagent[2399]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 28 05:18:13.173379 waagent[2399]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 28 05:18:13.173379 waagent[2399]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 28 05:18:13.173379 waagent[2399]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 28 05:18:13.173379 waagent[2399]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 28 05:18:13.173634 waagent[2399]: 2025-10-28T05:18:13.173603Z INFO EnvHandler ExtHandler Routes:None Oct 28 05:18:13.183812 waagent[2399]: 2025-10-28T05:18:13.183763Z INFO ExtHandler ExtHandler Oct 28 05:18:13.183885 waagent[2399]: 2025-10-28T05:18:13.183850Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 5c0bdf18-15b7-40ab-9b47-2ebc7dfefcbf correlation 8613f2b5-67b1-4780-ae74-37c7969fe7d6 created: 2025-10-28T05:16:30.732681Z] Oct 28 05:18:13.184272 waagent[2399]: 2025-10-28T05:18:13.184225Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 28 05:18:13.184867 waagent[2399]: 2025-10-28T05:18:13.184826Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Oct 28 05:18:13.249601 waagent[2399]: 2025-10-28T05:18:13.249534Z 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 28 05:18:13.249601 waagent[2399]: Try `iptables -h' or 'iptables --help' for more information.) Oct 28 05:18:13.250049 waagent[2399]: 2025-10-28T05:18:13.250011Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: CCDC9874-CAA3-47D1-B8D0-5B6D1302D775;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 28 05:18:13.323299 waagent[2399]: 2025-10-28T05:18:13.323235Z INFO MonitorHandler ExtHandler Network interfaces: Oct 28 05:18:13.323299 waagent[2399]: Executing ['ip', '-a', '-o', 'link']: Oct 28 05:18:13.323299 waagent[2399]: 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 28 05:18:13.323299 waagent[2399]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:de:17:d2 brd ff:ff:ff:ff:ff:ff\ altname enx6045bdde17d2 Oct 28 05:18:13.323299 waagent[2399]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 28 05:18:13.323299 waagent[2399]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 28 05:18:13.323299 waagent[2399]: 2: eth0 inet 10.200.8.22/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 28 05:18:13.323299 waagent[2399]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 28 05:18:13.323299 waagent[2399]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 28 05:18:13.323299 waagent[2399]: 2: eth0 inet6 fe80::6245:bdff:fede:17d2/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 28 05:18:13.438244 waagent[2399]: 2025-10-28T05:18:13.438114Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 28 05:18:13.438244 waagent[2399]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.438244 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.438244 waagent[2399]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.438244 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.438244 waagent[2399]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.438244 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.438244 waagent[2399]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 28 05:18:13.438244 waagent[2399]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 28 05:18:13.438244 waagent[2399]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 28 05:18:13.441714 waagent[2399]: 2025-10-28T05:18:13.441673Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 28 05:18:13.441714 waagent[2399]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.441714 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.441714 waagent[2399]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.441714 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.441714 waagent[2399]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 28 05:18:13.441714 waagent[2399]: pkts bytes target prot opt in out source destination Oct 28 05:18:13.441714 waagent[2399]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 28 05:18:13.441714 waagent[2399]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 28 05:18:13.441714 waagent[2399]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 28 05:18:13.441990 waagent[2399]: 2025-10-28T05:18:13.441930Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 28 05:18:13.442134 waagent[2399]: 2025-10-28T05:18:13.442102Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 28 05:18:17.207661 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 28 05:18:17.210591 systemd[1]: Started sshd@0-10.200.8.22:22-10.200.16.10:50510.service - OpenSSH per-connection server daemon (10.200.16.10:50510). Oct 28 05:18:18.199715 sshd[2548]: Accepted publickey for core from 10.200.16.10 port 50510 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:18.201005 sshd-session[2548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:18.205171 systemd-logind[2098]: New session 3 of user core. Oct 28 05:18:18.215071 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 28 05:18:18.614436 systemd[1]: Started sshd@1-10.200.8.22:22-10.200.16.10:50518.service - OpenSSH per-connection server daemon (10.200.16.10:50518). Oct 28 05:18:19.152426 sshd[2554]: Accepted publickey for core from 10.200.16.10 port 50518 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:19.153742 sshd-session[2554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:19.158020 systemd-logind[2098]: New session 4 of user core. Oct 28 05:18:19.169060 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 28 05:18:19.457988 sshd[2557]: Connection closed by 10.200.16.10 port 50518 Oct 28 05:18:19.458273 sshd-session[2554]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:19.461916 systemd[1]: sshd@1-10.200.8.22:22-10.200.16.10:50518.service: Deactivated successfully. Oct 28 05:18:19.463598 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 05:18:19.464306 systemd-logind[2098]: Session 4 logged out. Waiting for processes to exit. Oct 28 05:18:19.465186 systemd-logind[2098]: Removed session 4. Oct 28 05:18:19.576306 systemd[1]: Started sshd@2-10.200.8.22:22-10.200.16.10:50530.service - OpenSSH per-connection server daemon (10.200.16.10:50530). Oct 28 05:18:20.116373 sshd[2563]: Accepted publickey for core from 10.200.16.10 port 50530 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:20.117625 sshd-session[2563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:20.121815 systemd-logind[2098]: New session 5 of user core. Oct 28 05:18:20.133094 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 28 05:18:20.422062 sshd[2566]: Connection closed by 10.200.16.10 port 50530 Oct 28 05:18:20.422340 sshd-session[2563]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:20.425983 systemd[1]: sshd@2-10.200.8.22:22-10.200.16.10:50530.service: Deactivated successfully. Oct 28 05:18:20.427562 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 05:18:20.428323 systemd-logind[2098]: Session 5 logged out. Waiting for processes to exit. Oct 28 05:18:20.429174 systemd-logind[2098]: Removed session 5. Oct 28 05:18:24.149579 chronyd[2079]: Selected source PHC0 Oct 28 05:18:30.543008 systemd[1]: Started sshd@3-10.200.8.22:22-10.200.16.10:47106.service - OpenSSH per-connection server daemon (10.200.16.10:47106). Oct 28 05:18:31.095952 sshd[2573]: Accepted publickey for core from 10.200.16.10 port 47106 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:31.097277 sshd-session[2573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:31.101484 systemd-logind[2098]: New session 6 of user core. Oct 28 05:18:31.121056 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 28 05:18:31.401901 sshd[2576]: Connection closed by 10.200.16.10 port 47106 Oct 28 05:18:31.402170 sshd-session[2573]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:31.405829 systemd[1]: sshd@3-10.200.8.22:22-10.200.16.10:47106.service: Deactivated successfully. Oct 28 05:18:31.408028 systemd[1]: session-6.scope: Deactivated successfully. Oct 28 05:18:31.408849 systemd-logind[2098]: Session 6 logged out. Waiting for processes to exit. Oct 28 05:18:31.409705 systemd-logind[2098]: Removed session 6. Oct 28 05:18:31.517847 systemd[1]: Started sshd@4-10.200.8.22:22-10.200.16.10:47112.service - OpenSSH per-connection server daemon (10.200.16.10:47112). Oct 28 05:18:32.084476 sshd[2582]: Accepted publickey for core from 10.200.16.10 port 47112 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:32.085736 sshd-session[2582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:32.090177 systemd-logind[2098]: New session 7 of user core. Oct 28 05:18:32.107084 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 28 05:18:32.391264 sshd[2585]: Connection closed by 10.200.16.10 port 47112 Oct 28 05:18:32.391529 sshd-session[2582]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:32.395269 systemd[1]: sshd@4-10.200.8.22:22-10.200.16.10:47112.service: Deactivated successfully. Oct 28 05:18:32.396865 systemd[1]: session-7.scope: Deactivated successfully. Oct 28 05:18:32.398094 systemd-logind[2098]: Session 7 logged out. Waiting for processes to exit. Oct 28 05:18:32.399410 systemd-logind[2098]: Removed session 7. Oct 28 05:18:34.202136 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 28 05:18:42.506271 systemd[1]: Started sshd@5-10.200.8.22:22-10.200.16.10:60708.service - OpenSSH per-connection server daemon (10.200.16.10:60708). Oct 28 05:18:43.044360 sshd[2591]: Accepted publickey for core from 10.200.16.10 port 60708 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:43.045635 sshd-session[2591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:43.049754 systemd-logind[2098]: New session 8 of user core. Oct 28 05:18:43.062078 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 28 05:18:43.349602 sshd[2594]: Connection closed by 10.200.16.10 port 60708 Oct 28 05:18:43.349888 sshd-session[2591]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:43.353810 systemd[1]: sshd@5-10.200.8.22:22-10.200.16.10:60708.service: Deactivated successfully. Oct 28 05:18:43.355474 systemd[1]: session-8.scope: Deactivated successfully. Oct 28 05:18:43.356186 systemd-logind[2098]: Session 8 logged out. Waiting for processes to exit. Oct 28 05:18:43.357066 systemd-logind[2098]: Removed session 8. Oct 28 05:18:43.460390 systemd[1]: Started sshd@6-10.200.8.22:22-10.200.16.10:60718.service - OpenSSH per-connection server daemon (10.200.16.10:60718). Oct 28 05:18:43.997385 sshd[2600]: Accepted publickey for core from 10.200.16.10 port 60718 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:43.998626 sshd-session[2600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:44.002879 systemd-logind[2098]: New session 9 of user core. Oct 28 05:18:44.022084 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 28 05:18:44.301411 sshd[2603]: Connection closed by 10.200.16.10 port 60718 Oct 28 05:18:44.301751 sshd-session[2600]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:44.305323 systemd[1]: sshd@6-10.200.8.22:22-10.200.16.10:60718.service: Deactivated successfully. Oct 28 05:18:44.306886 systemd[1]: session-9.scope: Deactivated successfully. Oct 28 05:18:44.307578 systemd-logind[2098]: Session 9 logged out. Waiting for processes to exit. Oct 28 05:18:44.308425 systemd-logind[2098]: Removed session 9. Oct 28 05:18:46.263055 update_engine[2103]: I20251028 05:18:46.262960 2103 update_attempter.cc:509] Updating boot flags... Oct 28 05:18:54.417337 systemd[1]: Started sshd@7-10.200.8.22:22-10.200.16.10:43498.service - OpenSSH per-connection server daemon (10.200.16.10:43498). Oct 28 05:18:54.954911 sshd[2725]: Accepted publickey for core from 10.200.16.10 port 43498 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:54.956220 sshd-session[2725]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:54.960475 systemd-logind[2098]: New session 10 of user core. Oct 28 05:18:54.972068 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 28 05:18:55.260111 sshd[2728]: Connection closed by 10.200.16.10 port 43498 Oct 28 05:18:55.260392 sshd-session[2725]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:55.264036 systemd[1]: sshd@7-10.200.8.22:22-10.200.16.10:43498.service: Deactivated successfully. Oct 28 05:18:55.265608 systemd[1]: session-10.scope: Deactivated successfully. Oct 28 05:18:55.266529 systemd-logind[2098]: Session 10 logged out. Waiting for processes to exit. Oct 28 05:18:55.267354 systemd-logind[2098]: Removed session 10. Oct 28 05:18:55.371248 systemd[1]: Started sshd@8-10.200.8.22:22-10.200.16.10:43500.service - OpenSSH per-connection server daemon (10.200.16.10:43500). Oct 28 05:18:55.908135 sshd[2734]: Accepted publickey for core from 10.200.16.10 port 43500 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:18:55.909422 sshd-session[2734]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:18:55.913646 systemd-logind[2098]: New session 11 of user core. Oct 28 05:18:55.923075 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 28 05:18:56.213188 sshd[2737]: Connection closed by 10.200.16.10 port 43500 Oct 28 05:18:56.213463 sshd-session[2734]: pam_unix(sshd:session): session closed for user core Oct 28 05:18:56.217277 systemd[1]: sshd@8-10.200.8.22:22-10.200.16.10:43500.service: Deactivated successfully. Oct 28 05:18:56.218841 systemd[1]: session-11.scope: Deactivated successfully. Oct 28 05:18:56.219528 systemd-logind[2098]: Session 11 logged out. Waiting for processes to exit. Oct 28 05:18:56.220800 systemd-logind[2098]: Removed session 11. Oct 28 05:19:00.997359 setup-nvidia[2742]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 28 05:19:00.997802 setup-nvidia[2742]: Dload Upload Total Spent Left Speed Oct 28 05:19:06.324981 systemd[1]: Started sshd@9-10.200.8.22:22-10.200.16.10:52218.service - OpenSSH per-connection server daemon (10.200.16.10:52218). Oct 28 05:19:06.864028 sshd[2744]: Accepted publickey for core from 10.200.16.10 port 52218 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:06.865252 sshd-session[2744]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:06.869500 systemd-logind[2098]: New session 12 of user core. Oct 28 05:19:06.880062 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 28 05:19:07.169763 sshd[2747]: Connection closed by 10.200.16.10 port 52218 Oct 28 05:19:07.170028 sshd-session[2744]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:07.173576 systemd[1]: sshd@9-10.200.8.22:22-10.200.16.10:52218.service: Deactivated successfully. Oct 28 05:19:07.175363 systemd[1]: session-12.scope: Deactivated successfully. Oct 28 05:19:07.176233 systemd-logind[2098]: Session 12 logged out. Waiting for processes to exit. Oct 28 05:19:07.177228 systemd-logind[2098]: Removed session 12. Oct 28 05:19:07.284628 systemd[1]: Started sshd@10-10.200.8.22:22-10.200.16.10:52222.service - OpenSSH per-connection server daemon (10.200.16.10:52222). Oct 28 05:19:07.822841 sshd[2753]: Accepted publickey for core from 10.200.16.10 port 52222 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:07.824070 sshd-session[2753]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:07.828486 systemd-logind[2098]: New session 13 of user core. Oct 28 05:19:07.844090 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 28 05:19:08.127863 sshd[2756]: Connection closed by 10.200.16.10 port 52222 Oct 28 05:19:08.128158 sshd-session[2753]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:08.131750 systemd[1]: sshd@10-10.200.8.22:22-10.200.16.10:52222.service: Deactivated successfully. Oct 28 05:19:08.137220 systemd[1]: session-13.scope: Deactivated successfully. Oct 28 05:19:08.137886 systemd-logind[2098]: Session 13 logged out. Waiting for processes to exit. Oct 28 05:19:08.138789 systemd-logind[2098]: Removed session 13. Oct 28 05:19:18.262684 systemd[1]: Started sshd@11-10.200.8.22:22-10.200.16.10:43920.service - OpenSSH per-connection server daemon (10.200.16.10:43920). Oct 28 05:19:18.803420 sshd[2762]: Accepted publickey for core from 10.200.16.10 port 43920 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:18.804634 sshd-session[2762]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:18.808838 systemd-logind[2098]: New session 14 of user core. Oct 28 05:19:18.818175 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 28 05:19:19.110062 sshd[2765]: Connection closed by 10.200.16.10 port 43920 Oct 28 05:19:19.110333 sshd-session[2762]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:19.113929 systemd[1]: sshd@11-10.200.8.22:22-10.200.16.10:43920.service: Deactivated successfully. Oct 28 05:19:19.115488 systemd[1]: session-14.scope: Deactivated successfully. Oct 28 05:19:19.116168 systemd-logind[2098]: Session 14 logged out. Waiting for processes to exit. Oct 28 05:19:19.117032 systemd-logind[2098]: Removed session 14. Oct 28 05:19:19.225265 systemd[1]: Started sshd@12-10.200.8.22:22-10.200.16.10:43928.service - OpenSSH per-connection server daemon (10.200.16.10:43928). Oct 28 05:19:19.777221 sshd[2771]: Accepted publickey for core from 10.200.16.10 port 43928 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:19.778426 sshd-session[2771]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:19.782891 systemd-logind[2098]: New session 15 of user core. Oct 28 05:19:19.791072 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 28 05:19:20.081773 sshd[2774]: Connection closed by 10.200.16.10 port 43928 Oct 28 05:19:20.082129 sshd-session[2771]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:20.085811 systemd[1]: sshd@12-10.200.8.22:22-10.200.16.10:43928.service: Deactivated successfully. Oct 28 05:19:20.087585 systemd[1]: session-15.scope: Deactivated successfully. Oct 28 05:19:20.088347 systemd-logind[2098]: Session 15 logged out. Waiting for processes to exit. Oct 28 05:19:20.089210 systemd-logind[2098]: Removed session 15. Oct 28 05:19:23.712806 setup-nvidia[2742]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 528M 0 32768 0 0 56455 0 2:43:42 --:--:-- 2:43:42 56399\u000d 0 528M 0 3072k 0 0 1999k 0 0:04:30 0:00:01 0:04:29 2000k\u000d 5 528M 5 30.2M 0 0 11.9M 0 0:00:44 0:00:02 0:00:42 11.9M\u000d 10 528M 10 55.3M 0 0 15.1M 0 0:00:34 0:00:03 0:00:31 15.1M\u000d 15 528M 15 80.4M 0 0 17.3M 0 0:00:30 0:00:04 0:00:26 17.3M\u000d 19 528M 19 105M 0 0 18.6M 0 0:00:28 0:00:05 0:00:23 20.8M\u000d 24 528M 24 129M 0 0 19.5M 0 0:00:27 0:00:06 0:00:21 24.9M\u000d 29 528M 29 154M 0 0 20.3M 0 0:00:25 0:00:07 0:00:18 24.5M\u000d 33 528M 33 179M 0 0 20.9M 0 0:00:25 0:00:08 0:00:17 25.1M\u000d 38 528M 38 203M 0 0 21.2M 0 0:00:24 0:00:09 0:00:15 24.8M\u000d 43 528M 43 227M 0 0 21.5M 0 0:00:24 0:00:10 0:00:14 24.7M\u000d 47 528M 47 253M 0 0 21.8M 0 0:00:24 0:00:11 0:00:13 24.9M\u000d 52 528M 52 276M 0 0 22.0M 0 0:00:23 0:00:12 0:00:11 24.5M\u000d 57 528M 57 301M 0 0 22.2M 0 0:00:23 0:00:13 0:00:10 24.5M\u000d 61 528M 61 325M 0 0 22.3M 0 0:00:23 0:00:14 0:00:09 24.5M\u000d 66 528M 66 352M 0 0 22.5M 0 0:00:23 0:00:15 0:00:08 24.6M\u000d 71 528M 71 376M 0 0 22.5M 0 0:00:23 0:00:16 0:00:07 24.2M\u000d 75 528M 75 401M 0 0 22.7M 0 0:00:23 0:00:17 0:00:06 24.5M\u000d 80 528M 80 425M 0 0 22.8M 0 0:00:23 0:00:18 0:00:05 24.3M\u000d 84 528M 84 448M 0 0 22.9M 0 0:00:23 0:00:19 0:00:04 24.4M\u000d 89 528M 89 471M 0 0 22.9M 0 0:00:23 0:00:20 0:00:03 24.1M\u000d 94 528M 94 497M 0 0 23.0M 0 0:00:22 0:00:21 0:00:01 24.5M\u000d 98 528M 98 522M 0 0 23.1M 0 0:00:22 0:00:22 --:--:-- 24.5M\u000d100 528M 100 528M 0 0 23.2M 0 0:00:22 0:00:22 --:--:-- 25.2M Oct 28 05:19:30.205716 systemd[1]: Started sshd@13-10.200.8.22:22-10.200.16.10:45220.service - OpenSSH per-connection server daemon (10.200.16.10:45220). Oct 28 05:19:30.770921 sshd[2790]: Accepted publickey for core from 10.200.16.10 port 45220 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:30.773925 sshd-session[2790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:30.781512 systemd-logind[2098]: New session 16 of user core. Oct 28 05:19:30.791131 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 28 05:19:31.082907 sshd[2793]: Connection closed by 10.200.16.10 port 45220 Oct 28 05:19:31.081477 sshd-session[2790]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:31.085565 systemd[1]: sshd@13-10.200.8.22:22-10.200.16.10:45220.service: Deactivated successfully. Oct 28 05:19:31.089187 systemd[1]: session-16.scope: Deactivated successfully. Oct 28 05:19:31.090726 systemd-logind[2098]: Session 16 logged out. Waiting for processes to exit. Oct 28 05:19:31.091963 systemd-logind[2098]: Removed session 16. Oct 28 05:19:31.197545 systemd[1]: Started sshd@14-10.200.8.22:22-10.200.16.10:45232.service - OpenSSH per-connection server daemon (10.200.16.10:45232). Oct 28 05:19:31.773679 sshd[2799]: Accepted publickey for core from 10.200.16.10 port 45232 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:31.774783 sshd-session[2799]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:31.782473 systemd-logind[2098]: New session 17 of user core. Oct 28 05:19:31.790151 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 28 05:19:32.082983 sshd[2802]: Connection closed by 10.200.16.10 port 45232 Oct 28 05:19:32.085139 sshd-session[2799]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:32.088952 systemd[1]: sshd@14-10.200.8.22:22-10.200.16.10:45232.service: Deactivated successfully. Oct 28 05:19:32.090553 systemd[1]: session-17.scope: Deactivated successfully. Oct 28 05:19:32.091721 systemd-logind[2098]: Session 17 logged out. Waiting for processes to exit. Oct 28 05:19:32.093458 systemd-logind[2098]: Removed session 17. Oct 28 05:19:35.249695 setup-nvidia[2092]: Downloading NVIDIA 535.274.02 Driver Oct 28 05:19:35.253994 setup-nvidia[2808]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 28 05:19:35.254232 setup-nvidia[2808]: Dload Upload Total Spent Left Speed Oct 28 05:19:42.199225 systemd[1]: Started sshd@15-10.200.8.22:22-10.200.16.10:52626.service - OpenSSH per-connection server daemon (10.200.16.10:52626). Oct 28 05:19:42.737076 sshd[2810]: Accepted publickey for core from 10.200.16.10 port 52626 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:42.738321 sshd-session[2810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:42.742586 systemd-logind[2098]: New session 18 of user core. Oct 28 05:19:42.755089 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 28 05:19:43.042186 sshd[2813]: Connection closed by 10.200.16.10 port 52626 Oct 28 05:19:43.042515 sshd-session[2810]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:43.046020 systemd[1]: sshd@15-10.200.8.22:22-10.200.16.10:52626.service: Deactivated successfully. Oct 28 05:19:43.047582 systemd[1]: session-18.scope: Deactivated successfully. Oct 28 05:19:43.048291 systemd-logind[2098]: Session 18 logged out. Waiting for processes to exit. Oct 28 05:19:43.049142 systemd-logind[2098]: Removed session 18. Oct 28 05:19:43.154352 systemd[1]: Started sshd@16-10.200.8.22:22-10.200.16.10:52636.service - OpenSSH per-connection server daemon (10.200.16.10:52636). Oct 28 05:19:43.693143 sshd[2819]: Accepted publickey for core from 10.200.16.10 port 52636 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:43.694396 sshd-session[2819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:43.698712 systemd-logind[2098]: New session 19 of user core. Oct 28 05:19:43.710060 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 28 05:19:43.998838 sshd[2822]: Connection closed by 10.200.16.10 port 52636 Oct 28 05:19:43.999123 sshd-session[2819]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:44.002888 systemd[1]: sshd@16-10.200.8.22:22-10.200.16.10:52636.service: Deactivated successfully. Oct 28 05:19:44.004438 systemd[1]: session-19.scope: Deactivated successfully. Oct 28 05:19:44.005146 systemd-logind[2098]: Session 19 logged out. Waiting for processes to exit. Oct 28 05:19:44.006159 systemd-logind[2098]: Removed session 19. Oct 28 05:19:54.114048 systemd[1]: Started sshd@17-10.200.8.22:22-10.200.16.10:60866.service - OpenSSH per-connection server daemon (10.200.16.10:60866). Oct 28 05:19:54.651512 sshd[2828]: Accepted publickey for core from 10.200.16.10 port 60866 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:54.652739 sshd-session[2828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:54.656892 systemd-logind[2098]: New session 20 of user core. Oct 28 05:19:54.665075 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 28 05:19:54.956794 sshd[2831]: Connection closed by 10.200.16.10 port 60866 Oct 28 05:19:54.957061 sshd-session[2828]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:54.960721 systemd[1]: sshd@17-10.200.8.22:22-10.200.16.10:60866.service: Deactivated successfully. Oct 28 05:19:54.962524 systemd[1]: session-20.scope: Deactivated successfully. Oct 28 05:19:54.963302 systemd-logind[2098]: Session 20 logged out. Waiting for processes to exit. Oct 28 05:19:54.964142 systemd-logind[2098]: Removed session 20. Oct 28 05:19:55.068761 systemd[1]: Started sshd@18-10.200.8.22:22-10.200.16.10:60882.service - OpenSSH per-connection server daemon (10.200.16.10:60882). Oct 28 05:19:55.608861 sshd[2837]: Accepted publickey for core from 10.200.16.10 port 60882 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:19:55.610137 sshd-session[2837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:19:55.614363 systemd-logind[2098]: New session 21 of user core. Oct 28 05:19:55.634103 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 28 05:19:55.914985 sshd[2840]: Connection closed by 10.200.16.10 port 60882 Oct 28 05:19:55.915244 sshd-session[2837]: pam_unix(sshd:session): session closed for user core Oct 28 05:19:55.918911 systemd[1]: sshd@18-10.200.8.22:22-10.200.16.10:60882.service: Deactivated successfully. Oct 28 05:19:55.920481 systemd[1]: session-21.scope: Deactivated successfully. Oct 28 05:19:55.921212 systemd-logind[2098]: Session 21 logged out. Waiting for processes to exit. Oct 28 05:19:55.922045 systemd-logind[2098]: Removed session 21. Oct 28 05:20:02.275253 setup-nvidia[2808]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 16144 0 0 17909 0 5:18:05 --:--:-- 5:18:05 17898\u000d 0 325M 0 894k 0 0 701k 0 0:07:56 0:00:01 0:07:55 700k\u000d 3 325M 3 10.0M 0 0 4472k 0 0:01:14 0:00:02 0:01:12 4472k\u000d 7 325M 7 25.7M 0 0 8040k 0 0:00:41 0:00:03 0:00:38 8041k\u000d 11 325M 11 37.5M 0 0 8990k 0 0:00:37 0:00:04 0:00:33 8990k\u000d 15 325M 15 50.0M 0 0 9663k 0 0:00:34 0:00:05 0:00:29 11.3M\u000d 19 325M 19 61.9M 0 0 9.8M 0 0:00:33 0:00:06 0:00:27 12.2M\u000d 23 325M 23 75.4M 0 0 10.3M 0 0:00:31 0:00:07 0:00:24 13.1M\u000d 26 325M 26 88.0M 0 0 10.5M 0 0:00:30 0:00:08 0:00:22 12.2M\u000d 31 325M 31 101M 0 0 10.9M 0 0:00:29 0:00:09 0:00:20 12.7M\u000d 34 325M 34 114M 0 0 10.9M 0 0:00:29 0:00:10 0:00:19 12.6M\u000d 38 325M 38 126M 0 0 11.1M 0 0:00:29 0:00:11 0:00:18 12.6M\u000d 42 325M 42 138M 0 0 11.1M 0 0:00:29 0:00:12 0:00:17 12.3M\u000d 46 325M 46 152M 0 0 11.4M 0 0:00:28 0:00:13 0:00:15 12.9M\u000d 50 325M 50 163M 0 0 11.4M 0 0:00:28 0:00:14 0:00:14 12.3M\u000d 54 325M 54 176M 0 0 11.4M 0 0:00:28 0:00:15 0:00:13 12.5M\u000d 57 325M 57 188M 0 0 11.5M 0 0:00:28 0:00:16 0:00:12 12.4M\u000d 61 325M 61 200M 0 0 11.5M 0 0:00:28 0:00:17 0:00:11 12.5M\u000d 66 325M 66 216M 0 0 11.8M 0 0:00:27 0:00:18 0:00:09 12.7M\u000d 70 325M 70 228M 0 0 11.8M 0 0:00:27 0:00:19 0:00:08 13.0M\u000d 74 325M 74 242M 0 0 11.9M 0 0:00:27 0:00:20 0:00:07 13.1M\u000d 75 325M 75 246M 0 0 11.4M 0 0:00:28 0:00:21 0:00:07 11.0M\u000d 81 325M 81 266M 0 0 11.9M 0 0:00:27 0:00:22 0:00:05 13.1M\u000d 85 325M 85 278M 0 0 11.9M 0 0:00:27 0:00:23 0:00:04 12.3M\u000d 89 325M 89 292M 0 0 12.0M 0 0:00:27 0:00:24 0:00:03 12.6M\u000d 93 325M 93 306M 0 0 12.0M 0 0:00:27 0:00:25 0:00:02 12.6M\u000d 95 325M 95 310M 0 0 11.6M 0 0:00:27 0:00:26 0:00:01 12.6M\u000d100 325M 100 325M 0 0 12.0M 0 0:00:27 0:00:27 --:--:-- 12.7M Oct 28 05:20:03.570242 setup-nvidia[2092]: Extract the NVIDIA Driver Installer 535.274.02 Oct 28 05:20:03.570242 setup-nvidia[2092]: /opt/nvidia/workdir/nvidia-workdir / Oct 28 05:20:03.604996 setup-nvidia[2847]: Creating directory NVIDIA-Linux-x86_64-535.274.02 Oct 28 05:20:03.922260 setup-nvidia[2847]: Verifying archive integrity... OK Oct 28 05:20:04.767652 setup-nvidia[2847]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.274.02 Oct 28 05:20:06.026621 systemd[1]: Started sshd@19-10.200.8.22:22-10.200.16.10:46222.service - OpenSSH per-connection server daemon (10.200.16.10:46222). Oct 28 05:20:06.564491 sshd[2878]: Accepted publickey for core from 10.200.16.10 port 46222 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:06.565835 sshd-session[2878]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:06.570317 systemd-logind[2098]: New session 22 of user core. Oct 28 05:20:06.578124 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 28 05:20:06.668182 setup-nvidia[2873]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 28 05:20:06.767760 setup-nvidia[2092]: / Oct 28 05:20:06.768025 setup-nvidia[2092]: Spawn system-nspawn container to install the NVIDIA drivers Oct 28 05:20:06.868766 sshd[2881]: Connection closed by 10.200.16.10 port 46222 Oct 28 05:20:06.869046 sshd-session[2878]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:06.872564 systemd[1]: sshd@19-10.200.8.22:22-10.200.16.10:46222.service: Deactivated successfully. Oct 28 05:20:06.874134 systemd[1]: session-22.scope: Deactivated successfully. Oct 28 05:20:06.874811 systemd-logind[2098]: Session 22 logged out. Waiting for processes to exit. Oct 28 05:20:06.875654 systemd-logind[2098]: Removed session 22. Oct 28 05:20:06.995410 systemd[1]: Started sshd@20-10.200.8.22:22-10.200.16.10:46224.service - OpenSSH per-connection server daemon (10.200.16.10:46224). Oct 28 05:20:07.004823 sudo[2886]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4501.0.0+nightly-20251027-2100.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.274.02 Oct 28 05:20:07.006522 sudo[2886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 28 05:20:07.025552 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 28 05:20:07.028215 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 28 05:20:07.042160 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 28 05:20:07.045919 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 28 05:20:07.054950 (systemd)[2894]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 28 05:20:07.057163 systemd-logind[2098]: New session c3 of user root. Oct 28 05:20:07.223015 systemd[2894]: Queued start job for default target default.target. Oct 28 05:20:07.243141 systemd[2894]: Created slice app.slice - User Application Slice. Oct 28 05:20:07.243173 systemd[2894]: Reached target paths.target - Paths. Oct 28 05:20:07.243220 systemd[2894]: Reached target timers.target - Timers. Oct 28 05:20:07.244401 systemd[2894]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 28 05:20:07.255780 systemd[2894]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 28 05:20:07.255902 systemd[2894]: Reached target sockets.target - Sockets. Oct 28 05:20:07.255974 systemd[2894]: Reached target basic.target - Basic System. Oct 28 05:20:07.256063 systemd[2894]: Reached target default.target - Main User Target. Oct 28 05:20:07.256102 systemd[2894]: Startup finished in 193ms. Oct 28 05:20:07.256118 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 28 05:20:07.258979 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 28 05:20:07.336914 systemd-udevd[1782]: Received message with invalid length, ignoring Oct 28 05:20:07.340958 kernel: loop7: detected capacity change from 0 to 12644352 Oct 28 05:20:07.343956 kernel: loop7: p9 Oct 28 05:20:07.357518 setup-nvidia[2905]: ░ Spawning container flatcardevelopercontainer-4501.0.0nightly-20251027-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4501.0.0+nightly-20251027-2100.bin. Oct 28 05:20:07.475447 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Oct 28 05:20:07.475548 kernel: EXT4-fs (loop7p9): mounted filesystem b37e7e1b-d53c-4adb-b661-b546287c0e51 ro without journal. Quota mode: none. Oct 28 05:20:07.582038 sshd[2891]: Accepted publickey for core from 10.200.16.10 port 46224 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:07.583270 sshd-session[2891]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:07.587700 systemd-logind[2098]: New session 23 of user core. Oct 28 05:20:07.602089 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 28 05:20:07.887371 sshd[2922]: Connection closed by 10.200.16.10 port 46224 Oct 28 05:20:07.887633 sshd-session[2891]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:07.891227 systemd[1]: sshd@20-10.200.8.22:22-10.200.16.10:46224.service: Deactivated successfully. Oct 28 05:20:07.892778 systemd[1]: session-23.scope: Deactivated successfully. Oct 28 05:20:07.893448 systemd-logind[2098]: Session 23 logged out. Waiting for processes to exit. Oct 28 05:20:07.894403 systemd-logind[2098]: Removed session 23. Oct 28 05:20:18.007018 systemd[1]: Started sshd@21-10.200.8.22:22-10.200.16.10:50392.service - OpenSSH per-connection server daemon (10.200.16.10:50392). Oct 28 05:20:18.543293 sshd[3810]: Accepted publickey for core from 10.200.16.10 port 50392 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:18.545039 sshd-session[3810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:18.549184 systemd-logind[2098]: New session 24 of user core. Oct 28 05:20:18.561060 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 28 05:20:18.858154 sshd[3875]: Connection closed by 10.200.16.10 port 50392 Oct 28 05:20:18.858413 sshd-session[3810]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:18.862206 systemd[1]: sshd@21-10.200.8.22:22-10.200.16.10:50392.service: Deactivated successfully. Oct 28 05:20:18.864049 systemd[1]: session-24.scope: Deactivated successfully. Oct 28 05:20:18.864848 systemd-logind[2098]: Session 24 logged out. Waiting for processes to exit. Oct 28 05:20:18.865715 systemd-logind[2098]: Removed session 24. Oct 28 05:20:18.987990 systemd[1]: Started sshd@22-10.200.8.22:22-10.200.16.10:50398.service - OpenSSH per-connection server daemon (10.200.16.10:50398). Oct 28 05:20:19.726593 sshd[3881]: Accepted publickey for core from 10.200.16.10 port 50398 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:19.727860 sshd-session[3881]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:19.732186 systemd-logind[2098]: New session 25 of user core. Oct 28 05:20:19.742076 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 28 05:20:20.031669 sshd[3884]: Connection closed by 10.200.16.10 port 50398 Oct 28 05:20:20.031934 sshd-session[3881]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:20.035524 systemd[1]: sshd@22-10.200.8.22:22-10.200.16.10:50398.service: Deactivated successfully. Oct 28 05:20:20.037098 systemd[1]: session-25.scope: Deactivated successfully. Oct 28 05:20:20.037766 systemd-logind[2098]: Session 25 logged out. Waiting for processes to exit. Oct 28 05:20:20.038618 systemd-logind[2098]: Removed session 25. Oct 28 05:20:30.149917 systemd[1]: Started sshd@23-10.200.8.22:22-10.200.16.10:48476.service - OpenSSH per-connection server daemon (10.200.16.10:48476). Oct 28 05:20:30.967407 sshd[8066]: Accepted publickey for core from 10.200.16.10 port 48476 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:30.968691 sshd-session[8066]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:30.972969 systemd-logind[2098]: New session 26 of user core. Oct 28 05:20:30.983144 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 28 05:20:31.521580 sshd[8383]: Connection closed by 10.200.16.10 port 48476 Oct 28 05:20:31.524339 sshd-session[8066]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:31.536414 systemd[1]: sshd@23-10.200.8.22:22-10.200.16.10:48476.service: Deactivated successfully. Oct 28 05:20:31.538297 systemd[1]: session-26.scope: Deactivated successfully. Oct 28 05:20:31.539152 systemd-logind[2098]: Session 26 logged out. Waiting for processes to exit. Oct 28 05:20:31.541387 systemd-logind[2098]: Removed session 26. Oct 28 05:20:31.670259 systemd[1]: Started sshd@24-10.200.8.22:22-10.200.16.10:48484.service - OpenSSH per-connection server daemon (10.200.16.10:48484). Oct 28 05:20:32.287407 sshd[8612]: Accepted publickey for core from 10.200.16.10 port 48484 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:32.289099 sshd-session[8612]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:32.296135 systemd-logind[2098]: New session 27 of user core. Oct 28 05:20:32.304167 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 28 05:20:32.614743 sshd[8870]: Connection closed by 10.200.16.10 port 48484 Oct 28 05:20:32.616877 sshd-session[8612]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:32.621371 systemd-logind[2098]: Session 27 logged out. Waiting for processes to exit. Oct 28 05:20:32.622568 systemd[1]: sshd@24-10.200.8.22:22-10.200.16.10:48484.service: Deactivated successfully. Oct 28 05:20:32.625246 systemd[1]: session-27.scope: Deactivated successfully. Oct 28 05:20:32.627795 systemd-logind[2098]: Removed session 27. Oct 28 05:20:42.721166 systemd[1]: Started sshd@25-10.200.8.22:22-10.200.16.10:47582.service - OpenSSH per-connection server daemon (10.200.16.10:47582). Oct 28 05:20:43.353041 sshd[12636]: Accepted publickey for core from 10.200.16.10 port 47582 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:43.354516 sshd-session[12636]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:43.360538 systemd-logind[2098]: New session 28 of user core. Oct 28 05:20:43.375907 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 28 05:20:43.663023 sshd[12794]: Connection closed by 10.200.16.10 port 47582 Oct 28 05:20:43.663568 sshd-session[12636]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:43.668025 systemd-logind[2098]: Session 28 logged out. Waiting for processes to exit. Oct 28 05:20:43.671573 systemd[1]: sshd@25-10.200.8.22:22-10.200.16.10:47582.service: Deactivated successfully. Oct 28 05:20:43.674505 systemd[1]: session-28.scope: Deactivated successfully. Oct 28 05:20:43.675462 systemd-logind[2098]: Removed session 28. Oct 28 05:20:43.775559 systemd[1]: Started sshd@26-10.200.8.22:22-10.200.16.10:47584.service - OpenSSH per-connection server daemon (10.200.16.10:47584). Oct 28 05:20:44.337844 sshd[12872]: Accepted publickey for core from 10.200.16.10 port 47584 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:44.338407 sshd-session[12872]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:44.347681 systemd-logind[2098]: New session 29 of user core. Oct 28 05:20:44.357443 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 28 05:20:44.644533 sshd[13074]: Connection closed by 10.200.16.10 port 47584 Oct 28 05:20:44.646853 sshd-session[12872]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:44.650223 systemd-logind[2098]: Session 29 logged out. Waiting for processes to exit. Oct 28 05:20:44.651298 systemd[1]: sshd@26-10.200.8.22:22-10.200.16.10:47584.service: Deactivated successfully. Oct 28 05:20:44.653922 systemd[1]: session-29.scope: Deactivated successfully. Oct 28 05:20:44.657035 systemd-logind[2098]: Removed session 29. Oct 28 05:20:54.760238 systemd[1]: Started sshd@27-10.200.8.22:22-10.200.16.10:35628.service - OpenSSH per-connection server daemon (10.200.16.10:35628). Oct 28 05:20:55.334433 sshd[14688]: Accepted publickey for core from 10.200.16.10 port 35628 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:55.336287 sshd-session[14688]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:55.345876 systemd-logind[2098]: New session 30 of user core. Oct 28 05:20:55.350145 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 28 05:20:55.641923 sshd[14721]: Connection closed by 10.200.16.10 port 35628 Oct 28 05:20:55.643822 sshd-session[14688]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:55.649690 systemd-logind[2098]: Session 30 logged out. Waiting for processes to exit. Oct 28 05:20:55.651740 systemd[1]: sshd@27-10.200.8.22:22-10.200.16.10:35628.service: Deactivated successfully. Oct 28 05:20:55.653589 systemd[1]: session-30.scope: Deactivated successfully. Oct 28 05:20:55.655991 systemd-logind[2098]: Removed session 30. Oct 28 05:20:55.756546 systemd[1]: Started sshd@28-10.200.8.22:22-10.200.16.10:35640.service - OpenSSH per-connection server daemon (10.200.16.10:35640). Oct 28 05:20:56.317544 sshd[14753]: Accepted publickey for core from 10.200.16.10 port 35640 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:20:56.320869 sshd-session[14753]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:20:56.330298 systemd-logind[2098]: New session 31 of user core. Oct 28 05:20:56.337706 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 28 05:20:56.625047 sshd[14768]: Connection closed by 10.200.16.10 port 35640 Oct 28 05:20:56.625804 sshd-session[14753]: pam_unix(sshd:session): session closed for user core Oct 28 05:20:56.629808 systemd[1]: sshd@28-10.200.8.22:22-10.200.16.10:35640.service: Deactivated successfully. Oct 28 05:20:56.633158 systemd[1]: session-31.scope: Deactivated successfully. Oct 28 05:20:56.634685 systemd-logind[2098]: Session 31 logged out. Waiting for processes to exit. Oct 28 05:20:56.636372 systemd-logind[2098]: Removed session 31. Oct 28 05:21:06.736206 systemd[1]: Started sshd@29-10.200.8.22:22-10.200.16.10:56218.service - OpenSSH per-connection server daemon (10.200.16.10:56218). Oct 28 05:21:07.297787 sshd[15074]: Accepted publickey for core from 10.200.16.10 port 56218 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:07.300652 sshd-session[15074]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:07.309176 systemd-logind[2098]: New session 32 of user core. Oct 28 05:21:07.314563 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 28 05:21:07.604434 sshd[15101]: Connection closed by 10.200.16.10 port 56218 Oct 28 05:21:07.608116 sshd-session[15074]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:07.612025 systemd[1]: sshd@29-10.200.8.22:22-10.200.16.10:56218.service: Deactivated successfully. Oct 28 05:21:07.613680 systemd[1]: session-32.scope: Deactivated successfully. Oct 28 05:21:07.614133 systemd-logind[2098]: Session 32 logged out. Waiting for processes to exit. Oct 28 05:21:07.618863 systemd-logind[2098]: Removed session 32. Oct 28 05:21:07.717565 systemd[1]: Started sshd@30-10.200.8.22:22-10.200.16.10:56234.service - OpenSSH per-connection server daemon (10.200.16.10:56234). Oct 28 05:21:08.275823 sshd[15119]: Accepted publickey for core from 10.200.16.10 port 56234 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:08.280551 sshd-session[15119]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:08.287585 systemd-logind[2098]: New session 33 of user core. Oct 28 05:21:08.293161 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 28 05:21:08.585561 sshd[15146]: Connection closed by 10.200.16.10 port 56234 Oct 28 05:21:08.589135 sshd-session[15119]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:08.593626 systemd-logind[2098]: Session 33 logged out. Waiting for processes to exit. Oct 28 05:21:08.596143 systemd[1]: sshd@30-10.200.8.22:22-10.200.16.10:56234.service: Deactivated successfully. Oct 28 05:21:08.597699 systemd[1]: session-33.scope: Deactivated successfully. Oct 28 05:21:08.600110 systemd-logind[2098]: Removed session 33. Oct 28 05:21:18.698930 systemd[1]: Started sshd@31-10.200.8.22:22-10.200.16.10:44964.service - OpenSSH per-connection server daemon (10.200.16.10:44964). Oct 28 05:21:19.259528 sshd[15437]: Accepted publickey for core from 10.200.16.10 port 44964 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:19.261360 sshd-session[15437]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:19.268364 systemd-logind[2098]: New session 34 of user core. Oct 28 05:21:19.276030 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 28 05:21:19.568376 sshd[15467]: Connection closed by 10.200.16.10 port 44964 Oct 28 05:21:19.571127 sshd-session[15437]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:19.578240 systemd[1]: sshd@31-10.200.8.22:22-10.200.16.10:44964.service: Deactivated successfully. Oct 28 05:21:19.580637 systemd[1]: session-34.scope: Deactivated successfully. Oct 28 05:21:19.581931 systemd-logind[2098]: Session 34 logged out. Waiting for processes to exit. Oct 28 05:21:19.584260 systemd-logind[2098]: Removed session 34. Oct 28 05:21:19.695291 systemd[1]: Started sshd@32-10.200.8.22:22-10.200.16.10:44980.service - OpenSSH per-connection server daemon (10.200.16.10:44980). Oct 28 05:21:20.260564 sshd[15493]: Accepted publickey for core from 10.200.16.10 port 44980 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:20.261998 sshd-session[15493]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:20.269320 systemd-logind[2098]: New session 35 of user core. Oct 28 05:21:20.278140 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 28 05:21:20.567650 sshd[15525]: Connection closed by 10.200.16.10 port 44980 Oct 28 05:21:20.568007 sshd-session[15493]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:20.571694 systemd-logind[2098]: Session 35 logged out. Waiting for processes to exit. Oct 28 05:21:20.573361 systemd[1]: sshd@32-10.200.8.22:22-10.200.16.10:44980.service: Deactivated successfully. Oct 28 05:21:20.575216 systemd[1]: session-35.scope: Deactivated successfully. Oct 28 05:21:20.577540 systemd-logind[2098]: Removed session 35. Oct 28 05:21:29.865035 setup-nvidia[2905]: \u000d Oct 28 05:21:29.865035 setup-nvidia[2905]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 28 05:21:29.865035 setup-nvidia[2905]: frequently when this kernel module was built against the wrong or Oct 28 05:21:29.865035 setup-nvidia[2905]: improperly configured kernel sources, with a version of gcc that differs Oct 28 05:21:29.865035 setup-nvidia[2905]: from the one used to build the target kernel, or if another driver, such Oct 28 05:21:29.865035 setup-nvidia[2905]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 28 05:21:29.865035 setup-nvidia[2905]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 28 05:21:29.865035 setup-nvidia[2905]: installed in this system is supported by this NVIDIA Linux graphics Oct 28 05:21:29.865035 setup-nvidia[2905]: driver release. Oct 28 05:21:29.865035 setup-nvidia[2905]: \u000d Oct 28 05:21:29.865035 setup-nvidia[2905]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 28 05:21:29.865035 setup-nvidia[2905]: messages' at the end of the file Oct 28 05:21:29.865035 setup-nvidia[2905]: '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for more Oct 28 05:21:29.865035 setup-nvidia[2905]: information. Oct 28 05:21:29.865035 setup-nvidia[2905]: \u000d Oct 28 05:21:29.880495 setup-nvidia[2905]: dmesg: read kernel buffer failed: Operation not permitted Oct 28 05:21:29.899171 setup-nvidia[2905]: \u000d Oct 28 05:21:29.899171 setup-nvidia[2905]: ERROR: Installation has failed. Please see the file Oct 28 05:21:29.899171 setup-nvidia[2905]: '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for Oct 28 05:21:29.899171 setup-nvidia[2905]: details. You may find suggestions on fixing installation problems in Oct 28 05:21:29.899171 setup-nvidia[2905]: the README available on the Linux driver download page at Oct 28 05:21:29.899171 setup-nvidia[2905]: www.nvidia.com. Oct 28 05:21:29.899171 setup-nvidia[2905]: \u000d Oct 28 05:21:29.899621 setup-nvidia[2905]: Last 50 lines of nvidia-installer.log: Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 28 05:21:29.900439 setup-nvidia[2905]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/Module.symvers Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.mod.o Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/.module-common.o Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.mod.o Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.mod.o Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.mod.o Oct 28 05:21:29.900439 setup-nvidia[2905]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.mod.o Oct 28 05:21:29.900439 setup-nvidia[2905]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 28 05:21:29.900439 setup-nvidia[2905]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 28 05:21:29.900439 setup-nvidia[2905]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 28 05:21:29.900439 setup-nvidia[2905]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 28 05:21:29.900439 setup-nvidia[2905]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko Oct 28 05:21:29.900439 setup-nvidia[2905]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko due to unavailability of vmlinux Oct 28 05:21:29.900439 setup-nvidia[2905]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Oct 28 05:21:29.900439 setup-nvidia[2905]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Oct 28 05:21:29.900439 setup-nvidia[2905]: -> done. Oct 28 05:21:29.900439 setup-nvidia[2905]: -> Kernel module compilation complete. Oct 28 05:21:29.900439 setup-nvidia[2905]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 28 05:21:29.900439 setup-nvidia[2905]: 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 28 05:21:29.900439 setup-nvidia[2905]: \u000d Oct 28 05:21:29.901545 setup-nvidia[2905]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for more information. Oct 28 05:21:29.901545 setup-nvidia[2905]: -> Kernel module load error: Operation not permitted Oct 28 05:21:29.901545 setup-nvidia[2905]: -> Kernel messages: Oct 28 05:21:29.901545 setup-nvidia[2905]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 28 05:21:30.683299 systemd[1]: Started sshd@33-10.200.8.22:22-10.200.16.10:44016.service - OpenSSH per-connection server daemon (10.200.16.10:44016). Oct 28 05:21:31.242826 sshd[15767]: Accepted publickey for core from 10.200.16.10 port 44016 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:31.244115 sshd-session[15767]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:31.248837 systemd-logind[2098]: New session 36 of user core. Oct 28 05:21:31.258065 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 28 05:21:31.549320 sshd[15770]: Connection closed by 10.200.16.10 port 44016 Oct 28 05:21:31.549665 sshd-session[15767]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:31.553399 systemd[1]: sshd@33-10.200.8.22:22-10.200.16.10:44016.service: Deactivated successfully. Oct 28 05:21:31.554975 systemd[1]: session-36.scope: Deactivated successfully. Oct 28 05:21:31.555655 systemd-logind[2098]: Session 36 logged out. Waiting for processes to exit. Oct 28 05:21:31.556522 systemd-logind[2098]: Removed session 36. Oct 28 05:21:31.664173 systemd[1]: Started sshd@34-10.200.8.22:22-10.200.16.10:44032.service - OpenSSH per-connection server daemon (10.200.16.10:44032). Oct 28 05:21:32.207680 sshd[15776]: Accepted publickey for core from 10.200.16.10 port 44032 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:32.209041 sshd-session[15776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:32.213249 systemd-logind[2098]: New session 37 of user core. Oct 28 05:21:32.233065 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 28 05:21:32.512706 sshd[15779]: Connection closed by 10.200.16.10 port 44032 Oct 28 05:21:32.512973 sshd-session[15776]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:32.516521 systemd[1]: sshd@34-10.200.8.22:22-10.200.16.10:44032.service: Deactivated successfully. Oct 28 05:21:32.518108 systemd[1]: session-37.scope: Deactivated successfully. Oct 28 05:21:32.518777 systemd-logind[2098]: Session 37 logged out. Waiting for processes to exit. Oct 28 05:21:32.519626 systemd-logind[2098]: Removed session 37. Oct 28 05:21:42.631936 systemd[1]: Started sshd@35-10.200.8.22:22-10.200.16.10:39782.service - OpenSSH per-connection server daemon (10.200.16.10:39782). Oct 28 05:21:43.172093 sshd[15785]: Accepted publickey for core from 10.200.16.10 port 39782 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:43.173344 sshd-session[15785]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:43.177610 systemd-logind[2098]: New session 38 of user core. Oct 28 05:21:43.192068 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 28 05:21:43.478788 sshd[15788]: Connection closed by 10.200.16.10 port 39782 Oct 28 05:21:43.479083 sshd-session[15785]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:43.483505 systemd[1]: sshd@35-10.200.8.22:22-10.200.16.10:39782.service: Deactivated successfully. Oct 28 05:21:43.485418 systemd[1]: session-38.scope: Deactivated successfully. Oct 28 05:21:43.487145 systemd-logind[2098]: Session 38 logged out. Waiting for processes to exit. Oct 28 05:21:43.488032 systemd-logind[2098]: Removed session 38. Oct 28 05:21:43.601901 systemd[1]: Started sshd@36-10.200.8.22:22-10.200.16.10:39790.service - OpenSSH per-connection server daemon (10.200.16.10:39790). Oct 28 05:21:44.140892 sshd[15794]: Accepted publickey for core from 10.200.16.10 port 39790 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:44.142155 sshd-session[15794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:44.146646 systemd-logind[2098]: New session 39 of user core. Oct 28 05:21:44.157074 systemd[1]: Started session-39.scope - Session 39 of User core. Oct 28 05:21:44.447486 sshd[15797]: Connection closed by 10.200.16.10 port 39790 Oct 28 05:21:44.447772 sshd-session[15794]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:44.451528 systemd[1]: sshd@36-10.200.8.22:22-10.200.16.10:39790.service: Deactivated successfully. Oct 28 05:21:44.453146 systemd[1]: session-39.scope: Deactivated successfully. Oct 28 05:21:44.453926 systemd-logind[2098]: Session 39 logged out. Waiting for processes to exit. Oct 28 05:21:44.454790 systemd-logind[2098]: Removed session 39. Oct 28 05:21:54.570539 systemd[1]: Started sshd@37-10.200.8.22:22-10.200.16.10:55154.service - OpenSSH per-connection server daemon (10.200.16.10:55154). Oct 28 05:21:55.119675 sshd[15803]: Accepted publickey for core from 10.200.16.10 port 55154 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:55.121003 sshd-session[15803]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:55.125308 systemd-logind[2098]: New session 40 of user core. Oct 28 05:21:55.142061 systemd[1]: Started session-40.scope - Session 40 of User core. Oct 28 05:21:55.425027 sshd[15806]: Connection closed by 10.200.16.10 port 55154 Oct 28 05:21:55.425284 sshd-session[15803]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:55.429262 systemd[1]: sshd@37-10.200.8.22:22-10.200.16.10:55154.service: Deactivated successfully. Oct 28 05:21:55.431047 systemd[1]: session-40.scope: Deactivated successfully. Oct 28 05:21:55.431762 systemd-logind[2098]: Session 40 logged out. Waiting for processes to exit. Oct 28 05:21:55.433747 systemd-logind[2098]: Removed session 40. Oct 28 05:21:55.548447 systemd[1]: Started sshd@38-10.200.8.22:22-10.200.16.10:55170.service - OpenSSH per-connection server daemon (10.200.16.10:55170). Oct 28 05:21:56.086422 sshd[15813]: Accepted publickey for core from 10.200.16.10 port 55170 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:21:56.087741 sshd-session[15813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:21:56.092002 systemd-logind[2098]: New session 41 of user core. Oct 28 05:21:56.108878 systemd[1]: Started session-41.scope - Session 41 of User core. Oct 28 05:21:56.325972 kernel: EXT4-fs (loop7p9): unmounting filesystem b37e7e1b-d53c-4adb-b661-b546287c0e51. Oct 28 05:21:56.326257 setup-nvidia[2905]: Container flatcardevelopercontainer-4501.0.0nightly-20251027-2100.bin exited successfully. Oct 28 05:21:56.328349 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4501.0.0nightly\x2d20251027\x2d2100.bin.mount: Deactivated successfully. Oct 28 05:21:56.389768 sudo[2886]: pam_unix(sudo:session): session closed for user root Oct 28 05:21:56.391354 sshd[15816]: Connection closed by 10.200.16.10 port 55170 Oct 28 05:21:56.391688 sshd-session[15813]: pam_unix(sshd:session): session closed for user core Oct 28 05:21:56.391925 systemd[1]: session-c2.scope: Deactivated successfully. Oct 28 05:21:56.392203 systemd[1]: session-c2.scope: Consumed 5min 30.209s CPU time, 897.2M memory peak. Oct 28 05:21:56.397025 systemd[1]: sshd@38-10.200.8.22:22-10.200.16.10:55170.service: Deactivated successfully. Oct 28 05:21:56.398595 systemd[1]: session-41.scope: Deactivated successfully. Oct 28 05:21:56.399269 systemd-logind[2098]: Session 41 logged out. Waiting for processes to exit. Oct 28 05:21:56.400289 systemd-logind[2098]: Removed session 41. Oct 28 05:21:56.819075 setup-nvidia[2092]: /opt/nvidia / Oct 28 05:21:56.819725 setup-nvidia[2092]: / Oct 28 05:21:56.828962 kernel: IPMI message handler: version 39.2 Oct 28 05:21:56.832958 kernel: ipmi device interface Oct 28 05:21:56.845230 setup-nvidia[2092]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Oct 28 05:21:56.900246 kernel: nvidia: loading out-of-tree module taints kernel. Oct 28 05:21:56.900374 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 28 05:21:56.900399 kernel: Disabling lock debugging due to kernel taint Oct 28 05:21:56.907612 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 28 05:21:56.907655 kernel: nvidia: module license taints kernel. Oct 28 05:21:57.319974 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 28 05:21:57.721810 kernel: Oct 28 05:21:57.721991 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 28 05:21:57.931969 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.274.02 Thu Sep 4 22:13:52 UTC 2025 Oct 28 05:21:57.952966 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.274.02 Thu Sep 4 22:13:13 UTC 2025 Oct 28 05:21:57.971980 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 28 05:21:58.016979 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 28 05:21:58.017331 setup-nvidia[2092]: / Oct 28 05:21:58.026827 setup-nvidia[15855]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 28 05:21:59.894630 setup-nvidia[15872]: Tue Oct 28 05:21:59 2025 Oct 28 05:21:59.894630 setup-nvidia[15872]: +---------------------------------------------------------------------------------------+ Oct 28 05:21:59.894630 setup-nvidia[15872]: | NVIDIA-SMI 535.274.02 Driver Version: 535.274.02 CUDA Version: 12.2 | Oct 28 05:21:59.894630 setup-nvidia[15872]: |-----------------------------------------+----------------------+----------------------+ Oct 28 05:21:59.894630 setup-nvidia[15872]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 28 05:21:59.894630 setup-nvidia[15872]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 28 05:21:59.894630 setup-nvidia[15872]: | | | MIG M. | Oct 28 05:21:59.894630 setup-nvidia[15872]: |=========================================+======================+======================| Oct 28 05:21:59.898402 setup-nvidia[15872]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 28 05:21:59.898402 setup-nvidia[15872]: | N/A 24C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Oct 28 05:21:59.898402 setup-nvidia[15872]: | | | N/A | Oct 28 05:21:59.898402 setup-nvidia[15872]: +-----------------------------------------+----------------------+----------------------+ Oct 28 05:21:59.898402 setup-nvidia[15872]: Oct 28 05:21:59.898402 setup-nvidia[15872]: +---------------------------------------------------------------------------------------+ Oct 28 05:21:59.898402 setup-nvidia[15872]: | Processes: | Oct 28 05:21:59.898402 setup-nvidia[15872]: | GPU GI CI PID Type Process name GPU Memory | Oct 28 05:21:59.898402 setup-nvidia[15872]: | ID ID Usage | Oct 28 05:21:59.898402 setup-nvidia[15872]: |=======================================================================================| Oct 28 05:21:59.898649 setup-nvidia[15872]: | No running processes found | Oct 28 05:21:59.898649 setup-nvidia[15872]: +---------------------------------------------------------------------------------------+ Oct 28 05:22:00.284251 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 28 05:22:00.286377 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 28 05:22:00.292691 systemd[1]: Startup finished in 1.177s (firmware) + 17.950s (loader) + 5.272s (kernel) + 16.361s (initrd) + 4min 24.397s (userspace) = 5min 5.160s. Oct 28 05:22:06.515052 systemd[1]: Started sshd@39-10.200.8.22:22-10.200.16.10:36640.service - OpenSSH per-connection server daemon (10.200.16.10:36640). Oct 28 05:22:06.516051 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 28 05:22:06.516773 systemd[2894]: Activating special unit exit.target... Oct 28 05:22:06.516853 systemd[2894]: Stopped target default.target - Main User Target. Oct 28 05:22:06.516895 systemd[2894]: Stopped target basic.target - Basic System. Oct 28 05:22:06.516925 systemd[2894]: Stopped target paths.target - Paths. Oct 28 05:22:06.516974 systemd[2894]: Stopped target sockets.target - Sockets. Oct 28 05:22:06.517005 systemd[2894]: Stopped target timers.target - Timers. Oct 28 05:22:06.517332 systemd[2894]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 28 05:22:06.517744 systemd[2894]: Removed slice app.slice - User Application Slice. Oct 28 05:22:06.517780 systemd[2894]: Reached target shutdown.target - Shutdown. Oct 28 05:22:06.517975 systemd[2894]: Finished systemd-exit.service - Exit the Session. Oct 28 05:22:06.518015 systemd[2894]: Reached target exit.target - Exit the Session. Oct 28 05:22:06.527914 (sd-pam)[2896]: pam_unix(systemd-user:session): session closed for user root Oct 28 05:22:06.527978 systemd-logind[2098]: Removed session c3. Oct 28 05:22:06.529115 systemd[1]: user@0.service: Deactivated successfully. Oct 28 05:22:06.529510 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 28 05:22:06.532368 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 28 05:22:06.554220 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 28 05:22:06.555233 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 28 05:22:06.555436 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 28 05:22:06.557119 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 28 05:22:06.557214 systemd[1]: user-0.slice: Consumed 5min 30.440s CPU time, 903.3M memory peak. Oct 28 05:22:07.068438 sshd[15879]: Accepted publickey for core from 10.200.16.10 port 36640 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:22:07.066350 sshd-session[15879]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:22:07.071057 systemd-logind[2098]: New session 42 of user core. Oct 28 05:22:07.083117 systemd[1]: Started session-42.scope - Session 42 of User core. Oct 28 05:22:07.372315 sshd[15886]: Connection closed by 10.200.16.10 port 36640 Oct 28 05:22:07.372605 sshd-session[15879]: pam_unix(sshd:session): session closed for user core Oct 28 05:22:07.376437 systemd[1]: sshd@39-10.200.8.22:22-10.200.16.10:36640.service: Deactivated successfully. Oct 28 05:22:07.378084 systemd[1]: session-42.scope: Deactivated successfully. Oct 28 05:22:07.378795 systemd-logind[2098]: Session 42 logged out. Waiting for processes to exit. Oct 28 05:22:07.379937 systemd-logind[2098]: Removed session 42. Oct 28 05:22:07.487819 systemd[1]: Started sshd@40-10.200.8.22:22-10.200.16.10:36642.service - OpenSSH per-connection server daemon (10.200.16.10:36642). Oct 28 05:22:08.027155 sshd[15892]: Accepted publickey for core from 10.200.16.10 port 36642 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:22:08.028416 sshd-session[15892]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:22:08.032731 systemd-logind[2098]: New session 43 of user core. Oct 28 05:22:08.052103 systemd[1]: Started session-43.scope - Session 43 of User core. Oct 28 05:22:08.329246 sshd[15895]: Connection closed by 10.200.16.10 port 36642 Oct 28 05:22:08.329602 sshd-session[15892]: pam_unix(sshd:session): session closed for user core Oct 28 05:22:08.333325 systemd[1]: sshd@40-10.200.8.22:22-10.200.16.10:36642.service: Deactivated successfully. Oct 28 05:22:08.334904 systemd[1]: session-43.scope: Deactivated successfully. Oct 28 05:22:08.335580 systemd-logind[2098]: Session 43 logged out. Waiting for processes to exit. Oct 28 05:22:08.336433 systemd-logind[2098]: Removed session 43. Oct 28 05:22:08.445490 systemd[1]: Started sshd@41-10.200.8.22:22-10.200.16.10:36658.service - OpenSSH per-connection server daemon (10.200.16.10:36658). Oct 28 05:22:09.008574 sshd[15901]: Accepted publickey for core from 10.200.16.10 port 36658 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:22:09.009864 sshd-session[15901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:22:09.014084 systemd-logind[2098]: New session 44 of user core. Oct 28 05:22:09.026071 systemd[1]: Started session-44.scope - Session 44 of User core. Oct 28 05:22:09.315312 sshd[15904]: Connection closed by 10.200.16.10 port 36658 Oct 28 05:22:09.315678 sshd-session[15901]: pam_unix(sshd:session): session closed for user core Oct 28 05:22:09.319385 systemd[1]: sshd@41-10.200.8.22:22-10.200.16.10:36658.service: Deactivated successfully. Oct 28 05:22:09.321041 systemd[1]: session-44.scope: Deactivated successfully. Oct 28 05:22:09.321710 systemd-logind[2098]: Session 44 logged out. Waiting for processes to exit. Oct 28 05:22:09.322572 systemd-logind[2098]: Removed session 44. Oct 28 05:22:09.430695 systemd[1]: Started sshd@42-10.200.8.22:22-10.200.16.10:36670.service - OpenSSH per-connection server daemon (10.200.16.10:36670). Oct 28 05:22:09.970257 sshd[15910]: Accepted publickey for core from 10.200.16.10 port 36670 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:22:09.971533 sshd-session[15910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:22:09.975816 systemd-logind[2098]: New session 45 of user core. Oct 28 05:22:09.985114 systemd[1]: Started session-45.scope - Session 45 of User core. Oct 28 05:22:10.280525 sshd[15913]: Connection closed by 10.200.16.10 port 36670 Oct 28 05:22:10.280800 sshd-session[15910]: pam_unix(sshd:session): session closed for user core Oct 28 05:22:10.284569 systemd[1]: sshd@42-10.200.8.22:22-10.200.16.10:36670.service: Deactivated successfully. Oct 28 05:22:10.286178 systemd[1]: session-45.scope: Deactivated successfully. Oct 28 05:22:10.286841 systemd-logind[2098]: Session 45 logged out. Waiting for processes to exit. Oct 28 05:22:10.287749 systemd-logind[2098]: Removed session 45. Oct 28 05:22:10.395521 systemd[1]: Started sshd@43-10.200.8.22:22-10.200.16.10:51608.service - OpenSSH per-connection server daemon (10.200.16.10:51608). Oct 28 05:22:10.950611 sshd[15919]: Accepted publickey for core from 10.200.16.10 port 51608 ssh2: RSA SHA256:hlJ0AvpMun9fEg6hPiY9dQ4w1nQFH0qfcRzjTpg/z+Q Oct 28 05:22:10.951889 sshd-session[15919]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 05:22:10.956155 systemd-logind[2098]: New session 46 of user core. Oct 28 05:22:10.967065 systemd[1]: Started session-46.scope - Session 46 of User core. Oct 28 05:22:12.845019 sshd[15922]: Connection closed by 10.200.16.10 port 51608 Oct 28 05:22:12.849469 systemd[1]: sshd@43-10.200.8.22:22-10.200.16.10:51608.service: Deactivated successfully. Oct 28 05:22:12.845594 sshd-session[15919]: pam_unix(sshd:session): session closed for user core Oct 28 05:22:12.851068 systemd[1]: session-46.scope: Deactivated successfully. Oct 28 05:22:12.851260 systemd[1]: session-46.scope: Consumed 1.527s CPU time, 14.1M memory peak. Oct 28 05:22:12.851748 systemd-logind[2098]: Session 46 logged out. Waiting for processes to exit. Oct 28 05:22:12.852673 systemd-logind[2098]: Removed session 46.