Nov 4 23:51:31.854068 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 22:00:22 -00 2025 Nov 4 23:51:31.854100 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=c57c40de146020da5f35a7230cc1da8f1a5a7a7af49d0754317609f7e94976e2 Nov 4 23:51:31.854111 kernel: BIOS-provided physical RAM map: Nov 4 23:51:31.854119 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 4 23:51:31.854127 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 4 23:51:31.854135 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 4 23:51:31.854147 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 4 23:51:31.854156 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 4 23:51:31.854164 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 4 23:51:31.854172 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 4 23:51:31.854180 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 4 23:51:31.854188 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 4 23:51:31.854196 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 4 23:51:31.854205 kernel: NX (Execute Disable) protection: active Nov 4 23:51:31.854217 kernel: APIC: Static calls initialized Nov 4 23:51:31.854226 kernel: efi: EFI v2.7 by Microsoft Nov 4 23:51:31.854236 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee60a98 RNG=0x3ffd1018 Nov 4 23:51:31.854245 kernel: random: crng init done Nov 4 23:51:31.854254 kernel: secureboot: Secure boot disabled Nov 4 23:51:31.854262 kernel: SMBIOS 3.1.0 present. Nov 4 23:51:31.854272 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 4 23:51:31.854283 kernel: DMI: Memory slots populated: 3/3 Nov 4 23:51:31.854292 kernel: Hypervisor detected: Microsoft Hyper-V Nov 4 23:51:31.854301 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 4 23:51:31.854310 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 4 23:51:31.854319 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 4 23:51:31.854328 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 23:51:31.854337 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 23:51:31.854346 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 4 23:51:31.854356 kernel: tsc: Detected 2593.992 MHz processor Nov 4 23:51:31.854368 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 4 23:51:31.854378 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 4 23:51:31.854387 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 4 23:51:31.854396 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 4 23:51:31.854405 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 4 23:51:31.854415 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 4 23:51:31.854424 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 4 23:51:31.854435 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 4 23:51:31.854445 kernel: Using GB pages for direct mapping Nov 4 23:51:31.854454 kernel: ACPI: Early table checksum verification disabled Nov 4 23:51:31.854468 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 4 23:51:31.854477 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854487 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854500 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 4 23:51:31.854510 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 4 23:51:31.854519 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854529 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854539 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854549 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854561 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854571 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854581 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:51:31.854591 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 4 23:51:31.854600 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 4 23:51:31.854610 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 4 23:51:31.854623 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 4 23:51:31.854632 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 4 23:51:31.854642 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 4 23:51:31.854652 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 4 23:51:31.854661 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 4 23:51:31.854671 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 4 23:51:31.854681 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 4 23:51:31.854693 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 4 23:51:31.854703 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 4 23:51:31.854713 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 4 23:51:31.854723 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 4 23:51:31.854733 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 4 23:51:31.854742 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 4 23:51:31.854752 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 4 23:51:31.854765 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 4 23:51:31.854775 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 4 23:51:31.854785 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 4 23:51:31.854794 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 4 23:51:31.854804 kernel: Zone ranges: Nov 4 23:51:31.854814 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 4 23:51:31.854837 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 4 23:51:31.854851 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 4 23:51:31.854861 kernel: Device empty Nov 4 23:51:31.854871 kernel: Movable zone start for each node Nov 4 23:51:31.854881 kernel: Early memory node ranges Nov 4 23:51:31.854891 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 4 23:51:31.854900 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 4 23:51:31.854910 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 4 23:51:31.854920 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 4 23:51:31.854932 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 4 23:51:31.854942 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 4 23:51:31.854952 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 4 23:51:31.854961 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 4 23:51:31.854971 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 4 23:51:31.854981 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 4 23:51:31.854990 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 4 23:51:31.855003 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 4 23:51:31.855013 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 4 23:51:31.855022 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 4 23:51:31.855032 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 4 23:51:31.855042 kernel: CPU topo: Max. logical packages: 1 Nov 4 23:51:31.855052 kernel: CPU topo: Max. logical dies: 1 Nov 4 23:51:31.855061 kernel: CPU topo: Max. dies per package: 1 Nov 4 23:51:31.855074 kernel: CPU topo: Max. threads per core: 1 Nov 4 23:51:31.855084 kernel: CPU topo: Num. cores per package: 6 Nov 4 23:51:31.855093 kernel: CPU topo: Num. threads per package: 6 Nov 4 23:51:31.855103 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 4 23:51:31.855113 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 4 23:51:31.855122 kernel: Booting paravirtualized kernel on Hyper-V Nov 4 23:51:31.855132 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 4 23:51:31.855145 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 4 23:51:31.855155 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 4 23:51:31.855165 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 4 23:51:31.855174 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 4 23:51:31.855184 kernel: Hyper-V: PV spinlocks enabled Nov 4 23:51:31.855193 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 4 23:51:31.855204 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=c57c40de146020da5f35a7230cc1da8f1a5a7a7af49d0754317609f7e94976e2 Nov 4 23:51:31.855218 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 4 23:51:31.855227 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 4 23:51:31.855237 kernel: Fallback order for Node 0: 0 Nov 4 23:51:31.855247 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 4 23:51:31.855257 kernel: Policy zone: Normal Nov 4 23:51:31.855267 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 4 23:51:31.855286 kernel: software IO TLB: area num 8. Nov 4 23:51:31.855297 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 4 23:51:31.855307 kernel: Kernel/User page tables isolation: enabled Nov 4 23:51:31.855320 kernel: ftrace: allocating 40092 entries in 157 pages Nov 4 23:51:31.855330 kernel: ftrace: allocated 157 pages with 5 groups Nov 4 23:51:31.855340 kernel: Dynamic Preempt: voluntary Nov 4 23:51:31.855351 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 4 23:51:31.855361 kernel: rcu: RCU event tracing is enabled. Nov 4 23:51:31.855372 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 4 23:51:31.855382 kernel: Trampoline variant of Tasks RCU enabled. Nov 4 23:51:31.855395 kernel: Rude variant of Tasks RCU enabled. Nov 4 23:51:31.855405 kernel: Tracing variant of Tasks RCU enabled. Nov 4 23:51:31.855416 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 4 23:51:31.855426 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 4 23:51:31.855439 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 23:51:31.855449 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 23:51:31.855459 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 4 23:51:31.855470 kernel: Using NULL legacy PIC Nov 4 23:51:31.855480 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 4 23:51:31.855490 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 4 23:51:31.855500 kernel: Console: colour dummy device 80x25 Nov 4 23:51:31.855513 kernel: printk: legacy console [tty1] enabled Nov 4 23:51:31.855523 kernel: printk: legacy console [ttyS0] enabled Nov 4 23:51:31.855533 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 4 23:51:31.855543 kernel: ACPI: Core revision 20240827 Nov 4 23:51:31.855553 kernel: Failed to register legacy timer interrupt Nov 4 23:51:31.855563 kernel: APIC: Switch to symmetric I/O mode setup Nov 4 23:51:31.855573 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 4 23:51:31.855587 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 4 23:51:31.855597 kernel: Hyper-V: Using IPI hypercalls Nov 4 23:51:31.855607 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 4 23:51:31.855617 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 4 23:51:31.855627 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 4 23:51:31.855638 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 4 23:51:31.855648 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 4 23:51:31.855661 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 4 23:51:31.855671 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 4 23:51:31.855681 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 4 23:51:31.855691 kernel: APIC: read() replaced with hv_apic_read() Nov 4 23:51:31.855701 kernel: APIC: write() replaced with hv_apic_write() Nov 4 23:51:31.855711 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 4 23:51:31.855721 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 4 23:51:31.855735 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Nov 4 23:51:31.855745 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 4 23:51:31.855755 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 4 23:51:31.855765 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 4 23:51:31.855775 kernel: Spectre V2 : Mitigation: Retpolines Nov 4 23:51:31.855785 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 4 23:51:31.855794 kernel: Speculative Store Bypass: Vulnerable Nov 4 23:51:31.855804 kernel: MDS: Mitigation: Clear CPU buffers Nov 4 23:51:31.855814 kernel: TAA: Mitigation: Clear CPU buffers Nov 4 23:51:31.855849 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 4 23:51:31.855859 kernel: active return thunk: its_return_thunk Nov 4 23:51:31.855869 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 4 23:51:31.855896 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 4 23:51:31.855920 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 4 23:51:31.855936 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 4 23:51:31.855947 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 4 23:51:31.855957 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 4 23:51:31.855971 kernel: Freeing SMP alternatives memory: 32K Nov 4 23:51:31.855981 kernel: pid_max: default: 32768 minimum: 301 Nov 4 23:51:31.855996 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 4 23:51:31.856005 kernel: landlock: Up and running. Nov 4 23:51:31.856014 kernel: SELinux: Initializing. Nov 4 23:51:31.856023 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 4 23:51:31.856033 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 4 23:51:31.856042 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 4 23:51:31.856057 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 4 23:51:31.856066 kernel: signal: max sigframe size: 1776 Nov 4 23:51:31.856076 kernel: rcu: Hierarchical SRCU implementation. Nov 4 23:51:31.856089 kernel: rcu: Max phase no-delay instances is 400. Nov 4 23:51:31.856099 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 4 23:51:31.856109 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 4 23:51:31.856118 kernel: smp: Bringing up secondary CPUs ... Nov 4 23:51:31.856128 kernel: smpboot: x86: Booting SMP configuration: Nov 4 23:51:31.856137 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 4 23:51:31.856147 kernel: smp: Brought up 1 node, 6 CPUs Nov 4 23:51:31.856156 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Nov 4 23:51:31.856169 kernel: Memory: 115361620K/117439364K available (14336K kernel code, 2443K rwdata, 26064K rodata, 15936K init, 2108K bss, 2067468K reserved, 0K cma-reserved) Nov 4 23:51:31.856179 kernel: devtmpfs: initialized Nov 4 23:51:31.856188 kernel: x86/mm: Memory block size: 1024MB Nov 4 23:51:31.856198 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 4 23:51:31.856208 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 4 23:51:31.856217 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 4 23:51:31.856229 kernel: pinctrl core: initialized pinctrl subsystem Nov 4 23:51:31.856239 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 4 23:51:31.856248 kernel: audit: initializing netlink subsys (disabled) Nov 4 23:51:31.856258 kernel: audit: type=2000 audit(1762300286.028:1): state=initialized audit_enabled=0 res=1 Nov 4 23:51:31.856267 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 4 23:51:31.856277 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 4 23:51:31.856286 kernel: cpuidle: using governor menu Nov 4 23:51:31.856296 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 4 23:51:31.856308 kernel: dca service started, version 1.12.1 Nov 4 23:51:31.856317 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 4 23:51:31.856327 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 4 23:51:31.856336 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 4 23:51:31.856346 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 4 23:51:31.856355 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 4 23:51:31.856365 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 4 23:51:31.856376 kernel: ACPI: Added _OSI(Module Device) Nov 4 23:51:31.856386 kernel: ACPI: Added _OSI(Processor Device) Nov 4 23:51:31.856395 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 4 23:51:31.856405 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 4 23:51:31.856414 kernel: ACPI: Interpreter enabled Nov 4 23:51:31.856424 kernel: ACPI: PM: (supports S0 S5) Nov 4 23:51:31.856433 kernel: ACPI: Using IOAPIC for interrupt routing Nov 4 23:51:31.856445 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 4 23:51:31.856454 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 4 23:51:31.856464 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 4 23:51:31.856473 kernel: iommu: Default domain type: Translated Nov 4 23:51:31.856483 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 4 23:51:31.856492 kernel: efivars: Registered efivars operations Nov 4 23:51:31.856501 kernel: PCI: Using ACPI for IRQ routing Nov 4 23:51:31.856513 kernel: PCI: System does not support PCI Nov 4 23:51:31.856523 kernel: vgaarb: loaded Nov 4 23:51:31.856533 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 4 23:51:31.856542 kernel: VFS: Disk quotas dquot_6.6.0 Nov 4 23:51:31.856551 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 4 23:51:31.856561 kernel: pnp: PnP ACPI init Nov 4 23:51:31.856570 kernel: pnp: PnP ACPI: found 3 devices Nov 4 23:51:31.856582 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 4 23:51:31.856591 kernel: NET: Registered PF_INET protocol family Nov 4 23:51:31.856601 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 4 23:51:31.856611 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 4 23:51:31.856620 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 4 23:51:31.856630 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 4 23:51:31.856639 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 23:51:31.856651 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 4 23:51:31.856661 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 23:51:31.856670 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 23:51:31.856680 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 4 23:51:31.856689 kernel: NET: Registered PF_XDP protocol family Nov 4 23:51:31.856699 kernel: PCI: CLS 0 bytes, default 64 Nov 4 23:51:31.856708 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 4 23:51:31.856720 kernel: software IO TLB: mapped [mem 0x000000003ae60000-0x000000003ee60000] (64MB) Nov 4 23:51:31.856730 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 4 23:51:31.856739 kernel: Initialise system trusted keyrings Nov 4 23:51:31.856748 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 4 23:51:31.856758 kernel: Key type asymmetric registered Nov 4 23:51:31.856767 kernel: Asymmetric key parser 'x509' registered Nov 4 23:51:31.856776 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 4 23:51:31.856788 kernel: io scheduler mq-deadline registered Nov 4 23:51:31.856797 kernel: io scheduler kyber registered Nov 4 23:51:31.856807 kernel: io scheduler bfq registered Nov 4 23:51:31.856816 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 4 23:51:31.856838 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 4 23:51:31.856847 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 4 23:51:31.856857 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 4 23:51:31.856870 kernel: i8042: PNP: No PS/2 controller found. Nov 4 23:51:31.857071 kernel: rtc_cmos 00:02: registered as rtc0 Nov 4 23:51:31.857213 kernel: rtc_cmos 00:02: setting system clock to 2025-11-04T23:51:27 UTC (1762300287) Nov 4 23:51:31.857348 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 4 23:51:31.857362 kernel: intel_pstate: CPU model not supported Nov 4 23:51:31.857371 kernel: efifb: probing for efifb Nov 4 23:51:31.857386 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 4 23:51:31.857395 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 4 23:51:31.857405 kernel: efifb: scrolling: redraw Nov 4 23:51:31.857414 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 4 23:51:31.858499 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 23:51:31.858513 kernel: fb0: EFI VGA frame buffer device Nov 4 23:51:31.858523 kernel: pstore: Using crash dump compression: deflate Nov 4 23:51:31.858532 kernel: pstore: Registered efi_pstore as persistent store backend Nov 4 23:51:31.858548 kernel: NET: Registered PF_INET6 protocol family Nov 4 23:51:31.858557 kernel: Segment Routing with IPv6 Nov 4 23:51:31.858567 kernel: In-situ OAM (IOAM) with IPv6 Nov 4 23:51:31.858576 kernel: NET: Registered PF_PACKET protocol family Nov 4 23:51:31.858586 kernel: Key type dns_resolver registered Nov 4 23:51:31.858595 kernel: IPI shorthand broadcast: enabled Nov 4 23:51:31.858605 kernel: sched_clock: Marking stable (2042005600, 111837200)->(2507417900, -353575100) Nov 4 23:51:31.858617 kernel: registered taskstats version 1 Nov 4 23:51:31.858626 kernel: Loading compiled-in X.509 certificates Nov 4 23:51:31.858636 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: ace064fb6689a15889f35c6439909c760a72ef44' Nov 4 23:51:31.858645 kernel: Demotion targets for Node 0: null Nov 4 23:51:31.858655 kernel: Key type .fscrypt registered Nov 4 23:51:31.858664 kernel: Key type fscrypt-provisioning registered Nov 4 23:51:31.858673 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 4 23:51:31.858685 kernel: ima: Allocated hash algorithm: sha1 Nov 4 23:51:31.858695 kernel: ima: No architecture policies found Nov 4 23:51:31.858704 kernel: clk: Disabling unused clocks Nov 4 23:51:31.858713 kernel: Freeing unused kernel image (initmem) memory: 15936K Nov 4 23:51:31.858723 kernel: Write protecting the kernel read-only data: 40960k Nov 4 23:51:31.858733 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Nov 4 23:51:31.858742 kernel: Run /init as init process Nov 4 23:51:31.858754 kernel: with arguments: Nov 4 23:51:31.858763 kernel: /init Nov 4 23:51:31.858773 kernel: with environment: Nov 4 23:51:31.858782 kernel: HOME=/ Nov 4 23:51:31.858791 kernel: TERM=linux Nov 4 23:51:31.858800 kernel: hv_vmbus: Vmbus version:4.0 Nov 4 23:51:31.858810 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 4 23:51:31.858827 kernel: hv_vmbus: registering driver hid_hyperv Nov 4 23:51:31.858840 kernel: SCSI subsystem initialized Nov 4 23:51:31.858849 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 4 23:51:31.859036 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 4 23:51:31.859053 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 4 23:51:31.859063 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 23:51:31.859073 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 4 23:51:31.859087 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 4 23:51:31.859096 kernel: PTP clock support registered Nov 4 23:51:31.859105 kernel: hv_utils: Registering HyperV Utility Driver Nov 4 23:51:31.859115 kernel: hv_vmbus: registering driver hv_utils Nov 4 23:51:31.859124 kernel: hv_utils: Heartbeat IC version 3.0 Nov 4 23:51:31.859134 kernel: hv_utils: Shutdown IC version 3.2 Nov 4 23:51:31.859143 kernel: hv_utils: TimeSync IC version 4.0 Nov 4 23:51:31.859155 kernel: hv_vmbus: registering driver hv_storvsc Nov 4 23:51:31.859342 kernel: scsi host0: storvsc_host_t Nov 4 23:51:31.859506 kernel: scsi host1: storvsc_host_t Nov 4 23:51:31.859678 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 23:51:31.859871 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 23:51:31.860059 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 4 23:51:31.860221 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 4 23:51:31.860379 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 4 23:51:31.860537 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 4 23:51:31.860693 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 4 23:51:31.860884 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 4 23:51:31.861056 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 4 23:51:31.861213 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 4 23:51:31.861369 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 4 23:51:31.861525 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 23:51:31.861682 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 23:51:31.861868 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#69 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:31.862020 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#515 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:31.862169 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#76 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:31.862313 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#522 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:31.862327 kernel: sdb: sdb1 Nov 4 23:51:31.862481 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 4 23:51:31.862500 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 4 23:51:31.862655 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 4 23:51:31.862669 kernel: hv_vmbus: registering driver hv_pci Nov 4 23:51:31.862835 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 4 23:51:31.862986 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 4 23:51:31.863151 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 4 23:51:31.863304 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 4 23:51:31.863458 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 23:51:31.863651 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 4 23:51:31.863826 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 4 23:51:31.863993 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 4 23:51:31.864156 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 4 23:51:31.864171 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 4 23:51:31.864322 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 4 23:51:31.864337 kernel: device-mapper: uevent: version 1.0.3 Nov 4 23:51:31.864347 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 4 23:51:31.864491 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 23:51:31.864653 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 4 23:51:31.864811 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 4 23:51:31.864836 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 4 23:51:31.864992 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 4 23:51:31.865154 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 4 23:51:31.865169 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 4 23:51:31.865330 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 4 23:51:31.865344 kernel: raid6: avx2x4 gen() 30818 MB/s Nov 4 23:51:31.865354 kernel: raid6: avx2x2 gen() 30716 MB/s Nov 4 23:51:31.865364 kernel: raid6: avx2x1 gen() 26314 MB/s Nov 4 23:51:31.865374 kernel: raid6: using algorithm avx2x4 gen() 30818 MB/s Nov 4 23:51:31.865384 kernel: raid6: .... xor() 10104 MB/s, rmw enabled Nov 4 23:51:31.865394 kernel: raid6: using avx2x2 recovery algorithm Nov 4 23:51:31.865407 kernel: xor: automatically using best checksumming function avx Nov 4 23:51:31.865417 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 4 23:51:31.865430 kernel: BTRFS: device fsid f719dc90-1cf7-4f08-a80f-0dda441372cc devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (483) Nov 4 23:51:31.865440 kernel: BTRFS info (device dm-0): first mount of filesystem f719dc90-1cf7-4f08-a80f-0dda441372cc Nov 4 23:51:31.865450 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 4 23:51:31.865460 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 4 23:51:31.865470 kernel: BTRFS info (device dm-0): enabling free space tree Nov 4 23:51:31.865482 kernel: loop: module loaded Nov 4 23:51:31.865492 kernel: loop0: detected capacity change from 0 to 100120 Nov 4 23:51:31.865502 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 4 23:51:31.865513 systemd[1]: Successfully made /usr/ read-only. Nov 4 23:51:31.865526 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 23:51:31.865537 systemd[1]: Detected virtualization microsoft. Nov 4 23:51:31.865550 systemd[1]: Detected architecture x86-64. Nov 4 23:51:31.865560 systemd[1]: Running in initrd. Nov 4 23:51:31.865570 systemd[1]: No hostname configured, using default hostname. Nov 4 23:51:31.865580 systemd[1]: Hostname set to . Nov 4 23:51:31.865590 systemd[1]: Initializing machine ID from random generator. Nov 4 23:51:31.865600 systemd[1]: Queued start job for default target initrd.target. Nov 4 23:51:31.865610 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 23:51:31.865623 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:51:31.865633 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:51:31.865644 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 4 23:51:31.865655 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 23:51:31.865666 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 4 23:51:31.865679 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 4 23:51:31.865690 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:51:31.865702 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:51:31.865713 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 4 23:51:31.865726 systemd[1]: Reached target paths.target - Path Units. Nov 4 23:51:31.865736 systemd[1]: Reached target slices.target - Slice Units. Nov 4 23:51:31.865747 systemd[1]: Reached target swap.target - Swaps. Nov 4 23:51:31.865757 systemd[1]: Reached target timers.target - Timer Units. Nov 4 23:51:31.865767 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 23:51:31.865777 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 23:51:31.865788 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 4 23:51:31.865801 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 4 23:51:31.865811 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:51:31.865832 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 23:51:31.865843 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:51:31.865853 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 23:51:31.865864 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 4 23:51:31.865874 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 4 23:51:31.865887 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 23:51:31.865898 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 4 23:51:31.865909 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 23:51:31.865919 systemd[1]: Starting systemd-fsck-usr.service... Nov 4 23:51:31.865929 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 23:51:31.865939 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 23:51:31.866384 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:51:31.866400 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 4 23:51:31.866411 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:51:31.866421 systemd[1]: Finished systemd-fsck-usr.service. Nov 4 23:51:31.866437 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 23:51:31.866475 systemd-journald[619]: Collecting audit messages is disabled. Nov 4 23:51:31.866501 systemd-journald[619]: Journal started Nov 4 23:51:31.866525 systemd-journald[619]: Runtime Journal (/run/log/journal/f8e1658628b0442cb11f50606dffbd98) is 8M, max 2.2G, 2.1G free. Nov 4 23:51:31.874848 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 23:51:31.891262 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 23:51:31.986842 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 23:51:31.994644 systemd-tmpfiles[632]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 4 23:51:32.001201 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 23:51:32.004833 kernel: Bridge firewalling registered Nov 4 23:51:32.006037 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 23:51:32.023853 systemd-modules-load[622]: Inserted module 'br_netfilter' Nov 4 23:51:32.030004 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 23:51:32.032421 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 23:51:32.052239 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:51:32.067786 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:51:32.082510 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:51:32.085514 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 23:51:32.101616 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:32.104473 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 4 23:51:32.137606 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 23:51:32.140381 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 4 23:51:32.237328 systemd-resolved[648]: Positive Trust Anchors: Nov 4 23:51:32.237349 systemd-resolved[648]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 23:51:32.246418 dracut-cmdline[662]: 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=c57c40de146020da5f35a7230cc1da8f1a5a7a7af49d0754317609f7e94976e2 Nov 4 23:51:32.237354 systemd-resolved[648]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 23:51:32.237387 systemd-resolved[648]: 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 23:51:32.253509 systemd-resolved[648]: Defaulting to hostname 'linux'. Nov 4 23:51:32.254652 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 23:51:32.273852 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:51:32.481853 kernel: Loading iSCSI transport class v2.0-870. Nov 4 23:51:32.569848 kernel: iscsi: registered transport (tcp) Nov 4 23:51:32.680534 kernel: iscsi: registered transport (qla4xxx) Nov 4 23:51:32.680604 kernel: QLogic iSCSI HBA Driver Nov 4 23:51:32.766540 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 23:51:32.802385 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:51:32.805063 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 23:51:32.869766 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 4 23:51:32.879264 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 4 23:51:32.881535 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 4 23:51:32.925986 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 4 23:51:32.929035 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:51:32.972093 systemd-udevd[901]: Using default interface naming scheme 'v257'. Nov 4 23:51:32.983361 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:51:32.994465 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 4 23:51:33.013945 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 23:51:33.024438 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 23:51:33.030075 dracut-pre-trigger[996]: rd.md=0: removing MD RAID activation Nov 4 23:51:33.057778 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 23:51:33.060504 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 23:51:33.085561 systemd-networkd[1001]: lo: Link UP Nov 4 23:51:33.085571 systemd-networkd[1001]: lo: Gained carrier Nov 4 23:51:33.086132 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 23:51:33.092767 systemd[1]: Reached target network.target - Network. Nov 4 23:51:33.155277 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:51:33.158157 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 4 23:51:33.243846 kernel: cryptd: max_cpu_qlen set to 1000 Nov 4 23:51:33.268841 kernel: hv_vmbus: registering driver hv_netvsc Nov 4 23:51:33.276196 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:51:33.276274 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:33.285028 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:51:33.294619 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:51:33.316875 kernel: AES CTR mode by8 optimization enabled Nov 4 23:51:33.332587 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:33.553321 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 4 23:51:33.564760 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 4 23:51:33.705362 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 23:51:33.718244 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 4 23:51:33.731501 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 4 23:51:34.971053 disk-uuid[1179]: Warning: The kernel is still using the old partition table. Nov 4 23:51:34.971053 disk-uuid[1179]: The new table will be used at the next reboot or after you Nov 4 23:51:34.971053 disk-uuid[1179]: run partprobe(8) or kpartx(8) Nov 4 23:51:34.971053 disk-uuid[1179]: The operation has completed successfully. Nov 4 23:51:36.561599 systemd-networkd[1001]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:51:36.561611 systemd-networkd[1001]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:51:36.562740 systemd-networkd[1001]: eth0: Link UP Nov 4 23:51:36.562959 systemd-networkd[1001]: eth0: Gained carrier Nov 4 23:51:36.562970 systemd-networkd[1001]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:51:36.597878 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 4 23:51:36.597997 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 4 23:51:36.600773 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 4 23:51:36.621868 systemd-networkd[1001]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 23:51:36.630122 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 4 23:51:36.632575 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 23:51:36.640153 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:51:36.650099 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 23:51:36.659970 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 4 23:51:36.708162 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 4 23:51:36.727164 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1333) Nov 4 23:51:36.727194 kernel: BTRFS info (device sda6): first mount of filesystem c1921af5-b472-4b94-8690-4d6daf91a8cd Nov 4 23:51:36.727209 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 23:51:36.745746 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:51:36.745777 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:51:36.755839 kernel: BTRFS info (device sda6): last unmount of filesystem c1921af5-b472-4b94-8690-4d6daf91a8cd Nov 4 23:51:36.756629 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 4 23:51:36.759154 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 4 23:51:37.913003 ignition[1359]: Ignition 2.22.0 Nov 4 23:51:37.913018 ignition[1359]: Stage: fetch-offline Nov 4 23:51:37.913301 ignition[1359]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:37.913315 ignition[1359]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:37.913421 ignition[1359]: parsed url from cmdline: "" Nov 4 23:51:37.923712 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 23:51:37.915714 ignition[1359]: no config URL provided Nov 4 23:51:37.929017 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 4 23:51:37.915730 ignition[1359]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 23:51:37.942039 systemd-networkd[1001]: eth0: Gained IPv6LL Nov 4 23:51:37.915744 ignition[1359]: no config at "/usr/lib/ignition/user.ign" Nov 4 23:51:37.915750 ignition[1359]: failed to fetch config: resource requires networking Nov 4 23:51:37.921955 ignition[1359]: Ignition finished successfully Nov 4 23:51:37.977528 ignition[1369]: Ignition 2.22.0 Nov 4 23:51:37.977543 ignition[1369]: Stage: fetch Nov 4 23:51:37.977787 ignition[1369]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:37.977798 ignition[1369]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:37.977902 ignition[1369]: parsed url from cmdline: "" Nov 4 23:51:37.977905 ignition[1369]: no config URL provided Nov 4 23:51:37.977911 ignition[1369]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 23:51:37.977919 ignition[1369]: no config at "/usr/lib/ignition/user.ign" Nov 4 23:51:37.977937 ignition[1369]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 4 23:51:38.047267 ignition[1369]: GET result: OK Nov 4 23:51:38.048972 ignition[1369]: config has been read from IMDS userdata Nov 4 23:51:38.048991 ignition[1369]: parsing config with SHA512: 5214e7ad3a3f5993860ea568e0672b6081ef88538ea4ecc61debeb0c28e2b543a4f78e7767d9bdbba23757e961b0bc9cc6f9c10899e6b1081a466d86907d0d68 Nov 4 23:51:38.052225 unknown[1369]: fetched base config from "system" Nov 4 23:51:38.052401 ignition[1369]: fetch: fetch complete Nov 4 23:51:38.052234 unknown[1369]: fetched base config from "system" Nov 4 23:51:38.052406 ignition[1369]: fetch: fetch passed Nov 4 23:51:38.052239 unknown[1369]: fetched user config from "azure" Nov 4 23:51:38.052452 ignition[1369]: Ignition finished successfully Nov 4 23:51:38.055793 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 4 23:51:38.060366 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 4 23:51:38.104494 ignition[1380]: Ignition 2.22.0 Nov 4 23:51:38.104508 ignition[1380]: Stage: kargs Nov 4 23:51:38.104727 ignition[1380]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:38.104738 ignition[1380]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:38.105190 ignition[1380]: kargs: kargs passed Nov 4 23:51:38.105235 ignition[1380]: Ignition finished successfully Nov 4 23:51:38.118391 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 4 23:51:38.125664 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 4 23:51:38.168255 ignition[1390]: Ignition 2.22.0 Nov 4 23:51:38.168270 ignition[1390]: Stage: disks Nov 4 23:51:38.168520 ignition[1390]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:38.168531 ignition[1390]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:38.174377 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 4 23:51:38.169209 ignition[1390]: disks: disks passed Nov 4 23:51:38.174727 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 4 23:51:38.169253 ignition[1390]: Ignition finished successfully Nov 4 23:51:38.181828 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 4 23:51:38.193950 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 23:51:38.195450 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 23:51:38.205636 systemd[1]: Reached target basic.target - Basic System. Nov 4 23:51:38.236572 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 4 23:51:38.369425 systemd-fsck[1401]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Nov 4 23:51:38.375097 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 4 23:51:38.377860 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 4 23:51:38.775844 kernel: EXT4-fs (sda9): mounted filesystem cfb29ed0-6faf-41a8-b421-3abc514e4975 r/w with ordered data mode. Quota mode: none. Nov 4 23:51:38.776748 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 4 23:51:38.778833 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 4 23:51:38.809105 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 23:51:38.812026 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 4 23:51:38.832414 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 4 23:51:38.833900 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 4 23:51:38.875310 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1415) Nov 4 23:51:38.875343 kernel: BTRFS info (device sda6): first mount of filesystem c1921af5-b472-4b94-8690-4d6daf91a8cd Nov 4 23:51:38.875358 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 23:51:38.875371 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:51:38.875384 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:51:38.833935 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 23:51:38.859307 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 4 23:51:38.878076 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 23:51:38.888026 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 4 23:51:39.614892 coreos-metadata[1417]: Nov 04 23:51:39.614 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 23:51:39.621046 coreos-metadata[1417]: Nov 04 23:51:39.617 INFO Fetch successful Nov 4 23:51:39.621046 coreos-metadata[1417]: Nov 04 23:51:39.617 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 4 23:51:39.633579 coreos-metadata[1417]: Nov 04 23:51:39.624 INFO Fetch successful Nov 4 23:51:39.637614 coreos-metadata[1417]: Nov 04 23:51:39.637 INFO wrote hostname ci-4487.0.0-n-c26c48936b to /sysroot/etc/hostname Nov 4 23:51:39.645314 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 23:51:39.869275 initrd-setup-root[1446]: cut: /sysroot/etc/passwd: No such file or directory Nov 4 23:51:39.971262 initrd-setup-root[1453]: cut: /sysroot/etc/group: No such file or directory Nov 4 23:51:40.036305 initrd-setup-root[1460]: cut: /sysroot/etc/shadow: No such file or directory Nov 4 23:51:40.043666 initrd-setup-root[1467]: cut: /sysroot/etc/gshadow: No such file or directory Nov 4 23:51:41.066105 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 4 23:51:41.069692 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 4 23:51:41.088426 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 4 23:51:41.111084 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 4 23:51:41.120549 kernel: BTRFS info (device sda6): last unmount of filesystem c1921af5-b472-4b94-8690-4d6daf91a8cd Nov 4 23:51:41.137941 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 4 23:51:41.162726 ignition[1536]: INFO : Ignition 2.22.0 Nov 4 23:51:41.162726 ignition[1536]: INFO : Stage: mount Nov 4 23:51:41.168952 ignition[1536]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:41.168952 ignition[1536]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:41.168952 ignition[1536]: INFO : mount: mount passed Nov 4 23:51:41.168952 ignition[1536]: INFO : Ignition finished successfully Nov 4 23:51:41.179215 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 4 23:51:41.181188 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 4 23:51:41.211740 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 23:51:41.239348 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1551) Nov 4 23:51:41.239389 kernel: BTRFS info (device sda6): first mount of filesystem c1921af5-b472-4b94-8690-4d6daf91a8cd Nov 4 23:51:41.243724 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 4 23:51:41.253373 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:51:41.253400 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:51:41.256506 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 23:51:41.301611 ignition[1568]: INFO : Ignition 2.22.0 Nov 4 23:51:41.301611 ignition[1568]: INFO : Stage: files Nov 4 23:51:41.308015 ignition[1568]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:41.308015 ignition[1568]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:41.308015 ignition[1568]: DEBUG : files: compiled without relabeling support, skipping Nov 4 23:51:41.308015 ignition[1568]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 4 23:51:41.308015 ignition[1568]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 4 23:51:41.354802 ignition[1568]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 4 23:51:41.361761 ignition[1568]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 4 23:51:41.367315 unknown[1568]: wrote ssh authorized keys file for user: core Nov 4 23:51:41.371267 ignition[1568]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 4 23:51:41.401154 ignition[1568]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 23:51:41.408678 ignition[1568]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 23:51:41.408678 ignition[1568]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 4 23:51:41.408678 ignition[1568]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 4 23:51:41.408678 ignition[1568]: INFO : files: files passed Nov 4 23:51:41.408678 ignition[1568]: INFO : Ignition finished successfully Nov 4 23:51:41.417598 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 4 23:51:41.425453 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 4 23:51:41.454109 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 4 23:51:41.457626 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 4 23:51:41.457726 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 4 23:51:41.492112 initrd-setup-root-after-ignition[1604]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:51:41.492112 initrd-setup-root-after-ignition[1604]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:51:41.509538 initrd-setup-root-after-ignition[1608]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:51:41.494096 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 23:51:41.499925 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 4 23:51:41.511778 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 4 23:51:41.555252 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 4 23:51:41.555360 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 4 23:51:41.564123 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 4 23:51:41.575068 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 4 23:51:41.576806 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 4 23:51:41.577765 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 4 23:51:41.609141 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 23:51:41.620258 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 4 23:51:41.653689 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 23:51:41.653966 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:51:41.663290 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:51:41.672437 systemd[1]: Stopped target timers.target - Timer Units. Nov 4 23:51:41.680590 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 4 23:51:41.680751 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 23:51:41.694467 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 4 23:51:41.704790 systemd[1]: Stopped target basic.target - Basic System. Nov 4 23:51:41.712317 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 4 23:51:41.720227 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 23:51:41.729077 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 4 23:51:41.738020 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 4 23:51:41.746893 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 4 23:51:41.755123 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 23:51:41.758261 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 4 23:51:41.774860 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 4 23:51:41.786433 systemd[1]: Stopped target swap.target - Swaps. Nov 4 23:51:41.793562 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 4 23:51:41.793726 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 4 23:51:41.804650 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:51:41.813340 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:51:41.822548 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 4 23:51:41.822696 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:51:41.832356 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 4 23:51:41.832477 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 4 23:51:41.846202 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 4 23:51:41.846337 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 23:51:41.854941 systemd[1]: ignition-files.service: Deactivated successfully. Nov 4 23:51:41.855056 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 4 23:51:41.862771 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 4 23:51:41.862913 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 23:51:41.874133 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 4 23:51:41.881686 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 4 23:51:41.919634 ignition[1628]: INFO : Ignition 2.22.0 Nov 4 23:51:41.919634 ignition[1628]: INFO : Stage: umount Nov 4 23:51:41.881829 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:51:41.939529 ignition[1628]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:51:41.939529 ignition[1628]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:51:41.939529 ignition[1628]: INFO : umount: umount passed Nov 4 23:51:41.939529 ignition[1628]: INFO : Ignition finished successfully Nov 4 23:51:41.902428 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 4 23:51:41.904729 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 4 23:51:41.904881 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:51:41.912601 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 4 23:51:41.912729 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:51:41.921302 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 4 23:51:41.921426 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 23:51:41.938347 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 4 23:51:41.938444 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 4 23:51:41.941182 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 4 23:51:41.941273 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 4 23:51:41.950435 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 4 23:51:41.950492 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 4 23:51:41.957742 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 4 23:51:41.957796 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 4 23:51:41.968379 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 4 23:51:41.968438 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 4 23:51:41.976240 systemd[1]: Stopped target network.target - Network. Nov 4 23:51:41.983444 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 4 23:51:41.983510 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 23:51:41.985874 systemd[1]: Stopped target paths.target - Path Units. Nov 4 23:51:41.993728 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 4 23:51:42.004882 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:51:42.005671 systemd[1]: Stopped target slices.target - Slice Units. Nov 4 23:51:42.018170 systemd[1]: Stopped target sockets.target - Socket Units. Nov 4 23:51:42.025787 systemd[1]: iscsid.socket: Deactivated successfully. Nov 4 23:51:42.025862 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 23:51:42.034097 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 4 23:51:42.034147 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 23:51:42.065104 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 4 23:51:42.065209 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 4 23:51:42.073647 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 4 23:51:42.073699 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 4 23:51:42.081530 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 4 23:51:42.082843 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 4 23:51:42.093575 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 4 23:51:42.094523 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 4 23:51:42.094623 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 4 23:51:42.100047 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 4 23:51:42.100145 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 4 23:51:42.113075 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 4 23:51:42.113137 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 4 23:51:42.137316 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 4 23:51:42.137439 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 4 23:51:42.150258 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 4 23:51:42.153632 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 4 23:51:42.153671 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:51:42.169049 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 4 23:51:42.175230 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 4 23:51:42.175289 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 23:51:42.183633 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 4 23:51:42.183687 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:51:42.191133 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 4 23:51:42.191181 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 4 23:51:42.199329 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:51:42.235970 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 4 23:51:42.236107 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:51:42.244848 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 4 23:51:42.244905 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 4 23:51:42.252270 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 4 23:51:42.252308 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:51:42.260238 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 4 23:51:42.260294 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 4 23:51:42.272090 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 4 23:51:42.272144 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 4 23:51:42.285123 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 4 23:51:42.285179 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 23:51:42.298602 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 4 23:51:42.306330 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 4 23:51:42.306385 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:51:42.315073 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 4 23:51:42.315119 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:51:42.324211 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:51:42.324261 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:42.344691 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 4 23:51:42.344852 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 4 23:51:42.365524 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 4 23:51:42.365636 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 4 23:51:42.487746 systemd-journald[619]: Received SIGTERM from PID 1 (systemd). Nov 4 23:51:42.370019 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 4 23:51:42.377434 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 4 23:51:42.412863 systemd[1]: Switching root. Nov 4 23:51:42.498890 systemd-journald[619]: Journal stopped Nov 4 23:51:46.907987 kernel: SELinux: policy capability network_peer_controls=1 Nov 4 23:51:46.908021 kernel: SELinux: policy capability open_perms=1 Nov 4 23:51:46.908034 kernel: SELinux: policy capability extended_socket_class=1 Nov 4 23:51:46.908044 kernel: SELinux: policy capability always_check_network=0 Nov 4 23:51:46.908054 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 4 23:51:46.908064 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 4 23:51:46.908082 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 4 23:51:46.908096 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 4 23:51:46.908107 kernel: SELinux: policy capability userspace_initial_context=0 Nov 4 23:51:46.908117 kernel: audit: type=1403 audit(1762300303.293:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 4 23:51:46.908129 systemd[1]: Successfully loaded SELinux policy in 248.825ms. Nov 4 23:51:46.908142 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.631ms. Nov 4 23:51:46.908157 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 23:51:46.908168 systemd[1]: Detected virtualization microsoft. Nov 4 23:51:46.908180 systemd[1]: Detected architecture x86-64. Nov 4 23:51:46.908191 systemd[1]: Detected first boot. Nov 4 23:51:46.908206 systemd[1]: Hostname set to . Nov 4 23:51:46.908218 systemd[1]: Initializing machine ID from random generator. Nov 4 23:51:46.908229 zram_generator::config[1674]: No configuration found. Nov 4 23:51:46.908241 kernel: Guest personality initialized and is inactive Nov 4 23:51:46.908251 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 4 23:51:46.908262 kernel: Initialized host personality Nov 4 23:51:46.908275 kernel: NET: Registered PF_VSOCK protocol family Nov 4 23:51:46.908286 systemd[1]: Populated /etc with preset unit settings. Nov 4 23:51:46.908297 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 4 23:51:46.908309 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 4 23:51:46.908320 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 4 23:51:46.908336 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 4 23:51:46.908350 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 4 23:51:46.908363 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 4 23:51:46.908374 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 4 23:51:46.908386 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 4 23:51:46.908398 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 4 23:51:46.908410 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 4 23:51:46.908424 systemd[1]: Created slice user.slice - User and Session Slice. Nov 4 23:51:46.908436 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:51:46.908447 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:51:46.908459 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 4 23:51:46.908471 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 4 23:51:46.908483 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 4 23:51:46.908499 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 23:51:46.908513 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 4 23:51:46.908526 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:51:46.908537 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:51:46.908549 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 4 23:51:46.908561 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 4 23:51:46.908576 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 4 23:51:46.908588 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 4 23:51:46.908603 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:51:46.908615 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 23:51:46.908627 systemd[1]: Reached target slices.target - Slice Units. Nov 4 23:51:46.908639 systemd[1]: Reached target swap.target - Swaps. Nov 4 23:51:46.908651 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 4 23:51:46.908666 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 4 23:51:46.908678 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 4 23:51:46.908690 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:51:46.908702 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 23:51:46.908717 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:51:46.908729 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 4 23:51:46.908741 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 4 23:51:46.908753 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 4 23:51:46.908765 systemd[1]: Mounting media.mount - External Media Directory... Nov 4 23:51:46.908777 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 23:51:46.908792 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 4 23:51:46.908804 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 4 23:51:46.908816 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 4 23:51:46.908840 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 23:51:46.908852 systemd[1]: Reached target machines.target - Containers. Nov 4 23:51:46.908864 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 4 23:51:46.908880 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:51:46.908896 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 23:51:46.908909 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 4 23:51:46.908921 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:51:46.908933 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 23:51:46.908945 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:51:46.908957 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 4 23:51:46.908970 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:51:46.908985 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 4 23:51:46.908997 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 4 23:51:46.909010 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 4 23:51:46.909022 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 4 23:51:46.909033 systemd[1]: Stopped systemd-fsck-usr.service. Nov 4 23:51:46.909046 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 23:51:46.909061 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 23:51:46.909073 kernel: fuse: init (API version 7.41) Nov 4 23:51:46.909084 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 23:51:46.909097 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 23:51:46.909109 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 4 23:51:46.909121 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 4 23:51:46.909133 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 23:51:46.909152 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 23:51:46.909184 systemd-journald[1781]: Collecting audit messages is disabled. Nov 4 23:51:46.909210 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 4 23:51:46.909226 systemd-journald[1781]: Journal started Nov 4 23:51:46.909249 systemd-journald[1781]: Runtime Journal (/run/log/journal/da53251cecff41189a13149341285935) is 8M, max 2.2G, 2.1G free. Nov 4 23:51:46.142713 systemd[1]: Queued start job for default target multi-user.target. Nov 4 23:51:46.167254 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 4 23:51:46.167850 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 4 23:51:46.917368 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 23:51:46.922607 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 4 23:51:46.927306 systemd[1]: Mounted media.mount - External Media Directory. Nov 4 23:51:46.932346 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 4 23:51:46.937271 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 4 23:51:46.941982 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 4 23:51:46.946562 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 4 23:51:46.950839 kernel: ACPI: bus type drm_connector registered Nov 4 23:51:46.957140 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:51:46.963045 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 4 23:51:46.963410 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 4 23:51:46.968691 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:51:46.968941 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:51:46.974111 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 23:51:46.974349 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 23:51:46.979279 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:51:46.979504 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:51:46.985319 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 4 23:51:46.985536 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 4 23:51:46.990329 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:51:46.990566 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:51:46.995411 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 23:51:47.000463 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:51:47.007052 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 4 23:51:47.012660 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 4 23:51:47.023651 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:51:47.034850 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 23:51:47.039935 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 4 23:51:47.046893 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 4 23:51:47.064240 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 4 23:51:47.068775 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 4 23:51:47.068814 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 23:51:47.074507 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 4 23:51:47.080894 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:51:47.082066 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 4 23:51:47.087405 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 4 23:51:47.092215 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 23:51:47.093431 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 4 23:51:47.098142 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 23:51:47.099206 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 23:51:47.104649 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 4 23:51:47.110943 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 4 23:51:47.117195 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 4 23:51:47.122183 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 4 23:51:47.127356 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 4 23:51:47.133694 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 4 23:51:47.139225 systemd-journald[1781]: Time spent on flushing to /var/log/journal/da53251cecff41189a13149341285935 is 14.001ms for 930 entries. Nov 4 23:51:47.139225 systemd-journald[1781]: System Journal (/var/log/journal/da53251cecff41189a13149341285935) is 8M, max 2.2G, 2.2G free. Nov 4 23:51:47.195631 systemd-journald[1781]: Received client request to flush runtime journal. Nov 4 23:51:47.195698 kernel: loop1: detected capacity change from 0 to 27752 Nov 4 23:51:47.140323 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 4 23:51:47.190749 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:51:47.197118 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 4 23:51:47.209005 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 4 23:51:47.209665 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 4 23:51:47.262274 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 4 23:51:47.271052 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 23:51:47.277054 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 23:51:47.345288 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 4 23:51:47.350184 systemd-tmpfiles[1831]: ACLs are not supported, ignoring. Nov 4 23:51:47.350205 systemd-tmpfiles[1831]: ACLs are not supported, ignoring. Nov 4 23:51:47.354392 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:51:47.423038 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 4 23:51:47.526688 systemd-resolved[1830]: Positive Trust Anchors: Nov 4 23:51:47.526707 systemd-resolved[1830]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 23:51:47.526713 systemd-resolved[1830]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 23:51:47.526744 systemd-resolved[1830]: 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 23:51:47.537840 kernel: loop2: detected capacity change from 0 to 110984 Nov 4 23:51:47.667123 systemd-resolved[1830]: Using system hostname 'ci-4487.0.0-n-c26c48936b'. Nov 4 23:51:47.670082 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 23:51:47.674941 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:51:47.796802 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 4 23:51:47.803799 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:51:47.856658 systemd-udevd[1843]: Using default interface naming scheme 'v257'. Nov 4 23:51:48.032800 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:51:48.040773 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 23:51:48.085276 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 4 23:51:48.120442 kernel: loop3: detected capacity change from 0 to 128048 Nov 4 23:51:48.173319 systemd-networkd[1855]: lo: Link UP Nov 4 23:51:48.174879 systemd-networkd[1855]: lo: Gained carrier Nov 4 23:51:48.176490 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 23:51:48.176505 systemd-networkd[1855]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:51:48.176510 systemd-networkd[1855]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:51:48.181788 systemd[1]: Reached target network.target - Network. Nov 4 23:51:48.181863 systemd-networkd[1855]: eth0: Link UP Nov 4 23:51:48.181868 systemd-networkd[1855]: eth0: Gained carrier Nov 4 23:51:48.185939 systemd-networkd[1855]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:51:48.187652 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 4 23:51:48.194036 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 4 23:51:48.198724 kernel: mousedev: PS/2 mouse device common for all mice Nov 4 23:51:48.212034 kernel: hv_vmbus: registering driver hyperv_fb Nov 4 23:51:48.220149 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 4 23:51:48.220204 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 4 23:51:48.223862 kernel: Console: switching to colour dummy device 80x25 Nov 4 23:51:48.223914 kernel: hv_vmbus: registering driver hv_balloon Nov 4 23:51:48.226838 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 4 23:51:48.233812 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 23:51:48.234839 kernel: EDAC MC: Ver: 3.0.0 Nov 4 23:51:48.244883 systemd-networkd[1855]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 23:51:48.250557 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:51:48.265599 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:51:48.265812 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:48.271655 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:51:48.299998 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 4 23:51:48.479843 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 23:51:48.482556 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 4 23:51:48.578360 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 4 23:51:48.847847 kernel: loop4: detected capacity change from 0 to 27752 Nov 4 23:51:48.864847 kernel: loop5: detected capacity change from 0 to 110984 Nov 4 23:51:48.881846 kernel: loop6: detected capacity change from 0 to 128048 Nov 4 23:51:48.894410 (sd-merge)[1986]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 4 23:51:48.898033 (sd-merge)[1986]: Merged extensions into '/usr'. Nov 4 23:51:48.901849 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 4 23:51:48.905090 systemd[1]: Starting ensure-sysext.service... Nov 4 23:51:48.906694 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 23:51:48.946715 systemd[1]: Reload requested from client PID 1988 ('systemctl') (unit ensure-sysext.service)... Nov 4 23:51:48.946733 systemd[1]: Reloading... Nov 4 23:51:48.957563 systemd-tmpfiles[1989]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 4 23:51:48.957607 systemd-tmpfiles[1989]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 4 23:51:48.957909 systemd-tmpfiles[1989]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 4 23:51:48.958250 systemd-tmpfiles[1989]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 4 23:51:48.959226 systemd-tmpfiles[1989]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 4 23:51:48.959499 systemd-tmpfiles[1989]: ACLs are not supported, ignoring. Nov 4 23:51:48.959624 systemd-tmpfiles[1989]: ACLs are not supported, ignoring. Nov 4 23:51:48.978832 systemd-tmpfiles[1989]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 23:51:48.978847 systemd-tmpfiles[1989]: Skipping /boot Nov 4 23:51:48.988232 systemd-tmpfiles[1989]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 23:51:48.988377 systemd-tmpfiles[1989]: Skipping /boot Nov 4 23:51:48.990894 zram_generator::config[2026]: No configuration found. Nov 4 23:51:49.271086 systemd[1]: Reloading finished in 323 ms. Nov 4 23:51:49.292324 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:51:49.323272 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:51:49.339641 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 23:51:49.341009 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 23:51:49.359181 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 4 23:51:49.364077 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:51:49.365274 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 4 23:51:49.370475 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:51:49.376304 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:51:49.382080 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:51:49.386891 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:51:49.387027 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 23:51:49.388269 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 4 23:51:49.393993 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 4 23:51:49.399882 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 23:51:49.402803 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:51:49.403013 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:51:49.408172 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:51:49.408359 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:51:49.413729 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:51:49.414189 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:51:49.425559 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 23:51:49.425814 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:51:49.427184 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:51:49.445389 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:51:49.453245 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:51:49.457630 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:51:49.457875 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 23:51:49.458053 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 23:51:49.460282 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:51:49.460485 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:51:49.468464 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:51:49.468652 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:51:49.474235 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:51:49.474413 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:51:49.480100 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 4 23:51:49.485798 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 4 23:51:49.499090 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 23:51:49.499352 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:51:49.500471 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:51:49.515148 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 23:51:49.520141 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:51:49.525800 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:51:49.529889 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:51:49.529941 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 23:51:49.530000 systemd[1]: Reached target time-set.target - System Time Set. Nov 4 23:51:49.534442 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 23:51:49.535445 systemd[1]: Finished ensure-sysext.service. Nov 4 23:51:49.539060 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:51:49.539248 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:51:49.544250 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 23:51:49.544435 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 23:51:49.549288 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:51:49.549471 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:51:49.554698 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:51:49.554886 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:51:49.563214 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 23:51:49.563301 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 23:51:49.909952 systemd-networkd[1855]: eth0: Gained IPv6LL Nov 4 23:51:49.912336 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 4 23:51:49.918298 systemd[1]: Reached target network-online.target - Network is Online. Nov 4 23:51:49.925675 augenrules[2137]: No rules Nov 4 23:51:49.926831 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 23:51:49.927074 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 23:51:50.629076 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 4 23:51:50.634431 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 23:51:55.829470 ldconfig[2087]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 23:51:55.841358 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 4 23:51:55.848085 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 4 23:51:55.877808 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 4 23:51:55.882625 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 23:51:55.887071 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 4 23:51:55.891995 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 23:51:55.897334 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 4 23:51:55.902627 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 4 23:51:55.907155 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 4 23:51:55.912265 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 4 23:51:55.917390 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 4 23:51:55.917434 systemd[1]: Reached target paths.target - Path Units. Nov 4 23:51:55.921185 systemd[1]: Reached target timers.target - Timer Units. Nov 4 23:51:55.926096 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 4 23:51:55.931869 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 4 23:51:55.938291 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 4 23:51:55.943554 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 4 23:51:55.948712 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 4 23:51:55.969477 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 4 23:51:55.974317 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 4 23:51:55.979800 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 4 23:51:55.985181 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 23:51:55.989169 systemd[1]: Reached target basic.target - Basic System. Nov 4 23:51:55.993038 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 4 23:51:55.993076 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 4 23:51:55.995547 systemd[1]: Starting chronyd.service - NTP client/server... Nov 4 23:51:55.999993 systemd[1]: Starting containerd.service - containerd container runtime... Nov 4 23:51:56.019669 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 4 23:51:56.025084 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 4 23:51:56.029900 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 4 23:51:56.035608 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 4 23:51:56.040979 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 4 23:51:56.045121 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 23:51:56.046462 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 4 23:51:56.047719 jq[2157]: false Nov 4 23:51:56.050886 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 23:51:56.051942 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 4 23:51:56.054416 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 23:51:56.055424 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 4 23:51:56.061431 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 4 23:51:56.066460 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 4 23:51:56.068948 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 4 23:51:56.076859 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 4 23:51:56.081958 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 4 23:51:56.082735 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 4 23:51:56.083490 systemd[1]: Starting update-engine.service - Update Engine... Nov 4 23:51:56.088109 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 4 23:51:56.089939 extend-filesystems[2158]: Found /dev/sda6 Nov 4 23:51:56.091924 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 4 23:51:56.094646 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 4 23:51:56.094888 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 4 23:51:56.095256 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 4 23:51:56.095460 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 4 23:51:56.100714 KVP[2163]: KVP starting; pid is:2163 Nov 4 23:51:56.101293 chronyd[2149]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 4 23:51:56.103531 chronyd[2149]: Timezone right/UTC failed leap second check, ignoring Nov 4 23:51:56.106044 jq[2173]: true Nov 4 23:51:56.103686 chronyd[2149]: Loaded seccomp filter (level 2) Nov 4 23:51:56.105832 KVP[2163]: KVP LIC Version: 3.1 Nov 4 23:51:56.106841 kernel: hv_utils: KVP IC version 4.0 Nov 4 23:51:56.109459 systemd[1]: Started chronyd.service - NTP client/server. Nov 4 23:51:56.123324 jq[2180]: true Nov 4 23:51:56.137050 systemd[1]: motdgen.service: Deactivated successfully. Nov 4 23:51:56.137936 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 4 23:51:56.144968 extend-filesystems[2158]: Found /dev/sda9 Nov 4 23:51:56.149369 extend-filesystems[2158]: Checking size of /dev/sda9 Nov 4 23:51:56.145309 (ntainerd)[2198]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 4 23:51:56.153105 update_engine[2170]: I20251104 23:51:56.152559 2170 main.cc:92] Flatcar Update Engine starting Nov 4 23:51:56.156522 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Refreshing passwd entry cache Nov 4 23:51:56.156527 oslogin_cache_refresh[2159]: Refreshing passwd entry cache Nov 4 23:51:56.166604 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Failure getting users, quitting Nov 4 23:51:56.166604 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 23:51:56.166586 oslogin_cache_refresh[2159]: Failure getting users, quitting Nov 4 23:51:56.166756 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Refreshing group entry cache Nov 4 23:51:56.166603 oslogin_cache_refresh[2159]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 23:51:56.166646 oslogin_cache_refresh[2159]: Refreshing group entry cache Nov 4 23:51:56.171015 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Failure getting groups, quitting Nov 4 23:51:56.171015 google_oslogin_nss_cache[2159]: oslogin_cache_refresh[2159]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 23:51:56.171005 oslogin_cache_refresh[2159]: Failure getting groups, quitting Nov 4 23:51:56.171018 oslogin_cache_refresh[2159]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 23:51:56.175556 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 4 23:51:56.175878 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 4 23:51:56.179792 extend-filesystems[2158]: Resized partition /dev/sda9 Nov 4 23:51:56.214035 systemd-logind[2168]: New seat seat0. Nov 4 23:51:56.215416 systemd-logind[2168]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 4 23:51:56.216934 systemd[1]: Started systemd-logind.service - User Login Management. Nov 4 23:51:56.220841 extend-filesystems[2223]: resize2fs 1.47.3 (8-Jul-2025) Nov 4 23:51:56.257937 setup-nvidia[2165]: Downloading Flatcar Container Linux Developer Container for version: 4487.0.0+nightly-20251104-2100 Nov 4 23:51:56.277972 kernel: EXT4-fs (sda9): resizing filesystem from 6359552 to 6376955 blocks Nov 4 23:51:56.303540 kernel: EXT4-fs (sda9): resized filesystem to 6376955 Nov 4 23:51:56.311896 bash[2217]: Updated "/home/core/.ssh/authorized_keys" Nov 4 23:51:56.312771 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 4 23:51:56.318974 extend-filesystems[2223]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 4 23:51:56.318974 extend-filesystems[2223]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 4 23:51:56.318974 extend-filesystems[2223]: The filesystem on /dev/sda9 is now 6376955 (4k) blocks long. Nov 4 23:51:56.328253 dbus-daemon[2152]: [system] SELinux support is enabled Nov 4 23:51:56.357885 sshd_keygen[2201]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 4 23:51:56.320258 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 4 23:51:56.358059 update_engine[2170]: I20251104 23:51:56.331190 2170 update_check_scheduler.cc:74] Next update check in 6m13s Nov 4 23:51:56.358096 extend-filesystems[2158]: Resized filesystem in /dev/sda9 Nov 4 23:51:56.342024 dbus-daemon[2152]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 4 23:51:56.320534 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 4 23:51:56.329904 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 4 23:51:56.341678 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 4 23:51:56.341761 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 23:51:56.341788 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 4 23:51:56.348522 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 23:51:56.348548 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 4 23:51:56.353945 systemd[1]: Started update-engine.service - Update Engine. Nov 4 23:51:56.359906 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 4 23:51:56.368509 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 4 23:51:56.384809 coreos-metadata[2151]: Nov 04 23:51:56.384 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 23:51:56.386792 coreos-metadata[2151]: Nov 04 23:51:56.386 INFO Fetch successful Nov 4 23:51:56.387180 coreos-metadata[2151]: Nov 04 23:51:56.387 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 4 23:51:56.388378 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 4 23:51:56.390767 coreos-metadata[2151]: Nov 04 23:51:56.390 INFO Fetch successful Nov 4 23:51:56.391452 coreos-metadata[2151]: Nov 04 23:51:56.391 INFO Fetching http://168.63.129.16/machine/44b8f08f-b329-4f64-baac-6114276274a2/d51e69e2%2Db541%2D4265%2Daea2%2D68b2ddc1932e.%5Fci%2D4487.0.0%2Dn%2Dc26c48936b?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 4 23:51:56.393295 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 4 23:51:56.393757 coreos-metadata[2151]: Nov 04 23:51:56.393 INFO Fetch successful Nov 4 23:51:56.394333 coreos-metadata[2151]: Nov 04 23:51:56.394 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 4 23:51:56.401173 systemd[1]: issuegen.service: Deactivated successfully. Nov 4 23:51:56.401426 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 4 23:51:56.405871 coreos-metadata[2151]: Nov 04 23:51:56.405 INFO Fetch successful Nov 4 23:51:56.407554 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 4 23:51:56.438714 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 4 23:51:56.445265 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 4 23:51:56.462179 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 4 23:51:56.468485 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 4 23:51:56.473759 systemd[1]: Reached target getty.target - Login Prompts. Nov 4 23:51:56.479066 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 4 23:51:56.484440 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 4 23:51:56.517856 locksmithd[2309]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 4 23:51:56.821755 setup-nvidia[2340]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 4 23:51:56.822079 setup-nvidia[2340]: Dload Upload Total Spent Left Speed Nov 4 23:51:57.084605 containerd[2198]: time="2025-11-04T23:51:57Z" 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 23:51:57.085650 containerd[2198]: time="2025-11-04T23:51:57.085617100Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 4 23:51:57.094692 containerd[2198]: time="2025-11-04T23:51:57.094659800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.7µs" Nov 4 23:51:57.094692 containerd[2198]: time="2025-11-04T23:51:57.094685300Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 4 23:51:57.094794 containerd[2198]: time="2025-11-04T23:51:57.094702600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 4 23:51:57.094941 containerd[2198]: time="2025-11-04T23:51:57.094916200Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 4 23:51:57.094974 containerd[2198]: time="2025-11-04T23:51:57.094940400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 4 23:51:57.094974 containerd[2198]: time="2025-11-04T23:51:57.094963500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095042 containerd[2198]: time="2025-11-04T23:51:57.095021500Z" 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 23:51:57.095042 containerd[2198]: time="2025-11-04T23:51:57.095039400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095259 containerd[2198]: time="2025-11-04T23:51:57.095234400Z" 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 23:51:57.095259 containerd[2198]: time="2025-11-04T23:51:57.095256000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095315 containerd[2198]: time="2025-11-04T23:51:57.095275000Z" 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 23:51:57.095315 containerd[2198]: time="2025-11-04T23:51:57.095284100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095400 containerd[2198]: time="2025-11-04T23:51:57.095377600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095629 containerd[2198]: time="2025-11-04T23:51:57.095608400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 23:51:57.095656 containerd[2198]: time="2025-11-04T23:51:57.095643900Z" 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 23:51:57.095680 containerd[2198]: time="2025-11-04T23:51:57.095654700Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 4 23:51:57.095702 containerd[2198]: time="2025-11-04T23:51:57.095683700Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 4 23:51:57.096542 containerd[2198]: time="2025-11-04T23:51:57.096508500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 4 23:51:57.096629 containerd[2198]: time="2025-11-04T23:51:57.096606100Z" level=info msg="metadata content store policy set" policy=shared Nov 4 23:51:57.113500 containerd[2198]: time="2025-11-04T23:51:57.113458900Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 4 23:51:57.113575 containerd[2198]: time="2025-11-04T23:51:57.113513900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 4 23:51:57.113575 containerd[2198]: time="2025-11-04T23:51:57.113531100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 4 23:51:57.113575 containerd[2198]: time="2025-11-04T23:51:57.113556800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113575100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113587900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113599600Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113610400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113620800Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113630800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 4 23:51:57.113658 containerd[2198]: time="2025-11-04T23:51:57.113640000Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 4 23:51:57.113802 containerd[2198]: time="2025-11-04T23:51:57.113663700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 4 23:51:57.113881 containerd[2198]: time="2025-11-04T23:51:57.113853500Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 4 23:51:57.113920 containerd[2198]: time="2025-11-04T23:51:57.113880900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 4 23:51:57.113920 containerd[2198]: time="2025-11-04T23:51:57.113896300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 4 23:51:57.113920 containerd[2198]: time="2025-11-04T23:51:57.113912300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 4 23:51:57.113989 containerd[2198]: time="2025-11-04T23:51:57.113924000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 4 23:51:57.113989 containerd[2198]: time="2025-11-04T23:51:57.113946800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 4 23:51:57.113989 containerd[2198]: time="2025-11-04T23:51:57.113962200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 4 23:51:57.113989 containerd[2198]: time="2025-11-04T23:51:57.113972300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 4 23:51:57.113989 containerd[2198]: time="2025-11-04T23:51:57.113983600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 4 23:51:57.114094 containerd[2198]: time="2025-11-04T23:51:57.113996800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 4 23:51:57.114094 containerd[2198]: time="2025-11-04T23:51:57.114007400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 4 23:51:57.114094 containerd[2198]: time="2025-11-04T23:51:57.114062900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 4 23:51:57.114094 containerd[2198]: time="2025-11-04T23:51:57.114076200Z" level=info msg="Start snapshots syncer" Nov 4 23:51:57.114180 containerd[2198]: time="2025-11-04T23:51:57.114103900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 4 23:51:57.114392 containerd[2198]: time="2025-11-04T23:51:57.114338900Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 4 23:51:57.114512 containerd[2198]: time="2025-11-04T23:51:57.114412200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 4 23:51:57.114512 containerd[2198]: time="2025-11-04T23:51:57.114479300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 4 23:51:57.114608 containerd[2198]: time="2025-11-04T23:51:57.114582900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 4 23:51:57.114641 containerd[2198]: time="2025-11-04T23:51:57.114627100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 4 23:51:57.114664 containerd[2198]: time="2025-11-04T23:51:57.114643600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 4 23:51:57.114664 containerd[2198]: time="2025-11-04T23:51:57.114653800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 4 23:51:57.114709 containerd[2198]: time="2025-11-04T23:51:57.114666800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 4 23:51:57.114709 containerd[2198]: time="2025-11-04T23:51:57.114689100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 4 23:51:57.114709 containerd[2198]: time="2025-11-04T23:51:57.114703200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 4 23:51:57.114774 containerd[2198]: time="2025-11-04T23:51:57.114723500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 4 23:51:57.114774 containerd[2198]: time="2025-11-04T23:51:57.114736100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 4 23:51:57.114774 containerd[2198]: time="2025-11-04T23:51:57.114766500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 4 23:51:57.114891 containerd[2198]: time="2025-11-04T23:51:57.114797600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 23:51:57.114923 containerd[2198]: time="2025-11-04T23:51:57.114811200Z" 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 23:51:57.114923 containerd[2198]: time="2025-11-04T23:51:57.114905700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114925100Z" 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 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114933900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 4 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114943900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 4 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114954200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 4 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114972300Z" level=info msg="runtime interface created" Nov 4 23:51:57.114982 containerd[2198]: time="2025-11-04T23:51:57.114977800Z" level=info msg="created NRI interface" Nov 4 23:51:57.115095 containerd[2198]: time="2025-11-04T23:51:57.114985500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 4 23:51:57.115095 containerd[2198]: time="2025-11-04T23:51:57.114997800Z" level=info msg="Connect containerd service" Nov 4 23:51:57.115095 containerd[2198]: time="2025-11-04T23:51:57.115023500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 4 23:51:57.115750 containerd[2198]: time="2025-11-04T23:51:57.115719000Z" 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 23:51:57.616451 containerd[2198]: time="2025-11-04T23:51:57.616380800Z" level=info msg="Start subscribing containerd event" Nov 4 23:51:57.616568 containerd[2198]: time="2025-11-04T23:51:57.616485400Z" level=info msg="Start recovering state" Nov 4 23:51:57.616599 containerd[2198]: time="2025-11-04T23:51:57.616574700Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 4 23:51:57.616649 containerd[2198]: time="2025-11-04T23:51:57.616623200Z" level=info msg="Start event monitor" Nov 4 23:51:57.616680 containerd[2198]: time="2025-11-04T23:51:57.616642800Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 4 23:51:57.616718 containerd[2198]: time="2025-11-04T23:51:57.616701900Z" level=info msg="Start cni network conf syncer for default" Nov 4 23:51:57.616718 containerd[2198]: time="2025-11-04T23:51:57.616715700Z" level=info msg="Start streaming server" Nov 4 23:51:57.616773 containerd[2198]: time="2025-11-04T23:51:57.616752700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 4 23:51:57.616773 containerd[2198]: time="2025-11-04T23:51:57.616762400Z" level=info msg="runtime interface starting up..." Nov 4 23:51:57.616773 containerd[2198]: time="2025-11-04T23:51:57.616768000Z" level=info msg="starting plugins..." Nov 4 23:51:57.616859 containerd[2198]: time="2025-11-04T23:51:57.616784800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 4 23:51:57.617010 containerd[2198]: time="2025-11-04T23:51:57.616991300Z" level=info msg="containerd successfully booted in 0.532895s" Nov 4 23:51:57.617133 systemd[1]: Started containerd.service - containerd container runtime. Nov 4 23:51:58.545143 waagent[2325]: 2025-11-04T23:51:58.545065Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 4 23:51:58.549555 waagent[2325]: 2025-11-04T23:51:58.549499Z INFO Daemon Daemon OS: flatcar 4487.0.0 Nov 4 23:51:58.553476 waagent[2325]: 2025-11-04T23:51:58.552979Z INFO Daemon Daemon Python: 3.11.13 Nov 4 23:51:58.556337 waagent[2325]: 2025-11-04T23:51:58.556282Z INFO Daemon Daemon Run daemon Nov 4 23:51:58.563597 waagent[2325]: 2025-11-04T23:51:58.559497Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0' Nov 4 23:51:58.566719 waagent[2325]: 2025-11-04T23:51:58.566340Z INFO Daemon Daemon Using waagent for provisioning Nov 4 23:51:58.570523 waagent[2325]: 2025-11-04T23:51:58.570475Z INFO Daemon Daemon Activate resource disk Nov 4 23:51:58.574076 waagent[2325]: 2025-11-04T23:51:58.574032Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 4 23:51:58.581521 waagent[2325]: 2025-11-04T23:51:58.581473Z INFO Daemon Daemon Found device: sdb Nov 4 23:51:58.590590 waagent[2325]: 2025-11-04T23:51:58.590543Z INFO Daemon Daemon Examining partition table Nov 4 23:51:58.606788 waagent[2325]: 2025-11-04T23:51:58.606736Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 4 23:51:58.634611 waagent[2325]: 2025-11-04T23:51:58.634552Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 4 23:51:58.639332 waagent[2325]: 2025-11-04T23:51:58.639283Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 4 23:51:59.046535 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#317 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:59.064275 kernel: sdb: sdb1 Nov 4 23:51:59.113994 waagent[2325]: 2025-11-04T23:51:59.113918Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 4 23:51:59.133547 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#547 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:51:59.153840 kernel: sdb: sdb1 Nov 4 23:51:59.154415 waagent[2325]: 2025-11-04T23:51:59.154371Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 4 23:52:04.601162 login[2328]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 4 23:52:04.661047 login[2329]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 23:52:04.667706 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 4 23:52:04.668800 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 4 23:52:04.676074 systemd-logind[2168]: New session 2 of user core. Nov 4 23:52:04.799329 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 4 23:52:04.801903 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 4 23:52:04.866258 (systemd)[2421]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 4 23:52:04.868961 systemd-logind[2168]: New session c1 of user core. Nov 4 23:52:04.953122 waagent[2325]: 2025-11-04T23:52:04.953034Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 4 23:52:05.075572 waagent[2325]: 2025-11-04T23:52:05.075488Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 4 23:52:05.075838 kernel: EXT4-fs (sdb1): mounted filesystem a1af2696-7946-472e-bfac-cb17bec3343d r/w with ordered data mode. Quota mode: none. Nov 4 23:52:05.077695 waagent[2325]: 2025-11-04T23:52:05.077627Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 23:52:05.078762 waagent[2325]: 2025-11-04T23:52:05.078716Z INFO Daemon Daemon Running default provisioning handler Nov 4 23:52:05.093453 waagent[2325]: 2025-11-04T23:52:05.093397Z 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 23:52:05.095408 waagent[2325]: 2025-11-04T23:52:05.095365Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 4 23:52:05.095812 waagent[2325]: 2025-11-04T23:52:05.095771Z INFO Daemon Daemon cloud-init is enabled: False Nov 4 23:52:05.096521 waagent[2325]: 2025-11-04T23:52:05.096490Z INFO Daemon Daemon Copying ovf-env.xml Nov 4 23:52:05.128693 systemd[2421]: Queued start job for default target default.target. Nov 4 23:52:05.155831 systemd[2421]: Created slice app.slice - User Application Slice. Nov 4 23:52:05.155887 systemd[2421]: Reached target paths.target - Paths. Nov 4 23:52:05.155946 systemd[2421]: Reached target timers.target - Timers. Nov 4 23:52:05.157292 systemd[2421]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 23:52:05.168491 systemd[2421]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 23:52:05.168611 systemd[2421]: Reached target sockets.target - Sockets. Nov 4 23:52:05.168664 systemd[2421]: Reached target basic.target - Basic System. Nov 4 23:52:05.168709 systemd[2421]: Reached target default.target - Main User Target. Nov 4 23:52:05.168742 systemd[2421]: Startup finished in 286ms. Nov 4 23:52:05.168855 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 4 23:52:05.170263 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 4 23:52:05.204706 waagent[2325]: 2025-11-04T23:52:05.204623Z INFO Daemon Daemon Successfully mounted dvd Nov 4 23:52:05.237253 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 4 23:52:05.237516 waagent[2325]: 2025-11-04T23:52:05.237384Z INFO Daemon Daemon Detect protocol endpoint Nov 4 23:52:05.239107 waagent[2325]: 2025-11-04T23:52:05.239052Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 23:52:05.239842 waagent[2325]: 2025-11-04T23:52:05.239793Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 4 23:52:05.240535 waagent[2325]: 2025-11-04T23:52:05.240493Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 4 23:52:05.242279 waagent[2325]: 2025-11-04T23:52:05.242229Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 4 23:52:05.242713 waagent[2325]: 2025-11-04T23:52:05.242670Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 4 23:52:05.267032 waagent[2325]: 2025-11-04T23:52:05.266988Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 4 23:52:05.268790 waagent[2325]: 2025-11-04T23:52:05.268750Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 4 23:52:05.269381 waagent[2325]: 2025-11-04T23:52:05.269345Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 4 23:52:05.325502 waagent[2325]: 2025-11-04T23:52:05.325405Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 4 23:52:05.330573 waagent[2325]: 2025-11-04T23:52:05.330514Z INFO Daemon Daemon Forcing an update of the goal state. Nov 4 23:52:05.335230 waagent[2325]: 2025-11-04T23:52:05.335179Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 23:52:05.347804 waagent[2325]: 2025-11-04T23:52:05.347754Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 4 23:52:05.349908 waagent[2325]: 2025-11-04T23:52:05.349864Z INFO Daemon Nov 4 23:52:05.350203 waagent[2325]: 2025-11-04T23:52:05.350164Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 5b33af62-f249-4968-a670-610653702efe eTag: 2550581313209133582 source: Fabric] Nov 4 23:52:05.351943 waagent[2325]: 2025-11-04T23:52:05.351899Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 4 23:52:05.352651 waagent[2325]: 2025-11-04T23:52:05.352609Z INFO Daemon Nov 4 23:52:05.353903 waagent[2325]: 2025-11-04T23:52:05.353857Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 4 23:52:05.360933 waagent[2325]: 2025-11-04T23:52:05.360894Z INFO Daemon Daemon Downloading artifacts profile blob Nov 4 23:52:05.422183 waagent[2325]: 2025-11-04T23:52:05.422075Z INFO Daemon Downloaded certificate {'thumbprint': '0782D59FCE1AAE91A5067A14AEACF8D12942580F', 'hasPrivateKey': True} Nov 4 23:52:05.425219 waagent[2325]: 2025-11-04T23:52:05.425163Z INFO Daemon Fetch goal state completed Nov 4 23:52:05.431220 waagent[2325]: 2025-11-04T23:52:05.431177Z INFO Daemon Daemon Starting provisioning Nov 4 23:52:05.432872 waagent[2325]: 2025-11-04T23:52:05.432651Z INFO Daemon Daemon Handle ovf-env.xml. Nov 4 23:52:05.434132 waagent[2325]: 2025-11-04T23:52:05.434091Z INFO Daemon Daemon Set hostname [ci-4487.0.0-n-c26c48936b] Nov 4 23:52:05.451145 waagent[2325]: 2025-11-04T23:52:05.451104Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-n-c26c48936b] Nov 4 23:52:05.452875 waagent[2325]: 2025-11-04T23:52:05.452810Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 4 23:52:05.453570 waagent[2325]: 2025-11-04T23:52:05.453531Z INFO Daemon Daemon Primary interface is [eth0] Nov 4 23:52:05.471640 systemd-networkd[1855]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:52:05.471651 systemd-networkd[1855]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:52:05.471714 systemd-networkd[1855]: eth0: DHCP lease lost Nov 4 23:52:05.487005 waagent[2325]: 2025-11-04T23:52:05.486641Z INFO Daemon Daemon Create user account if not exists Nov 4 23:52:05.488298 waagent[2325]: 2025-11-04T23:52:05.488245Z INFO Daemon Daemon User core already exists, skip useradd Nov 4 23:52:05.489667 waagent[2325]: 2025-11-04T23:52:05.489625Z INFO Daemon Daemon Configure sudoer Nov 4 23:52:05.495119 waagent[2325]: 2025-11-04T23:52:05.495068Z INFO Daemon Daemon Configure sshd Nov 4 23:52:05.499522 waagent[2325]: 2025-11-04T23:52:05.499477Z 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 23:52:05.502295 waagent[2325]: 2025-11-04T23:52:05.502241Z INFO Daemon Daemon Deploy ssh public key. Nov 4 23:52:05.517859 systemd-networkd[1855]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 23:52:05.601597 login[2328]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 23:52:05.606311 systemd-logind[2168]: New session 1 of user core. Nov 4 23:52:05.629971 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 4 23:52:06.582340 waagent[2325]: 2025-11-04T23:52:06.582196Z INFO Daemon Daemon Provisioning complete Nov 4 23:52:06.594582 waagent[2325]: 2025-11-04T23:52:06.594199Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 4 23:52:06.595936 waagent[2325]: 2025-11-04T23:52:06.595891Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 4 23:52:06.596640 waagent[2325]: 2025-11-04T23:52:06.596603Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 4 23:52:06.771833 waagent[2479]: 2025-11-04T23:52:06.771751Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 4 23:52:06.772224 waagent[2479]: 2025-11-04T23:52:06.771919Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0 Nov 4 23:52:06.772224 waagent[2479]: 2025-11-04T23:52:06.771990Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 4 23:52:06.772224 waagent[2479]: 2025-11-04T23:52:06.772058Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 4 23:52:06.828670 waagent[2479]: 2025-11-04T23:52:06.828587Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 4 23:52:06.828897 waagent[2479]: 2025-11-04T23:52:06.828857Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:52:06.828981 waagent[2479]: 2025-11-04T23:52:06.828947Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:52:06.836530 waagent[2479]: 2025-11-04T23:52:06.836440Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 23:52:06.841287 waagent[2479]: 2025-11-04T23:52:06.841247Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 4 23:52:06.841737 waagent[2479]: 2025-11-04T23:52:06.841689Z INFO ExtHandler Nov 4 23:52:06.841845 waagent[2479]: 2025-11-04T23:52:06.841777Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 1eaabcf3-46c9-4d0f-b791-df0f0af26733 eTag: 2550581313209133582 source: Fabric] Nov 4 23:52:06.842165 waagent[2479]: 2025-11-04T23:52:06.842124Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 4 23:52:06.843125 waagent[2479]: 2025-11-04T23:52:06.843081Z INFO ExtHandler Nov 4 23:52:06.843233 waagent[2479]: 2025-11-04T23:52:06.843166Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 4 23:52:06.848032 waagent[2479]: 2025-11-04T23:52:06.847983Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 4 23:52:06.918604 waagent[2479]: 2025-11-04T23:52:06.918524Z INFO ExtHandler Downloaded certificate {'thumbprint': '0782D59FCE1AAE91A5067A14AEACF8D12942580F', 'hasPrivateKey': True} Nov 4 23:52:06.919169 waagent[2479]: 2025-11-04T23:52:06.919125Z INFO ExtHandler Fetch goal state completed Nov 4 23:52:06.932795 waagent[2479]: 2025-11-04T23:52:06.932736Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 4 23:52:06.937973 waagent[2479]: 2025-11-04T23:52:06.937914Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2479 Nov 4 23:52:06.938105 waagent[2479]: 2025-11-04T23:52:06.938064Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 4 23:52:06.938445 waagent[2479]: 2025-11-04T23:52:06.938404Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 4 23:52:06.940085 waagent[2479]: 2025-11-04T23:52:06.940031Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 4 23:52:06.940512 waagent[2479]: 2025-11-04T23:52:06.940468Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 4 23:52:06.940661 waagent[2479]: 2025-11-04T23:52:06.940623Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 4 23:52:06.941295 waagent[2479]: 2025-11-04T23:52:06.941239Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 4 23:52:07.092862 waagent[2479]: 2025-11-04T23:52:07.092726Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 4 23:52:07.093008 waagent[2479]: 2025-11-04T23:52:07.092966Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 4 23:52:07.099955 waagent[2479]: 2025-11-04T23:52:07.099918Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 4 23:52:07.106663 systemd[1]: Reload requested from client PID 2494 ('systemctl') (unit waagent.service)... Nov 4 23:52:07.106683 systemd[1]: Reloading... Nov 4 23:52:07.168850 zram_generator::config[2535]: No configuration found. Nov 4 23:52:07.504526 systemd[1]: Reloading finished in 397 ms. Nov 4 23:52:07.531505 waagent[2479]: 2025-11-04T23:52:07.529780Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 4 23:52:07.531505 waagent[2479]: 2025-11-04T23:52:07.531101Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 4 23:52:08.268703 waagent[2479]: 2025-11-04T23:52:08.268508Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 4 23:52:08.269119 waagent[2479]: 2025-11-04T23:52:08.269004Z 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 23:52:08.269853 waagent[2479]: 2025-11-04T23:52:08.269782Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 4 23:52:08.270228 waagent[2479]: 2025-11-04T23:52:08.270172Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:52:08.270461 waagent[2479]: 2025-11-04T23:52:08.270370Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 4 23:52:08.270886 waagent[2479]: 2025-11-04T23:52:08.270812Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 4 23:52:08.270954 waagent[2479]: 2025-11-04T23:52:08.270907Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 4 23:52:08.270992 waagent[2479]: 2025-11-04T23:52:08.270962Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:52:08.271161 waagent[2479]: 2025-11-04T23:52:08.271114Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:52:08.271638 waagent[2479]: 2025-11-04T23:52:08.271580Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 4 23:52:08.271861 waagent[2479]: 2025-11-04T23:52:08.271804Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 4 23:52:08.271917 waagent[2479]: 2025-11-04T23:52:08.271872Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 4 23:52:08.272314 waagent[2479]: 2025-11-04T23:52:08.272274Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 4 23:52:08.272425 waagent[2479]: 2025-11-04T23:52:08.272384Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:52:08.272799 waagent[2479]: 2025-11-04T23:52:08.272751Z INFO EnvHandler ExtHandler Configure routes Nov 4 23:52:08.273323 waagent[2479]: 2025-11-04T23:52:08.273281Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 4 23:52:08.273323 waagent[2479]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 4 23:52:08.273323 waagent[2479]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 4 23:52:08.273323 waagent[2479]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 4 23:52:08.273323 waagent[2479]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:52:08.273323 waagent[2479]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:52:08.273323 waagent[2479]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:52:08.273483 waagent[2479]: 2025-11-04T23:52:08.273456Z INFO EnvHandler ExtHandler Gateway:None Nov 4 23:52:08.274089 waagent[2479]: 2025-11-04T23:52:08.274040Z INFO EnvHandler ExtHandler Routes:None Nov 4 23:52:08.278313 waagent[2479]: 2025-11-04T23:52:08.278263Z INFO ExtHandler ExtHandler Nov 4 23:52:08.278766 waagent[2479]: 2025-11-04T23:52:08.278722Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 836a790c-eff7-43cb-9e07-211b9b5d91f4 correlation 602dcb55-4c2b-420a-a7c8-137e274a8fc8 created: 2025-11-04T23:50:48.814819Z] Nov 4 23:52:08.280055 waagent[2479]: 2025-11-04T23:52:08.280006Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 4 23:52:08.282060 waagent[2479]: 2025-11-04T23:52:08.282017Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Nov 4 23:52:08.329272 waagent[2479]: 2025-11-04T23:52:08.329204Z 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 23:52:08.329272 waagent[2479]: Try `iptables -h' or 'iptables --help' for more information.) Nov 4 23:52:08.329714 waagent[2479]: 2025-11-04T23:52:08.329672Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 68C68C02-04E0-440F-BF21-78B00AAD3392;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 4 23:52:08.418619 waagent[2479]: 2025-11-04T23:52:08.418482Z INFO MonitorHandler ExtHandler Network interfaces: Nov 4 23:52:08.418619 waagent[2479]: Executing ['ip', '-a', '-o', 'link']: Nov 4 23:52:08.418619 waagent[2479]: 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 23:52:08.418619 waagent[2479]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:20:33:05 brd ff:ff:ff:ff:ff:ff\ altname enx7c1e52203305 Nov 4 23:52:08.418619 waagent[2479]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 4 23:52:08.418619 waagent[2479]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 4 23:52:08.418619 waagent[2479]: 2: eth0 inet 10.200.8.36/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 4 23:52:08.418619 waagent[2479]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 4 23:52:08.418619 waagent[2479]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 4 23:52:08.418619 waagent[2479]: 2: eth0 inet6 fe80::7e1e:52ff:fe20:3305/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 4 23:52:08.499237 waagent[2479]: 2025-11-04T23:52:08.499179Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 4 23:52:08.499237 waagent[2479]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:52:08.499237 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.499237 waagent[2479]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:52:08.499237 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.499237 waagent[2479]: Chain OUTPUT (policy ACCEPT 2 packets, 104 bytes) Nov 4 23:52:08.499237 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.499237 waagent[2479]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 23:52:08.499237 waagent[2479]: 5 647 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 23:52:08.499237 waagent[2479]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 23:52:08.502688 waagent[2479]: 2025-11-04T23:52:08.502641Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 4 23:52:08.502688 waagent[2479]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:52:08.502688 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.502688 waagent[2479]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:52:08.502688 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.502688 waagent[2479]: Chain OUTPUT (policy ACCEPT 2 packets, 104 bytes) Nov 4 23:52:08.502688 waagent[2479]: pkts bytes target prot opt in out source destination Nov 4 23:52:08.502688 waagent[2479]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 23:52:08.502688 waagent[2479]: 5 647 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 23:52:08.502688 waagent[2479]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 23:52:08.502968 waagent[2479]: 2025-11-04T23:52:08.502933Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 4 23:52:08.503126 waagent[2479]: 2025-11-04T23:52:08.503095Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 4 23:52:19.888205 chronyd[2149]: Selected source PHC0 Nov 4 23:52:22.594528 setup-nvidia[2340]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 521M 0 32768 0 0 45671 0 3:19:44 --:--:-- 3:19:44 45637\u000d 1 521M 1 6432k 0 0 3787k 0 0:02:21 0:00:01 0:02:20 3787k\u000d 5 521M 5 29.5M 0 0 10.9M 0 0:00:47 0:00:02 0:00:45 10.9M\u000d 10 521M 10 54.4M 0 0 15.1M 0 0:00:34 0:00:03 0:00:31 15.1M\u000d 14 521M 14 75.9M 0 0 16.0M 0 0:00:32 0:00:04 0:00:28 16.0M\u000d 18 521M 18 96.0M 0 0 16.8M 0 0:00:31 0:00:05 0:00:26 19.2M\u000d 22 521M 22 117M 0 0 17.5M 0 0:00:29 0:00:06 0:00:23 22.2M\u000d 26 521M 26 138M 0 0 17.9M 0 0:00:29 0:00:07 0:00:22 21.7M\u000d 30 521M 30 159M 0 0 18.3M 0 0:00:28 0:00:08 0:00:20 20.5M\u000d 34 521M 34 182M 0 0 18.7M 0 0:00:27 0:00:09 0:00:18 21.3M\u000d 39 521M 39 204M 0 0 19.0M 0 0:00:27 0:00:10 0:00:17 21.5M\u000d 43 521M 43 226M 0 0 19.3M 0 0:00:26 0:00:11 0:00:15 21.8M\u000d 43 521M 43 229M 0 0 18.2M 0 0:00:28 0:00:12 0:00:16 18.6M\u000d 48 521M 48 252M 0 0 18.5M 0 0:00:28 0:00:13 0:00:15 18.9M\u000d 52 521M 52 272M 0 0 18.6M 0 0:00:27 0:00:14 0:00:13 18.4M\u000d 56 521M 56 293M 0 0 18.8M 0 0:00:27 0:00:15 0:00:12 18.3M\u000d 60 521M 60 314M 0 0 18.9M 0 0:00:27 0:00:16 0:00:11 18.0M\u000d 64 521M 64 336M 0 0 19.1M 0 0:00:27 0:00:17 0:00:10 21.4M\u000d 68 521M 68 358M 0 0 19.2M 0 0:00:27 0:00:18 0:00:09 21.3M\u000d 72 521M 72 380M 0 0 19.4M 0 0:00:26 0:00:19 0:00:07 21.5M\u000d 76 521M 76 401M 0 0 19.5M 0 0:00:26 0:00:20 0:00:06 21.6M\u000d 81 521M 81 424M 0 0 19.6M 0 0:00:26 0:00:21 0:00:05 21.7M\u000d 85 521M 85 446M 0 0 19.7M 0 0:00:26 0:00:22 0:00:04 21.8M\u000d 90 521M 90 469M 0 0 19.9M 0 0:00:26 0:00:23 0:00:03 22.1M\u000d 94 521M 94 494M 0 0 20.0M 0 0:00:26 0:00:24 0:00:02 22.6M\u000d 99 521M 99 517M 0 0 20.1M 0 0:00:25 0:00:25 --:--:-- 23.0M\u000d100 521M 100 521M 0 0 20.2M 0 0:00:25 0:00:25 --:--:-- 23.4M Nov 4 23:52:33.708442 setup-nvidia[2165]: Downloading NVIDIA 535.261.03 Driver Nov 4 23:52:33.712873 setup-nvidia[2638]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 4 23:52:33.713159 setup-nvidia[2638]: Dload Upload Total Spent Left Speed Nov 4 23:52:35.331254 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 4 23:52:35.334913 systemd[1]: Started sshd@0-10.200.8.36:22-10.200.16.10:46288.service - OpenSSH per-connection server daemon (10.200.16.10:46288). Nov 4 23:52:36.193886 sshd[2640]: Accepted publickey for core from 10.200.16.10 port 46288 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:52:36.195247 sshd-session[2640]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:52:36.199449 systemd-logind[2168]: New session 3 of user core. Nov 4 23:52:36.222940 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 4 23:52:36.356470 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 4 23:52:36.747900 systemd[1]: Started sshd@1-10.200.8.36:22-10.200.16.10:46302.service - OpenSSH per-connection server daemon (10.200.16.10:46302). Nov 4 23:52:37.378584 sshd[2646]: Accepted publickey for core from 10.200.16.10 port 46302 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:52:37.379848 sshd-session[2646]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:52:37.384068 systemd-logind[2168]: New session 4 of user core. Nov 4 23:52:37.400965 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 4 23:52:37.834276 sshd[2649]: Connection closed by 10.200.16.10 port 46302 Nov 4 23:52:37.834620 sshd-session[2646]: pam_unix(sshd:session): session closed for user core Nov 4 23:52:37.837679 systemd[1]: sshd@1-10.200.8.36:22-10.200.16.10:46302.service: Deactivated successfully. Nov 4 23:52:37.839584 systemd[1]: session-4.scope: Deactivated successfully. Nov 4 23:52:37.840838 systemd-logind[2168]: Session 4 logged out. Waiting for processes to exit. Nov 4 23:52:37.841763 systemd-logind[2168]: Removed session 4. Nov 4 23:52:37.950228 systemd[1]: Started sshd@2-10.200.8.36:22-10.200.16.10:46312.service - OpenSSH per-connection server daemon (10.200.16.10:46312). Nov 4 23:52:38.584413 sshd[2655]: Accepted publickey for core from 10.200.16.10 port 46312 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:52:38.585662 sshd-session[2655]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:52:38.589880 systemd-logind[2168]: New session 5 of user core. Nov 4 23:52:38.601939 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 4 23:52:39.041956 sshd[2658]: Connection closed by 10.200.16.10 port 46312 Nov 4 23:52:39.042296 sshd-session[2655]: pam_unix(sshd:session): session closed for user core Nov 4 23:52:39.046756 systemd[1]: sshd@2-10.200.8.36:22-10.200.16.10:46312.service: Deactivated successfully. Nov 4 23:52:39.048330 systemd[1]: session-5.scope: Deactivated successfully. Nov 4 23:52:39.049017 systemd-logind[2168]: Session 5 logged out. Waiting for processes to exit. Nov 4 23:52:39.049925 systemd-logind[2168]: Removed session 5. Nov 4 23:52:41.213109 update_engine[2170]: I20251104 23:52:41.213024 2170 update_attempter.cc:509] Updating boot flags... Nov 4 23:52:49.153331 systemd[1]: Started sshd@3-10.200.8.36:22-10.200.16.10:52942.service - OpenSSH per-connection server daemon (10.200.16.10:52942). Nov 4 23:52:49.783748 sshd[2780]: Accepted publickey for core from 10.200.16.10 port 52942 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:52:49.784971 sshd-session[2780]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:52:49.789209 systemd-logind[2168]: New session 6 of user core. Nov 4 23:52:49.797939 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 4 23:52:50.229150 sshd[2783]: Connection closed by 10.200.16.10 port 52942 Nov 4 23:52:50.229472 sshd-session[2780]: pam_unix(sshd:session): session closed for user core Nov 4 23:52:50.233160 systemd[1]: sshd@3-10.200.8.36:22-10.200.16.10:52942.service: Deactivated successfully. Nov 4 23:52:50.234776 systemd[1]: session-6.scope: Deactivated successfully. Nov 4 23:52:50.235510 systemd-logind[2168]: Session 6 logged out. Waiting for processes to exit. Nov 4 23:52:50.236353 systemd-logind[2168]: Removed session 6. Nov 4 23:52:50.345199 systemd[1]: Started sshd@4-10.200.8.36:22-10.200.16.10:36578.service - OpenSSH per-connection server daemon (10.200.16.10:36578). Nov 4 23:52:50.979075 sshd[2790]: Accepted publickey for core from 10.200.16.10 port 36578 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:52:50.980278 sshd-session[2790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:52:50.984511 systemd-logind[2168]: New session 7 of user core. Nov 4 23:52:51.000950 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 4 23:52:51.426895 sshd[2793]: Connection closed by 10.200.16.10 port 36578 Nov 4 23:52:51.427216 sshd-session[2790]: pam_unix(sshd:session): session closed for user core Nov 4 23:52:51.430857 systemd[1]: sshd@4-10.200.8.36:22-10.200.16.10:36578.service: Deactivated successfully. Nov 4 23:52:51.432530 systemd[1]: session-7.scope: Deactivated successfully. Nov 4 23:52:51.433215 systemd-logind[2168]: Session 7 logged out. Waiting for processes to exit. Nov 4 23:52:51.434081 systemd-logind[2168]: Removed session 7. Nov 4 23:53:01.542921 systemd[1]: Started sshd@5-10.200.8.36:22-10.200.16.10:51782.service - OpenSSH per-connection server daemon (10.200.16.10:51782). Nov 4 23:53:02.187590 sshd[2799]: Accepted publickey for core from 10.200.16.10 port 51782 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:02.188882 sshd-session[2799]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:02.193357 systemd-logind[2168]: New session 8 of user core. Nov 4 23:53:02.204959 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 4 23:53:02.634011 sshd[2802]: Connection closed by 10.200.16.10 port 51782 Nov 4 23:53:02.634337 sshd-session[2799]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:02.638187 systemd[1]: sshd@5-10.200.8.36:22-10.200.16.10:51782.service: Deactivated successfully. Nov 4 23:53:02.640293 systemd[1]: session-8.scope: Deactivated successfully. Nov 4 23:53:02.641663 systemd-logind[2168]: Session 8 logged out. Waiting for processes to exit. Nov 4 23:53:02.642669 systemd-logind[2168]: Removed session 8. Nov 4 23:53:02.749617 systemd[1]: Started sshd@6-10.200.8.36:22-10.200.16.10:51796.service - OpenSSH per-connection server daemon (10.200.16.10:51796). Nov 4 23:53:03.385055 sshd[2808]: Accepted publickey for core from 10.200.16.10 port 51796 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:03.386292 sshd-session[2808]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:03.391615 systemd-logind[2168]: New session 9 of user core. Nov 4 23:53:03.402951 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 4 23:53:03.832172 sshd[2811]: Connection closed by 10.200.16.10 port 51796 Nov 4 23:53:03.832535 sshd-session[2808]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:03.836367 systemd[1]: sshd@6-10.200.8.36:22-10.200.16.10:51796.service: Deactivated successfully. Nov 4 23:53:03.837957 systemd[1]: session-9.scope: Deactivated successfully. Nov 4 23:53:03.838623 systemd-logind[2168]: Session 9 logged out. Waiting for processes to exit. Nov 4 23:53:03.839533 systemd-logind[2168]: Removed session 9. Nov 4 23:53:13.946828 systemd[1]: Started sshd@7-10.200.8.36:22-10.200.16.10:58142.service - OpenSSH per-connection server daemon (10.200.16.10:58142). Nov 4 23:53:14.592424 sshd[2817]: Accepted publickey for core from 10.200.16.10 port 58142 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:14.593629 sshd-session[2817]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:14.597826 systemd-logind[2168]: New session 10 of user core. Nov 4 23:53:14.612948 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 4 23:53:15.037137 sshd[2820]: Connection closed by 10.200.16.10 port 58142 Nov 4 23:53:15.037504 sshd-session[2817]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:15.041166 systemd[1]: sshd@7-10.200.8.36:22-10.200.16.10:58142.service: Deactivated successfully. Nov 4 23:53:15.043071 systemd[1]: session-10.scope: Deactivated successfully. Nov 4 23:53:15.043912 systemd-logind[2168]: Session 10 logged out. Waiting for processes to exit. Nov 4 23:53:15.044800 systemd-logind[2168]: Removed session 10. Nov 4 23:53:15.155467 systemd[1]: Started sshd@8-10.200.8.36:22-10.200.16.10:58144.service - OpenSSH per-connection server daemon (10.200.16.10:58144). Nov 4 23:53:15.786747 sshd[2826]: Accepted publickey for core from 10.200.16.10 port 58144 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:15.787993 sshd-session[2826]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:15.792419 systemd-logind[2168]: New session 11 of user core. Nov 4 23:53:15.801951 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 4 23:53:16.242802 sshd[2829]: Connection closed by 10.200.16.10 port 58144 Nov 4 23:53:16.243151 sshd-session[2826]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:16.247004 systemd[1]: sshd@8-10.200.8.36:22-10.200.16.10:58144.service: Deactivated successfully. Nov 4 23:53:16.248583 systemd[1]: session-11.scope: Deactivated successfully. Nov 4 23:53:16.249287 systemd-logind[2168]: Session 11 logged out. Waiting for processes to exit. Nov 4 23:53:16.250413 systemd-logind[2168]: Removed session 11. Nov 4 23:53:26.355361 systemd[1]: Started sshd@9-10.200.8.36:22-10.200.16.10:52140.service - OpenSSH per-connection server daemon (10.200.16.10:52140). Nov 4 23:53:26.987422 sshd[2835]: Accepted publickey for core from 10.200.16.10 port 52140 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:26.988872 sshd-session[2835]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:26.993397 systemd-logind[2168]: New session 12 of user core. Nov 4 23:53:27.018975 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 4 23:53:27.440543 sshd[2838]: Connection closed by 10.200.16.10 port 52140 Nov 4 23:53:27.440996 sshd-session[2835]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:27.444731 systemd[1]: sshd@9-10.200.8.36:22-10.200.16.10:52140.service: Deactivated successfully. Nov 4 23:53:27.446419 systemd[1]: session-12.scope: Deactivated successfully. Nov 4 23:53:27.447129 systemd-logind[2168]: Session 12 logged out. Waiting for processes to exit. Nov 4 23:53:27.447980 systemd-logind[2168]: Removed session 12. Nov 4 23:53:27.556305 systemd[1]: Started sshd@10-10.200.8.36:22-10.200.16.10:52146.service - OpenSSH per-connection server daemon (10.200.16.10:52146). Nov 4 23:53:28.211844 sshd[2844]: Accepted publickey for core from 10.200.16.10 port 52146 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:28.213108 sshd-session[2844]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:28.217377 systemd-logind[2168]: New session 13 of user core. Nov 4 23:53:28.232945 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 4 23:53:28.659326 sshd[2847]: Connection closed by 10.200.16.10 port 52146 Nov 4 23:53:28.659666 sshd-session[2844]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:28.663323 systemd[1]: sshd@10-10.200.8.36:22-10.200.16.10:52146.service: Deactivated successfully. Nov 4 23:53:28.664911 systemd[1]: session-13.scope: Deactivated successfully. Nov 4 23:53:28.665607 systemd-logind[2168]: Session 13 logged out. Waiting for processes to exit. Nov 4 23:53:28.666546 systemd-logind[2168]: Removed session 13. Nov 4 23:53:38.776155 systemd[1]: Started sshd@11-10.200.8.36:22-10.200.16.10:47966.service - OpenSSH per-connection server daemon (10.200.16.10:47966). Nov 4 23:53:38.954705 setup-nvidia[2638]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 2049k 0 0 1735k 0 0:03:12 0:00:01 0:03:11 1736k\u000d 1 325M 1 6144k 0 0 3069k 0 0:01:48 0:00:02 0:01:46 3070k\u000d 3 325M 3 10.0M 0 0 3407k 0 0:01:37 0:00:03 0:01:34 3408k\u000d 4 325M 4 14.0M 0 0 3613k 0 0:01:32 0:00:03 0:01:29 3613k\u000d 5 325M 5 18.0M 0 0 3739k 0 0:01:29 0:00:04 0:01:25 3739k\u000d 6 325M 6 22.0M 0 0 3919k 0 0:01:25 0:00:05 0:01:20 4483k\u000d 8 325M 8 28.0M 0 0 4137k 0 0:01:20 0:00:06 0:01:14 4571k\u000d 10 325M 10 32.7M 0 0 4355k 0 0:01:16 0:00:07 0:01:09 4961k\u000d 11 325M 11 38.0M 0 0 4375k 0 0:01:16 0:00:08 0:01:08 4988k\u000d 13 325M 13 44.0M 0 0 4493k 0 0:01:14 0:00:10 0:01:04 5223k\u000d 14 325M 14 48.0M 0 0 4556k 0 0:01:13 0:00:10 0:01:03 5282k\u000d 16 325M 16 54.0M 0 0 4624k 0 0:01:12 0:00:11 0:01:01 5296k\u000d 17 325M 17 58.0M 0 0 4668k 0 0:01:11 0:00:12 0:00:59 5148k\u000d 19 325M 19 64.0M 0 0 4731k 0 0:01:10 0:00:13 0:00:57 5369k\u000d 21 325M 21 70.0M 0 0 4779k 0 0:01:09 0:00:14 0:00:55 5354k\u000d 22 325M 22 74.0M 0 0 4675k 0 0:01:11 0:00:16 0:00:55 4914k\u000d 23 325M 23 75.9M 0 0 4658k 0 0:01:11 0:00:16 0:00:55 4741k\u000d 24 325M 24 80.0M 0 0 4531k 0 0:01:13 0:00:18 0:00:55 4206k\u000d 25 325M 25 84.0M 0 0 4523k 0 0:01:13 0:00:19 0:00:54 3966k\u000d 27 325M 27 90.0M 0 0 4607k 0 0:01:12 0:00:20 0:00:52 4093k\u000d 28 325M 28 94.0M 0 0 4567k 0 0:01:13 0:00:21 0:00:52 4207k\u000d 30 325M 30 98.0M 0 0 4581k 0 0:01:12 0:00:21 0:00:51 4337k\u000d 31 325M 31 103M 0 0 4649k 0 0:01:11 0:00:22 0:00:49 5109k\u000d 32 325M 32 106M 0 0 4526k 0 0:01:13 0:00:24 0:00:49 4537k\u000d 34 325M 34 114M 0 0 4686k 0 0:01:11 0:00:24 0:00:47 5005k\u000d 36 325M 36 120M 0 0 4758k 0 0:01:10 0:00:25 0:00:45 5603k\u000d 38 325M 38 125M 0 0 4827k 0 0:01:09 0:00:26 0:00:43 5944k\u000d 39 325M 39 130M 0 0 4773k 0 0:01:09 0:00:27 0:00:42 5320k\u000d 41 325M 41 134M 0 0 4773k 0 0:01:09 0:00:28 0:00:41 6024k\u000d 42 325M 42 140M 0 0 4770k 0 0:01:09 0:00:30 0:00:39 5180k\u000d 44 325M 44 144M 0 0 4782k 0 0:01:09 0:00:30 0:00:39 4907k\u000d 45 325M 45 148M 0 0 4811k 0 0:01:09 0:00:31 0:00:38 4722k\u000d 47 325M 47 154M 0 0 4800k 0 0:01:09 0:00:32 0:00:37 4948k\u000d 49 325M 49 159M 0 0 4861k 0 0:01:08 0:00:33 0:00:35 5368k\u000d 50 325M 50 164M 0 0 4828k 0 0:01:09 0:00:34 0:00:35 5190k\u000d 52 325M 52 170M 0 0 4828k 0 0:01:09 0:00:36 0:00:33 5104k\u000d 53 325M 53 174M 0 0 4847k 0 0:01:08 0:00:36 0:00:32 5079k\u000d 55 325M 55 180M 0 0 4853k 0 0:01:08 0:00:37 0:00:31 5190k\u000d 56 325M 56 184M 0 0 4862k 0 0:01:08 0:00:38 0:00:30 4875k\u000d 58 325M 58 190M 0 0 4873k 0 0:01:08 0:00:39 0:00:29 5184k\u000d 59 325M 59 194M 0 0 4880k 0 0:01:08 0:00:40 0:00:28 5282k\u000d 61 325M 61 200M 0 0 4891k 0 0:01:08 0:00:41 0:00:27 5205k\u000d 63 325M 63 206M 0 0 4933k 0 0:01:07 0:00:42 0:00:25 5576k\u000d 65 325M 65 212M 0 0 4943k 0 0:01:07 0:00:43 0:00:24 5547k\u000d 66 325M 66 216M 0 0 4930k 0 0:01:07 0:00:44 0:00:23 5392k\u000d 68 325M 68 222M 0 0 4941k 0 0:01:07 0:00:46 0:00:21 5408k\u000d 69 325M 69 226M 0 0 4948k 0 0:01:07 0:00:46 0:00:21 5434k\u000d 71 325M 71 232M 0 0 4974k 0 0:01:07 0:00:47 0:00:20 5321k\u000d 73 325M 73 238M 0 0 5000k 0 0:01:06 0:00:48 0:00:18 5517k\u000d 75 325M 75 246M 0 0 5032k 0 0:01:06 0:00:50 0:00:16 5907k\u000d 76 325M 76 250M 0 0 5028k 0 0:01:06 0:00:50 0:00:16 5840k\u000d 78 325M 78 254M 0 0 5041k 0 0:01:06 0:00:51 0:00:15 5919k\u000d 79 325M 79 260M 0 0 5035k 0 0:01:06 0:00:52 0:00:14 5609k\u000d 81 325M 81 266M 0 0 5043k 0 0:01:06 0:00:54 0:00:12 5436k\u000d 82 325M 82 270M 0 0 5050k 0 0:01:06 0:00:54 0:00:12 5247k\u000d 84 325M 84 276M 0 0 5052k 0 0:01:06 0:00:55 0:00:11 5300k\u000d 85 325M 85 280M 0 0 5053k 0 0:01:06 0:00:56 0:00:10 5184k\u000d 87 325M 87 286M 0 0 5048k 0 0:01:06 0:00:58 0:00:08 5180k\u000d 88 325M 88 290M 0 0 5053k 0 0:01:06 0:00:58 0:00:08 5168k\u000d 90 325M 90 296M 0 0 5056k 0 0:01:06 0:00:59 0:00:07 5111k\u000d 92 325M 92 300M 0 0 5058k 0 0:01:05 0:01:00 0:00:05 5129k\u000d 93 325M 93 306M 0 0 5060k 0 0:01:05 0:01:01 0:00:04 5137k\u000d 95 325M 95 310M 0 0 5061k 0 0:01:05 0:01:02 0:00:03 5220k\u000d 96 325M 96 316M 0 0 5062k 0 0:01:05 0:01:03 0:00:02 5163k\u000d 98 325M 98 320M 0 0 5073k 0 0:01:05 0:01:04 0:00:01 5292k\u000d100 325M 100 325M 0 0 5115k 0 0:01:05 0:01:05 --:--:-- 5878k Nov 4 23:53:39.410398 sshd[2853]: Accepted publickey for core from 10.200.16.10 port 47966 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:39.411636 sshd-session[2853]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:39.415837 systemd-logind[2168]: New session 14 of user core. Nov 4 23:53:39.434984 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 4 23:53:39.443779 setup-nvidia[2165]: Extract the NVIDIA Driver Installer 535.261.03 Nov 4 23:53:39.443979 setup-nvidia[2165]: /opt/nvidia/workdir/nvidia-workdir / Nov 4 23:53:39.462525 setup-nvidia[2859]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 4 23:53:39.732568 setup-nvidia[2859]: Verifying archive integrity... OK Nov 4 23:53:39.859254 sshd[2857]: Connection closed by 10.200.16.10 port 47966 Nov 4 23:53:39.859615 sshd-session[2853]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:39.862809 systemd[1]: sshd@11-10.200.8.36:22-10.200.16.10:47966.service: Deactivated successfully. Nov 4 23:53:39.865006 systemd[1]: session-14.scope: Deactivated successfully. Nov 4 23:53:39.866417 systemd-logind[2168]: Session 14 logged out. Waiting for processes to exit. Nov 4 23:53:39.867425 systemd-logind[2168]: Removed session 14. Nov 4 23:53:39.977929 systemd[1]: Started sshd@12-10.200.8.36:22-10.200.16.10:34922.service - OpenSSH per-connection server daemon (10.200.16.10:34922). Nov 4 23:53:40.406234 setup-nvidia[2859]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 4 23:53:40.609566 sshd[2887]: Accepted publickey for core from 10.200.16.10 port 34922 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:40.610970 sshd-session[2887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:40.616076 systemd-logind[2168]: New session 15 of user core. Nov 4 23:53:40.624986 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 4 23:53:41.056041 sshd[2897]: Connection closed by 10.200.16.10 port 34922 Nov 4 23:53:41.056397 sshd-session[2887]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:41.060256 systemd[1]: sshd@12-10.200.8.36:22-10.200.16.10:34922.service: Deactivated successfully. Nov 4 23:53:41.062078 systemd[1]: session-15.scope: Deactivated successfully. Nov 4 23:53:41.062759 systemd-logind[2168]: Session 15 logged out. Waiting for processes to exit. Nov 4 23:53:41.063809 systemd-logind[2168]: Removed session 15. Nov 4 23:53:42.302946 setup-nvidia[2893]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 4 23:53:42.342751 setup-nvidia[2165]: / Nov 4 23:53:42.342982 setup-nvidia[2165]: Spawn system-nspawn container to install the NVIDIA drivers Nov 4 23:53:42.440610 sudo[2905]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4487.0.0+nightly-20251104-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.261.03 Nov 4 23:53:42.442468 sudo[2905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 4 23:53:42.448784 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 4 23:53:42.452569 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 4 23:53:42.484959 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 4 23:53:42.489268 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 4 23:53:42.505904 (systemd)[2908]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 4 23:53:42.508175 systemd-logind[2168]: New session c3 of user root. Nov 4 23:53:42.677745 systemd[2908]: Queued start job for default target default.target. Nov 4 23:53:42.697059 systemd[2908]: Created slice app.slice - User Application Slice. Nov 4 23:53:42.697090 systemd[2908]: Reached target paths.target - Paths. Nov 4 23:53:42.697139 systemd[2908]: Reached target timers.target - Timers. Nov 4 23:53:42.698363 systemd[2908]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 23:53:42.709969 systemd[2908]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 23:53:42.710090 systemd[2908]: Reached target sockets.target - Sockets. Nov 4 23:53:42.710144 systemd[2908]: Reached target basic.target - Basic System. Nov 4 23:53:42.710219 systemd[2908]: Reached target default.target - Main User Target. Nov 4 23:53:42.710258 systemd[2908]: Startup finished in 196ms. Nov 4 23:53:42.710319 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 4 23:53:42.713317 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 4 23:53:42.753155 systemd-udevd[1843]: Received message with invalid length, ignoring Nov 4 23:53:42.757837 kernel: loop7: detected capacity change from 0 to 12644352 Nov 4 23:53:42.811859 kernel: loop7: p9 Nov 4 23:53:42.825142 setup-nvidia[2917]: ░ Spawning container flatcardevelopercontainer-4487.0.0nightly-20251104-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4487.0.0+nightly-20251104-2100.bin. Nov 4 23:53:42.939412 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Nov 4 23:53:42.939531 kernel: EXT4-fs (loop7p9): mounted filesystem ff6c02a9-4867-4478-bb09-ef3fab0f393d ro without journal. Quota mode: none. Nov 4 23:53:51.170258 systemd[1]: Started sshd@13-10.200.8.36:22-10.200.16.10:60932.service - OpenSSH per-connection server daemon (10.200.16.10:60932). Nov 4 23:53:51.803171 sshd[4537]: Accepted publickey for core from 10.200.16.10 port 60932 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:51.804511 sshd-session[4537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:51.809041 systemd-logind[2168]: New session 16 of user core. Nov 4 23:53:51.819984 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 4 23:53:52.251840 sshd[4913]: Connection closed by 10.200.16.10 port 60932 Nov 4 23:53:52.252121 sshd-session[4537]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:52.255760 systemd[1]: sshd@13-10.200.8.36:22-10.200.16.10:60932.service: Deactivated successfully. Nov 4 23:53:52.257504 systemd[1]: session-16.scope: Deactivated successfully. Nov 4 23:53:52.258233 systemd-logind[2168]: Session 16 logged out. Waiting for processes to exit. Nov 4 23:53:52.259182 systemd-logind[2168]: Removed session 16. Nov 4 23:53:52.368202 systemd[1]: Started sshd@14-10.200.8.36:22-10.200.16.10:60936.service - OpenSSH per-connection server daemon (10.200.16.10:60936). Nov 4 23:53:53.004412 sshd[5234]: Accepted publickey for core from 10.200.16.10 port 60936 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:53:53.005695 sshd-session[5234]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:53:53.010350 systemd-logind[2168]: New session 17 of user core. Nov 4 23:53:53.016970 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 4 23:53:53.451261 sshd[5590]: Connection closed by 10.200.16.10 port 60936 Nov 4 23:53:53.451580 sshd-session[5234]: pam_unix(sshd:session): session closed for user core Nov 4 23:53:53.455481 systemd[1]: sshd@14-10.200.8.36:22-10.200.16.10:60936.service: Deactivated successfully. Nov 4 23:53:53.457227 systemd[1]: session-17.scope: Deactivated successfully. Nov 4 23:53:53.457936 systemd-logind[2168]: Session 17 logged out. Waiting for processes to exit. Nov 4 23:53:53.459047 systemd-logind[2168]: Removed session 17. Nov 4 23:54:03.570549 systemd[1]: Started sshd@15-10.200.8.36:22-10.200.16.10:59162.service - OpenSSH per-connection server daemon (10.200.16.10:59162). Nov 4 23:54:04.228661 sshd[9574]: Accepted publickey for core from 10.200.16.10 port 59162 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:04.230100 sshd-session[9574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:04.238410 systemd-logind[2168]: New session 18 of user core. Nov 4 23:54:04.247909 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 4 23:54:04.674385 sshd[9790]: Connection closed by 10.200.16.10 port 59162 Nov 4 23:54:04.676334 sshd-session[9574]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:04.680023 systemd[1]: sshd@15-10.200.8.36:22-10.200.16.10:59162.service: Deactivated successfully. Nov 4 23:54:04.682565 systemd[1]: session-18.scope: Deactivated successfully. Nov 4 23:54:04.684015 systemd-logind[2168]: Session 18 logged out. Waiting for processes to exit. Nov 4 23:54:04.685088 systemd-logind[2168]: Removed session 18. Nov 4 23:54:04.788389 systemd[1]: Started sshd@16-10.200.8.36:22-10.200.16.10:59170.service - OpenSSH per-connection server daemon (10.200.16.10:59170). Nov 4 23:54:05.433432 sshd[10096]: Accepted publickey for core from 10.200.16.10 port 59170 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:05.434671 sshd-session[10096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:05.438984 systemd-logind[2168]: New session 19 of user core. Nov 4 23:54:05.446969 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 4 23:54:05.881392 sshd[10488]: Connection closed by 10.200.16.10 port 59170 Nov 4 23:54:05.881679 sshd-session[10096]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:05.885702 systemd[1]: sshd@16-10.200.8.36:22-10.200.16.10:59170.service: Deactivated successfully. Nov 4 23:54:05.887324 systemd[1]: session-19.scope: Deactivated successfully. Nov 4 23:54:05.888299 systemd-logind[2168]: Session 19 logged out. Waiting for processes to exit. Nov 4 23:54:05.889193 systemd-logind[2168]: Removed session 19. Nov 4 23:54:15.998422 systemd[1]: Started sshd@17-10.200.8.36:22-10.200.16.10:43278.service - OpenSSH per-connection server daemon (10.200.16.10:43278). Nov 4 23:54:16.633842 sshd[14341]: Accepted publickey for core from 10.200.16.10 port 43278 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:16.635131 sshd-session[14341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:16.639765 systemd-logind[2168]: New session 20 of user core. Nov 4 23:54:16.647958 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 4 23:54:17.080306 sshd[14369]: Connection closed by 10.200.16.10 port 43278 Nov 4 23:54:17.080595 sshd-session[14341]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:17.084866 systemd[1]: sshd@17-10.200.8.36:22-10.200.16.10:43278.service: Deactivated successfully. Nov 4 23:54:17.086472 systemd[1]: session-20.scope: Deactivated successfully. Nov 4 23:54:17.087235 systemd-logind[2168]: Session 20 logged out. Waiting for processes to exit. Nov 4 23:54:17.088115 systemd-logind[2168]: Removed session 20. Nov 4 23:54:17.195306 systemd[1]: Started sshd@18-10.200.8.36:22-10.200.16.10:43292.service - OpenSSH per-connection server daemon (10.200.16.10:43292). Nov 4 23:54:17.848736 sshd[14375]: Accepted publickey for core from 10.200.16.10 port 43292 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:17.850309 sshd-session[14375]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:17.865034 systemd-logind[2168]: New session 21 of user core. Nov 4 23:54:17.873979 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 4 23:54:18.295344 sshd[14378]: Connection closed by 10.200.16.10 port 43292 Nov 4 23:54:18.295690 sshd-session[14375]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:18.299492 systemd[1]: sshd@18-10.200.8.36:22-10.200.16.10:43292.service: Deactivated successfully. Nov 4 23:54:18.302554 systemd[1]: session-21.scope: Deactivated successfully. Nov 4 23:54:18.303432 systemd-logind[2168]: Session 21 logged out. Waiting for processes to exit. Nov 4 23:54:18.304471 systemd-logind[2168]: Removed session 21. Nov 4 23:54:28.417343 systemd[1]: Started sshd@19-10.200.8.36:22-10.200.16.10:34686.service - OpenSSH per-connection server daemon (10.200.16.10:34686). Nov 4 23:54:29.062935 sshd[14780]: Accepted publickey for core from 10.200.16.10 port 34686 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:29.064666 sshd-session[14780]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:29.075099 systemd-logind[2168]: New session 22 of user core. Nov 4 23:54:29.079260 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 4 23:54:29.511689 sshd[14807]: Connection closed by 10.200.16.10 port 34686 Nov 4 23:54:29.514992 sshd-session[14780]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:29.518843 systemd[1]: sshd@19-10.200.8.36:22-10.200.16.10:34686.service: Deactivated successfully. Nov 4 23:54:29.521381 systemd[1]: session-22.scope: Deactivated successfully. Nov 4 23:54:29.522725 systemd-logind[2168]: Session 22 logged out. Waiting for processes to exit. Nov 4 23:54:29.524959 systemd-logind[2168]: Removed session 22. Nov 4 23:54:29.641508 systemd[1]: Started sshd@20-10.200.8.36:22-10.200.16.10:34698.service - OpenSSH per-connection server daemon (10.200.16.10:34698). Nov 4 23:54:30.297434 sshd[14831]: Accepted publickey for core from 10.200.16.10 port 34698 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:30.298809 sshd-session[14831]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:30.307636 systemd-logind[2168]: New session 23 of user core. Nov 4 23:54:30.316977 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 4 23:54:30.756078 sshd[14846]: Connection closed by 10.200.16.10 port 34698 Nov 4 23:54:30.761056 sshd-session[14831]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:30.766674 systemd-logind[2168]: Session 23 logged out. Waiting for processes to exit. Nov 4 23:54:30.767634 systemd[1]: sshd@20-10.200.8.36:22-10.200.16.10:34698.service: Deactivated successfully. Nov 4 23:54:30.771669 systemd[1]: session-23.scope: Deactivated successfully. Nov 4 23:54:30.773802 systemd-logind[2168]: Removed session 23. Nov 4 23:54:40.873189 systemd[1]: Started sshd@21-10.200.8.36:22-10.200.16.10:55794.service - OpenSSH per-connection server daemon (10.200.16.10:55794). Nov 4 23:54:41.537805 sshd[15176]: Accepted publickey for core from 10.200.16.10 port 55794 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:41.538725 sshd-session[15176]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:41.545067 systemd-logind[2168]: New session 24 of user core. Nov 4 23:54:41.556336 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 4 23:54:41.988427 sshd[15204]: Connection closed by 10.200.16.10 port 55794 Nov 4 23:54:41.988786 sshd-session[15176]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:41.995329 systemd-logind[2168]: Session 24 logged out. Waiting for processes to exit. Nov 4 23:54:41.996399 systemd[1]: sshd@21-10.200.8.36:22-10.200.16.10:55794.service: Deactivated successfully. Nov 4 23:54:41.998749 systemd[1]: session-24.scope: Deactivated successfully. Nov 4 23:54:42.003511 systemd-logind[2168]: Removed session 24. Nov 4 23:54:42.100360 systemd[1]: Started sshd@22-10.200.8.36:22-10.200.16.10:55810.service - OpenSSH per-connection server daemon (10.200.16.10:55810). Nov 4 23:54:42.752146 sshd[15221]: Accepted publickey for core from 10.200.16.10 port 55810 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:42.753666 sshd-session[15221]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:42.760816 systemd-logind[2168]: New session 25 of user core. Nov 4 23:54:42.771033 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 4 23:54:43.208296 sshd[15242]: Connection closed by 10.200.16.10 port 55810 Nov 4 23:54:43.208666 sshd-session[15221]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:43.212780 systemd-logind[2168]: Session 25 logged out. Waiting for processes to exit. Nov 4 23:54:43.214398 systemd[1]: sshd@22-10.200.8.36:22-10.200.16.10:55810.service: Deactivated successfully. Nov 4 23:54:43.216638 systemd[1]: session-25.scope: Deactivated successfully. Nov 4 23:54:43.219178 systemd-logind[2168]: Removed session 25. Nov 4 23:54:53.331381 systemd[1]: Started sshd@23-10.200.8.36:22-10.200.16.10:47122.service - OpenSSH per-connection server daemon (10.200.16.10:47122). Nov 4 23:54:53.970663 sshd[15545]: Accepted publickey for core from 10.200.16.10 port 47122 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:53.971932 sshd-session[15545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:53.976259 systemd-logind[2168]: New session 26 of user core. Nov 4 23:54:53.985942 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 4 23:54:54.429658 sshd[15548]: Connection closed by 10.200.16.10 port 47122 Nov 4 23:54:54.430009 sshd-session[15545]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:54.433744 systemd[1]: sshd@23-10.200.8.36:22-10.200.16.10:47122.service: Deactivated successfully. Nov 4 23:54:54.435315 systemd[1]: session-26.scope: Deactivated successfully. Nov 4 23:54:54.436017 systemd-logind[2168]: Session 26 logged out. Waiting for processes to exit. Nov 4 23:54:54.436869 systemd-logind[2168]: Removed session 26. Nov 4 23:54:54.544127 systemd[1]: Started sshd@24-10.200.8.36:22-10.200.16.10:47138.service - OpenSSH per-connection server daemon (10.200.16.10:47138). Nov 4 23:54:55.177282 sshd[15554]: Accepted publickey for core from 10.200.16.10 port 47138 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:54:55.178546 sshd-session[15554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:54:55.185947 systemd-logind[2168]: New session 27 of user core. Nov 4 23:54:55.193953 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 4 23:54:55.624956 sshd[15557]: Connection closed by 10.200.16.10 port 47138 Nov 4 23:54:55.625298 sshd-session[15554]: pam_unix(sshd:session): session closed for user core Nov 4 23:54:55.628996 systemd[1]: sshd@24-10.200.8.36:22-10.200.16.10:47138.service: Deactivated successfully. Nov 4 23:54:55.630574 systemd[1]: session-27.scope: Deactivated successfully. Nov 4 23:54:55.631288 systemd-logind[2168]: Session 27 logged out. Waiting for processes to exit. Nov 4 23:54:55.632174 systemd-logind[2168]: Removed session 27. Nov 4 23:55:00.416835 setup-nvidia[2917]: \u000d Nov 4 23:55:00.416835 setup-nvidia[2917]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 4 23:55:00.416835 setup-nvidia[2917]: frequently when this kernel module was built against the wrong or Nov 4 23:55:00.416835 setup-nvidia[2917]: improperly configured kernel sources, with a version of gcc that differs Nov 4 23:55:00.416835 setup-nvidia[2917]: from the one used to build the target kernel, or if another driver, such Nov 4 23:55:00.416835 setup-nvidia[2917]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 4 23:55:00.416835 setup-nvidia[2917]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 4 23:55:00.416835 setup-nvidia[2917]: installed in this system is supported by this NVIDIA Linux graphics Nov 4 23:55:00.416835 setup-nvidia[2917]: driver release. Nov 4 23:55:00.416835 setup-nvidia[2917]: \u000d Nov 4 23:55:00.416835 setup-nvidia[2917]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 4 23:55:00.416835 setup-nvidia[2917]: messages' at the end of the file Nov 4 23:55:00.416835 setup-nvidia[2917]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 4 23:55:00.416835 setup-nvidia[2917]: information. Nov 4 23:55:00.416835 setup-nvidia[2917]: \u000d Nov 4 23:55:00.432025 setup-nvidia[2917]: dmesg: read kernel buffer failed: Operation not permitted Nov 4 23:55:00.450395 setup-nvidia[2917]: \u000d Nov 4 23:55:00.450395 setup-nvidia[2917]: ERROR: Installation has failed. Please see the file Nov 4 23:55:00.450395 setup-nvidia[2917]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 4 23:55:00.450395 setup-nvidia[2917]: details. You may find suggestions on fixing installation problems in Nov 4 23:55:00.450395 setup-nvidia[2917]: the README available on the Linux driver download page at Nov 4 23:55:00.450395 setup-nvidia[2917]: www.nvidia.com. Nov 4 23:55:00.450395 setup-nvidia[2917]: \u000d Nov 4 23:55:00.450766 setup-nvidia[2917]: Last 50 lines of nvidia-installer.log: Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 4 23:55:00.451551 setup-nvidia[2917]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 4 23:55:00.451551 setup-nvidia[2917]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 4 23:55:00.451551 setup-nvidia[2917]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 4 23:55:00.451551 setup-nvidia[2917]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 4 23:55:00.451551 setup-nvidia[2917]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 4 23:55:00.452364 setup-nvidia[2917]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 4 23:55:00.452364 setup-nvidia[2917]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 4 23:55:00.452364 setup-nvidia[2917]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 4 23:55:00.452364 setup-nvidia[2917]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 4 23:55:00.452364 setup-nvidia[2917]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 4 23:55:00.452364 setup-nvidia[2917]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 4 23:55:00.452364 setup-nvidia[2917]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 4 23:55:00.452364 setup-nvidia[2917]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Nov 4 23:55:00.452364 setup-nvidia[2917]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Nov 4 23:55:00.452364 setup-nvidia[2917]: -> done. Nov 4 23:55:00.452364 setup-nvidia[2917]: -> Kernel module compilation complete. Nov 4 23:55:00.452364 setup-nvidia[2917]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 4 23:55:00.452364 setup-nvidia[2917]: 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 23:55:00.452364 setup-nvidia[2917]: \u000d Nov 4 23:55:00.452364 setup-nvidia[2917]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 4 23:55:00.452364 setup-nvidia[2917]: -> Kernel module load error: Operation not permitted Nov 4 23:55:00.452364 setup-nvidia[2917]: -> Kernel messages: Nov 4 23:55:00.452364 setup-nvidia[2917]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 4 23:55:05.743008 systemd[1]: Started sshd@25-10.200.8.36:22-10.200.16.10:59198.service - OpenSSH per-connection server daemon (10.200.16.10:59198). Nov 4 23:55:06.393295 sshd[15743]: Accepted publickey for core from 10.200.16.10 port 59198 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:06.394581 sshd-session[15743]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:06.398840 systemd-logind[2168]: New session 28 of user core. Nov 4 23:55:06.406939 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 4 23:55:06.839552 sshd[15746]: Connection closed by 10.200.16.10 port 59198 Nov 4 23:55:06.839845 sshd-session[15743]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:06.843647 systemd[1]: sshd@25-10.200.8.36:22-10.200.16.10:59198.service: Deactivated successfully. Nov 4 23:55:06.845509 systemd[1]: session-28.scope: Deactivated successfully. Nov 4 23:55:06.846361 systemd-logind[2168]: Session 28 logged out. Waiting for processes to exit. Nov 4 23:55:06.847297 systemd-logind[2168]: Removed session 28. Nov 4 23:55:06.954876 systemd[1]: Started sshd@26-10.200.8.36:22-10.200.16.10:59204.service - OpenSSH per-connection server daemon (10.200.16.10:59204). Nov 4 23:55:07.614126 sshd[15753]: Accepted publickey for core from 10.200.16.10 port 59204 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:07.615330 sshd-session[15753]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:07.619970 systemd-logind[2168]: New session 29 of user core. Nov 4 23:55:07.635948 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 4 23:55:08.060487 sshd[15757]: Connection closed by 10.200.16.10 port 59204 Nov 4 23:55:08.060755 sshd-session[15753]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:08.065029 systemd[1]: sshd@26-10.200.8.36:22-10.200.16.10:59204.service: Deactivated successfully. Nov 4 23:55:08.066629 systemd[1]: session-29.scope: Deactivated successfully. Nov 4 23:55:08.067340 systemd-logind[2168]: Session 29 logged out. Waiting for processes to exit. Nov 4 23:55:08.068235 systemd-logind[2168]: Removed session 29. Nov 4 23:55:15.777846 kernel: EXT4-fs (loop7p9): unmounting filesystem ff6c02a9-4867-4478-bb09-ef3fab0f393d. Nov 4 23:55:15.778352 setup-nvidia[2917]: Container flatcardevelopercontainer-4487.0.0nightly-20251104-2100.bin exited successfully. Nov 4 23:55:15.780598 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4487.0.0nightly\x2d20251104\x2d2100.bin.mount: Deactivated successfully. Nov 4 23:55:15.841660 sudo[2905]: pam_unix(sudo:session): session closed for user root Nov 4 23:55:15.843797 systemd[1]: session-c2.scope: Deactivated successfully. Nov 4 23:55:15.844101 systemd[1]: session-c2.scope: Consumed 5min 27.090s CPU time, 891.5M memory peak. Nov 4 23:55:16.289654 setup-nvidia[2165]: /opt/nvidia / Nov 4 23:55:16.290694 setup-nvidia[2165]: / Nov 4 23:55:16.299842 kernel: IPMI message handler: version 39.2 Nov 4 23:55:16.303836 kernel: ipmi device interface Nov 4 23:55:16.336071 setup-nvidia[2165]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Nov 4 23:55:16.390697 kernel: nvidia: loading out-of-tree module taints kernel. Nov 4 23:55:16.390856 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 4 23:55:16.390887 kernel: Disabling lock debugging due to kernel taint Nov 4 23:55:16.398060 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 4 23:55:16.398113 kernel: nvidia: module license taints kernel. Nov 4 23:55:16.805844 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 4 23:55:17.205071 kernel: Nov 4 23:55:17.205222 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 4 23:55:17.413842 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 4 23:55:17.435849 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 4 23:55:17.456861 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 4 23:55:17.510850 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 4 23:55:17.510954 setup-nvidia[2165]: / Nov 4 23:55:17.520417 setup-nvidia[15797]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 23:55:18.177217 systemd[1]: Started sshd@27-10.200.8.36:22-10.200.16.10:46928.service - OpenSSH per-connection server daemon (10.200.16.10:46928). Nov 4 23:55:18.810284 sshd[15819]: Accepted publickey for core from 10.200.16.10 port 46928 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:18.811510 sshd-session[15819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:18.816248 systemd-logind[2168]: New session 30 of user core. Nov 4 23:55:18.826952 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 4 23:55:19.257661 sshd[15823]: Connection closed by 10.200.16.10 port 46928 Nov 4 23:55:19.257995 sshd-session[15819]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:19.261547 systemd[1]: sshd@27-10.200.8.36:22-10.200.16.10:46928.service: Deactivated successfully. Nov 4 23:55:19.263146 systemd[1]: session-30.scope: Deactivated successfully. Nov 4 23:55:19.263903 systemd-logind[2168]: Session 30 logged out. Waiting for processes to exit. Nov 4 23:55:19.264719 systemd-logind[2168]: Removed session 30. Nov 4 23:55:19.328281 setup-nvidia[15814]: Tue Nov 4 23:55:19 2025 Nov 4 23:55:19.328281 setup-nvidia[15814]: +---------------------------------------------------------------------------------------+ Nov 4 23:55:19.328281 setup-nvidia[15814]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 4 23:55:19.328281 setup-nvidia[15814]: |-----------------------------------------+----------------------+----------------------+ Nov 4 23:55:19.328281 setup-nvidia[15814]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 4 23:55:19.328281 setup-nvidia[15814]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 4 23:55:19.328281 setup-nvidia[15814]: | | | MIG M. | Nov 4 23:55:19.328281 setup-nvidia[15814]: |=========================================+======================+======================| Nov 4 23:55:19.331729 setup-nvidia[15814]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 4 23:55:19.331729 setup-nvidia[15814]: | N/A 25C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Nov 4 23:55:19.331729 setup-nvidia[15814]: | | | N/A | Nov 4 23:55:19.331729 setup-nvidia[15814]: +-----------------------------------------+----------------------+----------------------+ Nov 4 23:55:19.331858 setup-nvidia[15814]: Nov 4 23:55:19.331858 setup-nvidia[15814]: +---------------------------------------------------------------------------------------+ Nov 4 23:55:19.331858 setup-nvidia[15814]: | Processes: | Nov 4 23:55:19.331858 setup-nvidia[15814]: | GPU GI CI PID Type Process name GPU Memory | Nov 4 23:55:19.331858 setup-nvidia[15814]: | ID ID Usage | Nov 4 23:55:19.331858 setup-nvidia[15814]: |=======================================================================================| Nov 4 23:55:19.331989 setup-nvidia[15814]: | No running processes found | Nov 4 23:55:19.331989 setup-nvidia[15814]: +---------------------------------------------------------------------------------------+ Nov 4 23:55:19.380095 systemd[1]: Started sshd@28-10.200.8.36:22-10.200.16.10:46938.service - OpenSSH per-connection server daemon (10.200.16.10:46938). Nov 4 23:55:19.702339 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 4 23:55:19.704521 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 4 23:55:19.704951 systemd[1]: Startup finished in 992ms (firmware) + 9.153s (loader) + 4.562s (kernel) + 12.273s (initrd) + 3min 36.658s (userspace) = 4min 3.640s. Nov 4 23:55:20.011978 sshd[15829]: Accepted publickey for core from 10.200.16.10 port 46938 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:20.013198 sshd-session[15829]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:20.017734 systemd-logind[2168]: New session 31 of user core. Nov 4 23:55:20.036955 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 4 23:55:20.457808 sshd[15834]: Connection closed by 10.200.16.10 port 46938 Nov 4 23:55:20.458187 sshd-session[15829]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:20.462000 systemd[1]: sshd@28-10.200.8.36:22-10.200.16.10:46938.service: Deactivated successfully. Nov 4 23:55:20.463582 systemd[1]: session-31.scope: Deactivated successfully. Nov 4 23:55:20.464309 systemd-logind[2168]: Session 31 logged out. Waiting for processes to exit. Nov 4 23:55:20.465184 systemd-logind[2168]: Removed session 31. Nov 4 23:55:25.860861 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 4 23:55:25.861617 systemd[2908]: Activating special unit exit.target... Nov 4 23:55:25.861705 systemd[2908]: Stopped target default.target - Main User Target. Nov 4 23:55:25.861747 systemd[2908]: Stopped target basic.target - Basic System. Nov 4 23:55:25.861776 systemd[2908]: Stopped target paths.target - Paths. Nov 4 23:55:25.861802 systemd[2908]: Stopped target sockets.target - Sockets. Nov 4 23:55:25.861845 systemd[2908]: Stopped target timers.target - Timers. Nov 4 23:55:25.862191 systemd[2908]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 4 23:55:25.862403 systemd[2908]: Removed slice app.slice - User Application Slice. Nov 4 23:55:25.862434 systemd[2908]: Reached target shutdown.target - Shutdown. Nov 4 23:55:25.862579 systemd[2908]: Finished systemd-exit.service - Exit the Session. Nov 4 23:55:25.862618 systemd[2908]: Reached target exit.target - Exit the Session. Nov 4 23:55:25.886980 systemd-logind[2168]: Removed session c3. Nov 4 23:55:25.886985 (sd-pam)[2909]: pam_unix(systemd-user:session): session closed for user root Nov 4 23:55:25.888283 systemd[1]: user@0.service: Deactivated successfully. Nov 4 23:55:25.888754 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 4 23:55:25.891529 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 4 23:55:25.911932 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 4 23:55:25.912923 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 4 23:55:25.913148 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 4 23:55:25.914903 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 4 23:55:25.915005 systemd[1]: user-0.slice: Consumed 5min 27.319s CPU time, 897.6M memory peak. Nov 4 23:55:30.574151 systemd[1]: Started sshd@29-10.200.8.36:22-10.200.16.10:32774.service - OpenSSH per-connection server daemon (10.200.16.10:32774). Nov 4 23:55:31.229514 sshd[15844]: Accepted publickey for core from 10.200.16.10 port 32774 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:31.230888 sshd-session[15844]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:31.235340 systemd-logind[2168]: New session 32 of user core. Nov 4 23:55:31.242940 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 4 23:55:31.682652 sshd[15847]: Connection closed by 10.200.16.10 port 32774 Nov 4 23:55:31.683048 sshd-session[15844]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:31.686203 systemd[1]: sshd@29-10.200.8.36:22-10.200.16.10:32774.service: Deactivated successfully. Nov 4 23:55:31.688011 systemd[1]: session-32.scope: Deactivated successfully. Nov 4 23:55:31.689201 systemd-logind[2168]: Session 32 logged out. Waiting for processes to exit. Nov 4 23:55:31.690218 systemd-logind[2168]: Removed session 32. Nov 4 23:55:31.798483 systemd[1]: Started sshd@30-10.200.8.36:22-10.200.16.10:32790.service - OpenSSH per-connection server daemon (10.200.16.10:32790). Nov 4 23:55:32.461065 sshd[15853]: Accepted publickey for core from 10.200.16.10 port 32790 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:32.462328 sshd-session[15853]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:32.466741 systemd-logind[2168]: New session 33 of user core. Nov 4 23:55:32.475939 systemd[1]: Started session-33.scope - Session 33 of User core. Nov 4 23:55:32.908037 sshd[15856]: Connection closed by 10.200.16.10 port 32790 Nov 4 23:55:32.908379 sshd-session[15853]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:32.912304 systemd[1]: sshd@30-10.200.8.36:22-10.200.16.10:32790.service: Deactivated successfully. Nov 4 23:55:32.913936 systemd[1]: session-33.scope: Deactivated successfully. Nov 4 23:55:32.914632 systemd-logind[2168]: Session 33 logged out. Waiting for processes to exit. Nov 4 23:55:32.915629 systemd-logind[2168]: Removed session 33. Nov 4 23:55:33.024250 systemd[1]: Started sshd@31-10.200.8.36:22-10.200.16.10:32796.service - OpenSSH per-connection server daemon (10.200.16.10:32796). Nov 4 23:55:33.670835 sshd[15862]: Accepted publickey for core from 10.200.16.10 port 32796 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:33.672119 sshd-session[15862]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:33.676431 systemd-logind[2168]: New session 34 of user core. Nov 4 23:55:33.686938 systemd[1]: Started session-34.scope - Session 34 of User core. Nov 4 23:55:34.120325 sshd[15865]: Connection closed by 10.200.16.10 port 32796 Nov 4 23:55:34.120663 sshd-session[15862]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:34.124383 systemd[1]: sshd@31-10.200.8.36:22-10.200.16.10:32796.service: Deactivated successfully. Nov 4 23:55:34.126042 systemd[1]: session-34.scope: Deactivated successfully. Nov 4 23:55:34.126852 systemd-logind[2168]: Session 34 logged out. Waiting for processes to exit. Nov 4 23:55:34.127730 systemd-logind[2168]: Removed session 34. Nov 4 23:55:34.237271 systemd[1]: Started sshd@32-10.200.8.36:22-10.200.16.10:32798.service - OpenSSH per-connection server daemon (10.200.16.10:32798). Nov 4 23:55:34.868668 sshd[15871]: Accepted publickey for core from 10.200.16.10 port 32798 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:34.869947 sshd-session[15871]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:34.874267 systemd-logind[2168]: New session 35 of user core. Nov 4 23:55:34.885952 systemd[1]: Started session-35.scope - Session 35 of User core. Nov 4 23:55:35.320249 sshd[15874]: Connection closed by 10.200.16.10 port 32798 Nov 4 23:55:35.320501 sshd-session[15871]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:35.324353 systemd[1]: sshd@32-10.200.8.36:22-10.200.16.10:32798.service: Deactivated successfully. Nov 4 23:55:35.326046 systemd[1]: session-35.scope: Deactivated successfully. Nov 4 23:55:35.326725 systemd-logind[2168]: Session 35 logged out. Waiting for processes to exit. Nov 4 23:55:35.327584 systemd-logind[2168]: Removed session 35. Nov 4 23:55:35.444197 systemd[1]: Started sshd@33-10.200.8.36:22-10.200.16.10:32814.service - OpenSSH per-connection server daemon (10.200.16.10:32814). Nov 4 23:55:36.102954 sshd[15880]: Accepted publickey for core from 10.200.16.10 port 32814 ssh2: RSA SHA256:doI8oKu/N6f14dWTU3qS9AkkBz7kdxEFfOoRFYt0JD8 Nov 4 23:55:36.104203 sshd-session[15880]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:55:36.109071 systemd-logind[2168]: New session 36 of user core. Nov 4 23:55:36.124963 systemd[1]: Started session-36.scope - Session 36 of User core. Nov 4 23:55:38.070027 sshd[15883]: Connection closed by 10.200.16.10 port 32814 Nov 4 23:55:38.070400 sshd-session[15880]: pam_unix(sshd:session): session closed for user core Nov 4 23:55:38.074344 systemd[1]: sshd@33-10.200.8.36:22-10.200.16.10:32814.service: Deactivated successfully. Nov 4 23:55:38.076377 systemd[1]: session-36.scope: Deactivated successfully. Nov 4 23:55:38.076619 systemd[1]: session-36.scope: Consumed 1.455s CPU time, 14.2M memory peak. Nov 4 23:55:38.077729 systemd-logind[2168]: Session 36 logged out. Waiting for processes to exit. Nov 4 23:55:38.078563 systemd-logind[2168]: Removed session 36.