Nov 4 04:59:03.266659 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 Nov 4 03:00:51 -00 2025 Nov 4 04:59:03.266688 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:59:03.266698 kernel: BIOS-provided physical RAM map: Nov 4 04:59:03.266705 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 4 04:59:03.266711 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 4 04:59:03.266718 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 4 04:59:03.266729 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 4 04:59:03.266736 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 4 04:59:03.266744 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 4 04:59:03.266751 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 4 04:59:03.266758 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 4 04:59:03.266765 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 4 04:59:03.266772 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 4 04:59:03.266779 kernel: NX (Execute Disable) protection: active Nov 4 04:59:03.266790 kernel: APIC: Static calls initialized Nov 4 04:59:03.266799 kernel: efi: EFI v2.7 by Microsoft Nov 4 04:59:03.266807 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Nov 4 04:59:03.266815 kernel: random: crng init done Nov 4 04:59:03.266823 kernel: secureboot: Secure boot disabled Nov 4 04:59:03.266830 kernel: SMBIOS 3.1.0 present. Nov 4 04:59:03.266838 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 4 04:59:03.266849 kernel: DMI: Memory slots populated: 3/3 Nov 4 04:59:03.266856 kernel: Hypervisor detected: Microsoft Hyper-V Nov 4 04:59:03.266864 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 4 04:59:03.266872 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 4 04:59:03.266880 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 4 04:59:03.266888 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 04:59:03.266896 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 04:59:03.266905 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 4 04:59:03.266913 kernel: tsc: Detected 2593.992 MHz processor Nov 4 04:59:03.266924 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 4 04:59:03.266933 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 4 04:59:03.266941 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 4 04:59:03.266949 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 4 04:59:03.266957 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 4 04:59:03.266966 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 4 04:59:03.266974 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 4 04:59:03.266984 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 4 04:59:03.266993 kernel: Using GB pages for direct mapping Nov 4 04:59:03.267001 kernel: ACPI: Early table checksum verification disabled Nov 4 04:59:03.267013 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 4 04:59:03.267022 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267031 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267042 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 4 04:59:03.267051 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 4 04:59:03.267059 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267068 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267077 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267086 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267097 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267106 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267115 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:59:03.267123 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 4 04:59:03.267132 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 4 04:59:03.267140 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 4 04:59:03.267152 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 4 04:59:03.267180 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 4 04:59:03.267189 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 4 04:59:03.267198 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 4 04:59:03.267206 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 4 04:59:03.267215 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 4 04:59:03.267224 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 4 04:59:03.267236 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 4 04:59:03.267244 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 4 04:59:03.267253 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 4 04:59:03.267262 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 4 04:59:03.267271 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 4 04:59:03.267279 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 4 04:59:03.267288 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 4 04:59:03.267299 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 4 04:59:03.267308 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 4 04:59:03.267317 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 4 04:59:03.267326 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 4 04:59:03.267335 kernel: Zone ranges: Nov 4 04:59:03.267343 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 4 04:59:03.267352 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 4 04:59:03.267363 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 4 04:59:03.267372 kernel: Device empty Nov 4 04:59:03.267381 kernel: Movable zone start for each node Nov 4 04:59:03.267389 kernel: Early memory node ranges Nov 4 04:59:03.267398 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 4 04:59:03.267407 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 4 04:59:03.267416 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 4 04:59:03.267424 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 4 04:59:03.267438 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 4 04:59:03.267447 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 4 04:59:03.267456 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 4 04:59:03.267464 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 4 04:59:03.267473 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 4 04:59:03.267482 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 4 04:59:03.267491 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 4 04:59:03.267502 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 4 04:59:03.267511 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 4 04:59:03.267519 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 4 04:59:03.267528 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 4 04:59:03.267537 kernel: CPU topo: Max. logical packages: 1 Nov 4 04:59:03.267545 kernel: CPU topo: Max. logical dies: 1 Nov 4 04:59:03.267554 kernel: CPU topo: Max. dies per package: 1 Nov 4 04:59:03.267565 kernel: CPU topo: Max. threads per core: 1 Nov 4 04:59:03.267574 kernel: CPU topo: Num. cores per package: 6 Nov 4 04:59:03.267582 kernel: CPU topo: Num. threads per package: 6 Nov 4 04:59:03.267591 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 4 04:59:03.267600 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 4 04:59:03.267609 kernel: Booting paravirtualized kernel on Hyper-V Nov 4 04:59:03.267618 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 4 04:59:03.267629 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 4 04:59:03.267638 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 4 04:59:03.267646 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 4 04:59:03.267655 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 4 04:59:03.267664 kernel: Hyper-V: PV spinlocks enabled Nov 4 04:59:03.267672 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 4 04:59:03.267682 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:59:03.267694 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 4 04:59:03.267703 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 4 04:59:03.267712 kernel: Fallback order for Node 0: 0 Nov 4 04:59:03.267720 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 4 04:59:03.267729 kernel: Policy zone: Normal Nov 4 04:59:03.267738 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 4 04:59:03.267756 kernel: software IO TLB: area num 8. Nov 4 04:59:03.267765 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 4 04:59:03.267775 kernel: Kernel/User page tables isolation: enabled Nov 4 04:59:03.267786 kernel: ftrace: allocating 40092 entries in 157 pages Nov 4 04:59:03.267795 kernel: ftrace: allocated 157 pages with 5 groups Nov 4 04:59:03.267804 kernel: Dynamic Preempt: voluntary Nov 4 04:59:03.267814 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 4 04:59:03.267823 kernel: rcu: RCU event tracing is enabled. Nov 4 04:59:03.267833 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 4 04:59:03.267842 kernel: Trampoline variant of Tasks RCU enabled. Nov 4 04:59:03.267854 kernel: Rude variant of Tasks RCU enabled. Nov 4 04:59:03.267863 kernel: Tracing variant of Tasks RCU enabled. Nov 4 04:59:03.267872 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 4 04:59:03.267881 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 4 04:59:03.267893 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 04:59:03.267902 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 04:59:03.267911 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 04:59:03.267920 kernel: Using NULL legacy PIC Nov 4 04:59:03.267930 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 4 04:59:03.267939 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 4 04:59:03.267948 kernel: Console: colour dummy device 80x25 Nov 4 04:59:03.267960 kernel: printk: legacy console [tty1] enabled Nov 4 04:59:03.267969 kernel: printk: legacy console [ttyS0] enabled Nov 4 04:59:03.267978 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 4 04:59:03.267987 kernel: ACPI: Core revision 20240827 Nov 4 04:59:03.267996 kernel: Failed to register legacy timer interrupt Nov 4 04:59:03.268006 kernel: APIC: Switch to symmetric I/O mode setup Nov 4 04:59:03.268015 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 4 04:59:03.268026 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 4 04:59:03.268036 kernel: Hyper-V: Using IPI hypercalls Nov 4 04:59:03.268045 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 4 04:59:03.268054 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 4 04:59:03.268063 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 4 04:59:03.268073 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 4 04:59:03.268082 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 4 04:59:03.268093 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 4 04:59:03.268102 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 4 04:59:03.268112 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 4 04:59:03.268121 kernel: APIC: read() replaced with hv_apic_read() Nov 4 04:59:03.268130 kernel: APIC: write() replaced with hv_apic_write() Nov 4 04:59:03.268139 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 4 04:59:03.268148 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 4 04:59:03.268173 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Nov 4 04:59:03.268183 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 4 04:59:03.268192 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 4 04:59:03.268201 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 4 04:59:03.268210 kernel: Spectre V2 : Mitigation: Retpolines Nov 4 04:59:03.268218 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 4 04:59:03.268227 kernel: Speculative Store Bypass: Vulnerable Nov 4 04:59:03.268236 kernel: MDS: Mitigation: Clear CPU buffers Nov 4 04:59:03.268245 kernel: TAA: Mitigation: Clear CPU buffers Nov 4 04:59:03.268256 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 4 04:59:03.268264 kernel: active return thunk: its_return_thunk Nov 4 04:59:03.268273 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 4 04:59:03.268282 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 4 04:59:03.268291 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 4 04:59:03.268299 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 4 04:59:03.268308 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 4 04:59:03.268317 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 4 04:59:03.268326 kernel: Freeing SMP alternatives memory: 32K Nov 4 04:59:03.268335 kernel: pid_max: default: 32768 minimum: 301 Nov 4 04:59:03.268346 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 4 04:59:03.268355 kernel: landlock: Up and running. Nov 4 04:59:03.268363 kernel: SELinux: Initializing. Nov 4 04:59:03.268372 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 4 04:59:03.268381 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 4 04:59:03.268390 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 4 04:59:03.268399 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 4 04:59:03.268408 kernel: signal: max sigframe size: 1776 Nov 4 04:59:03.268417 kernel: rcu: Hierarchical SRCU implementation. Nov 4 04:59:03.268429 kernel: rcu: Max phase no-delay instances is 400. Nov 4 04:59:03.268438 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 4 04:59:03.268447 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 4 04:59:03.268456 kernel: smp: Bringing up secondary CPUs ... Nov 4 04:59:03.268466 kernel: smpboot: x86: Booting SMP configuration: Nov 4 04:59:03.268475 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 4 04:59:03.268484 kernel: smp: Brought up 1 node, 6 CPUs Nov 4 04:59:03.268493 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Nov 4 04:59:03.268505 kernel: Memory: 115357524K/117439364K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15360K init, 2684K bss, 2071564K reserved, 0K cma-reserved) Nov 4 04:59:03.268514 kernel: devtmpfs: initialized Nov 4 04:59:03.268523 kernel: x86/mm: Memory block size: 1024MB Nov 4 04:59:03.268532 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 4 04:59:03.268542 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 4 04:59:03.268551 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 4 04:59:03.268562 kernel: pinctrl core: initialized pinctrl subsystem Nov 4 04:59:03.268571 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 4 04:59:03.268581 kernel: audit: initializing netlink subsys (disabled) Nov 4 04:59:03.268590 kernel: audit: type=2000 audit(1762232336.028:1): state=initialized audit_enabled=0 res=1 Nov 4 04:59:03.268599 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 4 04:59:03.268608 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 4 04:59:03.268617 kernel: cpuidle: using governor menu Nov 4 04:59:03.268626 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 4 04:59:03.268638 kernel: dca service started, version 1.12.1 Nov 4 04:59:03.268647 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 4 04:59:03.268656 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 4 04:59:03.268666 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 4 04:59:03.268675 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 4 04:59:03.268684 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 4 04:59:03.268693 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 4 04:59:03.268705 kernel: ACPI: Added _OSI(Module Device) Nov 4 04:59:03.268714 kernel: ACPI: Added _OSI(Processor Device) Nov 4 04:59:03.268723 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 4 04:59:03.268732 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 4 04:59:03.268742 kernel: ACPI: Interpreter enabled Nov 4 04:59:03.268751 kernel: ACPI: PM: (supports S0 S5) Nov 4 04:59:03.268760 kernel: ACPI: Using IOAPIC for interrupt routing Nov 4 04:59:03.268771 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 4 04:59:03.268781 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 4 04:59:03.268790 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 4 04:59:03.268799 kernel: iommu: Default domain type: Translated Nov 4 04:59:03.268808 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 4 04:59:03.268817 kernel: efivars: Registered efivars operations Nov 4 04:59:03.268826 kernel: PCI: Using ACPI for IRQ routing Nov 4 04:59:03.268837 kernel: PCI: System does not support PCI Nov 4 04:59:03.268847 kernel: vgaarb: loaded Nov 4 04:59:03.268856 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 4 04:59:03.268865 kernel: VFS: Disk quotas dquot_6.6.0 Nov 4 04:59:03.268874 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 4 04:59:03.268883 kernel: pnp: PnP ACPI init Nov 4 04:59:03.268892 kernel: pnp: PnP ACPI: found 3 devices Nov 4 04:59:03.268904 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 4 04:59:03.268913 kernel: NET: Registered PF_INET protocol family Nov 4 04:59:03.268922 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 4 04:59:03.268932 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 4 04:59:03.268941 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 4 04:59:03.268950 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 4 04:59:03.268959 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 04:59:03.268971 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 4 04:59:03.268980 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 04:59:03.268989 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 04:59:03.268998 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 4 04:59:03.269007 kernel: NET: Registered PF_XDP protocol family Nov 4 04:59:03.269017 kernel: PCI: CLS 0 bytes, default 64 Nov 4 04:59:03.269026 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 4 04:59:03.269037 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Nov 4 04:59:03.269047 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 4 04:59:03.269056 kernel: Initialise system trusted keyrings Nov 4 04:59:03.269067 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 4 04:59:03.269077 kernel: Key type asymmetric registered Nov 4 04:59:03.269086 kernel: Asymmetric key parser 'x509' registered Nov 4 04:59:03.269096 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 4 04:59:03.269115 kernel: io scheduler mq-deadline registered Nov 4 04:59:03.269132 kernel: io scheduler kyber registered Nov 4 04:59:03.269148 kernel: io scheduler bfq registered Nov 4 04:59:03.269164 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 4 04:59:03.269173 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 4 04:59:03.269186 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 4 04:59:03.269199 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 4 04:59:03.269214 kernel: i8042: PNP: No PS/2 controller found. Nov 4 04:59:03.269397 kernel: rtc_cmos 00:02: registered as rtc0 Nov 4 04:59:03.269534 kernel: rtc_cmos 00:02: setting system clock to 2025-11-04T04:58:58 UTC (1762232338) Nov 4 04:59:03.269663 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 4 04:59:03.269676 kernel: intel_pstate: CPU model not supported Nov 4 04:59:03.269685 kernel: efifb: probing for efifb Nov 4 04:59:03.269699 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 4 04:59:03.269708 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 4 04:59:03.269717 kernel: efifb: scrolling: redraw Nov 4 04:59:03.269726 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 4 04:59:03.269735 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:59:03.269745 kernel: fb0: EFI VGA frame buffer device Nov 4 04:59:03.269754 kernel: pstore: Using crash dump compression: deflate Nov 4 04:59:03.269763 kernel: pstore: Registered efi_pstore as persistent store backend Nov 4 04:59:03.269775 kernel: NET: Registered PF_INET6 protocol family Nov 4 04:59:03.269784 kernel: Segment Routing with IPv6 Nov 4 04:59:03.269793 kernel: In-situ OAM (IOAM) with IPv6 Nov 4 04:59:03.269802 kernel: NET: Registered PF_PACKET protocol family Nov 4 04:59:03.269811 kernel: Key type dns_resolver registered Nov 4 04:59:03.269821 kernel: IPI shorthand broadcast: enabled Nov 4 04:59:03.269830 kernel: sched_clock: Marking stable (2486004500, 108226700)->(2943605300, -349374100) Nov 4 04:59:03.269841 kernel: registered taskstats version 1 Nov 4 04:59:03.269851 kernel: Loading compiled-in X.509 certificates Nov 4 04:59:03.269860 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: dafbe857b8ef9eaad4381fdddb57853ce023547e' Nov 4 04:59:03.269869 kernel: Demotion targets for Node 0: null Nov 4 04:59:03.269878 kernel: Key type .fscrypt registered Nov 4 04:59:03.269893 kernel: Key type fscrypt-provisioning registered Nov 4 04:59:03.269904 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 4 04:59:03.269924 kernel: ima: Allocated hash algorithm: sha1 Nov 4 04:59:03.269936 kernel: ima: No architecture policies found Nov 4 04:59:03.269947 kernel: clk: Disabling unused clocks Nov 4 04:59:03.269956 kernel: Freeing unused kernel image (initmem) memory: 15360K Nov 4 04:59:03.269965 kernel: Write protecting the kernel read-only data: 45056k Nov 4 04:59:03.269975 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Nov 4 04:59:03.269984 kernel: Run /init as init process Nov 4 04:59:03.269995 kernel: with arguments: Nov 4 04:59:03.270004 kernel: /init Nov 4 04:59:03.270013 kernel: with environment: Nov 4 04:59:03.270022 kernel: HOME=/ Nov 4 04:59:03.270031 kernel: TERM=linux Nov 4 04:59:03.270040 kernel: hv_vmbus: Vmbus version:4.0 Nov 4 04:59:03.270049 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 4 04:59:03.270058 kernel: hv_vmbus: registering driver hid_hyperv Nov 4 04:59:03.270070 kernel: SCSI subsystem initialized Nov 4 04:59:03.270098 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 4 04:59:03.270306 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 4 04:59:03.270324 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 4 04:59:03.270334 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 Nov 4 04:59:03.270343 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 4 04:59:03.270357 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 4 04:59:03.270366 kernel: PTP clock support registered Nov 4 04:59:03.270376 kernel: hv_utils: Registering HyperV Utility Driver Nov 4 04:59:03.270385 kernel: hv_vmbus: registering driver hv_utils Nov 4 04:59:03.270394 kernel: hv_utils: Heartbeat IC version 3.0 Nov 4 04:59:03.270403 kernel: hv_utils: Shutdown IC version 3.2 Nov 4 04:59:03.270412 kernel: hv_utils: TimeSync IC version 4.0 Nov 4 04:59:03.270424 kernel: hv_vmbus: registering driver hv_storvsc Nov 4 04:59:03.270600 kernel: scsi host0: storvsc_host_t Nov 4 04:59:03.270761 kernel: scsi host1: storvsc_host_t Nov 4 04:59:03.270930 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 04:59:03.271095 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 04:59:03.271284 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 4 04:59:03.271441 kernel: sd 1:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Nov 4 04:59:03.271594 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Nov 4 04:59:03.271749 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 4 04:59:03.271904 kernel: sd 1:0:0:0: [sda] Write Protect is off Nov 4 04:59:03.272057 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Nov 4 04:59:03.272277 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 4 04:59:03.272440 kernel: sd 1:0:0:1: [sdb] Write Protect is off Nov 4 04:59:03.272594 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 04:59:03.272748 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 4 04:59:03.272901 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 04:59:03.273065 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#387 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:03.273221 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:03.273366 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#394 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:03.273506 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#588 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:03.273519 kernel: sdb: sdb1 Nov 4 04:59:03.273670 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Nov 4 04:59:03.273688 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 4 04:59:03.273840 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Nov 4 04:59:03.273853 kernel: hv_vmbus: registering driver hv_pci Nov 4 04:59:03.274001 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 4 04:59:03.274142 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 4 04:59:03.274317 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 4 04:59:03.274468 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 4 04:59:03.274613 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 04:59:03.274798 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 4 04:59:03.274958 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 4 04:59:03.275111 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 4 04:59:03.275282 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 4 04:59:03.275435 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 4 04:59:03.275449 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 4 04:59:03.275459 kernel: device-mapper: uevent: version 1.0.3 Nov 4 04:59:03.275469 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 4 04:59:03.275610 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 04:59:03.275767 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 4 04:59:03.275920 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 4 04:59:03.276072 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 4 04:59:03.276086 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 4 04:59:03.276251 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Nov 4 04:59:03.276266 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 4 04:59:03.276426 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Nov 4 04:59:03.276440 kernel: raid6: avx2x4 gen() 31034 MB/s Nov 4 04:59:03.276450 kernel: raid6: avx2x2 gen() 30142 MB/s Nov 4 04:59:03.276459 kernel: raid6: avx2x1 gen() 26727 MB/s Nov 4 04:59:03.276469 kernel: raid6: using algorithm avx2x4 gen() 31034 MB/s Nov 4 04:59:03.276479 kernel: raid6: .... xor() 10207 MB/s, rmw enabled Nov 4 04:59:03.276488 kernel: raid6: using avx2x2 recovery algorithm Nov 4 04:59:03.276502 kernel: xor: automatically using best checksumming function avx Nov 4 04:59:03.276514 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 4 04:59:03.276524 kernel: BTRFS: device fsid 6f0a5369-79b6-4a87-b9a6-85ec05be306c devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (493) Nov 4 04:59:03.276534 kernel: BTRFS info (device dm-0): first mount of filesystem 6f0a5369-79b6-4a87-b9a6-85ec05be306c Nov 4 04:59:03.276544 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:03.276553 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 4 04:59:03.276563 kernel: BTRFS info (device dm-0): enabling free space tree Nov 4 04:59:03.276575 kernel: loop: module loaded Nov 4 04:59:03.276585 kernel: loop0: detected capacity change from 0 to 100136 Nov 4 04:59:03.276595 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 4 04:59:03.276605 systemd[1]: Successfully made /usr/ read-only. Nov 4 04:59:03.276618 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) Nov 4 04:59:03.276629 systemd[1]: Detected virtualization microsoft. Nov 4 04:59:03.276641 systemd[1]: Detected architecture x86-64. Nov 4 04:59:03.276651 systemd[1]: Running in initrd. Nov 4 04:59:03.276661 systemd[1]: No hostname configured, using default hostname. Nov 4 04:59:03.276671 systemd[1]: Hostname set to . Nov 4 04:59:03.276681 systemd[1]: Initializing machine ID from random generator. Nov 4 04:59:03.276691 systemd[1]: Queued start job for default target initrd.target. Nov 4 04:59:03.276701 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:59:03.276713 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:59:03.276723 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:59:03.276734 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 4 04:59:03.276744 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:59:03.276755 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 4 04:59:03.276767 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 4 04:59:03.276778 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:59:03.276790 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:59:03.276801 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:59:03.276813 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:59:03.276824 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:59:03.276834 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:59:03.276844 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:59:03.276854 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:59:03.276865 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:59:03.276875 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 4 04:59:03.276887 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 4 04:59:03.276898 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:59:03.276908 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:59:03.276918 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:59:03.276928 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:59:03.276938 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 4 04:59:03.276949 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 4 04:59:03.276961 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:59:03.276971 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 4 04:59:03.276982 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). Nov 4 04:59:03.276992 systemd[1]: Starting systemd-fsck-usr.service... Nov 4 04:59:03.277002 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:59:03.277012 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:59:03.277044 systemd-journald[626]: Collecting audit messages is disabled. Nov 4 04:59:03.277071 systemd-journald[626]: Journal started Nov 4 04:59:03.277095 systemd-journald[626]: Runtime Journal (/run/log/journal/a984e04abf3946b8917c1466602b00f9) is 8M, max 2.2G, 2.1G free. Nov 4 04:59:03.285180 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:03.295350 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:59:03.299278 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 4 04:59:03.300884 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:59:03.308967 systemd[1]: Finished systemd-fsck-usr.service. Nov 4 04:59:03.320053 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 04:59:03.334007 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:59:03.483582 systemd-tmpfiles[639]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 4 04:59:03.489918 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 04:59:03.492672 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:59:03.510455 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:59:03.531168 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 4 04:59:03.541302 systemd-modules-load[631]: Inserted module 'br_netfilter' Nov 4 04:59:03.542941 kernel: Bridge firewalling registered Nov 4 04:59:03.542360 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:59:03.543791 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:59:03.573021 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:59:03.665255 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:59:03.671015 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:59:03.699351 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:03.701409 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 4 04:59:03.734212 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:59:03.745620 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 4 04:59:03.825264 dracut-cmdline[671]: 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:59:03.837357 systemd-resolved[657]: Positive Trust Anchors: Nov 4 04:59:03.837366 systemd-resolved[657]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:59:03.837370 systemd-resolved[657]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:59:03.837401 systemd-resolved[657]: 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 Nov 4 04:59:03.853588 systemd-resolved[657]: Defaulting to hostname 'linux'. Nov 4 04:59:03.854757 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:59:03.860477 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:59:04.102188 kernel: Loading iSCSI transport class v2.0-870. Nov 4 04:59:04.255184 kernel: iscsi: registered transport (tcp) Nov 4 04:59:04.329443 kernel: iscsi: registered transport (qla4xxx) Nov 4 04:59:04.329503 kernel: QLogic iSCSI HBA Driver Nov 4 04:59:04.414383 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:59:04.446874 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:59:04.449698 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:59:04.508695 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 4 04:59:04.511725 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 4 04:59:04.518802 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 4 04:59:04.563977 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:59:04.574434 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:59:04.614435 systemd-udevd[912]: Using default interface naming scheme 'v257'. Nov 4 04:59:04.630418 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:59:04.637299 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 4 04:59:04.666469 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:59:04.669663 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:59:04.696346 dracut-pre-trigger[998]: rd.md=0: removing MD RAID activation Nov 4 04:59:04.724480 systemd-networkd[1011]: lo: Link UP Nov 4 04:59:04.724489 systemd-networkd[1011]: lo: Gained carrier Nov 4 04:59:04.725190 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:59:04.727314 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:59:04.736476 systemd[1]: Reached target network.target - Network. Nov 4 04:59:04.745728 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:59:04.838037 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:59:04.903307 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 4 04:59:05.024183 kernel: cryptd: max_cpu_qlen set to 1000 Nov 4 04:59:05.026759 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:05.026822 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:05.036434 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:05.077531 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:05.106303 kernel: hv_vmbus: registering driver hv_netvsc Nov 4 04:59:05.106365 kernel: AES CTR mode by8 optimization enabled Nov 4 04:59:05.113522 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:05.401721 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 4 04:59:05.412830 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 4 04:59:05.526362 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 04:59:05.573810 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 4 04:59:05.595014 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 4 04:59:06.625585 disk-uuid[1191]: Warning: The kernel is still using the old partition table. Nov 4 04:59:06.625585 disk-uuid[1191]: The new table will be used at the next reboot or after you Nov 4 04:59:06.625585 disk-uuid[1191]: run partprobe(8) or kpartx(8) Nov 4 04:59:06.625585 disk-uuid[1191]: The operation has completed successfully. Nov 4 04:59:07.836134 systemd-networkd[1011]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:07.836146 systemd-networkd[1011]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:59:07.837226 systemd-networkd[1011]: eth0: Link UP Nov 4 04:59:07.837399 systemd-networkd[1011]: eth0: Gained carrier Nov 4 04:59:07.837411 systemd-networkd[1011]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:07.844999 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 4 04:59:07.845184 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 4 04:59:07.852486 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 4 04:59:07.878318 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 4 04:59:07.879196 systemd-networkd[1011]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:59:07.888443 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:59:07.896679 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:59:07.902231 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:59:07.930825 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 4 04:59:07.956169 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1344) Nov 4 04:59:07.962306 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:59:07.974713 kernel: BTRFS info (device sda6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:07.974736 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:07.988660 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:59:07.988692 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:59:08.001185 kernel: BTRFS info (device sda6): last unmount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:08.001807 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 4 04:59:08.003780 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 4 04:59:09.633421 systemd-networkd[1011]: eth0: Gained IPv6LL Nov 4 04:59:09.764595 ignition[1370]: Ignition 2.22.0 Nov 4 04:59:09.764612 ignition[1370]: Stage: fetch-offline Nov 4 04:59:09.764904 ignition[1370]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:09.769694 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:59:09.764921 ignition[1370]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:09.772468 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 4 04:59:09.765032 ignition[1370]: parsed url from cmdline: "" Nov 4 04:59:09.765037 ignition[1370]: no config URL provided Nov 4 04:59:09.765044 ignition[1370]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:59:09.765057 ignition[1370]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:59:09.765064 ignition[1370]: failed to fetch config: resource requires networking Nov 4 04:59:09.765251 ignition[1370]: Ignition finished successfully Nov 4 04:59:09.820354 ignition[1381]: Ignition 2.22.0 Nov 4 04:59:09.820368 ignition[1381]: Stage: fetch Nov 4 04:59:09.820668 ignition[1381]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:09.820681 ignition[1381]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:09.820835 ignition[1381]: parsed url from cmdline: "" Nov 4 04:59:09.820839 ignition[1381]: no config URL provided Nov 4 04:59:09.820846 ignition[1381]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:59:09.820856 ignition[1381]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:59:09.820874 ignition[1381]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 4 04:59:09.933672 ignition[1381]: GET result: OK Nov 4 04:59:09.933736 ignition[1381]: config has been read from IMDS userdata Nov 4 04:59:09.933757 ignition[1381]: parsing config with SHA512: dfbc80f80c209c862ce806865455be14d03e609adb5ad6ac63bf4e441660d77a96f8885cc543258cb8a5f86bcf6d917a76ffe1a72ee60239e776fa200f46e96c Nov 4 04:59:09.939119 unknown[1381]: fetched base config from "system" Nov 4 04:59:09.939424 ignition[1381]: fetch: fetch complete Nov 4 04:59:09.939128 unknown[1381]: fetched base config from "system" Nov 4 04:59:09.939430 ignition[1381]: fetch: fetch passed Nov 4 04:59:09.939137 unknown[1381]: fetched user config from "azure" Nov 4 04:59:09.939870 ignition[1381]: Ignition finished successfully Nov 4 04:59:09.945019 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 4 04:59:09.947256 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 4 04:59:09.996387 ignition[1390]: Ignition 2.22.0 Nov 4 04:59:09.996400 ignition[1390]: Stage: kargs Nov 4 04:59:09.996662 ignition[1390]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:09.996673 ignition[1390]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:10.005015 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 4 04:59:09.997344 ignition[1390]: kargs: kargs passed Nov 4 04:59:10.013566 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 4 04:59:09.997389 ignition[1390]: Ignition finished successfully Nov 4 04:59:10.063680 ignition[1400]: Ignition 2.22.0 Nov 4 04:59:10.063694 ignition[1400]: Stage: disks Nov 4 04:59:10.064016 ignition[1400]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:10.064028 ignition[1400]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:10.069894 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 4 04:59:10.064712 ignition[1400]: disks: disks passed Nov 4 04:59:10.081959 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 4 04:59:10.064767 ignition[1400]: Ignition finished successfully Nov 4 04:59:10.089762 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 4 04:59:10.097168 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:59:10.105569 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:59:10.112904 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:59:10.122555 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 4 04:59:10.303644 systemd-fsck[1411]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Nov 4 04:59:10.308805 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 4 04:59:10.311603 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 4 04:59:10.723181 kernel: EXT4-fs (sda9): mounted filesystem c35327fb-3cdd-496e-85aa-9e1b4133507f r/w with ordered data mode. Quota mode: none. Nov 4 04:59:10.723989 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 4 04:59:10.726021 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 4 04:59:10.759721 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:59:10.762340 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 4 04:59:10.785769 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 4 04:59:10.787242 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 4 04:59:10.811878 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1425) Nov 4 04:59:10.811902 kernel: BTRFS info (device sda6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:10.811915 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:10.787277 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:59:10.833063 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:59:10.833089 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:59:10.808597 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 4 04:59:10.832700 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:59:10.841065 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 4 04:59:11.569339 coreos-metadata[1427]: Nov 04 04:59:11.569 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:59:11.575301 coreos-metadata[1427]: Nov 04 04:59:11.571 INFO Fetch successful Nov 4 04:59:11.575301 coreos-metadata[1427]: Nov 04 04:59:11.571 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:59:11.590815 coreos-metadata[1427]: Nov 04 04:59:11.583 INFO Fetch successful Nov 4 04:59:11.599813 coreos-metadata[1427]: Nov 04 04:59:11.599 INFO wrote hostname ci-4508.0.0-n-2274277710 to /sysroot/etc/hostname Nov 4 04:59:11.606906 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:59:12.018306 initrd-setup-root[1455]: cut: /sysroot/etc/passwd: No such file or directory Nov 4 04:59:12.062748 initrd-setup-root[1462]: cut: /sysroot/etc/group: No such file or directory Nov 4 04:59:12.073135 initrd-setup-root[1469]: cut: /sysroot/etc/shadow: No such file or directory Nov 4 04:59:12.080582 initrd-setup-root[1476]: cut: /sysroot/etc/gshadow: No such file or directory Nov 4 04:59:13.263440 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 4 04:59:13.266831 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 4 04:59:13.274769 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 4 04:59:13.316656 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 4 04:59:13.325880 kernel: BTRFS info (device sda6): last unmount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:13.338260 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 4 04:59:13.356852 ignition[1545]: INFO : Ignition 2.22.0 Nov 4 04:59:13.356852 ignition[1545]: INFO : Stage: mount Nov 4 04:59:13.362798 ignition[1545]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:13.362798 ignition[1545]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:13.362798 ignition[1545]: INFO : mount: mount passed Nov 4 04:59:13.362798 ignition[1545]: INFO : Ignition finished successfully Nov 4 04:59:13.364144 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 4 04:59:13.371570 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 4 04:59:13.402752 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:59:13.438178 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1559) Nov 4 04:59:13.438215 kernel: BTRFS info (device sda6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:13.446935 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:13.457255 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:59:13.457279 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:59:13.459390 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:59:13.504710 ignition[1576]: INFO : Ignition 2.22.0 Nov 4 04:59:13.504710 ignition[1576]: INFO : Stage: files Nov 4 04:59:13.510426 ignition[1576]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:13.510426 ignition[1576]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:13.510426 ignition[1576]: DEBUG : files: compiled without relabeling support, skipping Nov 4 04:59:13.510426 ignition[1576]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 4 04:59:13.510426 ignition[1576]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 4 04:59:13.548252 ignition[1576]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 4 04:59:13.553619 ignition[1576]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 4 04:59:13.553619 ignition[1576]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 4 04:59:13.548906 unknown[1576]: wrote ssh authorized keys file for user: core Nov 4 04:59:13.579614 ignition[1576]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:59:13.587100 ignition[1576]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:59:13.587100 ignition[1576]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:59:13.587100 ignition[1576]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:59:13.587100 ignition[1576]: INFO : files: files passed Nov 4 04:59:13.587100 ignition[1576]: INFO : Ignition finished successfully Nov 4 04:59:13.608975 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 4 04:59:13.616259 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 4 04:59:13.623681 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 4 04:59:13.646012 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 4 04:59:13.646123 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 4 04:59:13.736643 initrd-setup-root-after-ignition[1611]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:13.742948 initrd-setup-root-after-ignition[1611]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:13.749269 initrd-setup-root-after-ignition[1615]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:13.750259 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:59:13.760414 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 4 04:59:13.763194 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 4 04:59:13.817479 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 4 04:59:13.817591 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 4 04:59:13.826332 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 4 04:59:13.828333 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 4 04:59:13.847046 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 4 04:59:13.847933 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 4 04:59:13.894218 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:59:13.903798 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 4 04:59:13.934302 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:59:13.934549 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:59:13.943849 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:59:13.953280 systemd[1]: Stopped target timers.target - Timer Units. Nov 4 04:59:13.955478 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 4 04:59:13.955603 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:59:13.970168 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 4 04:59:13.978167 systemd[1]: Stopped target basic.target - Basic System. Nov 4 04:59:13.986570 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 4 04:59:13.998798 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:59:14.007703 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 4 04:59:14.016929 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:59:14.026067 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 4 04:59:14.034654 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:59:14.043512 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 4 04:59:14.045861 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 4 04:59:14.057829 systemd[1]: Stopped target swap.target - Swaps. Nov 4 04:59:14.065592 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 4 04:59:14.065734 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:59:14.078286 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:59:14.086526 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:59:14.095774 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 4 04:59:14.095886 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:59:14.105369 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 4 04:59:14.105497 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 4 04:59:14.118658 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 4 04:59:14.118795 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:59:14.127556 systemd[1]: ignition-files.service: Deactivated successfully. Nov 4 04:59:14.127675 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 4 04:59:14.135372 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 4 04:59:14.135505 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:59:14.145728 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 4 04:59:14.153021 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 4 04:59:14.153150 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:59:14.191241 ignition[1635]: INFO : Ignition 2.22.0 Nov 4 04:59:14.191241 ignition[1635]: INFO : Stage: umount Nov 4 04:59:14.191241 ignition[1635]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:14.191241 ignition[1635]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:14.174760 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 4 04:59:14.224487 ignition[1635]: INFO : umount: umount passed Nov 4 04:59:14.224487 ignition[1635]: INFO : Ignition finished successfully Nov 4 04:59:14.181112 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 4 04:59:14.181266 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:59:14.189874 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 4 04:59:14.190001 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:59:14.198952 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 4 04:59:14.199072 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:59:14.210427 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 4 04:59:14.210526 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 4 04:59:14.221950 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 4 04:59:14.222051 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 4 04:59:14.226410 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 4 04:59:14.226500 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 4 04:59:14.234346 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 4 04:59:14.234393 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 4 04:59:14.248500 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 4 04:59:14.248553 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 4 04:59:14.250678 systemd[1]: Stopped target network.target - Network. Nov 4 04:59:14.263628 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 4 04:59:14.263695 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:59:14.271580 systemd[1]: Stopped target paths.target - Path Units. Nov 4 04:59:14.280223 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 4 04:59:14.285221 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:59:14.288440 systemd[1]: Stopped target slices.target - Slice Units. Nov 4 04:59:14.296972 systemd[1]: Stopped target sockets.target - Socket Units. Nov 4 04:59:14.304923 systemd[1]: iscsid.socket: Deactivated successfully. Nov 4 04:59:14.304980 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:59:14.313949 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 4 04:59:14.313997 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:59:14.322504 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 4 04:59:14.322556 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 4 04:59:14.330453 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 4 04:59:14.330504 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 4 04:59:14.338186 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 4 04:59:14.345530 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 4 04:59:14.348670 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 4 04:59:14.349354 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 4 04:59:14.349457 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 4 04:59:14.355666 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 4 04:59:14.355766 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 4 04:59:14.365638 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 4 04:59:14.365751 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 4 04:59:14.375121 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 4 04:59:14.375240 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 4 04:59:14.387401 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 4 04:59:14.390593 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 4 04:59:14.390642 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:59:14.405406 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 4 04:59:14.407320 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 4 04:59:14.407379 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:59:14.423481 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 4 04:59:14.423538 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:59:14.434229 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 4 04:59:14.434291 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 4 04:59:14.435756 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:59:14.490591 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 4 04:59:14.490749 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:59:14.492318 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 4 04:59:14.492367 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 4 04:59:14.504022 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 4 04:59:14.504058 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:59:14.511929 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 4 04:59:14.511985 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:59:14.530442 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 4 04:59:14.530496 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 4 04:59:14.542107 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 4 04:59:14.542181 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:59:14.559949 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 4 04:59:14.568314 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 4 04:59:14.568373 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:59:14.576761 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 4 04:59:14.576815 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:59:14.585519 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:14.585567 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:14.595060 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 4 04:59:14.595165 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 4 04:59:14.622932 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 4 04:59:14.623084 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 4 04:59:14.626541 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 4 04:59:14.635069 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 4 04:59:14.667273 systemd[1]: Switching root. Nov 4 04:59:14.802274 systemd-journald[626]: Journal stopped Nov 4 04:59:20.086288 systemd-journald[626]: Received SIGTERM from PID 1 (systemd). Nov 4 04:59:20.086324 kernel: SELinux: policy capability network_peer_controls=1 Nov 4 04:59:20.086339 kernel: SELinux: policy capability open_perms=1 Nov 4 04:59:20.086350 kernel: SELinux: policy capability extended_socket_class=1 Nov 4 04:59:20.086361 kernel: SELinux: policy capability always_check_network=0 Nov 4 04:59:20.086372 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 4 04:59:20.086387 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 4 04:59:20.086399 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 4 04:59:20.086410 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 4 04:59:20.086421 kernel: SELinux: policy capability userspace_initial_context=0 Nov 4 04:59:20.086433 kernel: audit: type=1403 audit(1762232355.882:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 4 04:59:20.086445 systemd[1]: Successfully loaded SELinux policy in 248.761ms. Nov 4 04:59:20.086462 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.842ms. Nov 4 04:59:20.086476 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) Nov 4 04:59:20.086490 systemd[1]: Detected virtualization microsoft. Nov 4 04:59:20.086502 systemd[1]: Detected architecture x86-64. Nov 4 04:59:20.086518 systemd[1]: Detected first boot. Nov 4 04:59:20.086532 systemd[1]: Hostname set to . Nov 4 04:59:20.086544 systemd[1]: Initializing machine ID from random generator. Nov 4 04:59:20.086557 zram_generator::config[1682]: No configuration found. Nov 4 04:59:20.086571 kernel: Guest personality initialized and is inactive Nov 4 04:59:20.086585 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 4 04:59:20.086597 kernel: Initialized host personality Nov 4 04:59:20.086609 kernel: NET: Registered PF_VSOCK protocol family Nov 4 04:59:20.086621 systemd[1]: Populated /etc with preset unit settings. Nov 4 04:59:20.086634 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 4 04:59:20.086646 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 4 04:59:20.086660 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 4 04:59:20.086676 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 4 04:59:20.086689 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 4 04:59:20.086703 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 4 04:59:20.086715 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 4 04:59:20.086728 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 4 04:59:20.086744 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 4 04:59:20.086758 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 4 04:59:20.086771 systemd[1]: Created slice user.slice - User and Session Slice. Nov 4 04:59:20.086784 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:59:20.086797 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:59:20.086809 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 4 04:59:20.086823 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 4 04:59:20.086841 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 4 04:59:20.086858 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:59:20.086871 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 4 04:59:20.086885 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:59:20.086898 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:59:20.086911 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 4 04:59:20.086928 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 4 04:59:20.086941 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 4 04:59:20.086954 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 4 04:59:20.086968 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:59:20.086981 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:59:20.086994 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:59:20.087007 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:59:20.087024 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 4 04:59:20.087038 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 4 04:59:20.087051 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 4 04:59:20.087065 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:59:20.087081 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:59:20.087095 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:59:20.087108 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 4 04:59:20.087122 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 4 04:59:20.087136 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 4 04:59:20.087149 systemd[1]: Mounting media.mount - External Media Directory... Nov 4 04:59:20.087186 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:20.087201 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 4 04:59:20.087215 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 4 04:59:20.087228 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 4 04:59:20.087242 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). Nov 4 04:59:20.087255 systemd[1]: Reached target machines.target - Containers. Nov 4 04:59:20.087269 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 4 04:59:20.087286 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:59:20.087299 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:59:20.087313 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 4 04:59:20.087326 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:59:20.087342 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:59:20.087356 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:59:20.087372 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 4 04:59:20.087386 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:59:20.087400 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 4 04:59:20.087413 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 4 04:59:20.087427 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 4 04:59:20.087440 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 4 04:59:20.087454 systemd[1]: Stopped systemd-fsck-usr.service. Nov 4 04:59:20.087471 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). Nov 4 04:59:20.087485 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:59:20.087498 kernel: fuse: init (API version 7.41) Nov 4 04:59:20.087511 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:59:20.087524 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:59:20.087560 systemd-journald[1768]: Collecting audit messages is disabled. Nov 4 04:59:20.087592 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 4 04:59:20.087606 systemd-journald[1768]: Journal started Nov 4 04:59:20.087629 systemd-journald[1768]: Runtime Journal (/run/log/journal/84daac5042914acb8e60eb5094030431) is 8M, max 2.2G, 2.1G free. Nov 4 04:59:19.315204 systemd[1]: Queued start job for default target multi-user.target. Nov 4 04:59:19.328455 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 4 04:59:19.329056 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 4 04:59:20.103482 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 4 04:59:20.112141 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:59:20.122174 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:20.128311 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:59:20.133022 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 4 04:59:20.137483 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 4 04:59:20.142060 systemd[1]: Mounted media.mount - External Media Directory. Nov 4 04:59:20.146018 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 4 04:59:20.150469 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 4 04:59:20.154875 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 4 04:59:20.159149 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 4 04:59:20.164329 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:59:20.169776 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 4 04:59:20.170108 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 4 04:59:20.175291 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:59:20.175488 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:59:20.180351 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:59:20.180532 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:59:20.185707 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 4 04:59:20.185968 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 4 04:59:20.190576 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:59:20.190766 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:59:20.195460 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:59:20.200595 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:59:20.206934 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 4 04:59:20.219964 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:59:20.225071 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 4 04:59:20.231215 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 4 04:59:20.245465 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 4 04:59:20.249896 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 4 04:59:20.249933 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:59:20.254907 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 4 04:59:20.260129 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:59:20.274808 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 4 04:59:20.296541 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 4 04:59:20.301259 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:59:20.302292 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 4 04:59:20.306644 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:59:20.307674 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:59:20.312855 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 4 04:59:20.320264 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 4 04:59:20.327268 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 4 04:59:20.333523 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:59:20.336597 systemd-journald[1768]: Time spent on flushing to /var/log/journal/84daac5042914acb8e60eb5094030431 is 14.599ms for 923 entries. Nov 4 04:59:20.336597 systemd-journald[1768]: System Journal (/var/log/journal/84daac5042914acb8e60eb5094030431) is 8M, max 2.6G, 2.6G free. Nov 4 04:59:20.406406 systemd-journald[1768]: Received client request to flush runtime journal. Nov 4 04:59:20.406467 kernel: ACPI: bus type drm_connector registered Nov 4 04:59:20.406496 kernel: loop1: detected capacity change from 0 to 27736 Nov 4 04:59:20.342360 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 4 04:59:20.348272 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 4 04:59:20.353638 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 4 04:59:20.361079 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 4 04:59:20.370144 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 4 04:59:20.375037 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:59:20.375260 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:59:20.407511 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 4 04:59:20.446472 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 4 04:59:20.447203 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 4 04:59:20.486603 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:59:20.560166 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 4 04:59:20.568025 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:59:20.573081 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:59:20.693671 systemd-tmpfiles[1840]: ACLs are not supported, ignoring. Nov 4 04:59:20.693694 systemd-tmpfiles[1840]: ACLs are not supported, ignoring. Nov 4 04:59:20.698266 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 4 04:59:20.702616 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:59:20.759699 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 4 04:59:20.871814 systemd-resolved[1839]: Positive Trust Anchors: Nov 4 04:59:20.871834 systemd-resolved[1839]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:59:20.871839 systemd-resolved[1839]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:59:20.871870 systemd-resolved[1839]: 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 Nov 4 04:59:21.089230 systemd-resolved[1839]: Using system hostname 'ci-4508.0.0-n-2274277710'. Nov 4 04:59:21.091016 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:59:21.095589 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:59:21.196182 kernel: loop2: detected capacity change from 0 to 119080 Nov 4 04:59:21.390044 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 4 04:59:21.396592 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:59:21.450818 systemd-udevd[1852]: Using default interface naming scheme 'v257'. Nov 4 04:59:21.585183 kernel: loop3: detected capacity change from 0 to 111544 Nov 4 04:59:21.692859 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:59:21.700263 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:59:21.735979 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 4 04:59:21.808751 systemd-networkd[1867]: lo: Link UP Nov 4 04:59:21.808763 systemd-networkd[1867]: lo: Gained carrier Nov 4 04:59:21.810499 systemd-networkd[1867]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:21.810504 systemd-networkd[1867]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:59:21.811373 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:59:21.811455 systemd-networkd[1867]: eth0: Link UP Nov 4 04:59:21.811459 systemd-networkd[1867]: eth0: Gained carrier Nov 4 04:59:21.811474 systemd-networkd[1867]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:21.817632 systemd[1]: Reached target network.target - Network. Nov 4 04:59:21.822694 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 4 04:59:21.828791 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 4 04:59:21.850237 systemd-networkd[1867]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:59:21.855174 kernel: hv_vmbus: registering driver hyperv_fb Nov 4 04:59:21.855257 kernel: hv_vmbus: registering driver hv_balloon Nov 4 04:59:21.858175 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 4 04:59:21.858222 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 4 04:59:21.863182 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 4 04:59:21.869176 kernel: Console: switching to colour dummy device 80x25 Nov 4 04:59:21.869233 kernel: mousedev: PS/2 mouse device common for all mice Nov 4 04:59:21.876183 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:59:21.913037 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:21.928201 kernel: loop4: detected capacity change from 0 to 27736 Nov 4 04:59:21.928290 kernel: EDAC MC: Ver: 3.0.0 Nov 4 04:59:21.927958 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:21.928195 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:21.935560 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:21.954178 kernel: loop5: detected capacity change from 0 to 119080 Nov 4 04:59:22.006520 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 4 04:59:22.020184 kernel: loop6: detected capacity change from 0 to 111544 Nov 4 04:59:22.038340 (sd-merge)[1923]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 4 04:59:22.041913 (sd-merge)[1923]: Merged extensions into '/usr'. Nov 4 04:59:22.045574 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 4 04:59:22.048624 systemd[1]: Starting ensure-sysext.service... Nov 4 04:59:22.050168 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:59:22.089125 systemd[1]: Reload requested from client PID 1930 ('systemctl') (unit ensure-sysext.service)... Nov 4 04:59:22.089148 systemd[1]: Reloading... Nov 4 04:59:22.136383 systemd-tmpfiles[1931]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 4 04:59:22.136431 systemd-tmpfiles[1931]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 4 04:59:22.136857 systemd-tmpfiles[1931]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 4 04:59:22.137207 systemd-tmpfiles[1931]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 4 04:59:22.138369 systemd-tmpfiles[1931]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 4 04:59:22.138723 systemd-tmpfiles[1931]: ACLs are not supported, ignoring. Nov 4 04:59:22.138858 systemd-tmpfiles[1931]: ACLs are not supported, ignoring. Nov 4 04:59:22.151188 zram_generator::config[2026]: No configuration found. Nov 4 04:59:22.178569 systemd-tmpfiles[1931]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:59:22.178587 systemd-tmpfiles[1931]: Skipping /boot Nov 4 04:59:22.188932 systemd-tmpfiles[1931]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:59:22.188948 systemd-tmpfiles[1931]: Skipping /boot Nov 4 04:59:22.425577 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 04:59:22.427418 systemd[1]: Reloading finished in 337 ms. Nov 4 04:59:22.470131 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:59:22.542224 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:22.557889 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:22.559241 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 04:59:22.564178 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 4 04:59:22.569025 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:59:22.578710 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 4 04:59:22.583828 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:59:22.589025 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:59:22.594443 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:59:22.600083 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:59:22.604174 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:59:22.605561 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 4 04:59:22.610344 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). Nov 4 04:59:22.611807 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 4 04:59:22.616448 systemd[1]: Reached target time-set.target - System Time Set. Nov 4 04:59:22.621731 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 4 04:59:22.626555 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:22.631033 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:59:22.631284 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:59:22.636450 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:59:22.636667 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:59:22.641437 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:59:22.641659 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:59:22.647584 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:59:22.647799 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:59:22.654955 systemd[1]: Finished ensure-sysext.service. Nov 4 04:59:22.661781 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:59:22.661935 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:59:22.663542 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 4 04:59:22.674028 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 4 04:59:22.699689 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 4 04:59:23.028466 augenrules[2129]: No rules Nov 4 04:59:23.029664 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 04:59:23.029919 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 04:59:23.804990 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 4 04:59:23.810433 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). Nov 4 04:59:23.841271 systemd-networkd[1867]: eth0: Gained IPv6LL Nov 4 04:59:23.843413 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 4 04:59:23.848762 systemd[1]: Reached target network-online.target - Network is Online. Nov 4 04:59:28.755265 ldconfig[2093]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 04:59:28.768473 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 4 04:59:28.775068 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 4 04:59:28.802626 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 4 04:59:28.807206 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:59:28.811536 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 4 04:59:28.816510 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 4 04:59:28.821476 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 4 04:59:28.826303 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 4 04:59:28.830544 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 4 04:59:28.835427 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 4 04:59:28.840486 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 4 04:59:28.840563 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:59:28.844191 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:59:28.879516 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 4 04:59:28.885358 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 4 04:59:28.891345 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 4 04:59:28.914546 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 4 04:59:28.919594 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 4 04:59:28.925978 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 4 04:59:28.930407 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 4 04:59:28.935859 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 4 04:59:28.940988 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:59:28.944800 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:59:28.948501 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:59:28.948529 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:59:28.967637 systemd[1]: Starting chronyd.service - NTP client/server... Nov 4 04:59:28.972229 systemd[1]: Starting containerd.service - containerd container runtime... Nov 4 04:59:28.994201 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 4 04:59:28.999600 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 4 04:59:29.004484 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 4 04:59:29.007029 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 4 04:59:29.014305 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 4 04:59:29.018233 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). Nov 4 04:59:29.018331 jq[2150]: false Nov 4 04:59:29.019823 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 4 04:59:29.023922 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). Nov 4 04:59:29.025138 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 4 04:59:29.029268 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 4 04:59:29.031320 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 4 04:59:29.036062 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 4 04:59:29.041105 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 4 04:59:29.041143 chronyd[2142]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 4 04:59:29.041554 extend-filesystems[2151]: Found /dev/sda6 Nov 4 04:59:29.043663 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 4 04:59:29.054231 KVP[2156]: KVP starting; pid is:2156 Nov 4 04:59:29.054637 chronyd[2142]: Timezone right/UTC failed leap second check, ignoring Nov 4 04:59:29.054826 chronyd[2142]: Loaded seccomp filter (level 2) Nov 4 04:59:29.055058 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 4 04:59:29.059060 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 4 04:59:29.059499 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 4 04:59:29.060099 systemd[1]: Starting update-engine.service - Update Engine... Nov 4 04:59:29.060261 KVP[2156]: KVP LIC Version: 3.1 Nov 4 04:59:29.064134 kernel: hv_utils: KVP IC version 4.0 Nov 4 04:59:29.066790 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 4 04:59:29.073420 systemd[1]: Started chronyd.service - NTP client/server. Nov 4 04:59:29.078447 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 4 04:59:29.081003 jq[2172]: true Nov 4 04:59:29.083991 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 4 04:59:29.084209 extend-filesystems[2151]: Found /dev/sda9 Nov 4 04:59:29.084237 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 4 04:59:29.084524 systemd[1]: motdgen.service: Deactivated successfully. Nov 4 04:59:29.084722 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 4 04:59:29.087427 extend-filesystems[2151]: Checking size of /dev/sda9 Nov 4 04:59:29.091520 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 4 04:59:29.092737 oslogin_cache_refresh[2152]: Refreshing passwd entry cache Nov 4 04:59:29.093202 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Refreshing passwd entry cache Nov 4 04:59:29.091724 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 4 04:59:29.095768 extend-filesystems[2151]: Resized partition /dev/sda9 Nov 4 04:59:29.102931 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Failure getting users, quitting Nov 4 04:59:29.102931 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 04:59:29.102931 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Refreshing group entry cache Nov 4 04:59:29.100604 oslogin_cache_refresh[2152]: Failure getting users, quitting Nov 4 04:59:29.100621 oslogin_cache_refresh[2152]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 04:59:29.100737 oslogin_cache_refresh[2152]: Refreshing group entry cache Nov 4 04:59:29.107581 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Failure getting groups, quitting Nov 4 04:59:29.107581 google_oslogin_nss_cache[2152]: oslogin_cache_refresh[2152]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 04:59:29.107575 oslogin_cache_refresh[2152]: Failure getting groups, quitting Nov 4 04:59:29.107587 oslogin_cache_refresh[2152]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 04:59:29.109034 jq[2189]: true Nov 4 04:59:29.109044 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 4 04:59:29.109316 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 4 04:59:29.123724 update_engine[2169]: I20251104 04:59:29.123652 2169 main.cc:92] Flatcar Update Engine starting Nov 4 04:59:29.124348 extend-filesystems[2199]: resize2fs 1.47.3 (8-Jul-2025) Nov 4 04:59:29.133185 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Nov 4 04:59:29.148305 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Nov 4 04:59:29.214313 sshd_keygen[2168]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 4 04:59:29.219350 setup-nvidia[2158]: Downloading Flatcar Container Linux Developer Container for version: 4508.0.0+nightly-20251103-2100 Nov 4 04:59:29.221999 extend-filesystems[2199]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 4 04:59:29.221999 extend-filesystems[2199]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 4 04:59:29.221999 extend-filesystems[2199]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Nov 4 04:59:29.240478 extend-filesystems[2151]: Resized filesystem in /dev/sda9 Nov 4 04:59:29.223270 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 4 04:59:29.248343 bash[2226]: Updated "/home/core/.ssh/authorized_keys" Nov 4 04:59:29.223571 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 4 04:59:29.236834 systemd-logind[2167]: New seat seat0. Nov 4 04:59:29.239658 systemd-logind[2167]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 4 04:59:29.239966 systemd[1]: Started systemd-logind.service - User Login Management. Nov 4 04:59:29.248713 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 4 04:59:29.253408 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 4 04:59:29.261246 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 4 04:59:29.265123 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 4 04:59:29.271461 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 4 04:59:29.277485 systemd[1]: issuegen.service: Deactivated successfully. Nov 4 04:59:29.277703 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 4 04:59:29.280431 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 4 04:59:29.305293 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 4 04:59:29.310003 update_engine[2169]: I20251104 04:59:29.308959 2169 update_check_scheduler.cc:74] Next update check in 11m27s Nov 4 04:59:29.305849 dbus-daemon[2145]: [system] SELinux support is enabled Nov 4 04:59:29.310081 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 4 04:59:29.317384 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 4 04:59:29.323663 dbus-daemon[2145]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 4 04:59:29.323862 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 4 04:59:29.328609 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 4 04:59:29.333228 systemd[1]: Reached target getty.target - Login Prompts. Nov 4 04:59:29.339487 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). Nov 4 04:59:29.339516 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 4 04:59:29.344600 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). Nov 4 04:59:29.344623 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 4 04:59:29.350015 systemd[1]: Started update-engine.service - Update Engine. Nov 4 04:59:29.355672 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 4 04:59:29.374888 coreos-metadata[2144]: Nov 04 04:59:29.374 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:59:29.377229 coreos-metadata[2144]: Nov 04 04:59:29.377 INFO Fetch successful Nov 4 04:59:29.377395 coreos-metadata[2144]: Nov 04 04:59:29.377 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 4 04:59:29.381280 coreos-metadata[2144]: Nov 04 04:59:29.381 INFO Fetch successful Nov 4 04:59:29.381569 coreos-metadata[2144]: Nov 04 04:59:29.381 INFO Fetching http://168.63.129.16/machine/efaa1ef8-6844-4067-8594-51c137276f03/d4c4bb6e%2Dff6b%2D47b0%2Dbc48%2Df9ec4d6a0260.%5Fci%2D4508.0.0%2Dn%2D2274277710?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 4 04:59:29.383144 coreos-metadata[2144]: Nov 04 04:59:29.383 INFO Fetch successful Nov 4 04:59:29.383312 coreos-metadata[2144]: Nov 04 04:59:29.383 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:59:29.390350 coreos-metadata[2144]: Nov 04 04:59:29.390 INFO Fetch successful Nov 4 04:59:29.424140 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 4 04:59:29.429399 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 4 04:59:29.610065 locksmithd[2318]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 4 04:59:30.266525 containerd[2190]: time="2025-11-04T04:59:30Z" 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 Nov 4 04:59:30.267099 containerd[2190]: time="2025-11-04T04:59:30.267063300Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 4 04:59:30.276896 containerd[2190]: time="2025-11-04T04:59:30.276861000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.4µs" Nov 4 04:59:30.276896 containerd[2190]: time="2025-11-04T04:59:30.276890400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 4 04:59:30.276971 containerd[2190]: time="2025-11-04T04:59:30.276924200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 4 04:59:30.276971 containerd[2190]: time="2025-11-04T04:59:30.276936800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 4 04:59:30.277092 containerd[2190]: time="2025-11-04T04:59:30.277069700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 4 04:59:30.277127 containerd[2190]: time="2025-11-04T04:59:30.277091900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277185 containerd[2190]: time="2025-11-04T04:59:30.277150800Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277185 containerd[2190]: time="2025-11-04T04:59:30.277182000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277401 containerd[2190]: time="2025-11-04T04:59:30.277377400Z" 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 Nov 4 04:59:30.277437 containerd[2190]: time="2025-11-04T04:59:30.277398400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277437 containerd[2190]: time="2025-11-04T04:59:30.277410300Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277437 containerd[2190]: time="2025-11-04T04:59:30.277418600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277593 containerd[2190]: time="2025-11-04T04:59:30.277571500Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277593 containerd[2190]: time="2025-11-04T04:59:30.277590600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277682 containerd[2190]: time="2025-11-04T04:59:30.277663000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277880 containerd[2190]: time="2025-11-04T04:59:30.277859200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 04:59:30.277912 containerd[2190]: time="2025-11-04T04:59:30.277894600Z" 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 Nov 4 04:59:30.277912 containerd[2190]: time="2025-11-04T04:59:30.277905000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 4 04:59:30.277958 containerd[2190]: time="2025-11-04T04:59:30.277944500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 4 04:59:30.278145 containerd[2190]: time="2025-11-04T04:59:30.278123000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 4 04:59:30.278226 containerd[2190]: time="2025-11-04T04:59:30.278207700Z" level=info msg="metadata content store policy set" policy=shared Nov 4 04:59:30.296460 containerd[2190]: time="2025-11-04T04:59:30.296433000Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 4 04:59:30.296518 containerd[2190]: time="2025-11-04T04:59:30.296471400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:59:30.296586 containerd[2190]: time="2025-11-04T04:59:30.296562300Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:59:30.296586 containerd[2190]: time="2025-11-04T04:59:30.296580600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 4 04:59:30.296648 containerd[2190]: time="2025-11-04T04:59:30.296595400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 4 04:59:30.296648 containerd[2190]: time="2025-11-04T04:59:30.296606900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 4 04:59:30.296648 containerd[2190]: time="2025-11-04T04:59:30.296627700Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 4 04:59:30.296648 containerd[2190]: time="2025-11-04T04:59:30.296640000Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296651000Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296662500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296672000Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296681400Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296691900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 4 04:59:30.296723 containerd[2190]: time="2025-11-04T04:59:30.296702400Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 4 04:59:30.296839 containerd[2190]: time="2025-11-04T04:59:30.296796700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 4 04:59:30.296839 containerd[2190]: time="2025-11-04T04:59:30.296816400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 4 04:59:30.296839 containerd[2190]: time="2025-11-04T04:59:30.296828900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296843500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296853300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296862000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296872200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296882300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 4 04:59:30.296905 containerd[2190]: time="2025-11-04T04:59:30.296892800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 4 04:59:30.297020 containerd[2190]: time="2025-11-04T04:59:30.296917300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 4 04:59:30.297020 containerd[2190]: time="2025-11-04T04:59:30.296930200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 4 04:59:30.297020 containerd[2190]: time="2025-11-04T04:59:30.296949700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 4 04:59:30.297020 containerd[2190]: time="2025-11-04T04:59:30.296988700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 4 04:59:30.297020 containerd[2190]: time="2025-11-04T04:59:30.297001000Z" level=info msg="Start snapshots syncer" Nov 4 04:59:30.297122 containerd[2190]: time="2025-11-04T04:59:30.297024700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 4 04:59:30.297392 containerd[2190]: time="2025-11-04T04:59:30.297349400Z" 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,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":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\"}" Nov 4 04:59:30.297520 containerd[2190]: time="2025-11-04T04:59:30.297420500Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 4 04:59:30.303135 containerd[2190]: time="2025-11-04T04:59:30.303095300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 4 04:59:30.303276 containerd[2190]: time="2025-11-04T04:59:30.303248400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 4 04:59:30.303317 containerd[2190]: time="2025-11-04T04:59:30.303282300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 4 04:59:30.303317 containerd[2190]: time="2025-11-04T04:59:30.303295300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 4 04:59:30.303317 containerd[2190]: time="2025-11-04T04:59:30.303305500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 4 04:59:30.303388 containerd[2190]: time="2025-11-04T04:59:30.303316700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 4 04:59:30.303388 containerd[2190]: time="2025-11-04T04:59:30.303327400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 4 04:59:30.303388 containerd[2190]: time="2025-11-04T04:59:30.303337700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 4 04:59:30.303388 containerd[2190]: time="2025-11-04T04:59:30.303347300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 4 04:59:30.303388 containerd[2190]: time="2025-11-04T04:59:30.303370000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303404800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303418800Z" 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 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303427500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303436700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303444900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303464100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303477700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 4 04:59:30.303493 containerd[2190]: time="2025-11-04T04:59:30.303490600Z" level=info msg="runtime interface created" Nov 4 04:59:30.303664 containerd[2190]: time="2025-11-04T04:59:30.303496000Z" level=info msg="created NRI interface" Nov 4 04:59:30.303664 containerd[2190]: time="2025-11-04T04:59:30.303504300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 4 04:59:30.303664 containerd[2190]: time="2025-11-04T04:59:30.303516400Z" level=info msg="Connect containerd service" Nov 4 04:59:30.303664 containerd[2190]: time="2025-11-04T04:59:30.303537900Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 4 04:59:30.304305 containerd[2190]: time="2025-11-04T04:59:30.304277400Z" 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" Nov 4 04:59:30.908403 containerd[2190]: time="2025-11-04T04:59:30.908354500Z" level=info msg="Start subscribing containerd event" Nov 4 04:59:30.908524 containerd[2190]: time="2025-11-04T04:59:30.908433900Z" level=info msg="Start recovering state" Nov 4 04:59:30.908618 containerd[2190]: time="2025-11-04T04:59:30.908595400Z" level=info msg="Start event monitor" Nov 4 04:59:30.908655 containerd[2190]: time="2025-11-04T04:59:30.908605500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 4 04:59:30.908680 containerd[2190]: time="2025-11-04T04:59:30.908624600Z" level=info msg="Start cni network conf syncer for default" Nov 4 04:59:30.908701 containerd[2190]: time="2025-11-04T04:59:30.908686200Z" level=info msg="Start streaming server" Nov 4 04:59:30.908701 containerd[2190]: time="2025-11-04T04:59:30.908696100Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 4 04:59:30.908751 containerd[2190]: time="2025-11-04T04:59:30.908703900Z" level=info msg="runtime interface starting up..." Nov 4 04:59:30.908751 containerd[2190]: time="2025-11-04T04:59:30.908711200Z" level=info msg="starting plugins..." Nov 4 04:59:30.908751 containerd[2190]: time="2025-11-04T04:59:30.908728400Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 4 04:59:30.908814 containerd[2190]: time="2025-11-04T04:59:30.908706800Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 4 04:59:30.908900 containerd[2190]: time="2025-11-04T04:59:30.908882500Z" level=info msg="containerd successfully booted in 0.642810s" Nov 4 04:59:30.909036 systemd[1]: Started containerd.service - containerd container runtime. Nov 4 04:59:31.789217 waagent[2314]: 2025-11-04T04:59:31.789106Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 4 04:59:31.793525 waagent[2314]: 2025-11-04T04:59:31.793477Z INFO Daemon Daemon OS: flatcar 4508.0.0 Nov 4 04:59:31.796957 waagent[2314]: 2025-11-04T04:59:31.796904Z INFO Daemon Daemon Python: 3.11.13 Nov 4 04:59:31.800202 waagent[2314]: 2025-11-04T04:59:31.800141Z INFO Daemon Daemon Run daemon Nov 4 04:59:31.803367 waagent[2314]: 2025-11-04T04:59:31.803322Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4508.0.0' Nov 4 04:59:31.809830 waagent[2314]: 2025-11-04T04:59:31.809774Z INFO Daemon Daemon Using waagent for provisioning Nov 4 04:59:31.813910 waagent[2314]: 2025-11-04T04:59:31.813859Z INFO Daemon Daemon Activate resource disk Nov 4 04:59:31.817500 waagent[2314]: 2025-11-04T04:59:31.817448Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 4 04:59:31.827558 waagent[2314]: 2025-11-04T04:59:31.827512Z INFO Daemon Daemon Found device: sdb Nov 4 04:59:31.836406 waagent[2314]: 2025-11-04T04:59:31.836354Z INFO Daemon Daemon Examining partition table Nov 4 04:59:31.877586 waagent[2314]: 2025-11-04T04:59:31.877540Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 4 04:59:31.922546 waagent[2314]: 2025-11-04T04:59:31.922481Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 4 04:59:31.927134 waagent[2314]: 2025-11-04T04:59:31.927087Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 4 04:59:32.273554 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#349 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:32.282177 kernel: sdb: sdb1 Nov 4 04:59:32.289177 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#632 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:32.296041 waagent[2314]: 2025-11-04T04:59:32.295970Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 4 04:59:32.304170 kernel: sdb: sdb1 Nov 4 04:59:32.314184 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#357 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:59:32.325270 kernel: sdb: sdb1 Nov 4 04:59:32.325327 waagent[2314]: 2025-11-04T04:59:32.325111Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 4 04:59:37.501628 login[2316]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 4 04:59:37.650458 login[2317]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 04:59:37.657272 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 4 04:59:37.658381 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 4 04:59:37.664443 systemd-logind[2167]: New session 1 of user core. Nov 4 04:59:37.853981 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 4 04:59:37.856675 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 4 04:59:37.963636 (systemd)[2421]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 4 04:59:37.967373 systemd-logind[2167]: New session c1 of user core. Nov 4 04:59:38.013080 waagent[2314]: 2025-11-04T04:59:38.012997Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 4 04:59:38.125193 kernel: EXT4-fs (sdb1): mounted filesystem d9b63fb2-bb7f-481b-8732-a2fa08c1a157 r/w with ordered data mode. Quota mode: none. Nov 4 04:59:38.125665 waagent[2314]: 2025-11-04T04:59:38.125588Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 4 04:59:38.127933 waagent[2314]: 2025-11-04T04:59:38.127879Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:59:38.129069 waagent[2314]: 2025-11-04T04:59:38.129033Z INFO Daemon Daemon Running default provisioning handler Nov 4 04:59:38.137130 waagent[2314]: 2025-11-04T04:59:38.137079Z 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. Nov 4 04:59:38.137854 waagent[2314]: 2025-11-04T04:59:38.137812Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 4 04:59:38.138180 waagent[2314]: 2025-11-04T04:59:38.138135Z INFO Daemon Daemon cloud-init is enabled: False Nov 4 04:59:38.138858 waagent[2314]: 2025-11-04T04:59:38.138827Z INFO Daemon Daemon Copying ovf-env.xml Nov 4 04:59:38.235590 systemd[2421]: Queued start job for default target default.target. Nov 4 04:59:38.253002 waagent[2314]: 2025-11-04T04:59:38.252933Z INFO Daemon Daemon Successfully mounted dvd Nov 4 04:59:38.257223 systemd[2421]: Created slice app.slice - User Application Slice. Nov 4 04:59:38.257254 systemd[2421]: Reached target paths.target - Paths. Nov 4 04:59:38.257304 systemd[2421]: Reached target timers.target - Timers. Nov 4 04:59:38.258705 systemd[2421]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 04:59:38.270454 systemd[2421]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 04:59:38.270576 systemd[2421]: Reached target sockets.target - Sockets. Nov 4 04:59:38.270631 systemd[2421]: Reached target basic.target - Basic System. Nov 4 04:59:38.270677 systemd[2421]: Reached target default.target - Main User Target. Nov 4 04:59:38.270710 systemd[2421]: Startup finished in 295ms. Nov 4 04:59:38.270795 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 4 04:59:38.272208 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 4 04:59:38.298559 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 4 04:59:38.298779 waagent[2314]: 2025-11-04T04:59:38.298718Z INFO Daemon Daemon Detect protocol endpoint Nov 4 04:59:38.300453 waagent[2314]: 2025-11-04T04:59:38.300402Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:59:38.301217 waagent[2314]: 2025-11-04T04:59:38.301172Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 4 04:59:38.302850 waagent[2314]: 2025-11-04T04:59:38.302801Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 4 04:59:38.304435 waagent[2314]: 2025-11-04T04:59:38.304394Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 4 04:59:38.305070 waagent[2314]: 2025-11-04T04:59:38.305032Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 4 04:59:38.339927 waagent[2314]: 2025-11-04T04:59:38.339886Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 4 04:59:38.341600 waagent[2314]: 2025-11-04T04:59:38.341575Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 4 04:59:38.342193 waagent[2314]: 2025-11-04T04:59:38.342151Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 4 04:59:38.502049 login[2316]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 04:59:38.506680 systemd-logind[2167]: New session 2 of user core. Nov 4 04:59:38.520290 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 4 04:59:38.579116 waagent[2314]: 2025-11-04T04:59:38.579041Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 4 04:59:38.581233 waagent[2314]: 2025-11-04T04:59:38.581190Z INFO Daemon Daemon Forcing an update of the goal state. Nov 4 04:59:38.585005 waagent[2314]: 2025-11-04T04:59:38.584956Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:59:38.625458 waagent[2314]: 2025-11-04T04:59:38.625405Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 4 04:59:38.627535 waagent[2314]: 2025-11-04T04:59:38.627482Z INFO Daemon Nov 4 04:59:38.627813 waagent[2314]: 2025-11-04T04:59:38.627778Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 53b5c81f-cd75-49ff-8901-6a8332e3262c eTag: 1103927278750114551 source: Fabric] Nov 4 04:59:38.629479 waagent[2314]: 2025-11-04T04:59:38.629439Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 4 04:59:38.630195 waagent[2314]: 2025-11-04T04:59:38.630142Z INFO Daemon Nov 4 04:59:38.631386 waagent[2314]: 2025-11-04T04:59:38.631346Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:59:38.635697 waagent[2314]: 2025-11-04T04:59:38.635666Z INFO Daemon Daemon Downloading artifacts profile blob Nov 4 04:59:38.698935 waagent[2314]: 2025-11-04T04:59:38.698875Z INFO Daemon Downloaded certificate {'thumbprint': 'CC5D69197382F56706EC0665EDD56CF4324D465E', 'hasPrivateKey': True} Nov 4 04:59:38.701025 waagent[2314]: 2025-11-04T04:59:38.700824Z INFO Daemon Fetch goal state completed Nov 4 04:59:38.738874 waagent[2314]: 2025-11-04T04:59:38.738834Z INFO Daemon Daemon Starting provisioning Nov 4 04:59:38.741328 waagent[2314]: 2025-11-04T04:59:38.741268Z INFO Daemon Daemon Handle ovf-env.xml. Nov 4 04:59:38.742087 waagent[2314]: 2025-11-04T04:59:38.741982Z INFO Daemon Daemon Set hostname [ci-4508.0.0-n-2274277710] Nov 4 04:59:38.763715 waagent[2314]: 2025-11-04T04:59:38.763671Z INFO Daemon Daemon Publish hostname [ci-4508.0.0-n-2274277710] Nov 4 04:59:38.765471 waagent[2314]: 2025-11-04T04:59:38.765420Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 4 04:59:38.766181 waagent[2314]: 2025-11-04T04:59:38.766132Z INFO Daemon Daemon Primary interface is [eth0] Nov 4 04:59:38.783329 systemd-networkd[1867]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:38.783340 systemd-networkd[1867]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:59:38.783407 systemd-networkd[1867]: eth0: DHCP lease lost Nov 4 04:59:38.802011 waagent[2314]: 2025-11-04T04:59:38.801955Z INFO Daemon Daemon Create user account if not exists Nov 4 04:59:38.803671 waagent[2314]: 2025-11-04T04:59:38.803628Z INFO Daemon Daemon User core already exists, skip useradd Nov 4 04:59:38.805059 waagent[2314]: 2025-11-04T04:59:38.805024Z INFO Daemon Daemon Configure sudoer Nov 4 04:59:38.817593 waagent[2314]: 2025-11-04T04:59:38.817459Z INFO Daemon Daemon Configure sshd Nov 4 04:59:38.824038 waagent[2314]: 2025-11-04T04:59:38.823985Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 4 04:59:38.826949 waagent[2314]: 2025-11-04T04:59:38.826902Z INFO Daemon Daemon Deploy ssh public key. Nov 4 04:59:38.845232 systemd-networkd[1867]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:59:39.940877 waagent[2314]: 2025-11-04T04:59:39.940817Z INFO Daemon Daemon Provisioning complete Nov 4 04:59:39.951576 waagent[2314]: 2025-11-04T04:59:39.951533Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 4 04:59:39.953215 waagent[2314]: 2025-11-04T04:59:39.953174Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 4 04:59:39.953902 waagent[2314]: 2025-11-04T04:59:39.953869Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 4 04:59:40.102903 waagent[2484]: 2025-11-04T04:59:40.102811Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 4 04:59:40.103274 waagent[2484]: 2025-11-04T04:59:40.102959Z INFO ExtHandler ExtHandler OS: flatcar 4508.0.0 Nov 4 04:59:40.103274 waagent[2484]: 2025-11-04T04:59:40.103025Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 4 04:59:40.103274 waagent[2484]: 2025-11-04T04:59:40.103091Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 4 04:59:40.192938 waagent[2484]: 2025-11-04T04:59:40.192807Z INFO ExtHandler ExtHandler Distro: flatcar-4508.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 4 04:59:40.193092 waagent[2484]: 2025-11-04T04:59:40.193053Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:40.193186 waagent[2484]: 2025-11-04T04:59:40.193137Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:40.199833 waagent[2484]: 2025-11-04T04:59:40.199786Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:59:40.204717 waagent[2484]: 2025-11-04T04:59:40.204674Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 4 04:59:40.205157 waagent[2484]: 2025-11-04T04:59:40.205116Z INFO ExtHandler Nov 4 04:59:40.205257 waagent[2484]: 2025-11-04T04:59:40.205223Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a8710b86-8dd5-4568-9e92-33760c009e3e eTag: 1103927278750114551 source: Fabric] Nov 4 04:59:40.205576 waagent[2484]: 2025-11-04T04:59:40.205537Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 4 04:59:40.206216 waagent[2484]: 2025-11-04T04:59:40.206176Z INFO ExtHandler Nov 4 04:59:40.206289 waagent[2484]: 2025-11-04T04:59:40.206258Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:59:40.209690 waagent[2484]: 2025-11-04T04:59:40.209653Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 4 04:59:40.287801 waagent[2484]: 2025-11-04T04:59:40.287734Z INFO ExtHandler Downloaded certificate {'thumbprint': 'CC5D69197382F56706EC0665EDD56CF4324D465E', 'hasPrivateKey': True} Nov 4 04:59:40.288313 waagent[2484]: 2025-11-04T04:59:40.288271Z INFO ExtHandler Fetch goal state completed Nov 4 04:59:40.299474 waagent[2484]: 2025-11-04T04:59:40.299428Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.3 30 Sep 2025 (Library: OpenSSL 3.4.3 30 Sep 2025) Nov 4 04:59:40.304270 waagent[2484]: 2025-11-04T04:59:40.304228Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2484 Nov 4 04:59:40.304417 waagent[2484]: 2025-11-04T04:59:40.304374Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 4 04:59:40.304754 waagent[2484]: 2025-11-04T04:59:40.304716Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 4 04:59:40.306354 waagent[2484]: 2025-11-04T04:59:40.306316Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 4 04:59:40.306785 waagent[2484]: 2025-11-04T04:59:40.306748Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 4 04:59:40.306933 waagent[2484]: 2025-11-04T04:59:40.306899Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 4 04:59:40.307547 waagent[2484]: 2025-11-04T04:59:40.307509Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 4 04:59:40.362875 waagent[2484]: 2025-11-04T04:59:40.362835Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 4 04:59:40.363054 waagent[2484]: 2025-11-04T04:59:40.363016Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 4 04:59:40.369874 waagent[2484]: 2025-11-04T04:59:40.369846Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 4 04:59:40.376676 systemd[1]: Reload requested from client PID 2499 ('systemctl') (unit waagent.service)... Nov 4 04:59:40.376694 systemd[1]: Reloading... Nov 4 04:59:40.439255 zram_generator::config[2543]: No configuration found. Nov 4 04:59:40.749102 systemd[1]: Reloading finished in 372 ms. Nov 4 04:59:40.773872 waagent[2484]: 2025-11-04T04:59:40.772374Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 4 04:59:40.773872 waagent[2484]: 2025-11-04T04:59:40.772531Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 4 04:59:41.204210 waagent[2484]: 2025-11-04T04:59:41.204066Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 4 04:59:41.204527 waagent[2484]: 2025-11-04T04:59:41.204422Z 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] Nov 4 04:59:41.205214 waagent[2484]: 2025-11-04T04:59:41.205168Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 4 04:59:41.205572 waagent[2484]: 2025-11-04T04:59:41.205526Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:41.205747 waagent[2484]: 2025-11-04T04:59:41.205706Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 4 04:59:41.205917 waagent[2484]: 2025-11-04T04:59:41.205881Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:41.206337 waagent[2484]: 2025-11-04T04:59:41.206294Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 4 04:59:41.206412 waagent[2484]: 2025-11-04T04:59:41.206370Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:41.206674 waagent[2484]: 2025-11-04T04:59:41.206613Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 4 04:59:41.206736 waagent[2484]: 2025-11-04T04:59:41.206697Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 4 04:59:41.207032 waagent[2484]: 2025-11-04T04:59:41.206986Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:41.207528 waagent[2484]: 2025-11-04T04:59:41.207481Z INFO EnvHandler ExtHandler Configure routes Nov 4 04:59:41.207638 waagent[2484]: 2025-11-04T04:59:41.207585Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 4 04:59:41.207745 waagent[2484]: 2025-11-04T04:59:41.207707Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 4 04:59:41.207884 waagent[2484]: 2025-11-04T04:59:41.207801Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 4 04:59:41.207884 waagent[2484]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 4 04:59:41.207884 waagent[2484]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 4 04:59:41.207884 waagent[2484]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 4 04:59:41.207884 waagent[2484]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:41.207884 waagent[2484]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:41.207884 waagent[2484]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:41.208255 waagent[2484]: 2025-11-04T04:59:41.208214Z INFO EnvHandler ExtHandler Gateway:None Nov 4 04:59:41.208370 waagent[2484]: 2025-11-04T04:59:41.208329Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 4 04:59:41.210974 waagent[2484]: 2025-11-04T04:59:41.210878Z INFO EnvHandler ExtHandler Routes:None Nov 4 04:59:41.215089 waagent[2484]: 2025-11-04T04:59:41.215045Z INFO ExtHandler ExtHandler Nov 4 04:59:41.215445 waagent[2484]: 2025-11-04T04:59:41.215406Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 6f8fff4e-175f-4528-9f0b-f9ec64eb77cc correlation ddd8e59d-1144-4b39-8adc-e3cf284ff734 created: 2025-11-04T04:58:24.307587Z] Nov 4 04:59:41.216516 waagent[2484]: 2025-11-04T04:59:41.216472Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 4 04:59:41.217229 waagent[2484]: 2025-11-04T04:59:41.217192Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Nov 4 04:59:41.250099 waagent[2484]: 2025-11-04T04:59:41.250047Z 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 Nov 4 04:59:41.250099 waagent[2484]: Try `iptables -h' or 'iptables --help' for more information.) Nov 4 04:59:41.250502 waagent[2484]: 2025-11-04T04:59:41.250462Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 92A1E4D4-E028-498A-B391-277931FF4F43;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 4 04:59:41.285090 waagent[2484]: 2025-11-04T04:59:41.285038Z INFO MonitorHandler ExtHandler Network interfaces: Nov 4 04:59:41.285090 waagent[2484]: Executing ['ip', '-a', '-o', 'link']: Nov 4 04:59:41.285090 waagent[2484]: 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 Nov 4 04:59:41.285090 waagent[2484]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:77:3b:66 brd ff:ff:ff:ff:ff:ff\ altname enx7c1e52773b66 Nov 4 04:59:41.285090 waagent[2484]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 4 04:59:41.285090 waagent[2484]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 4 04:59:41.285090 waagent[2484]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 4 04:59:41.285090 waagent[2484]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 4 04:59:41.285090 waagent[2484]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 4 04:59:41.285090 waagent[2484]: 2: eth0 inet6 fe80::7e1e:52ff:fe77:3b66/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 4 04:59:41.341221 waagent[2484]: 2025-11-04T04:59:41.341152Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 4 04:59:41.341221 waagent[2484]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:41.341221 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.341221 waagent[2484]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:41.341221 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.341221 waagent[2484]: Chain OUTPUT (policy ACCEPT 1 packets, 52 bytes) Nov 4 04:59:41.341221 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.341221 waagent[2484]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:59:41.341221 waagent[2484]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:59:41.341221 waagent[2484]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:59:41.344560 waagent[2484]: 2025-11-04T04:59:41.344511Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 4 04:59:41.344560 waagent[2484]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:41.344560 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.344560 waagent[2484]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:41.344560 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.344560 waagent[2484]: Chain OUTPUT (policy ACCEPT 1 packets, 52 bytes) Nov 4 04:59:41.344560 waagent[2484]: pkts bytes target prot opt in out source destination Nov 4 04:59:41.344560 waagent[2484]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:59:41.344560 waagent[2484]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:59:41.344560 waagent[2484]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:59:41.344817 waagent[2484]: 2025-11-04T04:59:41.344767Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 4 04:59:41.344943 waagent[2484]: 2025-11-04T04:59:41.344909Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 4 04:59:52.835209 chronyd[2142]: Selected source PHC0 Nov 4 05:00:09.967428 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 4 05:00:10.788791 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 4 05:00:10.791707 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:41994.service - OpenSSH per-connection server daemon (10.200.16.10:41994). Nov 4 05:00:11.588642 sshd[2631]: Accepted publickey for core from 10.200.16.10 port 41994 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:11.589913 sshd-session[2631]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:11.594348 systemd-logind[2167]: New session 3 of user core. Nov 4 05:00:11.611303 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 4 05:00:11.998252 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:41998.service - OpenSSH per-connection server daemon (10.200.16.10:41998). Nov 4 05:00:12.534351 sshd[2637]: Accepted publickey for core from 10.200.16.10 port 41998 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:12.535622 sshd-session[2637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:12.539798 systemd-logind[2167]: New session 4 of user core. Nov 4 05:00:12.549295 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 4 05:00:12.840222 sshd[2640]: Connection closed by 10.200.16.10 port 41998 Nov 4 05:00:12.840473 sshd-session[2637]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:12.844088 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:41998.service: Deactivated successfully. Nov 4 05:00:12.845907 systemd[1]: session-4.scope: Deactivated successfully. Nov 4 05:00:12.846698 systemd-logind[2167]: Session 4 logged out. Waiting for processes to exit. Nov 4 05:00:12.848009 systemd-logind[2167]: Removed session 4. Nov 4 05:00:12.950696 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:42010.service - OpenSSH per-connection server daemon (10.200.16.10:42010). Nov 4 05:00:13.486649 sshd[2646]: Accepted publickey for core from 10.200.16.10 port 42010 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:13.487914 sshd-session[2646]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:13.492412 systemd-logind[2167]: New session 5 of user core. Nov 4 05:00:13.507291 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 4 05:00:13.791901 sshd[2649]: Connection closed by 10.200.16.10 port 42010 Nov 4 05:00:13.792239 sshd-session[2646]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:13.795695 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:42010.service: Deactivated successfully. Nov 4 05:00:13.797277 systemd[1]: session-5.scope: Deactivated successfully. Nov 4 05:00:13.797946 systemd-logind[2167]: Session 5 logged out. Waiting for processes to exit. Nov 4 05:00:13.798793 systemd-logind[2167]: Removed session 5. Nov 4 05:00:14.367848 update_engine[2169]: I20251104 05:00:14.367770 2169 update_attempter.cc:509] Updating boot flags... Nov 4 05:00:23.916178 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:57268.service - OpenSSH per-connection server daemon (10.200.16.10:57268). Nov 4 05:00:24.454682 sshd[2719]: Accepted publickey for core from 10.200.16.10 port 57268 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:24.455942 sshd-session[2719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:24.460138 systemd-logind[2167]: New session 6 of user core. Nov 4 05:00:24.473274 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 4 05:00:24.761051 sshd[2722]: Connection closed by 10.200.16.10 port 57268 Nov 4 05:00:24.761422 sshd-session[2719]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:24.764505 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:57268.service: Deactivated successfully. Nov 4 05:00:24.766412 systemd[1]: session-6.scope: Deactivated successfully. Nov 4 05:00:24.767642 systemd-logind[2167]: Session 6 logged out. Waiting for processes to exit. Nov 4 05:00:24.768581 systemd-logind[2167]: Removed session 6. Nov 4 05:00:24.872573 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:57270.service - OpenSSH per-connection server daemon (10.200.16.10:57270). Nov 4 05:00:25.410516 sshd[2728]: Accepted publickey for core from 10.200.16.10 port 57270 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:25.411807 sshd-session[2728]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:25.416016 systemd-logind[2167]: New session 7 of user core. Nov 4 05:00:25.430287 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 4 05:00:25.716066 sshd[2731]: Connection closed by 10.200.16.10 port 57270 Nov 4 05:00:25.716365 sshd-session[2728]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:25.720395 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:57270.service: Deactivated successfully. Nov 4 05:00:25.722411 systemd[1]: session-7.scope: Deactivated successfully. Nov 4 05:00:25.723260 systemd-logind[2167]: Session 7 logged out. Waiting for processes to exit. Nov 4 05:00:25.724951 systemd-logind[2167]: Removed session 7. Nov 4 05:00:29.621439 setup-nvidia[2736]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 4 05:00:29.621439 setup-nvidia[2736]: Dload Upload Total Spent Left Speed Nov 4 05:00:35.829672 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:39042.service - OpenSSH per-connection server daemon (10.200.16.10:39042). Nov 4 05:00:36.368628 sshd[2738]: Accepted publickey for core from 10.200.16.10 port 39042 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:36.371259 sshd-session[2738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:36.375457 systemd-logind[2167]: New session 8 of user core. Nov 4 05:00:36.390277 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 4 05:00:36.675292 sshd[2741]: Connection closed by 10.200.16.10 port 39042 Nov 4 05:00:36.675568 sshd-session[2738]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:36.679205 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:39042.service: Deactivated successfully. Nov 4 05:00:36.680798 systemd[1]: session-8.scope: Deactivated successfully. Nov 4 05:00:36.681499 systemd-logind[2167]: Session 8 logged out. Waiting for processes to exit. Nov 4 05:00:36.682360 systemd-logind[2167]: Removed session 8. Nov 4 05:00:36.798537 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:39054.service - OpenSSH per-connection server daemon (10.200.16.10:39054). Nov 4 05:00:37.339021 sshd[2747]: Accepted publickey for core from 10.200.16.10 port 39054 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:37.340280 sshd-session[2747]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:37.344487 systemd-logind[2167]: New session 9 of user core. Nov 4 05:00:37.358271 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 4 05:00:37.646087 sshd[2750]: Connection closed by 10.200.16.10 port 39054 Nov 4 05:00:37.646358 sshd-session[2747]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:37.649516 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:39054.service: Deactivated successfully. Nov 4 05:00:37.651120 systemd[1]: session-9.scope: Deactivated successfully. Nov 4 05:00:37.652302 systemd-logind[2167]: Session 9 logged out. Waiting for processes to exit. Nov 4 05:00:37.653237 systemd-logind[2167]: Removed session 9. Nov 4 05:00:47.762621 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:59786.service - OpenSSH per-connection server daemon (10.200.16.10:59786). Nov 4 05:00:48.301278 sshd[2756]: Accepted publickey for core from 10.200.16.10 port 59786 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:48.302524 sshd-session[2756]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:48.306948 systemd-logind[2167]: New session 10 of user core. Nov 4 05:00:48.323282 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 4 05:00:48.607532 sshd[2759]: Connection closed by 10.200.16.10 port 59786 Nov 4 05:00:48.607812 sshd-session[2756]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:48.612557 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:59786.service: Deactivated successfully. Nov 4 05:00:48.614206 systemd[1]: session-10.scope: Deactivated successfully. Nov 4 05:00:48.614928 systemd-logind[2167]: Session 10 logged out. Waiting for processes to exit. Nov 4 05:00:48.615806 systemd-logind[2167]: Removed session 10. Nov 4 05:00:48.731832 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:59790.service - OpenSSH per-connection server daemon (10.200.16.10:59790). Nov 4 05:00:49.270820 sshd[2765]: Accepted publickey for core from 10.200.16.10 port 59790 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:00:49.272036 sshd-session[2765]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:49.276385 systemd-logind[2167]: New session 11 of user core. Nov 4 05:00:49.284287 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 4 05:00:49.576295 sshd[2768]: Connection closed by 10.200.16.10 port 59790 Nov 4 05:00:49.576580 sshd-session[2765]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:49.580318 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:59790.service: Deactivated successfully. Nov 4 05:00:49.581906 systemd[1]: session-11.scope: Deactivated successfully. Nov 4 05:00:49.582604 systemd-logind[2167]: Session 11 logged out. Waiting for processes to exit. Nov 4 05:00:49.583543 systemd-logind[2167]: Removed session 11. Nov 4 05:00:52.753246 setup-nvidia[2736]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 531M 0 191k 0 0 208k 0 0:43:30 --:--:-- 0:43:30 208k\u000d 1 531M 1 9536k 0 0 5055k 0 0:01:47 0:00:01 0:01:46 5056k\u000d 6 531M 6 36.5M 0 0 12.6M 0 0:00:41 0:00:02 0:00:39 12.6M\u000d 11 531M 11 62.1M 0 0 15.5M 0 0:00:34 0:00:04 0:00:30 15.5M\u000d 15 531M 15 81.4M 0 0 16.3M 0 0:00:32 0:00:04 0:00:28 16.3M\u000d 20 531M 20 106M 0 0 17.8M 0 0:00:29 0:00:05 0:00:24 21.0M\u000d 24 531M 24 131M 0 0 18.8M 0 0:00:28 0:00:06 0:00:22 24.0M\u000d 29 531M 29 156M 0 0 19.7M 0 0:00:26 0:00:07 0:00:19 23.7M\u000d 34 531M 34 182M 0 0 20.3M 0 0:00:26 0:00:08 0:00:18 24.3M\u000d 39 531M 39 207M 0 0 20.8M 0 0:00:25 0:00:09 0:00:16 25.2M\u000d 43 531M 43 231M 0 0 21.1M 0 0:00:25 0:00:10 0:00:15 25.1M\u000d 48 531M 48 256M 0 0 21.4M 0 0:00:24 0:00:11 0:00:13 25.0M\u000d 52 531M 52 279M 0 0 21.6M 0 0:00:24 0:00:12 0:00:12 24.5M\u000d 56 531M 56 302M 0 0 21.7M 0 0:00:24 0:00:13 0:00:11 24.1M\u000d 61 531M 61 327M 0 0 21.9M 0 0:00:24 0:00:14 0:00:10 24.2M\u000d 66 531M 66 351M 0 0 22.0M 0 0:00:24 0:00:15 0:00:09 24.1M\u000d 70 531M 70 376M 0 0 22.2M 0 0:00:23 0:00:16 0:00:07 24.2M\u000d 75 531M 75 400M 0 0 22.3M 0 0:00:23 0:00:17 0:00:06 24.3M\u000d 80 531M 80 425M 0 0 22.5M 0 0:00:23 0:00:18 0:00:05 24.6M\u000d 85 531M 85 451M 0 0 22.7M 0 0:00:23 0:00:19 0:00:04 24.9M\u000d 89 531M 89 477M 0 0 22.7M 0 0:00:23 0:00:21 0:00:02 24.7M\u000d 94 531M 94 500M 0 0 22.7M 0 0:00:23 0:00:21 0:00:02 24.3M\u000d 98 531M 98 525M 0 0 22.8M 0 0:00:23 0:00:22 0:00:01 24.5M\u000d100 531M 100 531M 0 0 22.9M 0 0:00:23 0:00:23 --:--:-- 24.9M Nov 4 05:00:59.696806 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:54726.service - OpenSSH per-connection server daemon (10.200.16.10:54726). Nov 4 05:01:00.254879 sshd[2784]: Accepted publickey for core from 10.200.16.10 port 54726 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:00.256259 sshd-session[2784]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:00.266144 systemd-logind[2167]: New session 12 of user core. Nov 4 05:01:00.271316 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 4 05:01:00.566912 sshd[2787]: Connection closed by 10.200.16.10 port 54726 Nov 4 05:01:00.567364 sshd-session[2784]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:00.571854 systemd-logind[2167]: Session 12 logged out. Waiting for processes to exit. Nov 4 05:01:00.572760 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:54726.service: Deactivated successfully. Nov 4 05:01:00.575981 systemd[1]: session-12.scope: Deactivated successfully. Nov 4 05:01:00.579550 systemd-logind[2167]: Removed session 12. Nov 4 05:01:00.681605 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:52650.service - OpenSSH per-connection server daemon (10.200.16.10:52650). Nov 4 05:01:01.243007 sshd[2793]: Accepted publickey for core from 10.200.16.10 port 52650 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:01.244320 sshd-session[2793]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:01.250861 systemd-logind[2167]: New session 13 of user core. Nov 4 05:01:01.257295 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 4 05:01:01.556077 sshd[2796]: Connection closed by 10.200.16.10 port 52650 Nov 4 05:01:01.556675 sshd-session[2793]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:01.562737 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:52650.service: Deactivated successfully. Nov 4 05:01:01.565109 systemd-logind[2167]: Session 13 logged out. Waiting for processes to exit. Nov 4 05:01:01.567845 systemd[1]: session-13.scope: Deactivated successfully. Nov 4 05:01:01.571014 systemd-logind[2167]: Removed session 13. Nov 4 05:01:04.247659 setup-nvidia[2158]: Downloading NVIDIA 535.274.02 Driver Nov 4 05:01:04.251888 setup-nvidia[2802]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 4 05:01:04.252200 setup-nvidia[2802]: Dload Upload Total Spent Left Speed Nov 4 05:01:11.056884 setup-nvidia[2802]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 226k 0 0 890k 0 0:06:14 --:--:-- 0:06:14 891k\u000d 15 325M 15 50.0M 0 0 39.0M 0 0:00:08 0:00:01 0:00:07 39.0M\u000d 30 325M 30 98.0M 0 0 43.1M 0 0:00:07 0:00:02 0:00:05 43.1M\u000d 46 325M 46 150M 0 0 46.2M 0 0:00:07 0:00:03 0:00:04 46.2M\u000d 60 325M 60 198M 0 0 46.3M 0 0:00:07 0:00:04 0:00:03 46.3M\u000d 76 325M 76 250M 0 0 47.4M 0 0:00:06 0:00:05 0:00:01 49.8M\u000d 92 325M 92 300M 0 0 47.9M 0 0:00:06 0:00:06 --:--:-- 50.2M\u000d100 325M 100 325M 0 0 47.8M 0 0:00:06 0:00:06 --:--:-- 50.2M Nov 4 05:01:11.123021 setup-nvidia[2158]: Extract the NVIDIA Driver Installer 535.274.02 Nov 4 05:01:11.123276 setup-nvidia[2158]: /opt/nvidia/workdir/nvidia-workdir / Nov 4 05:01:11.141437 setup-nvidia[2805]: Creating directory NVIDIA-Linux-x86_64-535.274.02 Nov 4 05:01:11.395675 setup-nvidia[2805]: Verifying archive integrity... OK Nov 4 05:01:11.666758 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:49840.service - OpenSSH per-connection server daemon (10.200.16.10:49840). Nov 4 05:01:12.165213 setup-nvidia[2805]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.274.02 Nov 4 05:01:12.203536 sshd[2829]: Accepted publickey for core from 10.200.16.10 port 49840 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:12.205092 sshd-session[2829]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:12.211974 systemd-logind[2167]: New session 14 of user core. Nov 4 05:01:12.218390 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 4 05:01:12.507676 sshd[2839]: Connection closed by 10.200.16.10 port 49840 Nov 4 05:01:12.509719 sshd-session[2829]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:12.514071 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:49840.service: Deactivated successfully. Nov 4 05:01:12.516266 systemd[1]: session-14.scope: Deactivated successfully. Nov 4 05:01:12.517214 systemd-logind[2167]: Session 14 logged out. Waiting for processes to exit. Nov 4 05:01:12.519626 systemd-logind[2167]: Removed session 14. Nov 4 05:01:12.632177 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:49848.service - OpenSSH per-connection server daemon (10.200.16.10:49848). Nov 4 05:01:13.170662 sshd[2845]: Accepted publickey for core from 10.200.16.10 port 49848 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:13.172027 sshd-session[2845]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:13.176400 systemd-logind[2167]: New session 15 of user core. Nov 4 05:01:13.184284 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 4 05:01:13.476164 sshd[2848]: Connection closed by 10.200.16.10 port 49848 Nov 4 05:01:13.476747 sshd-session[2845]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:13.483557 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:49848.service: Deactivated successfully. Nov 4 05:01:13.485228 systemd[1]: session-15.scope: Deactivated successfully. Nov 4 05:01:13.485952 systemd-logind[2167]: Session 15 logged out. Waiting for processes to exit. Nov 4 05:01:13.487026 systemd-logind[2167]: Removed session 15. Nov 4 05:01:13.994859 setup-nvidia[2835]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 4 05:01:14.077451 setup-nvidia[2158]: / Nov 4 05:01:14.077451 setup-nvidia[2158]: Spawn system-nspawn container to install the NVIDIA drivers Nov 4 05:01:14.266286 sudo[2856]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4508.0.0+nightly-20251103-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 Nov 4 05:01:14.267962 sudo[2856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 4 05:01:14.273816 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 4 05:01:14.276518 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 4 05:01:14.297364 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 4 05:01:14.301012 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 4 05:01:14.320980 (systemd)[2859]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 4 05:01:14.323376 systemd-logind[2167]: New session c3 of user root. Nov 4 05:01:14.496831 systemd[2859]: Queued start job for default target default.target. Nov 4 05:01:14.518251 systemd[2859]: Created slice app.slice - User Application Slice. Nov 4 05:01:14.518280 systemd[2859]: Reached target paths.target - Paths. Nov 4 05:01:14.518329 systemd[2859]: Reached target timers.target - Timers. Nov 4 05:01:14.519482 systemd[2859]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 05:01:14.532662 systemd[2859]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 05:01:14.532781 systemd[2859]: Reached target sockets.target - Sockets. Nov 4 05:01:14.532836 systemd[2859]: Reached target basic.target - Basic System. Nov 4 05:01:14.532911 systemd[2859]: Reached target default.target - Main User Target. Nov 4 05:01:14.532949 systemd[2859]: Startup finished in 203ms. Nov 4 05:01:14.532988 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 4 05:01:14.535990 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 4 05:01:14.631087 systemd-udevd[1852]: Received message with invalid length, ignoring Nov 4 05:01:14.635172 kernel: loop7: detected capacity change from 0 to 12644352 Nov 4 05:01:14.678176 kernel: loop7: p9 Nov 4 05:01:14.800372 setup-nvidia[2868]: ░ Spawning container flatcardevelopercontainer-4508.0.0nightly-20251103-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4508.0.0+nightly-20251103-2100.bin. Nov 4 05:01:16.523578 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Nov 4 05:01:16.523702 kernel: EXT4-fs (loop7p9): mounted filesystem 529b6239-dbd9-49d8-96e9-f4d4706f22e1 ro without journal. Quota mode: none. Nov 4 05:01:23.595441 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:48138.service - OpenSSH per-connection server daemon (10.200.16.10:48138). Nov 4 05:01:24.132458 sshd[2951]: Accepted publickey for core from 10.200.16.10 port 48138 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:24.133608 sshd-session[2951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:24.137840 systemd-logind[2167]: New session 16 of user core. Nov 4 05:01:24.147788 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 4 05:01:24.438128 sshd[2983]: Connection closed by 10.200.16.10 port 48138 Nov 4 05:01:24.438391 sshd-session[2951]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:24.441908 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:48138.service: Deactivated successfully. Nov 4 05:01:24.443497 systemd[1]: session-16.scope: Deactivated successfully. Nov 4 05:01:24.444176 systemd-logind[2167]: Session 16 logged out. Waiting for processes to exit. Nov 4 05:01:24.445037 systemd-logind[2167]: Removed session 16. Nov 4 05:01:24.548344 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:48146.service - OpenSSH per-connection server daemon (10.200.16.10:48146). Nov 4 05:01:25.091960 sshd[2994]: Accepted publickey for core from 10.200.16.10 port 48146 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:25.093196 sshd-session[2994]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:25.097642 systemd-logind[2167]: New session 17 of user core. Nov 4 05:01:25.114280 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 4 05:01:25.396190 sshd[3083]: Connection closed by 10.200.16.10 port 48146 Nov 4 05:01:25.396435 sshd-session[2994]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:25.399533 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:48146.service: Deactivated successfully. Nov 4 05:01:25.401098 systemd[1]: session-17.scope: Deactivated successfully. Nov 4 05:01:25.402319 systemd-logind[2167]: Session 17 logged out. Waiting for processes to exit. Nov 4 05:01:25.403211 systemd-logind[2167]: Removed session 17. Nov 4 05:01:35.508581 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:41052.service - OpenSSH per-connection server daemon (10.200.16.10:41052). Nov 4 05:01:36.045965 sshd[3673]: Accepted publickey for core from 10.200.16.10 port 41052 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:36.047249 sshd-session[3673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:36.052042 systemd-logind[2167]: New session 18 of user core. Nov 4 05:01:36.065116 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 4 05:01:36.352414 sshd[3676]: Connection closed by 10.200.16.10 port 41052 Nov 4 05:01:36.352685 sshd-session[3673]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:36.356437 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:41052.service: Deactivated successfully. Nov 4 05:01:36.358009 systemd[1]: session-18.scope: Deactivated successfully. Nov 4 05:01:36.358706 systemd-logind[2167]: Session 18 logged out. Waiting for processes to exit. Nov 4 05:01:36.359621 systemd-logind[2167]: Removed session 18. Nov 4 05:01:36.467532 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:41062.service - OpenSSH per-connection server daemon (10.200.16.10:41062). Nov 4 05:01:37.002870 sshd[3682]: Accepted publickey for core from 10.200.16.10 port 41062 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:37.004188 sshd-session[3682]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:37.008485 systemd-logind[2167]: New session 19 of user core. Nov 4 05:01:37.020311 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 4 05:01:37.306886 sshd[3685]: Connection closed by 10.200.16.10 port 41062 Nov 4 05:01:37.307142 sshd-session[3682]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:37.310746 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:41062.service: Deactivated successfully. Nov 4 05:01:37.312333 systemd[1]: session-19.scope: Deactivated successfully. Nov 4 05:01:37.313008 systemd-logind[2167]: Session 19 logged out. Waiting for processes to exit. Nov 4 05:01:37.313953 systemd-logind[2167]: Removed session 19. Nov 4 05:01:47.424726 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:53108.service - OpenSSH per-connection server daemon (10.200.16.10:53108). Nov 4 05:01:47.963100 sshd[3806]: Accepted publickey for core from 10.200.16.10 port 53108 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:47.964359 sshd-session[3806]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:47.968820 systemd-logind[2167]: New session 20 of user core. Nov 4 05:01:47.976341 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 4 05:01:48.270137 sshd[3864]: Connection closed by 10.200.16.10 port 53108 Nov 4 05:01:48.270485 sshd-session[3806]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:48.274100 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:53108.service: Deactivated successfully. Nov 4 05:01:48.275728 systemd[1]: session-20.scope: Deactivated successfully. Nov 4 05:01:48.276419 systemd-logind[2167]: Session 20 logged out. Waiting for processes to exit. Nov 4 05:01:48.277302 systemd-logind[2167]: Removed session 20. Nov 4 05:01:48.381545 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:53124.service - OpenSSH per-connection server daemon (10.200.16.10:53124). Nov 4 05:01:48.919297 sshd[3877]: Accepted publickey for core from 10.200.16.10 port 53124 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:48.920522 sshd-session[3877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:48.925316 systemd-logind[2167]: New session 21 of user core. Nov 4 05:01:48.938297 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 4 05:01:49.225235 sshd[3880]: Connection closed by 10.200.16.10 port 53124 Nov 4 05:01:49.225548 sshd-session[3877]: pam_unix(sshd:session): session closed for user core Nov 4 05:01:49.229191 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:53124.service: Deactivated successfully. Nov 4 05:01:49.230811 systemd[1]: session-21.scope: Deactivated successfully. Nov 4 05:01:49.231517 systemd-logind[2167]: Session 21 logged out. Waiting for processes to exit. Nov 4 05:01:49.232371 systemd-logind[2167]: Removed session 21. Nov 4 05:01:59.342002 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:55386.service - OpenSSH per-connection server daemon (10.200.16.10:55386). Nov 4 05:01:59.883123 sshd[7367]: Accepted publickey for core from 10.200.16.10 port 55386 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:01:59.884445 sshd-session[7367]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:01:59.890037 systemd-logind[2167]: New session 22 of user core. Nov 4 05:01:59.897294 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 4 05:02:00.190069 sshd[7516]: Connection closed by 10.200.16.10 port 55386 Nov 4 05:02:00.190329 sshd-session[7367]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:00.194226 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:55386.service: Deactivated successfully. Nov 4 05:02:00.196128 systemd[1]: session-22.scope: Deactivated successfully. Nov 4 05:02:00.198335 systemd-logind[2167]: Session 22 logged out. Waiting for processes to exit. Nov 4 05:02:00.199567 systemd-logind[2167]: Removed session 22. Nov 4 05:02:00.310149 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:46870.service - OpenSSH per-connection server daemon (10.200.16.10:46870). Nov 4 05:02:00.859347 sshd[7667]: Accepted publickey for core from 10.200.16.10 port 46870 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:01.511832 sshd-session[7667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:01.518781 systemd-logind[2167]: New session 23 of user core. Nov 4 05:02:01.527338 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 4 05:02:01.744223 sshd[7769]: Connection closed by 10.200.16.10 port 46870 Nov 4 05:02:01.744778 sshd-session[7667]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:01.751890 systemd-logind[2167]: Session 23 logged out. Waiting for processes to exit. Nov 4 05:02:01.752687 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:46870.service: Deactivated successfully. Nov 4 05:02:01.756032 systemd[1]: session-23.scope: Deactivated successfully. Nov 4 05:02:01.759095 systemd-logind[2167]: Removed session 23. Nov 4 05:02:11.870553 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:34900.service - OpenSSH per-connection server daemon (10.200.16.10:34900). Nov 4 05:02:12.424610 sshd[11315]: Accepted publickey for core from 10.200.16.10 port 34900 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:12.426381 sshd-session[11315]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:12.431404 systemd-logind[2167]: New session 24 of user core. Nov 4 05:02:12.437434 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 4 05:02:12.730687 sshd[11545]: Connection closed by 10.200.16.10 port 34900 Nov 4 05:02:12.732350 sshd-session[11315]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:12.739103 systemd-logind[2167]: Session 24 logged out. Waiting for processes to exit. Nov 4 05:02:12.740758 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:34900.service: Deactivated successfully. Nov 4 05:02:12.743328 systemd[1]: session-24.scope: Deactivated successfully. Nov 4 05:02:12.747060 systemd-logind[2167]: Removed session 24. Nov 4 05:02:12.849442 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:34908.service - OpenSSH per-connection server daemon (10.200.16.10:34908). Nov 4 05:02:13.407596 sshd[11699]: Accepted publickey for core from 10.200.16.10 port 34908 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:13.409331 sshd-session[11699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:13.417403 systemd-logind[2167]: New session 25 of user core. Nov 4 05:02:13.426424 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 4 05:02:13.716591 sshd[11898]: Connection closed by 10.200.16.10 port 34908 Nov 4 05:02:13.716598 sshd-session[11699]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:13.720699 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:34908.service: Deactivated successfully. Nov 4 05:02:13.721055 systemd-logind[2167]: Session 25 logged out. Waiting for processes to exit. Nov 4 05:02:13.723392 systemd[1]: session-25.scope: Deactivated successfully. Nov 4 05:02:13.727734 systemd-logind[2167]: Removed session 25. Nov 4 05:02:23.844385 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:51378.service - OpenSSH per-connection server daemon (10.200.16.10:51378). Nov 4 05:02:24.415347 sshd[14497]: Accepted publickey for core from 10.200.16.10 port 51378 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:24.417194 sshd-session[14497]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:24.424087 systemd-logind[2167]: New session 26 of user core. Nov 4 05:02:24.429795 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 4 05:02:24.723189 sshd[14536]: Connection closed by 10.200.16.10 port 51378 Nov 4 05:02:24.723913 sshd-session[14497]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:24.727710 systemd-logind[2167]: Session 26 logged out. Waiting for processes to exit. Nov 4 05:02:24.731725 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:51378.service: Deactivated successfully. Nov 4 05:02:24.734047 systemd[1]: session-26.scope: Deactivated successfully. Nov 4 05:02:24.736345 systemd-logind[2167]: Removed session 26. Nov 4 05:02:24.838938 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:51390.service - OpenSSH per-connection server daemon (10.200.16.10:51390). Nov 4 05:02:25.394386 sshd[14549]: Accepted publickey for core from 10.200.16.10 port 51390 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:25.396057 sshd-session[14549]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:25.401356 systemd-logind[2167]: New session 27 of user core. Nov 4 05:02:25.408305 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 4 05:02:25.703994 sshd[14647]: Connection closed by 10.200.16.10 port 51390 Nov 4 05:02:25.707654 sshd-session[14549]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:25.711782 systemd-logind[2167]: Session 27 logged out. Waiting for processes to exit. Nov 4 05:02:25.714557 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:51390.service: Deactivated successfully. Nov 4 05:02:25.716812 systemd[1]: session-27.scope: Deactivated successfully. Nov 4 05:02:25.720462 systemd-logind[2167]: Removed session 27. Nov 4 05:02:35.841006 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:57630.service - OpenSSH per-connection server daemon (10.200.16.10:57630). Nov 4 05:02:36.407125 sshd[14965]: Accepted publickey for core from 10.200.16.10 port 57630 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:36.408936 sshd-session[14965]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:36.417217 systemd-logind[2167]: New session 28 of user core. Nov 4 05:02:36.423303 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 4 05:02:36.719026 sshd[14990]: Connection closed by 10.200.16.10 port 57630 Nov 4 05:02:36.720369 sshd-session[14965]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:36.725101 systemd-logind[2167]: Session 28 logged out. Waiting for processes to exit. Nov 4 05:02:36.725924 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:57630.service: Deactivated successfully. Nov 4 05:02:36.728061 systemd[1]: session-28.scope: Deactivated successfully. Nov 4 05:02:36.731950 systemd-logind[2167]: Removed session 28. Nov 4 05:02:36.835531 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:57642.service - OpenSSH per-connection server daemon (10.200.16.10:57642). Nov 4 05:02:37.401203 sshd[15005]: Accepted publickey for core from 10.200.16.10 port 57642 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:37.402843 sshd-session[15005]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:37.408132 systemd-logind[2167]: New session 29 of user core. Nov 4 05:02:37.418321 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 4 05:02:37.713873 sshd[15039]: Connection closed by 10.200.16.10 port 57642 Nov 4 05:02:37.714316 sshd-session[15005]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:37.718186 systemd-logind[2167]: Session 29 logged out. Waiting for processes to exit. Nov 4 05:02:37.720047 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:57642.service: Deactivated successfully. Nov 4 05:02:37.722215 systemd[1]: session-29.scope: Deactivated successfully. Nov 4 05:02:37.724429 systemd-logind[2167]: Removed session 29. Nov 4 05:02:47.829402 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:55120.service - OpenSSH per-connection server daemon (10.200.16.10:55120). Nov 4 05:02:48.398786 sshd[15363]: Accepted publickey for core from 10.200.16.10 port 55120 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:48.400623 sshd-session[15363]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:48.408065 systemd-logind[2167]: New session 30 of user core. Nov 4 05:02:48.415347 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 4 05:02:48.714748 sshd[15380]: Connection closed by 10.200.16.10 port 55120 Nov 4 05:02:48.719323 sshd-session[15363]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:48.723143 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:55120.service: Deactivated successfully. Nov 4 05:02:48.724751 systemd[1]: session-30.scope: Deactivated successfully. Nov 4 05:02:48.727588 systemd-logind[2167]: Session 30 logged out. Waiting for processes to exit. Nov 4 05:02:48.729072 systemd-logind[2167]: Removed session 30. Nov 4 05:02:48.833346 systemd[1]: Started sshd@28-10.200.8.12:22-10.200.16.10:55132.service - OpenSSH per-connection server daemon (10.200.16.10:55132). Nov 4 05:02:49.390799 sshd[15392]: Accepted publickey for core from 10.200.16.10 port 55132 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:02:49.392317 sshd-session[15392]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:02:49.399393 systemd-logind[2167]: New session 31 of user core. Nov 4 05:02:49.405526 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 4 05:02:49.697029 sshd[15419]: Connection closed by 10.200.16.10 port 55132 Nov 4 05:02:49.700325 sshd-session[15392]: pam_unix(sshd:session): session closed for user core Nov 4 05:02:49.703478 systemd[1]: sshd@28-10.200.8.12:22-10.200.16.10:55132.service: Deactivated successfully. Nov 4 05:02:49.705170 systemd[1]: session-31.scope: Deactivated successfully. Nov 4 05:02:49.707886 systemd-logind[2167]: Session 31 logged out. Waiting for processes to exit. Nov 4 05:02:49.709179 systemd-logind[2167]: Removed session 31. Nov 4 05:02:59.815604 systemd[1]: Started sshd@29-10.200.8.12:22-10.200.16.10:46932.service - OpenSSH per-connection server daemon (10.200.16.10:46932). Nov 4 05:03:00.352654 sshd[15615]: Accepted publickey for core from 10.200.16.10 port 46932 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:00.353923 sshd-session[15615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:00.358098 systemd-logind[2167]: New session 32 of user core. Nov 4 05:03:00.371287 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 4 05:03:00.663257 sshd[15618]: Connection closed by 10.200.16.10 port 46932 Nov 4 05:03:00.666325 sshd-session[15615]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:00.670553 systemd[1]: sshd@29-10.200.8.12:22-10.200.16.10:46932.service: Deactivated successfully. Nov 4 05:03:00.672623 systemd-logind[2167]: Session 32 logged out. Waiting for processes to exit. Nov 4 05:03:00.673203 systemd[1]: session-32.scope: Deactivated successfully. Nov 4 05:03:00.676419 systemd-logind[2167]: Removed session 32. Nov 4 05:03:00.773892 systemd[1]: Started sshd@30-10.200.8.12:22-10.200.16.10:56864.service - OpenSSH per-connection server daemon (10.200.16.10:56864). Nov 4 05:03:01.322616 sshd[15643]: Accepted publickey for core from 10.200.16.10 port 56864 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:01.323831 sshd-session[15643]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:01.328342 systemd-logind[2167]: New session 33 of user core. Nov 4 05:03:01.340279 systemd[1]: Started session-33.scope - Session 33 of User core. Nov 4 05:03:01.626731 sshd[15678]: Connection closed by 10.200.16.10 port 56864 Nov 4 05:03:01.626984 sshd-session[15643]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:01.630612 systemd[1]: sshd@30-10.200.8.12:22-10.200.16.10:56864.service: Deactivated successfully. Nov 4 05:03:01.632202 systemd[1]: session-33.scope: Deactivated successfully. Nov 4 05:03:01.632867 systemd-logind[2167]: Session 33 logged out. Waiting for processes to exit. Nov 4 05:03:01.633772 systemd-logind[2167]: Removed session 33. Nov 4 05:03:01.872459 setup-nvidia[2868]: \u000d Nov 4 05:03:01.872459 setup-nvidia[2868]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 4 05:03:01.872459 setup-nvidia[2868]: frequently when this kernel module was built against the wrong or Nov 4 05:03:01.872459 setup-nvidia[2868]: improperly configured kernel sources, with a version of gcc that differs Nov 4 05:03:01.872459 setup-nvidia[2868]: from the one used to build the target kernel, or if another driver, such Nov 4 05:03:01.872459 setup-nvidia[2868]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 4 05:03:01.872459 setup-nvidia[2868]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 4 05:03:01.872459 setup-nvidia[2868]: installed in this system is supported by this NVIDIA Linux graphics Nov 4 05:03:01.872459 setup-nvidia[2868]: driver release. Nov 4 05:03:01.872459 setup-nvidia[2868]: \u000d Nov 4 05:03:01.872459 setup-nvidia[2868]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 4 05:03:01.872459 setup-nvidia[2868]: messages' at the end of the file Nov 4 05:03:01.872459 setup-nvidia[2868]: '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for more Nov 4 05:03:01.872459 setup-nvidia[2868]: information. Nov 4 05:03:01.872459 setup-nvidia[2868]: \u000d Nov 4 05:03:01.887779 setup-nvidia[2868]: dmesg: read kernel buffer failed: Operation not permitted Nov 4 05:03:01.907056 setup-nvidia[2868]: \u000d Nov 4 05:03:01.907056 setup-nvidia[2868]: ERROR: Installation has failed. Please see the file Nov 4 05:03:01.907056 setup-nvidia[2868]: '/nvidia/NVIDIA-Linux-x86_64-535.274.02/nvidia-installer.log' for Nov 4 05:03:01.907056 setup-nvidia[2868]: details. You may find suggestions on fixing installation problems in Nov 4 05:03:01.907056 setup-nvidia[2868]: the README available on the Linux driver download page at Nov 4 05:03:01.907056 setup-nvidia[2868]: www.nvidia.com. Nov 4 05:03:01.907056 setup-nvidia[2868]: \u000d Nov 4 05:03:01.907511 setup-nvidia[2868]: Last 50 lines of nvidia-installer.log: Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 05:03:01.908328 setup-nvidia[2868]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/Module.symvers Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.mod.o Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/.module-common.o Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.mod.o Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.mod.o Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.mod.o Nov 4 05:03:01.908328 setup-nvidia[2868]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.mod.o Nov 4 05:03:01.908328 setup-nvidia[2868]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 4 05:03:01.908328 setup-nvidia[2868]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 4 05:03:01.908328 setup-nvidia[2868]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 4 05:03:01.908328 setup-nvidia[2868]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 4 05:03:01.908328 setup-nvidia[2868]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko Nov 4 05:03:01.908328 setup-nvidia[2868]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.274.02/kernel/nvidia.ko due to unavailability of vmlinux Nov 4 05:03:01.908328 setup-nvidia[2868]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Nov 4 05:03:01.908328 setup-nvidia[2868]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Nov 4 05:03:01.908328 setup-nvidia[2868]: -> done. Nov 4 05:03:01.908328 setup-nvidia[2868]: -> Kernel module compilation complete. Nov 4 05:03:01.908328 setup-nvidia[2868]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 4 05:03:01.909364 setup-nvidia[2868]: 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. Nov 4 05:03:01.909364 setup-nvidia[2868]: \u000d Nov 4 05:03:01.909364 setup-nvidia[2868]: 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. Nov 4 05:03:01.909364 setup-nvidia[2868]: -> Kernel module load error: Operation not permitted Nov 4 05:03:01.909364 setup-nvidia[2868]: -> Kernel messages: Nov 4 05:03:01.909364 setup-nvidia[2868]: 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. Nov 4 05:03:11.748251 systemd[1]: Started sshd@31-10.200.8.12:22-10.200.16.10:44198.service - OpenSSH per-connection server daemon (10.200.16.10:44198). Nov 4 05:03:12.286213 sshd[15781]: Accepted publickey for core from 10.200.16.10 port 44198 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:12.287475 sshd-session[15781]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:12.291711 systemd-logind[2167]: New session 34 of user core. Nov 4 05:03:12.308285 systemd[1]: Started session-34.scope - Session 34 of User core. Nov 4 05:03:12.591937 sshd[15784]: Connection closed by 10.200.16.10 port 44198 Nov 4 05:03:12.592207 sshd-session[15781]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:12.595741 systemd[1]: sshd@31-10.200.8.12:22-10.200.16.10:44198.service: Deactivated successfully. Nov 4 05:03:12.597871 systemd[1]: session-34.scope: Deactivated successfully. Nov 4 05:03:12.598702 systemd-logind[2167]: Session 34 logged out. Waiting for processes to exit. Nov 4 05:03:12.599574 systemd-logind[2167]: Removed session 34. Nov 4 05:03:12.702753 systemd[1]: Started sshd@32-10.200.8.12:22-10.200.16.10:44204.service - OpenSSH per-connection server daemon (10.200.16.10:44204). Nov 4 05:03:13.239401 sshd[15790]: Accepted publickey for core from 10.200.16.10 port 44204 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:13.240605 sshd-session[15790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:13.244953 systemd-logind[2167]: New session 35 of user core. Nov 4 05:03:13.257290 systemd[1]: Started session-35.scope - Session 35 of User core. Nov 4 05:03:13.544714 sshd[15793]: Connection closed by 10.200.16.10 port 44204 Nov 4 05:03:13.545145 sshd-session[15790]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:13.549766 systemd[1]: sshd@32-10.200.8.12:22-10.200.16.10:44204.service: Deactivated successfully. Nov 4 05:03:13.551710 systemd[1]: session-35.scope: Deactivated successfully. Nov 4 05:03:13.552878 systemd-logind[2167]: Session 35 logged out. Waiting for processes to exit. Nov 4 05:03:13.553841 systemd-logind[2167]: Removed session 35. Nov 4 05:03:17.751186 kernel: EXT4-fs (loop7p9): unmounting filesystem 529b6239-dbd9-49d8-96e9-f4d4706f22e1. Nov 4 05:03:17.751912 setup-nvidia[2868]: Container flatcardevelopercontainer-4508.0.0nightly-20251103-2100.bin exited successfully. Nov 4 05:03:17.754270 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4508.0.0nightly\x2d20251103\x2d2100.bin.mount: Deactivated successfully. Nov 4 05:03:17.806351 sudo[2856]: pam_unix(sudo:session): session closed for user root Nov 4 05:03:17.809780 systemd[1]: session-c2.scope: Deactivated successfully. Nov 4 05:03:17.810046 systemd[1]: session-c2.scope: Consumed 5min 22.816s CPU time, 896.9M memory peak. Nov 4 05:03:18.266111 setup-nvidia[2158]: /opt/nvidia / Nov 4 05:03:18.267142 setup-nvidia[2158]: / Nov 4 05:03:18.277181 kernel: IPMI message handler: version 39.2 Nov 4 05:03:18.281423 kernel: ipmi device interface Nov 4 05:03:18.293407 setup-nvidia[2158]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Nov 4 05:03:18.346137 kernel: nvidia: loading out-of-tree module taints kernel. Nov 4 05:03:18.346289 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 4 05:03:18.346316 kernel: Disabling lock debugging due to kernel taint Nov 4 05:03:18.353437 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 4 05:03:18.353493 kernel: nvidia: module license taints kernel. Nov 4 05:03:18.783184 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 4 05:03:19.196686 kernel: Nov 4 05:03:19.196831 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 4 05:03:19.407175 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.274.02 Thu Sep 4 22:13:52 UTC 2025 Nov 4 05:03:19.428174 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.274.02 Thu Sep 4 22:13:13 UTC 2025 Nov 4 05:03:19.447191 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 4 05:03:19.493186 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 4 05:03:19.493540 setup-nvidia[2158]: / Nov 4 05:03:19.503285 setup-nvidia[15833]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 05:03:21.285650 setup-nvidia[15850]: Tue Nov 4 05:03:21 2025 Nov 4 05:03:21.285650 setup-nvidia[15850]: +---------------------------------------------------------------------------------------+ Nov 4 05:03:21.285650 setup-nvidia[15850]: | NVIDIA-SMI 535.274.02 Driver Version: 535.274.02 CUDA Version: 12.2 | Nov 4 05:03:21.285650 setup-nvidia[15850]: |-----------------------------------------+----------------------+----------------------+ Nov 4 05:03:21.285650 setup-nvidia[15850]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 4 05:03:21.285650 setup-nvidia[15850]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 4 05:03:21.285650 setup-nvidia[15850]: | | | MIG M. | Nov 4 05:03:21.285650 setup-nvidia[15850]: |=========================================+======================+======================| Nov 4 05:03:21.292096 setup-nvidia[15850]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 4 05:03:21.292096 setup-nvidia[15850]: | N/A 26C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Nov 4 05:03:21.292096 setup-nvidia[15850]: | | | N/A | Nov 4 05:03:21.292096 setup-nvidia[15850]: +-----------------------------------------+----------------------+----------------------+ Nov 4 05:03:21.292096 setup-nvidia[15850]: Nov 4 05:03:21.292096 setup-nvidia[15850]: +---------------------------------------------------------------------------------------+ Nov 4 05:03:21.292096 setup-nvidia[15850]: | Processes: | Nov 4 05:03:21.292096 setup-nvidia[15850]: | GPU GI CI PID Type Process name GPU Memory | Nov 4 05:03:21.292096 setup-nvidia[15850]: | ID ID Usage | Nov 4 05:03:21.292096 setup-nvidia[15850]: |=======================================================================================| Nov 4 05:03:21.292405 setup-nvidia[15850]: | No running processes found | Nov 4 05:03:21.292405 setup-nvidia[15850]: +---------------------------------------------------------------------------------------+ Nov 4 05:03:21.663352 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 4 05:03:21.666982 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 4 05:03:21.669229 systemd[1]: Startup finished in 1.058s (firmware) + 11.412s (loader) + 5.401s (kernel) + 13.866s (initrd) + 4min 6.034s (userspace) = 4min 37.772s. Nov 4 05:03:23.660647 systemd[1]: Started sshd@33-10.200.8.12:22-10.200.16.10:43766.service - OpenSSH per-connection server daemon (10.200.16.10:43766). Nov 4 05:03:24.197467 sshd[15857]: Accepted publickey for core from 10.200.16.10 port 43766 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:24.198713 sshd-session[15857]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:24.202932 systemd-logind[2167]: New session 36 of user core. Nov 4 05:03:24.223297 systemd[1]: Started session-36.scope - Session 36 of User core. Nov 4 05:03:24.503507 sshd[15860]: Connection closed by 10.200.16.10 port 43766 Nov 4 05:03:24.503849 sshd-session[15857]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:24.507434 systemd[1]: sshd@33-10.200.8.12:22-10.200.16.10:43766.service: Deactivated successfully. Nov 4 05:03:24.509022 systemd[1]: session-36.scope: Deactivated successfully. Nov 4 05:03:24.509700 systemd-logind[2167]: Session 36 logged out. Waiting for processes to exit. Nov 4 05:03:24.510548 systemd-logind[2167]: Removed session 36. Nov 4 05:03:24.619315 systemd[1]: Started sshd@34-10.200.8.12:22-10.200.16.10:43778.service - OpenSSH per-connection server daemon (10.200.16.10:43778). Nov 4 05:03:25.180543 sshd[15866]: Accepted publickey for core from 10.200.16.10 port 43778 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:25.181813 sshd-session[15866]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:25.186083 systemd-logind[2167]: New session 37 of user core. Nov 4 05:03:25.195284 systemd[1]: Started session-37.scope - Session 37 of User core. Nov 4 05:03:25.483688 sshd[15869]: Connection closed by 10.200.16.10 port 43778 Nov 4 05:03:25.483967 sshd-session[15866]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:25.502891 systemd[1]: sshd@34-10.200.8.12:22-10.200.16.10:43778.service: Deactivated successfully. Nov 4 05:03:25.504489 systemd[1]: session-37.scope: Deactivated successfully. Nov 4 05:03:25.511550 systemd-logind[2167]: Session 37 logged out. Waiting for processes to exit. Nov 4 05:03:25.522541 systemd-logind[2167]: Removed session 37. Nov 4 05:03:25.598396 systemd[1]: Started sshd@35-10.200.8.12:22-10.200.16.10:43790.service - OpenSSH per-connection server daemon (10.200.16.10:43790). Nov 4 05:03:26.138017 sshd[15875]: Accepted publickey for core from 10.200.16.10 port 43790 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:26.139300 sshd-session[15875]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:26.143528 systemd-logind[2167]: New session 38 of user core. Nov 4 05:03:26.167282 systemd[1]: Started session-38.scope - Session 38 of User core. Nov 4 05:03:26.443745 sshd[15878]: Connection closed by 10.200.16.10 port 43790 Nov 4 05:03:26.443981 sshd-session[15875]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:26.447749 systemd[1]: sshd@35-10.200.8.12:22-10.200.16.10:43790.service: Deactivated successfully. Nov 4 05:03:26.449342 systemd[1]: session-38.scope: Deactivated successfully. Nov 4 05:03:26.450023 systemd-logind[2167]: Session 38 logged out. Waiting for processes to exit. Nov 4 05:03:26.450890 systemd-logind[2167]: Removed session 38. Nov 4 05:03:26.565381 systemd[1]: Started sshd@36-10.200.8.12:22-10.200.16.10:43802.service - OpenSSH per-connection server daemon (10.200.16.10:43802). Nov 4 05:03:27.132026 sshd[15884]: Accepted publickey for core from 10.200.16.10 port 43802 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:27.133275 sshd-session[15884]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:27.137431 systemd-logind[2167]: New session 39 of user core. Nov 4 05:03:27.149278 systemd[1]: Started session-39.scope - Session 39 of User core. Nov 4 05:03:27.440596 sshd[15887]: Connection closed by 10.200.16.10 port 43802 Nov 4 05:03:27.440864 sshd-session[15884]: pam_unix(sshd:session): session closed for user core Nov 4 05:03:27.444599 systemd[1]: sshd@36-10.200.8.12:22-10.200.16.10:43802.service: Deactivated successfully. Nov 4 05:03:27.446366 systemd[1]: session-39.scope: Deactivated successfully. Nov 4 05:03:27.447110 systemd-logind[2167]: Session 39 logged out. Waiting for processes to exit. Nov 4 05:03:27.448184 systemd-logind[2167]: Removed session 39. Nov 4 05:03:27.556459 systemd[1]: Started sshd@37-10.200.8.12:22-10.200.16.10:43818.service - OpenSSH per-connection server daemon (10.200.16.10:43818). Nov 4 05:03:27.995633 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 4 05:03:27.996399 systemd[2859]: Activating special unit exit.target... Nov 4 05:03:27.996475 systemd[2859]: Stopped target default.target - Main User Target. Nov 4 05:03:27.996516 systemd[2859]: Stopped target basic.target - Basic System. Nov 4 05:03:27.996545 systemd[2859]: Stopped target paths.target - Paths. Nov 4 05:03:27.996572 systemd[2859]: Stopped target sockets.target - Sockets. Nov 4 05:03:27.996597 systemd[2859]: Stopped target timers.target - Timers. Nov 4 05:03:27.996933 systemd[2859]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 4 05:03:27.997135 systemd[2859]: Removed slice app.slice - User Application Slice. Nov 4 05:03:27.997182 systemd[2859]: Reached target shutdown.target - Shutdown. Nov 4 05:03:27.997329 systemd[2859]: Finished systemd-exit.service - Exit the Session. Nov 4 05:03:27.997365 systemd[2859]: Reached target exit.target - Exit the Session. Nov 4 05:03:28.007791 systemd-logind[2167]: Removed session c3. Nov 4 05:03:28.007818 (sd-pam)[2860]: pam_unix(systemd-user:session): session closed for user root Nov 4 05:03:28.009070 systemd[1]: user@0.service: Deactivated successfully. Nov 4 05:03:28.009446 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 4 05:03:28.011786 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 4 05:03:28.042739 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 4 05:03:28.043626 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 4 05:03:28.043814 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 4 05:03:28.045300 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 4 05:03:28.045390 systemd[1]: user-0.slice: Consumed 5min 23.031s CPU time, 903.1M memory peak. Nov 4 05:03:28.095671 sshd[15893]: Accepted publickey for core from 10.200.16.10 port 43818 ssh2: RSA SHA256:vIprEubM+pdBYTvh12MxLUq2qwfhiEYF0iyyAye9Om0 Nov 4 05:03:28.096908 sshd-session[15893]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:03:28.101017 systemd-logind[2167]: New session 40 of user core. Nov 4 05:03:28.118273 systemd[1]: Started session-40.scope - Session 40 of User core.