Oct 30 00:16:43.185499 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Oct 29 22:07:32 -00 2025 Oct 30 00:16:43.185528 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=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:16:43.185538 kernel: BIOS-provided physical RAM map: Oct 30 00:16:43.185544 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 30 00:16:43.185550 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 30 00:16:43.185557 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 30 00:16:43.185565 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 30 00:16:43.185574 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 30 00:16:43.185581 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 30 00:16:43.185588 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 30 00:16:43.185594 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 30 00:16:43.185601 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 30 00:16:43.185608 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 30 00:16:43.185615 kernel: NX (Execute Disable) protection: active Oct 30 00:16:43.185625 kernel: APIC: Static calls initialized Oct 30 00:16:43.185633 kernel: efi: EFI v2.7 by Microsoft Oct 30 00:16:43.185640 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 30 00:16:43.185648 kernel: random: crng init done Oct 30 00:16:43.185655 kernel: secureboot: Secure boot disabled Oct 30 00:16:43.185662 kernel: SMBIOS 3.1.0 present. Oct 30 00:16:43.185670 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 30 00:16:43.185677 kernel: DMI: Memory slots populated: 3/3 Oct 30 00:16:43.185685 kernel: Hypervisor detected: Microsoft Hyper-V Oct 30 00:16:43.185692 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 30 00:16:43.185702 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 30 00:16:43.185709 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 30 00:16:43.185716 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 30 00:16:43.185724 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 30 00:16:43.185732 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 30 00:16:43.185740 kernel: tsc: Detected 2593.994 MHz processor Oct 30 00:16:43.185747 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 30 00:16:43.185755 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 30 00:16:43.185762 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 30 00:16:43.185772 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 30 00:16:43.185780 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 30 00:16:43.185787 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 30 00:16:43.185795 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 30 00:16:43.185802 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 30 00:16:43.185809 kernel: Using GB pages for direct mapping Oct 30 00:16:43.185817 kernel: ACPI: Early table checksum verification disabled Oct 30 00:16:43.185828 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 30 00:16:43.185839 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185847 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185855 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 30 00:16:43.185863 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 30 00:16:43.185871 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185879 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185889 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185897 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185905 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185913 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185921 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:16:43.185929 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 30 00:16:43.185937 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 30 00:16:43.185944 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 30 00:16:43.185952 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 30 00:16:43.185962 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 30 00:16:43.185970 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 30 00:16:43.185978 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 30 00:16:43.185986 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 30 00:16:43.185994 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 30 00:16:43.186002 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 30 00:16:43.186010 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 30 00:16:43.186018 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 30 00:16:43.186028 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 30 00:16:43.186036 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 30 00:16:43.186044 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 30 00:16:43.186052 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 30 00:16:43.186060 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 30 00:16:43.186068 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 30 00:16:43.186076 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 30 00:16:43.186084 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 30 00:16:43.186092 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 30 00:16:43.186102 kernel: Zone ranges: Oct 30 00:16:43.186110 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 30 00:16:43.186118 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 30 00:16:43.186126 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 30 00:16:43.186134 kernel: Device empty Oct 30 00:16:43.186142 kernel: Movable zone start for each node Oct 30 00:16:43.186150 kernel: Early memory node ranges Oct 30 00:16:43.186158 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 30 00:16:43.186165 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 30 00:16:43.186176 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 30 00:16:43.186184 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 30 00:16:43.186191 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 30 00:16:43.186199 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 30 00:16:43.186207 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 30 00:16:43.186215 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 30 00:16:43.186223 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 30 00:16:43.186231 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 30 00:16:43.186239 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 30 00:16:43.186249 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 30 00:16:43.186257 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 30 00:16:43.186265 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 30 00:16:43.186273 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 30 00:16:43.186292 kernel: CPU topo: Max. logical packages: 1 Oct 30 00:16:43.186301 kernel: CPU topo: Max. logical dies: 1 Oct 30 00:16:43.186309 kernel: CPU topo: Max. dies per package: 1 Oct 30 00:16:43.186317 kernel: CPU topo: Max. threads per core: 1 Oct 30 00:16:43.186325 kernel: CPU topo: Num. cores per package: 6 Oct 30 00:16:43.186336 kernel: CPU topo: Num. threads per package: 6 Oct 30 00:16:43.186344 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 30 00:16:43.186351 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 30 00:16:43.186359 kernel: Booting paravirtualized kernel on Hyper-V Oct 30 00:16:43.186368 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 30 00:16:43.186376 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 30 00:16:43.186384 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 30 00:16:43.186391 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 30 00:16:43.186399 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 30 00:16:43.186410 kernel: Hyper-V: PV spinlocks enabled Oct 30 00:16:43.186418 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 30 00:16:43.186427 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=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:16:43.186435 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 30 00:16:43.186482 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 30 00:16:43.186495 kernel: Fallback order for Node 0: 0 Oct 30 00:16:43.186503 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 30 00:16:43.186511 kernel: Policy zone: Normal Oct 30 00:16:43.186523 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 00:16:43.186539 kernel: software IO TLB: area num 8. Oct 30 00:16:43.186548 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 30 00:16:43.186559 kernel: Kernel/User page tables isolation: enabled Oct 30 00:16:43.186567 kernel: ftrace: allocating 40021 entries in 157 pages Oct 30 00:16:43.186576 kernel: ftrace: allocated 157 pages with 5 groups Oct 30 00:16:43.186584 kernel: Dynamic Preempt: voluntary Oct 30 00:16:43.186592 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 30 00:16:43.186601 kernel: rcu: RCU event tracing is enabled. Oct 30 00:16:43.186610 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 30 00:16:43.186618 kernel: Trampoline variant of Tasks RCU enabled. Oct 30 00:16:43.186629 kernel: Rude variant of Tasks RCU enabled. Oct 30 00:16:43.186638 kernel: Tracing variant of Tasks RCU enabled. Oct 30 00:16:43.186646 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 30 00:16:43.186654 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 30 00:16:43.186663 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:16:43.186671 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:16:43.186682 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:16:43.186691 kernel: Using NULL legacy PIC Oct 30 00:16:43.186699 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 30 00:16:43.186708 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 30 00:16:43.186716 kernel: Console: colour dummy device 80x25 Oct 30 00:16:43.186724 kernel: printk: legacy console [tty1] enabled Oct 30 00:16:43.186733 kernel: printk: legacy console [ttyS0] enabled Oct 30 00:16:43.186741 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 30 00:16:43.186749 kernel: ACPI: Core revision 20240827 Oct 30 00:16:43.186760 kernel: Failed to register legacy timer interrupt Oct 30 00:16:43.186768 kernel: APIC: Switch to symmetric I/O mode setup Oct 30 00:16:43.186777 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 30 00:16:43.186785 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 30 00:16:43.186793 kernel: Hyper-V: Using IPI hypercalls Oct 30 00:16:43.186801 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 30 00:16:43.186810 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 30 00:16:43.186818 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 30 00:16:43.186827 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 30 00:16:43.186838 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 30 00:16:43.186846 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 30 00:16:43.186854 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 30 00:16:43.186863 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 30 00:16:43.186871 kernel: APIC: read() replaced with hv_apic_read() Oct 30 00:16:43.186880 kernel: APIC: write() replaced with hv_apic_write() Oct 30 00:16:43.186888 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 30 00:16:43.186896 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 30 00:16:43.186905 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593994) Oct 30 00:16:43.186916 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 30 00:16:43.186924 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 30 00:16:43.186933 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 30 00:16:43.186941 kernel: Spectre V2 : Mitigation: Retpolines Oct 30 00:16:43.186949 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 30 00:16:43.186957 kernel: Speculative Store Bypass: Vulnerable Oct 30 00:16:43.186965 kernel: MDS: Mitigation: Clear CPU buffers Oct 30 00:16:43.186973 kernel: TAA: Mitigation: Clear CPU buffers Oct 30 00:16:43.186982 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 30 00:16:43.186990 kernel: active return thunk: its_return_thunk Oct 30 00:16:43.186998 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 30 00:16:43.187009 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 30 00:16:43.187017 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 30 00:16:43.187025 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 30 00:16:43.187033 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 30 00:16:43.187042 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 30 00:16:43.187050 kernel: Freeing SMP alternatives memory: 32K Oct 30 00:16:43.187058 kernel: pid_max: default: 32768 minimum: 301 Oct 30 00:16:43.187066 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 30 00:16:43.187075 kernel: landlock: Up and running. Oct 30 00:16:43.187083 kernel: SELinux: Initializing. Oct 30 00:16:43.187091 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 30 00:16:43.187102 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 30 00:16:43.187110 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 30 00:16:43.187119 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 30 00:16:43.187127 kernel: signal: max sigframe size: 1776 Oct 30 00:16:43.187135 kernel: rcu: Hierarchical SRCU implementation. Oct 30 00:16:43.187144 kernel: rcu: Max phase no-delay instances is 400. Oct 30 00:16:43.187152 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 30 00:16:43.187161 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 30 00:16:43.187169 kernel: smp: Bringing up secondary CPUs ... Oct 30 00:16:43.187180 kernel: smpboot: x86: Booting SMP configuration: Oct 30 00:16:43.187188 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 30 00:16:43.187196 kernel: smp: Brought up 1 node, 6 CPUs Oct 30 00:16:43.187205 kernel: smpboot: Total of 6 processors activated (31127.92 BogoMIPS) Oct 30 00:16:43.187214 kernel: Memory: 115332944K/117439364K available (14336K kernel code, 2436K rwdata, 26048K rodata, 45544K init, 1184K bss, 2096144K reserved, 0K cma-reserved) Oct 30 00:16:43.187222 kernel: devtmpfs: initialized Oct 30 00:16:43.187230 kernel: x86/mm: Memory block size: 1024MB Oct 30 00:16:43.187239 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 30 00:16:43.187247 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 30 00:16:43.187258 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 30 00:16:43.187267 kernel: pinctrl core: initialized pinctrl subsystem Oct 30 00:16:43.187275 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 30 00:16:43.187300 kernel: audit: initializing netlink subsys (disabled) Oct 30 00:16:43.187309 kernel: audit: type=2000 audit(1761783399.029:1): state=initialized audit_enabled=0 res=1 Oct 30 00:16:43.187317 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 00:16:43.187325 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 30 00:16:43.187334 kernel: cpuidle: using governor menu Oct 30 00:16:43.187342 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 30 00:16:43.187354 kernel: dca service started, version 1.12.1 Oct 30 00:16:43.187363 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 30 00:16:43.187371 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 30 00:16:43.187379 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 00:16:43.187388 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 30 00:16:43.187396 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 00:16:43.187405 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 30 00:16:43.187413 kernel: ACPI: Added _OSI(Module Device) Oct 30 00:16:43.187421 kernel: ACPI: Added _OSI(Processor Device) Oct 30 00:16:43.187432 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 30 00:16:43.187441 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 30 00:16:43.187449 kernel: ACPI: Interpreter enabled Oct 30 00:16:43.187457 kernel: ACPI: PM: (supports S0 S5) Oct 30 00:16:43.187466 kernel: ACPI: Using IOAPIC for interrupt routing Oct 30 00:16:43.187474 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 30 00:16:43.187482 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 30 00:16:43.187491 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 30 00:16:43.187500 kernel: iommu: Default domain type: Translated Oct 30 00:16:43.187510 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 30 00:16:43.187519 kernel: efivars: Registered efivars operations Oct 30 00:16:43.187527 kernel: PCI: Using ACPI for IRQ routing Oct 30 00:16:43.187535 kernel: PCI: System does not support PCI Oct 30 00:16:43.187544 kernel: vgaarb: loaded Oct 30 00:16:43.187552 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 30 00:16:43.187561 kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 00:16:43.187569 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 30 00:16:43.187577 kernel: pnp: PnP ACPI init Oct 30 00:16:43.187588 kernel: pnp: PnP ACPI: found 3 devices Oct 30 00:16:43.187597 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 30 00:16:43.187605 kernel: NET: Registered PF_INET protocol family Oct 30 00:16:43.187614 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 00:16:43.187622 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 30 00:16:43.187631 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 30 00:16:43.187639 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 00:16:43.187648 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:16:43.187656 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 30 00:16:43.187667 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:16:43.187675 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:16:43.187684 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 30 00:16:43.187692 kernel: NET: Registered PF_XDP protocol family Oct 30 00:16:43.187700 kernel: PCI: CLS 0 bytes, default 64 Oct 30 00:16:43.187709 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 30 00:16:43.187717 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Oct 30 00:16:43.187726 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 30 00:16:43.187734 kernel: Initialise system trusted keyrings Oct 30 00:16:43.187745 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 30 00:16:43.187753 kernel: Key type asymmetric registered Oct 30 00:16:43.187761 kernel: Asymmetric key parser 'x509' registered Oct 30 00:16:43.187769 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 30 00:16:43.187778 kernel: io scheduler mq-deadline registered Oct 30 00:16:43.187786 kernel: io scheduler kyber registered Oct 30 00:16:43.187794 kernel: io scheduler bfq registered Oct 30 00:16:43.187803 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 30 00:16:43.187811 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 30 00:16:43.187822 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 30 00:16:43.187830 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 30 00:16:43.187839 kernel: i8042: PNP: No PS/2 controller found. Oct 30 00:16:43.187994 kernel: rtc_cmos 00:02: registered as rtc0 Oct 30 00:16:43.188100 kernel: rtc_cmos 00:02: setting system clock to 2025-10-30T00:16:42 UTC (1761783402) Oct 30 00:16:43.188198 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 30 00:16:43.188211 kernel: intel_pstate: CPU model not supported Oct 30 00:16:43.188223 kernel: efifb: probing for efifb Oct 30 00:16:43.188232 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 30 00:16:43.188240 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 30 00:16:43.188249 kernel: efifb: scrolling: redraw Oct 30 00:16:43.188257 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 30 00:16:43.188266 kernel: Console: switching to colour frame buffer device 128x48 Oct 30 00:16:43.188274 kernel: fb0: EFI VGA frame buffer device Oct 30 00:16:43.188294 kernel: pstore: Using crash dump compression: deflate Oct 30 00:16:43.188303 kernel: pstore: Registered efi_pstore as persistent store backend Oct 30 00:16:43.188315 kernel: NET: Registered PF_INET6 protocol family Oct 30 00:16:43.188323 kernel: Segment Routing with IPv6 Oct 30 00:16:43.188332 kernel: In-situ OAM (IOAM) with IPv6 Oct 30 00:16:43.188340 kernel: NET: Registered PF_PACKET protocol family Oct 30 00:16:43.188349 kernel: Key type dns_resolver registered Oct 30 00:16:43.188357 kernel: IPI shorthand broadcast: enabled Oct 30 00:16:43.188366 kernel: sched_clock: Marking stable (3573005100, 112371000)->(4028203600, -342827500) Oct 30 00:16:43.188374 kernel: registered taskstats version 1 Oct 30 00:16:43.188383 kernel: Loading compiled-in X.509 certificates Oct 30 00:16:43.188391 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 815fc40077fbc06b8d9e8a6016fea83aecff0a2a' Oct 30 00:16:43.188402 kernel: Demotion targets for Node 0: null Oct 30 00:16:43.188410 kernel: Key type .fscrypt registered Oct 30 00:16:43.188418 kernel: Key type fscrypt-provisioning registered Oct 30 00:16:43.188426 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 30 00:16:43.188435 kernel: ima: Allocated hash algorithm: sha1 Oct 30 00:16:43.188443 kernel: ima: No architecture policies found Oct 30 00:16:43.188451 kernel: clk: Disabling unused clocks Oct 30 00:16:43.188460 kernel: Warning: unable to open an initial console. Oct 30 00:16:43.188468 kernel: Freeing unused kernel image (initmem) memory: 45544K Oct 30 00:16:43.188479 kernel: Write protecting the kernel read-only data: 40960k Oct 30 00:16:43.188488 kernel: Freeing unused kernel image (rodata/data gap) memory: 576K Oct 30 00:16:43.188496 kernel: Run /init as init process Oct 30 00:16:43.188505 kernel: with arguments: Oct 30 00:16:43.188513 kernel: /init Oct 30 00:16:43.188521 kernel: with environment: Oct 30 00:16:43.188529 kernel: HOME=/ Oct 30 00:16:43.188537 kernel: TERM=linux Oct 30 00:16:43.188547 systemd[1]: Successfully made /usr/ read-only. Oct 30 00:16:43.188561 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 30 00:16:43.188570 systemd[1]: Detected virtualization microsoft. Oct 30 00:16:43.188579 systemd[1]: Detected architecture x86-64. Oct 30 00:16:43.188587 systemd[1]: Running in initrd. Oct 30 00:16:43.188596 systemd[1]: No hostname configured, using default hostname. Oct 30 00:16:43.188605 systemd[1]: Hostname set to . Oct 30 00:16:43.188614 systemd[1]: Initializing machine ID from random generator. Oct 30 00:16:43.188625 systemd[1]: Queued start job for default target initrd.target. Oct 30 00:16:43.188634 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:16:43.188643 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:16:43.188652 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 30 00:16:43.188661 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 00:16:43.188671 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 30 00:16:43.188680 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 30 00:16:43.188692 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 30 00:16:43.188702 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 30 00:16:43.188711 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:16:43.188719 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:16:43.188728 systemd[1]: Reached target paths.target - Path Units. Oct 30 00:16:43.188737 systemd[1]: Reached target slices.target - Slice Units. Oct 30 00:16:43.188746 systemd[1]: Reached target swap.target - Swaps. Oct 30 00:16:43.188755 systemd[1]: Reached target timers.target - Timer Units. Oct 30 00:16:43.188766 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 00:16:43.188775 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 00:16:43.188784 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 30 00:16:43.188793 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 30 00:16:43.188802 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:16:43.188810 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 00:16:43.188819 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:16:43.188828 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 00:16:43.188837 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 30 00:16:43.188848 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 00:16:43.188857 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 30 00:16:43.188866 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 30 00:16:43.188875 systemd[1]: Starting systemd-fsck-usr.service... Oct 30 00:16:43.188884 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 00:16:43.188893 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 00:16:43.188924 systemd-journald[214]: Collecting audit messages is disabled. Oct 30 00:16:43.188949 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:16:43.188959 systemd-journald[214]: Journal started Oct 30 00:16:43.188982 systemd-journald[214]: Runtime Journal (/run/log/journal/1ad50335d58748efa9cc52c342f5c1d1) is 8M, max 2.2G, 2.1G free. Oct 30 00:16:43.197299 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 00:16:43.201584 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 30 00:16:43.201861 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:16:43.202631 systemd[1]: Finished systemd-fsck-usr.service. Oct 30 00:16:43.204872 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 30 00:16:43.206140 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 00:16:43.246982 systemd-modules-load[216]: Inserted module 'overlay' Oct 30 00:16:43.252223 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 30 00:16:43.254507 systemd-tmpfiles[224]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 30 00:16:43.269965 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 00:16:43.270338 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:16:43.280346 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:16:43.295641 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 30 00:16:43.319300 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 30 00:16:43.323294 kernel: Bridge firewalling registered Oct 30 00:16:43.323347 systemd-modules-load[216]: Inserted module 'br_netfilter' Oct 30 00:16:43.328970 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 00:16:43.334581 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 00:16:43.343366 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:16:43.362766 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 00:16:43.363057 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:16:43.382294 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 30 00:16:43.401538 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 00:16:43.419887 dracut-cmdline[256]: 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=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:16:43.436581 systemd-resolved[257]: Positive Trust Anchors: Oct 30 00:16:43.436590 systemd-resolved[257]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 00:16:43.436620 systemd-resolved[257]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 30 00:16:43.439107 systemd-resolved[257]: Defaulting to hostname 'linux'. Oct 30 00:16:43.440122 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 00:16:43.450599 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:16:43.589309 kernel: SCSI subsystem initialized Oct 30 00:16:43.600302 kernel: Loading iSCSI transport class v2.0-870. Oct 30 00:16:43.612302 kernel: iscsi: registered transport (tcp) Oct 30 00:16:43.636421 kernel: iscsi: registered transport (qla4xxx) Oct 30 00:16:43.636453 kernel: QLogic iSCSI HBA Driver Oct 30 00:16:43.658820 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 00:16:43.687678 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:16:43.688771 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 00:16:43.754688 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 30 00:16:43.756111 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 30 00:16:43.823303 kernel: raid6: avx2x4 gen() 31040 MB/s Oct 30 00:16:43.843297 kernel: raid6: avx2x2 gen() 31174 MB/s Oct 30 00:16:43.864731 kernel: raid6: avx2x1 gen() 26865 MB/s Oct 30 00:16:43.864757 kernel: raid6: using algorithm avx2x2 gen() 31174 MB/s Oct 30 00:16:43.886673 kernel: raid6: .... xor() 18518 MB/s, rmw enabled Oct 30 00:16:43.886697 kernel: raid6: using avx2x2 recovery algorithm Oct 30 00:16:43.910300 kernel: xor: automatically using best checksumming function avx Oct 30 00:16:44.069309 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 30 00:16:44.076041 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 30 00:16:44.077621 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:16:44.124763 systemd-udevd[467]: Using default interface naming scheme 'v255'. Oct 30 00:16:44.131067 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:16:44.131956 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 30 00:16:44.170535 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Oct 30 00:16:44.195586 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 00:16:44.197584 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 00:16:44.273097 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:16:44.274424 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 30 00:16:44.304302 kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 00:16:44.314575 kernel: AES CTR mode by8 optimization enabled Oct 30 00:16:44.335299 kernel: hv_vmbus: Vmbus version:4.0 Oct 30 00:16:44.339503 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:16:44.340008 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:16:44.368201 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 30 00:16:44.368240 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Oct 30 00:16:44.363500 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:16:44.373562 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:16:44.396207 kernel: hv_vmbus: registering driver hv_netvsc Oct 30 00:16:44.396238 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 30 00:16:44.396251 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 30 00:16:44.396262 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 30 00:16:44.396274 kernel: hv_vmbus: registering driver hv_pci Oct 30 00:16:44.386678 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:16:44.427286 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 30 00:16:44.427474 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 30 00:16:44.427609 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 30 00:16:44.427760 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 30 00:16:44.427958 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 30 00:16:44.428085 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 30 00:16:44.406910 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:16:44.446053 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 30 00:16:44.446094 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 30 00:16:44.446115 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 30 00:16:44.407009 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:16:44.458209 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 30 00:16:44.458382 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:16:44.469431 kernel: PTP clock support registered Oct 30 00:16:44.474417 kernel: hv_vmbus: registering driver hid_hyperv Oct 30 00:16:44.474444 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 30 00:16:44.474611 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 30 00:16:44.485971 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 30 00:16:44.486157 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 30 00:16:44.491373 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 30 00:16:44.498368 kernel: hv_vmbus: registering driver hv_storvsc Oct 30 00:16:44.498399 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 30 00:16:44.502468 kernel: hv_utils: Registering HyperV Utility Driver Oct 30 00:16:44.510300 kernel: hv_vmbus: registering driver hv_utils Oct 30 00:16:44.545609 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:16:44.981541 kernel: hv_utils: Heartbeat IC version 3.0 Oct 30 00:16:44.981600 kernel: scsi host1: storvsc_host_t Oct 30 00:16:44.981799 kernel: scsi host0: storvsc_host_t Oct 30 00:16:44.981949 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 30 00:16:44.982103 kernel: hv_utils: Shutdown IC version 3.2 Oct 30 00:16:44.982118 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 30 00:16:44.982257 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 30 00:16:44.996202 kernel: hv_utils: TimeSync IC version 4.0 Oct 30 00:16:45.425885 systemd-resolved[257]: Clock change detected. Flushing caches. Oct 30 00:16:45.477239 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 30 00:16:45.477497 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 30 00:16:45.477674 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Oct 30 00:16:45.477816 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Oct 30 00:16:45.477949 kernel: sd 0:0:0:1: [sda] Write Protect is off Oct 30 00:16:45.478081 kernel: sd 0:0:0:0: [sdb] Write Protect is off Oct 30 00:16:45.478211 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Oct 30 00:16:45.478339 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Oct 30 00:16:45.478490 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 30 00:16:45.478627 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 30 00:16:45.478757 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#56 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:45.478893 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#389 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:45.479010 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#63 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:45.479130 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#396 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:45.497161 kernel: sda: sda1 Oct 30 00:16:45.497191 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Oct 30 00:16:45.505480 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:16:45.510485 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Oct 30 00:16:45.510663 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 30 00:16:45.515349 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 30 00:16:45.517489 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 30 00:16:46.075756 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 30 00:16:46.111096 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 30 00:16:46.174835 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 30 00:16:46.221032 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 30 00:16:46.226308 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 30 00:16:46.239695 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 30 00:16:46.283483 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#423 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:46.300485 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:16:47.313501 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#281 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:16:47.329483 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:16:47.329520 disk-uuid[705]: The operation has completed successfully. Oct 30 00:16:48.862494 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 30 00:16:48.862610 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 30 00:16:48.863907 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 30 00:16:48.891928 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 30 00:16:48.892767 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 00:16:48.915112 sh[869]: Success Oct 30 00:16:48.904105 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:16:48.922954 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 00:16:48.930850 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 30 00:16:48.961247 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 30 00:16:48.961281 kernel: device-mapper: uevent: version 1.0.3 Oct 30 00:16:48.966007 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 30 00:16:48.977483 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Oct 30 00:16:48.978388 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 30 00:16:49.418956 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 30 00:16:49.429067 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 30 00:16:49.450242 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 30 00:16:49.468592 kernel: BTRFS: device fsid ad8523d8-35e6-44b9-a685-e8d871101da4 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (896) Oct 30 00:16:49.477660 kernel: BTRFS info (device dm-0): first mount of filesystem ad8523d8-35e6-44b9-a685-e8d871101da4 Oct 30 00:16:49.477689 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:16:49.951193 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 30 00:16:49.951256 kernel: BTRFS info (device dm-0): enabling free space tree Oct 30 00:16:50.027035 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 30 00:16:50.031326 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 30 00:16:50.034421 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 30 00:16:50.043396 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 30 00:16:50.052085 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 30 00:16:50.114483 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (929) Oct 30 00:16:50.124288 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:16:50.124321 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:16:50.175527 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 00:16:50.186847 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:16:50.186872 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:16:50.186885 kernel: BTRFS info (device sdb6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:16:50.178224 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 00:16:50.197221 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 30 00:16:50.216031 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 30 00:16:50.248306 systemd-networkd[1063]: lo: Link UP Oct 30 00:16:50.248317 systemd-networkd[1063]: lo: Gained carrier Oct 30 00:16:50.249379 systemd-networkd[1063]: Enumeration completed Oct 30 00:16:50.249491 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 00:16:50.249747 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:16:50.249752 systemd-networkd[1063]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:16:50.250526 systemd-networkd[1063]: eth0: Link UP Oct 30 00:16:50.250688 systemd-networkd[1063]: eth0: Gained carrier Oct 30 00:16:50.250698 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:16:50.255988 systemd[1]: Reached target network.target - Network. Oct 30 00:16:50.319507 systemd-networkd[1063]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:16:52.117603 systemd-networkd[1063]: eth0: Gained IPv6LL Oct 30 00:16:52.548617 ignition[1066]: Ignition 2.22.0 Oct 30 00:16:52.548631 ignition[1066]: Stage: fetch-offline Oct 30 00:16:52.550677 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 00:16:52.548754 ignition[1066]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:52.558319 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 30 00:16:52.548765 ignition[1066]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:52.548859 ignition[1066]: parsed url from cmdline: "" Oct 30 00:16:52.548863 ignition[1066]: no config URL provided Oct 30 00:16:52.548872 ignition[1066]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 00:16:52.548881 ignition[1066]: no config at "/usr/lib/ignition/user.ign" Oct 30 00:16:52.548886 ignition[1066]: failed to fetch config: resource requires networking Oct 30 00:16:52.549044 ignition[1066]: Ignition finished successfully Oct 30 00:16:52.601715 ignition[1078]: Ignition 2.22.0 Oct 30 00:16:52.601722 ignition[1078]: Stage: fetch Oct 30 00:16:52.601955 ignition[1078]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:52.601967 ignition[1078]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:52.602056 ignition[1078]: parsed url from cmdline: "" Oct 30 00:16:52.602061 ignition[1078]: no config URL provided Oct 30 00:16:52.602066 ignition[1078]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 00:16:52.602075 ignition[1078]: no config at "/usr/lib/ignition/user.ign" Oct 30 00:16:52.602093 ignition[1078]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 30 00:16:52.714374 ignition[1078]: GET result: OK Oct 30 00:16:52.714485 ignition[1078]: config has been read from IMDS userdata Oct 30 00:16:52.714509 ignition[1078]: parsing config with SHA512: 3fd6a38c3a5a08a27ba32797e169fe43e45d348907ccce97abc4e97c3b9acfc5d452cbf50a031798794134966e06ef438dcf88c7e9e10e97282c513b4897cee6 Oct 30 00:16:52.719813 unknown[1078]: fetched base config from "system" Oct 30 00:16:52.719979 ignition[1078]: fetch: fetch complete Oct 30 00:16:52.719821 unknown[1078]: fetched base config from "system" Oct 30 00:16:52.719985 ignition[1078]: fetch: fetch passed Oct 30 00:16:52.719826 unknown[1078]: fetched user config from "azure" Oct 30 00:16:52.720031 ignition[1078]: Ignition finished successfully Oct 30 00:16:52.727098 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 30 00:16:52.728821 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 30 00:16:52.776859 ignition[1088]: Ignition 2.22.0 Oct 30 00:16:52.776873 ignition[1088]: Stage: kargs Oct 30 00:16:52.777098 ignition[1088]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:52.777109 ignition[1088]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:52.777572 ignition[1088]: kargs: kargs passed Oct 30 00:16:52.786693 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 30 00:16:52.777618 ignition[1088]: Ignition finished successfully Oct 30 00:16:52.797942 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 30 00:16:52.840607 ignition[1097]: Ignition 2.22.0 Oct 30 00:16:52.840620 ignition[1097]: Stage: disks Oct 30 00:16:52.840959 ignition[1097]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:52.840971 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:52.841656 ignition[1097]: disks: disks passed Oct 30 00:16:52.841702 ignition[1097]: Ignition finished successfully Oct 30 00:16:52.855210 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 30 00:16:52.857056 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 30 00:16:52.864266 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 30 00:16:52.878678 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 00:16:52.887495 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 00:16:52.896616 systemd[1]: Reached target basic.target - Basic System. Oct 30 00:16:52.905193 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 30 00:16:52.993625 systemd-fsck[1108]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Oct 30 00:16:52.999200 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 30 00:16:53.010812 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 30 00:16:54.994490 kernel: EXT4-fs (sdb9): mounted filesystem 02607114-2ead-44bc-a76e-2d51f82b108e r/w with ordered data mode. Quota mode: none. Oct 30 00:16:54.995416 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 30 00:16:54.999585 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 30 00:16:55.028303 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 00:16:55.054964 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 30 00:16:55.057218 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 30 00:16:55.086076 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1122) Oct 30 00:16:55.086108 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:16:55.086122 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:16:55.063129 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 30 00:16:55.063162 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 00:16:55.092364 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 30 00:16:55.124211 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:16:55.124235 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:16:55.102873 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 30 00:16:55.126135 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 00:16:56.045548 coreos-metadata[1124]: Oct 30 00:16:56.045 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 30 00:16:56.053635 coreos-metadata[1124]: Oct 30 00:16:56.053 INFO Fetch successful Oct 30 00:16:56.057764 coreos-metadata[1124]: Oct 30 00:16:56.053 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 30 00:16:56.066458 coreos-metadata[1124]: Oct 30 00:16:56.061 INFO Fetch successful Oct 30 00:16:56.075421 coreos-metadata[1124]: Oct 30 00:16:56.075 INFO wrote hostname ci-4459.1.0-n-a485231bb0 to /sysroot/etc/hostname Oct 30 00:16:56.083230 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 30 00:16:56.620033 initrd-setup-root[1152]: cut: /sysroot/etc/passwd: No such file or directory Oct 30 00:16:56.719159 initrd-setup-root[1159]: cut: /sysroot/etc/group: No such file or directory Oct 30 00:16:56.833844 initrd-setup-root[1166]: cut: /sysroot/etc/shadow: No such file or directory Oct 30 00:16:56.850858 initrd-setup-root[1173]: cut: /sysroot/etc/gshadow: No such file or directory Oct 30 00:16:58.291103 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 30 00:16:58.301691 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 30 00:16:58.309737 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 30 00:16:58.335742 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 30 00:16:58.344920 kernel: BTRFS info (device sdb6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:16:58.363552 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 30 00:16:58.377680 ignition[1242]: INFO : Ignition 2.22.0 Oct 30 00:16:58.377680 ignition[1242]: INFO : Stage: mount Oct 30 00:16:58.383764 ignition[1242]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:58.383764 ignition[1242]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:58.383764 ignition[1242]: INFO : mount: mount passed Oct 30 00:16:58.383764 ignition[1242]: INFO : Ignition finished successfully Oct 30 00:16:58.385219 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 30 00:16:58.389398 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 30 00:16:58.423843 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 00:16:58.461499 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1258) Oct 30 00:16:58.471112 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:16:58.471141 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:16:58.480834 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:16:58.480859 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:16:58.483082 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 00:16:58.525932 ignition[1275]: INFO : Ignition 2.22.0 Oct 30 00:16:58.525932 ignition[1275]: INFO : Stage: files Oct 30 00:16:58.532179 ignition[1275]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:58.532179 ignition[1275]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:58.532179 ignition[1275]: DEBUG : files: compiled without relabeling support, skipping Oct 30 00:16:58.569360 ignition[1275]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 30 00:16:58.569360 ignition[1275]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 30 00:16:58.645549 ignition[1275]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 30 00:16:58.651228 ignition[1275]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 30 00:16:58.656916 unknown[1275]: wrote ssh authorized keys file for user: core Oct 30 00:16:58.660944 ignition[1275]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 30 00:16:58.759110 ignition[1275]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 00:16:58.766836 ignition[1275]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 00:16:58.766836 ignition[1275]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 30 00:16:58.766836 ignition[1275]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 30 00:16:58.766836 ignition[1275]: INFO : files: files passed Oct 30 00:16:58.766836 ignition[1275]: INFO : Ignition finished successfully Oct 30 00:16:58.797284 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 30 00:16:58.798454 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 30 00:16:58.826679 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 30 00:16:58.830251 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 30 00:16:58.830349 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 30 00:16:58.873081 initrd-setup-root-after-ignition[1309]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:16:58.873081 initrd-setup-root-after-ignition[1309]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:16:58.886363 initrd-setup-root-after-ignition[1313]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:16:58.881644 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 00:16:58.891891 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 30 00:16:58.903802 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 30 00:16:58.971547 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 30 00:16:58.971662 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 30 00:16:58.975918 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 30 00:16:58.986057 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 30 00:16:58.994508 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 30 00:16:58.995296 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 30 00:16:59.029982 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 00:16:59.031223 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 30 00:16:59.049037 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:16:59.059287 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:16:59.059517 systemd[1]: Stopped target timers.target - Timer Units. Oct 30 00:16:59.069071 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 30 00:16:59.069195 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 00:16:59.085216 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 30 00:16:59.090173 systemd[1]: Stopped target basic.target - Basic System. Oct 30 00:16:59.100689 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 30 00:16:59.108079 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 00:16:59.117009 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 30 00:16:59.126607 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 30 00:16:59.141488 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 30 00:16:59.146028 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 00:16:59.154033 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 30 00:16:59.164052 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 30 00:16:59.180002 systemd[1]: Stopped target swap.target - Swaps. Oct 30 00:16:59.188128 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 30 00:16:59.188280 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 30 00:16:59.205611 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:16:59.209946 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:16:59.218813 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 30 00:16:59.218921 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:16:59.228735 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 30 00:16:59.228862 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 30 00:16:59.247959 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 30 00:16:59.248083 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 00:16:59.251892 systemd[1]: ignition-files.service: Deactivated successfully. Oct 30 00:16:59.252010 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 30 00:16:59.261388 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 30 00:16:59.261534 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 30 00:16:59.271019 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 30 00:16:59.280253 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 30 00:16:59.280379 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:16:59.335137 ignition[1333]: INFO : Ignition 2.22.0 Oct 30 00:16:59.335137 ignition[1333]: INFO : Stage: umount Oct 30 00:16:59.335137 ignition[1333]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:16:59.335137 ignition[1333]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:16:59.335137 ignition[1333]: INFO : umount: umount passed Oct 30 00:16:59.335137 ignition[1333]: INFO : Ignition finished successfully Oct 30 00:16:59.301364 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 30 00:16:59.306513 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 30 00:16:59.306644 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:16:59.320522 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 30 00:16:59.320659 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 00:16:59.337071 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 30 00:16:59.337170 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 30 00:16:59.341438 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 30 00:16:59.341571 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 30 00:16:59.345737 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 30 00:16:59.345812 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 30 00:16:59.364526 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 30 00:16:59.364580 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 30 00:16:59.367756 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 30 00:16:59.367804 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 30 00:16:59.379574 systemd[1]: Stopped target network.target - Network. Oct 30 00:16:59.387022 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 30 00:16:59.387078 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 00:16:59.391332 systemd[1]: Stopped target paths.target - Path Units. Oct 30 00:16:59.400684 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 00:16:59.405533 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:16:59.408350 systemd[1]: Stopped target slices.target - Slice Units. Oct 30 00:16:59.418026 systemd[1]: Stopped target sockets.target - Socket Units. Oct 30 00:16:59.425652 systemd[1]: iscsid.socket: Deactivated successfully. Oct 30 00:16:59.425708 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 00:16:59.433364 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 30 00:16:59.433410 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 00:16:59.445269 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 30 00:16:59.445327 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 30 00:16:59.450212 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 30 00:16:59.450259 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 30 00:16:59.466806 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 30 00:16:59.470718 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 30 00:16:59.479696 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 30 00:16:59.480311 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 30 00:16:59.480414 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 30 00:16:59.496151 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 30 00:16:59.496435 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 30 00:16:59.496546 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 30 00:16:59.498545 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 30 00:16:59.498641 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 30 00:16:59.516430 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 30 00:16:59.517987 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 30 00:16:59.519398 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 30 00:16:59.519454 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:16:59.528178 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 30 00:16:59.528257 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 30 00:16:59.541929 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 30 00:16:59.549604 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 30 00:16:59.549662 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 00:16:59.556988 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 00:16:59.557037 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:16:59.573594 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 30 00:16:59.573643 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 30 00:16:59.573857 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 30 00:16:59.573914 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:16:59.591037 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:16:59.600342 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 30 00:16:59.600407 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:16:59.644153 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 30 00:16:59.644320 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:16:59.645998 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 30 00:16:59.646042 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 30 00:16:59.659135 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 30 00:16:59.659168 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:16:59.663484 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 30 00:16:59.663537 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 30 00:16:59.681452 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 30 00:16:59.681515 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 30 00:16:59.693865 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 30 00:16:59.693914 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 00:16:59.708378 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 30 00:16:59.711710 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 30 00:16:59.711765 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:16:59.730744 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 00:16:59.730791 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:16:59.744271 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:16:59.744318 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:16:59.758428 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 30 00:16:59.758497 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 30 00:16:59.758547 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:16:59.758856 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 30 00:16:59.758950 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 30 00:16:59.761887 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 30 00:16:59.761977 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 30 00:16:59.776696 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 30 00:16:59.781881 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 30 00:16:59.855827 systemd[1]: Switching root. Oct 30 00:16:59.950570 systemd-journald[214]: Journal stopped Oct 30 00:17:09.978342 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Oct 30 00:17:09.978374 kernel: SELinux: policy capability network_peer_controls=1 Oct 30 00:17:09.978387 kernel: SELinux: policy capability open_perms=1 Oct 30 00:17:09.978396 kernel: SELinux: policy capability extended_socket_class=1 Oct 30 00:17:09.978405 kernel: SELinux: policy capability always_check_network=0 Oct 30 00:17:09.978414 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 30 00:17:09.978424 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 30 00:17:09.978436 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 30 00:17:09.978445 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 30 00:17:09.978454 kernel: SELinux: policy capability userspace_initial_context=0 Oct 30 00:17:09.978475 kernel: audit: type=1403 audit(1761783421.430:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 30 00:17:09.978487 systemd[1]: Successfully loaded SELinux policy in 269.042ms. Oct 30 00:17:09.978498 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.179ms. Oct 30 00:17:09.978509 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 30 00:17:09.978524 systemd[1]: Detected virtualization microsoft. Oct 30 00:17:09.978535 systemd[1]: Detected architecture x86-64. Oct 30 00:17:09.978545 systemd[1]: Detected first boot. Oct 30 00:17:09.978556 systemd[1]: Hostname set to . Oct 30 00:17:09.978566 systemd[1]: Initializing machine ID from random generator. Oct 30 00:17:09.978583 zram_generator::config[1381]: No configuration found. Oct 30 00:17:09.978594 kernel: Guest personality initialized and is inactive Oct 30 00:17:09.978604 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 30 00:17:09.978613 kernel: Initialized host personality Oct 30 00:17:09.978623 kernel: NET: Registered PF_VSOCK protocol family Oct 30 00:17:09.978633 systemd[1]: Populated /etc with preset unit settings. Oct 30 00:17:09.978645 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 30 00:17:09.978658 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 30 00:17:09.978669 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 30 00:17:09.978679 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 30 00:17:09.978690 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 30 00:17:09.978701 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 30 00:17:09.978711 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 30 00:17:09.978722 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 30 00:17:09.978735 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 30 00:17:09.978746 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 30 00:17:09.978757 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 30 00:17:09.978767 systemd[1]: Created slice user.slice - User and Session Slice. Oct 30 00:17:09.978777 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:17:09.978788 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:17:09.978799 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 30 00:17:09.978816 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 30 00:17:09.978827 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 30 00:17:09.978841 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 00:17:09.978852 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 30 00:17:09.978863 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:17:09.978873 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:17:09.978884 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 30 00:17:09.978895 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 30 00:17:09.978906 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 30 00:17:09.978919 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 30 00:17:09.978930 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:17:09.978941 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 00:17:09.978952 systemd[1]: Reached target slices.target - Slice Units. Oct 30 00:17:09.978963 systemd[1]: Reached target swap.target - Swaps. Oct 30 00:17:09.978974 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 30 00:17:09.978985 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 30 00:17:09.978999 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 30 00:17:09.979010 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:17:09.979021 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 00:17:09.979032 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:17:09.979043 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 30 00:17:09.979057 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 30 00:17:09.979071 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 30 00:17:09.979082 systemd[1]: Mounting media.mount - External Media Directory... Oct 30 00:17:09.979093 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:09.979104 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 30 00:17:09.979115 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 30 00:17:09.979127 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 30 00:17:09.979138 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 30 00:17:09.979149 systemd[1]: Reached target machines.target - Containers. Oct 30 00:17:09.979163 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 30 00:17:09.979175 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:17:09.979186 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 00:17:09.979197 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 30 00:17:09.979207 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:17:09.979218 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 00:17:09.979229 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:17:09.979240 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 30 00:17:09.979254 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:17:09.979265 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 30 00:17:09.979276 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 30 00:17:09.979287 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 30 00:17:09.979302 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 30 00:17:09.979313 systemd[1]: Stopped systemd-fsck-usr.service. Oct 30 00:17:09.979324 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 30 00:17:09.979335 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 00:17:09.979346 kernel: fuse: init (API version 7.41) Oct 30 00:17:09.979360 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 00:17:09.979370 kernel: loop: module loaded Oct 30 00:17:09.979404 systemd-journald[1488]: Collecting audit messages is disabled. Oct 30 00:17:09.979429 systemd-journald[1488]: Journal started Oct 30 00:17:09.979454 systemd-journald[1488]: Runtime Journal (/run/log/journal/f5aa308cabfd49f399aec29c522ac0af) is 8M, max 2.2G, 2.1G free. Oct 30 00:17:08.952684 systemd[1]: Queued start job for default target multi-user.target. Oct 30 00:17:08.966863 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Oct 30 00:17:08.967392 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 30 00:17:09.992640 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 00:17:10.003482 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 30 00:17:10.016483 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 30 00:17:10.027489 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 00:17:10.038447 systemd[1]: verity-setup.service: Deactivated successfully. Oct 30 00:17:10.038502 systemd[1]: Stopped verity-setup.service. Oct 30 00:17:10.052589 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:10.060481 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 00:17:10.065188 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 30 00:17:10.069812 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 30 00:17:10.074414 systemd[1]: Mounted media.mount - External Media Directory. Oct 30 00:17:10.079883 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 30 00:17:10.085330 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 30 00:17:10.090274 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 30 00:17:10.094689 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 30 00:17:10.100076 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:17:10.105715 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 30 00:17:10.105958 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 30 00:17:10.111408 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:17:10.111614 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:17:10.117626 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:17:10.117812 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:17:10.123417 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 30 00:17:10.123617 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 30 00:17:10.128548 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:17:10.128731 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:17:10.133796 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:17:10.139387 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 30 00:17:10.152293 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 00:17:10.158651 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 30 00:17:10.178322 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 30 00:17:10.183198 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 30 00:17:10.183228 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 00:17:10.188606 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 30 00:17:10.194914 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 30 00:17:10.218742 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:17:10.249548 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 30 00:17:10.268752 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 30 00:17:10.273766 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 00:17:10.274768 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 30 00:17:10.279649 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 00:17:10.281004 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 30 00:17:10.286953 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 30 00:17:10.293122 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 00:17:10.298334 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 30 00:17:10.304936 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:17:10.310337 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 30 00:17:10.315582 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 30 00:17:10.316369 systemd-journald[1488]: Time spent on flushing to /var/log/journal/f5aa308cabfd49f399aec29c522ac0af is 15.446ms for 947 entries. Oct 30 00:17:10.316369 systemd-journald[1488]: System Journal (/var/log/journal/f5aa308cabfd49f399aec29c522ac0af) is 8M, max 2.6G, 2.6G free. Oct 30 00:17:10.365235 systemd-journald[1488]: Received client request to flush runtime journal. Oct 30 00:17:10.365293 kernel: ACPI: bus type drm_connector registered Oct 30 00:17:10.329616 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 00:17:10.342876 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 00:17:10.343067 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 00:17:10.366225 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 30 00:17:10.383070 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 30 00:17:10.388543 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 30 00:17:10.394801 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 30 00:17:10.503018 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 30 00:17:10.503777 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 30 00:17:10.519040 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:17:11.134138 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 30 00:17:11.140153 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 00:17:11.363750 systemd-tmpfiles[1537]: ACLs are not supported, ignoring. Oct 30 00:17:11.363772 systemd-tmpfiles[1537]: ACLs are not supported, ignoring. Oct 30 00:17:11.385089 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:17:11.490856 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 30 00:17:11.497893 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:17:11.542016 systemd-udevd[1541]: Using default interface naming scheme 'v255'. Oct 30 00:17:12.262360 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:17:12.272441 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 00:17:12.303277 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 30 00:17:12.359495 kernel: hv_vmbus: registering driver hyperv_fb Oct 30 00:17:12.377699 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 30 00:17:12.383481 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 30 00:17:12.387505 kernel: Console: switching to colour dummy device 80x25 Oct 30 00:17:12.393478 kernel: hv_vmbus: registering driver hv_balloon Oct 30 00:17:12.393534 kernel: Console: switching to colour frame buffer device 128x48 Oct 30 00:17:12.393564 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 30 00:17:12.416794 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 30 00:17:12.483789 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 30 00:17:12.511542 kernel: mousedev: PS/2 mouse device common for all mice Oct 30 00:17:12.513370 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:17:12.528417 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:17:12.528639 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:17:12.534599 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:17:12.537544 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:17:12.563500 kernel: EDAC MC: Ver: 3.0.0 Oct 30 00:17:12.727493 kernel: loop0: detected capacity change from 0 to 110984 Oct 30 00:17:12.770339 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 30 00:17:12.772046 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 30 00:17:12.851692 systemd-networkd[1565]: lo: Link UP Oct 30 00:17:12.851701 systemd-networkd[1565]: lo: Gained carrier Oct 30 00:17:12.853003 systemd-networkd[1565]: Enumeration completed Oct 30 00:17:12.853125 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 00:17:12.854355 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 30 00:17:12.855704 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 30 00:17:12.867302 systemd-networkd[1565]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:17:12.867313 systemd-networkd[1565]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:17:12.868004 systemd-networkd[1565]: eth0: Link UP Oct 30 00:17:12.868013 systemd-networkd[1565]: eth0: Gained carrier Oct 30 00:17:12.868028 systemd-networkd[1565]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:17:12.899531 systemd-networkd[1565]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:17:12.914444 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 30 00:17:12.932046 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 30 00:17:13.544488 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 30 00:17:13.602534 kernel: loop1: detected capacity change from 0 to 27936 Oct 30 00:17:13.999699 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:17:14.133625 systemd-networkd[1565]: eth0: Gained IPv6LL Oct 30 00:17:14.137006 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 30 00:17:14.606492 kernel: loop2: detected capacity change from 0 to 128016 Oct 30 00:17:15.499490 kernel: loop3: detected capacity change from 0 to 110984 Oct 30 00:17:15.516487 kernel: loop4: detected capacity change from 0 to 27936 Oct 30 00:17:15.530484 kernel: loop5: detected capacity change from 0 to 128016 Oct 30 00:17:15.543155 (sd-merge)[1706]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 30 00:17:15.543652 (sd-merge)[1706]: Merged extensions into '/usr'. Oct 30 00:17:15.547921 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 30 00:17:15.554883 systemd[1]: Starting ensure-sysext.service... Oct 30 00:17:15.558927 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 00:17:15.601641 systemd[1]: Reload requested from client PID 1708 ('systemctl') (unit ensure-sysext.service)... Oct 30 00:17:15.601658 systemd[1]: Reloading... Oct 30 00:17:15.612170 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 30 00:17:15.612211 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 30 00:17:15.612506 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 30 00:17:15.612794 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 30 00:17:15.613687 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 30 00:17:15.613962 systemd-tmpfiles[1709]: ACLs are not supported, ignoring. Oct 30 00:17:15.614027 systemd-tmpfiles[1709]: ACLs are not supported, ignoring. Oct 30 00:17:15.639502 zram_generator::config[1741]: No configuration found. Oct 30 00:17:15.732590 systemd-tmpfiles[1709]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 00:17:15.732605 systemd-tmpfiles[1709]: Skipping /boot Oct 30 00:17:15.740900 systemd-tmpfiles[1709]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 00:17:15.740915 systemd-tmpfiles[1709]: Skipping /boot Oct 30 00:17:15.898999 systemd[1]: Reloading finished in 296 ms. Oct 30 00:17:15.955029 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:17:15.972080 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:15.973236 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 30 00:17:16.058173 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 30 00:17:16.063251 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:17:16.064386 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:17:16.089820 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:17:16.095742 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:17:16.100308 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:17:16.100440 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 30 00:17:16.101646 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 30 00:17:16.108670 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 00:17:16.114145 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 30 00:17:16.119146 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:16.121258 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:17:16.121445 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:17:16.127542 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:17:16.127725 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:17:16.134141 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:17:16.134320 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:17:16.151600 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 30 00:17:16.163272 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:16.163548 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:17:16.164793 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:17:16.176940 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 00:17:16.182296 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:17:16.188596 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:17:16.192912 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:17:16.193036 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 30 00:17:16.193210 systemd[1]: Reached target time-set.target - System Time Set. Oct 30 00:17:16.197604 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:17:16.199196 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:17:16.199380 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:17:16.205046 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 00:17:16.205222 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 00:17:16.211751 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:17:16.211931 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:17:16.217975 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:17:16.218162 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:17:16.225666 systemd[1]: Finished ensure-sysext.service. Oct 30 00:17:16.232338 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 00:17:16.232395 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 00:17:16.301904 systemd-resolved[1811]: Positive Trust Anchors: Oct 30 00:17:16.301920 systemd-resolved[1811]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 00:17:16.301953 systemd-resolved[1811]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 30 00:17:16.312601 augenrules[1840]: No rules Oct 30 00:17:16.313943 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 00:17:16.314202 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 30 00:17:16.320449 systemd-resolved[1811]: Using system hostname 'ci-4459.1.0-n-a485231bb0'. Oct 30 00:17:16.322133 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 00:17:16.327574 systemd[1]: Reached target network.target - Network. Oct 30 00:17:16.331452 systemd[1]: Reached target network-online.target - Network is Online. Oct 30 00:17:16.336701 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:17:16.525763 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 30 00:17:19.293694 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 30 00:17:19.300187 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 30 00:17:22.174835 ldconfig[1514]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 30 00:17:22.200877 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 30 00:17:22.207660 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 30 00:17:22.256185 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 30 00:17:22.261595 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 00:17:22.266340 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 30 00:17:22.271644 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 30 00:17:22.277041 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 30 00:17:22.282154 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 30 00:17:22.286794 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 30 00:17:22.292510 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 30 00:17:22.297916 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 30 00:17:22.297964 systemd[1]: Reached target paths.target - Path Units. Oct 30 00:17:22.301814 systemd[1]: Reached target timers.target - Timer Units. Oct 30 00:17:22.321159 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 30 00:17:22.327233 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 30 00:17:22.333396 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 30 00:17:22.338866 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 30 00:17:22.344168 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 30 00:17:22.354113 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 30 00:17:22.358775 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 30 00:17:22.364381 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 30 00:17:22.369838 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 00:17:22.373924 systemd[1]: Reached target basic.target - Basic System. Oct 30 00:17:22.377782 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 30 00:17:22.377823 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 30 00:17:22.393194 systemd[1]: Starting chronyd.service - NTP client/server... Oct 30 00:17:22.397668 systemd[1]: Starting containerd.service - containerd container runtime... Oct 30 00:17:22.421332 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 30 00:17:22.427022 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 30 00:17:22.431846 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 30 00:17:22.437599 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 30 00:17:22.443290 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 30 00:17:22.447674 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 30 00:17:22.448802 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 30 00:17:22.453213 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Oct 30 00:17:22.454259 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 30 00:17:22.458776 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 30 00:17:22.459806 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 30 00:17:22.461665 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 30 00:17:22.464400 jq[1861]: false Oct 30 00:17:22.469844 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 30 00:17:22.475985 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 30 00:17:22.483633 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 30 00:17:22.488849 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 30 00:17:22.489285 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 30 00:17:22.490579 systemd[1]: Starting update-engine.service - Update Engine... Oct 30 00:17:22.495699 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 30 00:17:22.502724 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 30 00:17:22.504029 jq[1874]: true Oct 30 00:17:22.508302 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 30 00:17:22.508539 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 30 00:17:22.508827 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 30 00:17:22.509309 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 30 00:17:22.512620 KVP[1864]: KVP starting; pid is:1864 Oct 30 00:17:22.520607 chronyd[1853]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 30 00:17:22.523050 KVP[1864]: KVP LIC Version: 3.1 Oct 30 00:17:22.522891 (ntainerd)[1881]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 30 00:17:22.524527 kernel: hv_utils: KVP IC version 4.0 Oct 30 00:17:22.525507 jq[1880]: true Oct 30 00:17:22.528580 systemd[1]: motdgen.service: Deactivated successfully. Oct 30 00:17:22.528909 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 30 00:17:22.533753 extend-filesystems[1862]: Found /dev/sdb6 Oct 30 00:17:22.545992 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Refreshing passwd entry cache Oct 30 00:17:22.545992 oslogin_cache_refresh[1863]: Refreshing passwd entry cache Oct 30 00:17:22.549847 extend-filesystems[1862]: Found /dev/sdb9 Oct 30 00:17:22.555859 extend-filesystems[1862]: Checking size of /dev/sdb9 Oct 30 00:17:22.556649 oslogin_cache_refresh[1863]: Failure getting users, quitting Oct 30 00:17:22.560105 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Failure getting users, quitting Oct 30 00:17:22.560105 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 30 00:17:22.560105 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Refreshing group entry cache Oct 30 00:17:22.556666 oslogin_cache_refresh[1863]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 30 00:17:22.556710 oslogin_cache_refresh[1863]: Refreshing group entry cache Oct 30 00:17:22.571332 chronyd[1853]: Timezone right/UTC failed leap second check, ignoring Oct 30 00:17:22.571635 chronyd[1853]: Loaded seccomp filter (level 2) Oct 30 00:17:22.571762 systemd[1]: Started chronyd.service - NTP client/server. Oct 30 00:17:22.575375 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Failure getting groups, quitting Oct 30 00:17:22.575375 google_oslogin_nss_cache[1863]: oslogin_cache_refresh[1863]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 30 00:17:22.575364 oslogin_cache_refresh[1863]: Failure getting groups, quitting Oct 30 00:17:22.575376 oslogin_cache_refresh[1863]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 30 00:17:22.576829 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 30 00:17:22.577073 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 30 00:17:22.604405 update_engine[1872]: I20251030 00:17:22.604326 1872 main.cc:92] Flatcar Update Engine starting Oct 30 00:17:22.608879 extend-filesystems[1862]: Old size kept for /dev/sdb9 Oct 30 00:17:22.609933 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 30 00:17:22.610194 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 30 00:17:22.647474 systemd-logind[1870]: New seat seat0. Oct 30 00:17:22.648844 systemd-logind[1870]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 30 00:17:22.649050 systemd[1]: Started systemd-logind.service - User Login Management. Oct 30 00:17:22.733583 bash[1913]: Updated "/home/core/.ssh/authorized_keys" Oct 30 00:17:22.735398 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 30 00:17:22.741370 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 30 00:17:22.856527 setup-nvidia[1866]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.0+nightly-20251029-2100 Oct 30 00:17:22.889837 sshd_keygen[1903]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 30 00:17:22.911318 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 30 00:17:22.917422 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 30 00:17:22.932688 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 30 00:17:22.938554 systemd[1]: issuegen.service: Deactivated successfully. Oct 30 00:17:22.938760 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 30 00:17:22.946174 dbus-daemon[1856]: [system] SELinux support is enabled Oct 30 00:17:22.946622 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 30 00:17:22.951078 update_engine[1872]: I20251030 00:17:22.950523 1872 update_check_scheduler.cc:74] Next update check in 6m28s Oct 30 00:17:22.951153 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 30 00:17:22.959450 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 30 00:17:22.966185 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 30 00:17:22.966238 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 30 00:17:22.967024 dbus-daemon[1856]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 30 00:17:22.971672 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 30 00:17:22.971696 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 30 00:17:22.977341 systemd[1]: Started update-engine.service - Update Engine. Oct 30 00:17:22.983213 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 30 00:17:22.988329 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 30 00:17:23.013563 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 30 00:17:23.018831 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 30 00:17:23.023702 systemd[1]: Reached target getty.target - Login Prompts. Oct 30 00:17:23.065258 coreos-metadata[1855]: Oct 30 00:17:23.065 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 30 00:17:23.067627 coreos-metadata[1855]: Oct 30 00:17:23.067 INFO Fetch successful Oct 30 00:17:23.067782 coreos-metadata[1855]: Oct 30 00:17:23.067 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 30 00:17:23.072108 coreos-metadata[1855]: Oct 30 00:17:23.072 INFO Fetch successful Oct 30 00:17:23.072412 coreos-metadata[1855]: Oct 30 00:17:23.072 INFO Fetching http://168.63.129.16/machine/eba7acba-e90a-42f9-acf9-c10bf42bb80a/46beeed4%2Deaeb%2D4ad0%2D89e1%2Df7c9916e85bc.%5Fci%2D4459.1.0%2Dn%2Da485231bb0?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 30 00:17:23.074142 coreos-metadata[1855]: Oct 30 00:17:23.074 INFO Fetch successful Oct 30 00:17:23.074276 coreos-metadata[1855]: Oct 30 00:17:23.074 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 30 00:17:23.081565 coreos-metadata[1855]: Oct 30 00:17:23.081 INFO Fetch successful Oct 30 00:17:23.118493 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 30 00:17:23.124195 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 30 00:17:23.289295 locksmithd[2015]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 30 00:17:23.415802 setup-nvidia[2033]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 30 00:17:23.416121 setup-nvidia[2033]: Dload Upload Total Spent Left Speed Oct 30 00:17:23.643256 containerd[1881]: time="2025-10-30T00:17:23Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 30 00:17:23.643889 containerd[1881]: time="2025-10-30T00:17:23.643857100Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 30 00:17:23.651507 containerd[1881]: time="2025-10-30T00:17:23.651457400Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.9µs" Oct 30 00:17:23.651507 containerd[1881]: time="2025-10-30T00:17:23.651503400Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 30 00:17:23.651669 containerd[1881]: time="2025-10-30T00:17:23.651548100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 30 00:17:23.651836 containerd[1881]: time="2025-10-30T00:17:23.651810300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 30 00:17:23.651876 containerd[1881]: time="2025-10-30T00:17:23.651853600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 30 00:17:23.651904 containerd[1881]: time="2025-10-30T00:17:23.651885200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 30 00:17:23.651968 containerd[1881]: time="2025-10-30T00:17:23.651946900Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 30 00:17:23.651968 containerd[1881]: time="2025-10-30T00:17:23.651966100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652178 containerd[1881]: time="2025-10-30T00:17:23.652154900Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652207 containerd[1881]: time="2025-10-30T00:17:23.652176300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652207 containerd[1881]: time="2025-10-30T00:17:23.652187100Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652207 containerd[1881]: time="2025-10-30T00:17:23.652195200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652289 containerd[1881]: time="2025-10-30T00:17:23.652269500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652518 containerd[1881]: time="2025-10-30T00:17:23.652497900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652548 containerd[1881]: time="2025-10-30T00:17:23.652532000Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 30 00:17:23.652548 containerd[1881]: time="2025-10-30T00:17:23.652542100Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 30 00:17:23.652600 containerd[1881]: time="2025-10-30T00:17:23.652572200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 30 00:17:23.652789 containerd[1881]: time="2025-10-30T00:17:23.652760900Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 30 00:17:23.652850 containerd[1881]: time="2025-10-30T00:17:23.652831800Z" level=info msg="metadata content store policy set" policy=shared Oct 30 00:17:23.672963 containerd[1881]: time="2025-10-30T00:17:23.672934300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 30 00:17:23.673021 containerd[1881]: time="2025-10-30T00:17:23.672973100Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 30 00:17:23.673021 containerd[1881]: time="2025-10-30T00:17:23.672986400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 30 00:17:23.673021 containerd[1881]: time="2025-10-30T00:17:23.672997100Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 30 00:17:23.673021 containerd[1881]: time="2025-10-30T00:17:23.673009300Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 30 00:17:23.673021 containerd[1881]: time="2025-10-30T00:17:23.673019300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673031500Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673042400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673052200Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673061800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673070100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 30 00:17:23.673145 containerd[1881]: time="2025-10-30T00:17:23.673080700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673183000Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673202500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673217100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673227400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673253500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673268300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673278700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673287000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673296900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 30 00:17:23.673310 containerd[1881]: time="2025-10-30T00:17:23.673306400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 30 00:17:23.673539 containerd[1881]: time="2025-10-30T00:17:23.673317900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 30 00:17:23.673539 containerd[1881]: time="2025-10-30T00:17:23.673371900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 30 00:17:23.673539 containerd[1881]: time="2025-10-30T00:17:23.673385500Z" level=info msg="Start snapshots syncer" Oct 30 00:17:23.673539 containerd[1881]: time="2025-10-30T00:17:23.673409000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 30 00:17:23.673646 containerd[1881]: time="2025-10-30T00:17:23.673612500Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 30 00:17:23.673755 containerd[1881]: time="2025-10-30T00:17:23.673668400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 30 00:17:23.673755 containerd[1881]: time="2025-10-30T00:17:23.673736800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 30 00:17:23.673862 containerd[1881]: time="2025-10-30T00:17:23.673833300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 30 00:17:23.673862 containerd[1881]: time="2025-10-30T00:17:23.673859400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 30 00:17:23.673926 containerd[1881]: time="2025-10-30T00:17:23.673872900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 30 00:17:23.673926 containerd[1881]: time="2025-10-30T00:17:23.673883300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 30 00:17:23.673926 containerd[1881]: time="2025-10-30T00:17:23.673907600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 30 00:17:23.673926 containerd[1881]: time="2025-10-30T00:17:23.673922100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 30 00:17:23.674017 containerd[1881]: time="2025-10-30T00:17:23.673932600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 30 00:17:23.674017 containerd[1881]: time="2025-10-30T00:17:23.673964400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 30 00:17:23.674017 containerd[1881]: time="2025-10-30T00:17:23.673978900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 30 00:17:23.674017 containerd[1881]: time="2025-10-30T00:17:23.673988900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674020600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674036700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674044900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674054300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674062500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674081700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 30 00:17:23.674100 containerd[1881]: time="2025-10-30T00:17:23.674096100Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 30 00:17:23.674248 containerd[1881]: time="2025-10-30T00:17:23.674111800Z" level=info msg="runtime interface created" Oct 30 00:17:23.674248 containerd[1881]: time="2025-10-30T00:17:23.674117000Z" level=info msg="created NRI interface" Oct 30 00:17:23.674248 containerd[1881]: time="2025-10-30T00:17:23.674124200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 30 00:17:23.674248 containerd[1881]: time="2025-10-30T00:17:23.674133800Z" level=info msg="Connect containerd service" Oct 30 00:17:23.674248 containerd[1881]: time="2025-10-30T00:17:23.674157300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 30 00:17:23.674870 containerd[1881]: time="2025-10-30T00:17:23.674837100Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 30 00:17:24.852300 containerd[1881]: time="2025-10-30T00:17:24.852245700Z" level=info msg="Start subscribing containerd event" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852302500Z" level=info msg="Start recovering state" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852401100Z" level=info msg="Start event monitor" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852418600Z" level=info msg="Start cni network conf syncer for default" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852435400Z" level=info msg="Start streaming server" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852446500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852453900Z" level=info msg="runtime interface starting up..." Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852459900Z" level=info msg="starting plugins..." Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852487700Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852548500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852618800Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 30 00:17:24.852691 containerd[1881]: time="2025-10-30T00:17:24.852688000Z" level=info msg="containerd successfully booted in 1.209775s" Oct 30 00:17:24.852814 systemd[1]: Started containerd.service - containerd container runtime. Oct 30 00:17:26.527796 waagent[2013]: 2025-10-30T00:17:26.527707Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 30 00:17:26.532297 waagent[2013]: 2025-10-30T00:17:26.532198Z INFO Daemon Daemon OS: flatcar 4459.1.0 Oct 30 00:17:26.535799 waagent[2013]: 2025-10-30T00:17:26.535751Z INFO Daemon Daemon Python: 3.11.13 Oct 30 00:17:26.539392 waagent[2013]: 2025-10-30T00:17:26.539345Z INFO Daemon Daemon Run daemon Oct 30 00:17:26.542711 waagent[2013]: 2025-10-30T00:17:26.542658Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.0' Oct 30 00:17:26.549657 waagent[2013]: 2025-10-30T00:17:26.549607Z INFO Daemon Daemon Using waagent for provisioning Oct 30 00:17:26.553945 waagent[2013]: 2025-10-30T00:17:26.553896Z INFO Daemon Daemon Activate resource disk Oct 30 00:17:26.557616 waagent[2013]: 2025-10-30T00:17:26.557568Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 30 00:17:26.565413 waagent[2013]: 2025-10-30T00:17:26.565366Z INFO Daemon Daemon Found device: sda Oct 30 00:17:26.576186 waagent[2013]: 2025-10-30T00:17:26.576133Z INFO Daemon Daemon Examining partition table Oct 30 00:17:26.867435 waagent[2013]: 2025-10-30T00:17:26.867313Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 30 00:17:27.002202 waagent[2013]: 2025-10-30T00:17:27.002119Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Oct 30 00:17:27.007155 waagent[2013]: 2025-10-30T00:17:27.007106Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 30 00:17:31.622240 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#218 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:17:31.633485 kernel: sda: sda1 Oct 30 00:17:31.641485 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#74 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:17:31.658486 kernel: sda: sda1 Oct 30 00:17:35.579497 login[2018]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 30 00:17:35.691340 login[2017]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 30 00:17:35.697997 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 30 00:17:35.699055 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 30 00:17:35.705480 systemd-logind[1870]: New session 1 of user core. Oct 30 00:17:35.939586 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 30 00:17:35.942122 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 30 00:17:36.114672 (systemd)[2119]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 30 00:17:36.117263 systemd-logind[1870]: New session c1 of user core. Oct 30 00:17:36.668401 login[2018]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 30 00:17:36.672925 systemd-logind[1870]: New session 2 of user core. Oct 30 00:17:38.545757 systemd[2119]: Queued start job for default target default.target. Oct 30 00:17:38.564507 systemd[2119]: Created slice app.slice - User Application Slice. Oct 30 00:17:38.564535 systemd[2119]: Reached target paths.target - Paths. Oct 30 00:17:38.564584 systemd[2119]: Reached target timers.target - Timers. Oct 30 00:17:38.565900 systemd[2119]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 30 00:17:38.576887 systemd[2119]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:17:38.577011 systemd[2119]: Reached target sockets.target - Sockets. Oct 30 00:17:38.577065 systemd[2119]: Reached target basic.target - Basic System. Oct 30 00:17:38.577115 systemd[2119]: Reached target default.target - Main User Target. Oct 30 00:17:38.577146 systemd[2119]: Startup finished in 2.453s. Oct 30 00:17:38.577185 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 30 00:17:38.578572 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 30 00:17:38.579261 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 30 00:17:44.536765 waagent[2013]: 2025-10-30T00:17:44.536676Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Oct 30 00:17:44.551482 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#241 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:17:44.572488 kernel: sda: sda1 Oct 30 00:17:44.572758 waagent[2013]: 2025-10-30T00:17:44.572697Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Oct 30 00:17:46.351810 chronyd[1853]: Selected source PHC0 Oct 30 00:17:50.406543 waagent[2013]: 2025-10-30T00:17:50.406443Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Oct 30 00:17:50.513497 kernel: EXT4-fs (sda1): mounted filesystem 795727ab-fb70-4219-ab00-98097c8b2dbf r/w with ordered data mode. Quota mode: none. Oct 30 00:17:50.513589 waagent[2013]: 2025-10-30T00:17:50.513451Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Oct 30 00:17:50.520197 waagent[2013]: 2025-10-30T00:17:50.520148Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 30 00:17:50.553446 waagent[2013]: 2025-10-30T00:17:50.520412Z INFO Daemon Daemon Running default provisioning handler Oct 30 00:17:50.553446 waagent[2013]: 2025-10-30T00:17:50.528840Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Oct 30 00:17:50.553446 waagent[2013]: 2025-10-30T00:17:50.530064Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 30 00:17:50.553446 waagent[2013]: 2025-10-30T00:17:50.530352Z INFO Daemon Daemon cloud-init is enabled: False Oct 30 00:17:50.553446 waagent[2013]: 2025-10-30T00:17:50.531884Z INFO Daemon Daemon Copying ovf-env.xml Oct 30 00:17:50.677157 waagent[2013]: 2025-10-30T00:17:50.677037Z INFO Daemon Daemon Successfully mounted dvd Oct 30 00:17:50.739034 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 30 00:17:50.739333 waagent[2013]: 2025-10-30T00:17:50.739088Z INFO Daemon Daemon Detect protocol endpoint Oct 30 00:17:50.743241 waagent[2013]: 2025-10-30T00:17:50.743197Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 30 00:17:50.748522 waagent[2013]: 2025-10-30T00:17:50.748080Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 30 00:17:50.753545 waagent[2013]: 2025-10-30T00:17:50.753504Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 30 00:17:50.757737 waagent[2013]: 2025-10-30T00:17:50.757693Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 30 00:17:50.761998 waagent[2013]: 2025-10-30T00:17:50.761949Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 30 00:17:50.797875 waagent[2013]: 2025-10-30T00:17:50.797831Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 30 00:17:50.798440 waagent[2013]: 2025-10-30T00:17:50.798377Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 30 00:17:50.799612 waagent[2013]: 2025-10-30T00:17:50.799575Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 30 00:17:51.006057 waagent[2013]: 2025-10-30T00:17:51.005924Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 30 00:17:51.015111 waagent[2013]: 2025-10-30T00:17:51.006362Z INFO Daemon Daemon Forcing an update of the goal state. Oct 30 00:17:51.017923 waagent[2013]: 2025-10-30T00:17:51.017875Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 30 00:17:51.050671 waagent[2013]: 2025-10-30T00:17:51.050608Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 30 00:17:51.057677 waagent[2013]: 2025-10-30T00:17:51.051902Z INFO Daemon Oct 30 00:17:51.057677 waagent[2013]: 2025-10-30T00:17:51.052156Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 8c90bd29-9530-4968-92fc-f2d44c185e97 eTag: 7723352741436529867 source: Fabric] Oct 30 00:17:51.057677 waagent[2013]: 2025-10-30T00:17:51.053941Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 30 00:17:51.057677 waagent[2013]: 2025-10-30T00:17:51.054798Z INFO Daemon Oct 30 00:17:51.057677 waagent[2013]: 2025-10-30T00:17:51.055986Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 30 00:17:51.082227 waagent[2013]: 2025-10-30T00:17:51.060780Z INFO Daemon Daemon Downloading artifacts profile blob Oct 30 00:17:51.194906 waagent[2013]: 2025-10-30T00:17:51.194835Z INFO Daemon Downloaded certificate {'thumbprint': '61D030AA7E12FBE335A669CDF9558F187A071AC2', 'hasPrivateKey': True} Oct 30 00:17:51.195519 waagent[2013]: 2025-10-30T00:17:51.195459Z INFO Daemon Fetch goal state completed Oct 30 00:17:51.228088 waagent[2013]: 2025-10-30T00:17:51.228045Z INFO Daemon Daemon Starting provisioning Oct 30 00:17:51.228295 waagent[2013]: 2025-10-30T00:17:51.228253Z INFO Daemon Daemon Handle ovf-env.xml. Oct 30 00:17:51.230667 waagent[2013]: 2025-10-30T00:17:51.230619Z INFO Daemon Daemon Set hostname [ci-4459.1.0-n-a485231bb0] Oct 30 00:17:51.338423 waagent[2013]: 2025-10-30T00:17:51.338355Z INFO Daemon Daemon Publish hostname [ci-4459.1.0-n-a485231bb0] Oct 30 00:17:51.338878 waagent[2013]: 2025-10-30T00:17:51.338835Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 30 00:17:51.339639 waagent[2013]: 2025-10-30T00:17:51.339587Z INFO Daemon Daemon Primary interface is [eth0] Oct 30 00:17:51.359169 systemd-networkd[1565]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:17:51.359178 systemd-networkd[1565]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:17:51.359206 systemd-networkd[1565]: eth0: DHCP lease lost Oct 30 00:17:51.359644 waagent[2013]: 2025-10-30T00:17:51.359599Z INFO Daemon Daemon Create user account if not exists Oct 30 00:17:51.359886 waagent[2013]: 2025-10-30T00:17:51.359849Z INFO Daemon Daemon User core already exists, skip useradd Oct 30 00:17:51.361273 waagent[2013]: 2025-10-30T00:17:51.361240Z INFO Daemon Daemon Configure sudoer Oct 30 00:17:51.371793 waagent[2013]: 2025-10-30T00:17:51.371745Z INFO Daemon Daemon Configure sshd Oct 30 00:17:51.375695 waagent[2013]: 2025-10-30T00:17:51.375645Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 30 00:17:51.375883 waagent[2013]: 2025-10-30T00:17:51.375842Z INFO Daemon Daemon Deploy ssh public key. Oct 30 00:17:51.427537 systemd-networkd[1565]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:17:52.778250 waagent[2013]: 2025-10-30T00:17:52.778187Z INFO Daemon Daemon Provisioning complete Oct 30 00:17:52.786889 waagent[2013]: 2025-10-30T00:17:52.786850Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 30 00:17:52.787129 waagent[2013]: 2025-10-30T00:17:52.787088Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 30 00:17:52.788592 waagent[2013]: 2025-10-30T00:17:52.788553Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 30 00:17:52.987076 waagent[2195]: 2025-10-30T00:17:52.986998Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 30 00:17:52.987426 waagent[2195]: 2025-10-30T00:17:52.987140Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.0 Oct 30 00:17:52.987426 waagent[2195]: 2025-10-30T00:17:52.987208Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 30 00:17:52.987426 waagent[2195]: 2025-10-30T00:17:52.987274Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 30 00:17:53.472353 waagent[2195]: 2025-10-30T00:17:53.472265Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 30 00:17:53.472572 waagent[2195]: 2025-10-30T00:17:53.472532Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:17:53.472654 waagent[2195]: 2025-10-30T00:17:53.472621Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:17:53.479734 waagent[2195]: 2025-10-30T00:17:53.479674Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 30 00:17:53.484773 waagent[2195]: 2025-10-30T00:17:53.484730Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 30 00:17:53.485203 waagent[2195]: 2025-10-30T00:17:53.485163Z INFO ExtHandler Oct 30 00:17:53.485282 waagent[2195]: 2025-10-30T00:17:53.485250Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 47ded8be-418e-43b9-90ce-7569390d9d22 eTag: 7723352741436529867 source: Fabric] Oct 30 00:17:53.485622 waagent[2195]: 2025-10-30T00:17:53.485584Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 30 00:17:53.486238 waagent[2195]: 2025-10-30T00:17:53.486201Z INFO ExtHandler Oct 30 00:17:53.486305 waagent[2195]: 2025-10-30T00:17:53.486276Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 30 00:17:53.490064 waagent[2195]: 2025-10-30T00:17:53.490020Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 30 00:17:53.551857 waagent[2195]: 2025-10-30T00:17:53.551793Z INFO ExtHandler Downloaded certificate {'thumbprint': '61D030AA7E12FBE335A669CDF9558F187A071AC2', 'hasPrivateKey': True} Oct 30 00:17:53.552344 waagent[2195]: 2025-10-30T00:17:53.552304Z INFO ExtHandler Fetch goal state completed Oct 30 00:17:53.563708 waagent[2195]: 2025-10-30T00:17:53.563661Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 30 00:17:53.571890 waagent[2195]: 2025-10-30T00:17:53.571846Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2195 Oct 30 00:17:53.572033 waagent[2195]: 2025-10-30T00:17:53.571997Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 30 00:17:53.572369 waagent[2195]: 2025-10-30T00:17:53.572331Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 30 00:17:53.574017 waagent[2195]: 2025-10-30T00:17:53.573979Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 30 00:17:53.574458 waagent[2195]: 2025-10-30T00:17:53.574420Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 30 00:17:53.574637 waagent[2195]: 2025-10-30T00:17:53.574600Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 30 00:17:53.575228 waagent[2195]: 2025-10-30T00:17:53.575190Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 30 00:17:53.733085 waagent[2195]: 2025-10-30T00:17:53.733049Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 30 00:17:53.733284 waagent[2195]: 2025-10-30T00:17:53.733248Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 30 00:17:53.739724 waagent[2195]: 2025-10-30T00:17:53.739695Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 30 00:17:53.746125 systemd[1]: Reload requested from client PID 2210 ('systemctl') (unit waagent.service)... Oct 30 00:17:53.746143 systemd[1]: Reloading... Oct 30 00:17:53.811511 zram_generator::config[2253]: No configuration found. Oct 30 00:17:54.091781 systemd[1]: Reloading finished in 345 ms. Oct 30 00:17:54.115826 waagent[2195]: 2025-10-30T00:17:54.115752Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 30 00:17:54.116128 waagent[2195]: 2025-10-30T00:17:54.115904Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 30 00:17:54.680201 waagent[2195]: 2025-10-30T00:17:54.680124Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 30 00:17:54.680534 waagent[2195]: 2025-10-30T00:17:54.680492Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Oct 30 00:17:54.681290 waagent[2195]: 2025-10-30T00:17:54.681244Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 30 00:17:54.681656 waagent[2195]: 2025-10-30T00:17:54.681608Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:17:54.681829 waagent[2195]: 2025-10-30T00:17:54.681788Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 30 00:17:54.682013 waagent[2195]: 2025-10-30T00:17:54.681973Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:17:54.682218 waagent[2195]: 2025-10-30T00:17:54.682174Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:17:54.682495 waagent[2195]: 2025-10-30T00:17:54.682427Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 30 00:17:54.682815 waagent[2195]: 2025-10-30T00:17:54.682758Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 30 00:17:54.682995 waagent[2195]: 2025-10-30T00:17:54.682948Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 30 00:17:54.683166 waagent[2195]: 2025-10-30T00:17:54.683119Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:17:54.683443 waagent[2195]: 2025-10-30T00:17:54.683396Z INFO EnvHandler ExtHandler Configure routes Oct 30 00:17:54.683880 waagent[2195]: 2025-10-30T00:17:54.683819Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 30 00:17:54.683956 waagent[2195]: 2025-10-30T00:17:54.683925Z INFO EnvHandler ExtHandler Gateway:None Oct 30 00:17:54.684074 waagent[2195]: 2025-10-30T00:17:54.684020Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 30 00:17:54.684221 waagent[2195]: 2025-10-30T00:17:54.684183Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 30 00:17:54.684360 waagent[2195]: 2025-10-30T00:17:54.684326Z INFO EnvHandler ExtHandler Routes:None Oct 30 00:17:54.684505 waagent[2195]: 2025-10-30T00:17:54.684449Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 30 00:17:54.684505 waagent[2195]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 30 00:17:54.684505 waagent[2195]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 30 00:17:54.684505 waagent[2195]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 30 00:17:54.684505 waagent[2195]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:17:54.684505 waagent[2195]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:17:54.684505 waagent[2195]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:17:54.690823 waagent[2195]: 2025-10-30T00:17:54.690781Z INFO ExtHandler ExtHandler Oct 30 00:17:54.691272 waagent[2195]: 2025-10-30T00:17:54.691233Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: e07bb8d6-7249-4717-8b2e-2e148a1c87ba correlation 65e8334f-54d5-438d-8e66-b768d5c1eab9 created: 2025-10-30T00:15:43.116005Z] Oct 30 00:17:54.692331 waagent[2195]: 2025-10-30T00:17:54.692289Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 30 00:17:54.694170 waagent[2195]: 2025-10-30T00:17:54.694134Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 30 00:17:54.727711 waagent[2195]: 2025-10-30T00:17:54.727660Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Oct 30 00:17:54.727711 waagent[2195]: Try `iptables -h' or 'iptables --help' for more information.) Oct 30 00:17:54.728077 waagent[2195]: 2025-10-30T00:17:54.728035Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D4AFD21C-1F50-4E48-947A-610F1436C924;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 30 00:17:54.744744 waagent[2195]: 2025-10-30T00:17:54.744690Z INFO MonitorHandler ExtHandler Network interfaces: Oct 30 00:17:54.744744 waagent[2195]: Executing ['ip', '-a', '-o', 'link']: Oct 30 00:17:54.744744 waagent[2195]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Oct 30 00:17:54.744744 waagent[2195]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:fb:3a:1e brd ff:ff:ff:ff:ff:ff Oct 30 00:17:54.744744 waagent[2195]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 30 00:17:54.744744 waagent[2195]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 30 00:17:54.744744 waagent[2195]: 2: eth0 inet 10.200.8.10/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 30 00:17:54.744744 waagent[2195]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 30 00:17:54.744744 waagent[2195]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 30 00:17:54.744744 waagent[2195]: 2: eth0 inet6 fe80::7e1e:52ff:fefb:3a1e/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 30 00:17:54.945103 waagent[2195]: 2025-10-30T00:17:54.944996Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 30 00:17:54.945103 waagent[2195]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:17:54.945103 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.945103 waagent[2195]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:17:54.945103 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.945103 waagent[2195]: Chain OUTPUT (policy ACCEPT 71 packets, 4544 bytes) Oct 30 00:17:54.945103 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.945103 waagent[2195]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 30 00:17:54.945103 waagent[2195]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 30 00:17:54.945103 waagent[2195]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 30 00:17:54.948408 waagent[2195]: 2025-10-30T00:17:54.948359Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 30 00:17:54.948408 waagent[2195]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:17:54.948408 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.948408 waagent[2195]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:17:54.948408 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.948408 waagent[2195]: Chain OUTPUT (policy ACCEPT 71 packets, 4544 bytes) Oct 30 00:17:54.948408 waagent[2195]: pkts bytes target prot opt in out source destination Oct 30 00:17:54.948408 waagent[2195]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 30 00:17:54.948408 waagent[2195]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 30 00:17:54.948408 waagent[2195]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 30 00:17:54.948684 waagent[2195]: 2025-10-30T00:17:54.948648Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 30 00:17:54.948832 waagent[2195]: 2025-10-30T00:17:54.948798Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 30 00:17:56.977515 setup-nvidia[2033]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 531M 0 176k 0 0 135k 0 1:07:08 0:00:01 1:07:07 135k\u000d 1 531M 1 10.0M 0 0 4544k 0 0:01:59 0:00:02 0:01:57 4545k\u000d 5 531M 5 30.6M 0 0 9763k 0 0:00:55 0:00:03 0:00:52 9764k\u000d 9 531M 9 52.7M 0 0 12.3M 0 0:00:43 0:00:04 0:00:39 12.3M\u000d 13 531M 13 73.4M 0 0 14.0M 0 0:00:37 0:00:05 0:00:32 14.7M\u000d 16 531M 16 89.7M 0 0 14.3M 0 0:00:37 0:00:06 0:00:31 18.0M\u000d 21 531M 21 111M 0 0 15.4M 0 0:00:34 0:00:07 0:00:27 20.4M\u000d 25 531M 25 134M 0 0 16.1M 0 0:00:33 0:00:08 0:00:25 20.2M\u000d 29 531M 29 157M 0 0 16.8M 0 0:00:31 0:00:09 0:00:22 20.7M\u000d 33 531M 33 179M 0 0 17.5M 0 0:00:30 0:00:10 0:00:20 21.1M\u000d 38 531M 38 202M 0 0 17.9M 0 0:00:29 0:00:11 0:00:18 22.6M\u000d 42 531M 42 225M 0 0 18.5M 0 0:00:28 0:00:12 0:00:16 22.8M\u000d 45 531M 45 244M 0 0 18.4M 0 0:00:28 0:00:13 0:00:15 22.3M\u000d 50 531M 50 267M 0 0 18.8M 0 0:00:28 0:00:14 0:00:14 22.5M\u000d 55 531M 55 292M 0 0 19.0M 0 0:00:27 0:00:15 0:00:12 22.3M\u000d 58 531M 58 313M 0 0 19.2M 0 0:00:27 0:00:16 0:00:11 22.0M\u000d 62 531M 62 333M 0 0 19.3M 0 0:00:27 0:00:17 0:00:10 21.4M\u000d 66 531M 66 350M 0 0 16.6M 0 0:00:31 0:00:21 0:00:10 13.6M\u000d 66 531M 66 355M 0 0 16.7M 0 0:00:31 0:00:21 0:00:10 12.4M\u000d 68 531M 68 362M 0 0 16.2M 0 0:00:32 0:00:22 0:00:10 10.0M\u000d 72 531M 72 384M 0 0 16.4M 0 0:00:32 0:00:23 0:00:09 10.0M\u000d 76 531M 76 404M 0 0 16.6M 0 0:00:31 0:00:24 0:00:07 10.1M\u000d 80 531M 80 425M 0 0 16.8M 0 0:00:31 0:00:25 0:00:06 18.1M\u000d 84 531M 84 449M 0 0 17.0M 0 0:00:31 0:00:26 0:00:05 18.5M\u000d 88 531M 88 471M 0 0 17.2M 0 0:00:30 0:00:27 0:00:03 21.8M\u000d 91 531M 91 487M 0 0 17.1M 0 0:00:30 0:00:28 0:00:02 20.0M\u000d 91 531M 91 487M 0 0 16.6M 0 0:00:31 0:00:29 0:00:02 16.6M\u000d 91 531M 91 487M 0 0 16.0M 0 0:00:33 0:00:30 0:00:03 12.2M\u000d 92 531M 92 489M 0 0 15.6M 0 0:00:33 0:00:31 0:00:02 8307k\u000d 93 531M 93 497M 0 0 15.4M 0 0:00:34 0:00:32 0:00:02 5345k\u000d 98 531M 98 522M 0 0 15.7M 0 0:00:33 0:00:33 --:--:-- 7442k\u000d100 531M 100 531M 0 0 15.8M 0 0:00:33 0:00:33 --:--:-- 10.1M Oct 30 00:17:59.633976 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 30 00:17:59.636833 systemd[1]: Started sshd@0-10.200.8.10:22-10.200.16.10:33714.service - OpenSSH per-connection server daemon (10.200.16.10:33714). Oct 30 00:18:00.515495 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 30 00:18:00.587541 sshd[2354]: Accepted publickey for core from 10.200.16.10 port 33714 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:00.588818 sshd-session[2354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:00.593528 systemd-logind[1870]: New session 3 of user core. Oct 30 00:18:00.603640 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 30 00:18:01.144974 systemd[1]: Started sshd@1-10.200.8.10:22-10.200.16.10:39846.service - OpenSSH per-connection server daemon (10.200.16.10:39846). Oct 30 00:18:01.797919 sshd[2360]: Accepted publickey for core from 10.200.16.10 port 39846 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:01.801018 sshd-session[2360]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:01.805554 systemd-logind[1870]: New session 4 of user core. Oct 30 00:18:01.815671 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 30 00:18:02.262589 sshd[2363]: Connection closed by 10.200.16.10 port 39846 Oct 30 00:18:02.263669 sshd-session[2360]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:02.267842 systemd[1]: sshd@1-10.200.8.10:22-10.200.16.10:39846.service: Deactivated successfully. Oct 30 00:18:02.270207 systemd[1]: session-4.scope: Deactivated successfully. Oct 30 00:18:02.272979 systemd-logind[1870]: Session 4 logged out. Waiting for processes to exit. Oct 30 00:18:02.277600 systemd-logind[1870]: Removed session 4. Oct 30 00:18:02.380738 systemd[1]: Started sshd@2-10.200.8.10:22-10.200.16.10:39850.service - OpenSSH per-connection server daemon (10.200.16.10:39850). Oct 30 00:18:03.020595 sshd[2369]: Accepted publickey for core from 10.200.16.10 port 39850 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:03.022064 sshd-session[2369]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:03.029580 systemd-logind[1870]: New session 5 of user core. Oct 30 00:18:03.035615 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 30 00:18:03.478230 sshd[2372]: Connection closed by 10.200.16.10 port 39850 Oct 30 00:18:03.475324 sshd-session[2369]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:03.481363 systemd[1]: sshd@2-10.200.8.10:22-10.200.16.10:39850.service: Deactivated successfully. Oct 30 00:18:03.483093 systemd[1]: session-5.scope: Deactivated successfully. Oct 30 00:18:03.484116 systemd-logind[1870]: Session 5 logged out. Waiting for processes to exit. Oct 30 00:18:03.488044 systemd-logind[1870]: Removed session 5. Oct 30 00:18:08.165954 setup-nvidia[1866]: Downloading NVIDIA 535.261.03 Driver Oct 30 00:18:08.170164 setup-nvidia[2378]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 30 00:18:08.170409 setup-nvidia[2378]: Dload Upload Total Spent Left Speed Oct 30 00:18:08.444644 update_engine[1872]: I20251030 00:18:08.444495 1872 update_attempter.cc:509] Updating boot flags... Oct 30 00:18:10.747052 setup-nvidia[2378]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 49152 0 0 100k 0 0:55:16 --:--:-- 0:55:16 100k\u000d 48 325M 48 156M 0 0 107M 0 0:00:03 0:00:01 0:00:02 107M\u000d 92 325M 92 302M 0 0 122M 0 0:00:02 0:00:02 --:--:-- 122M\u000d100 325M 100 325M 0 0 126M 0 0:00:02 0:00:02 --:--:-- 126M Oct 30 00:18:10.863645 setup-nvidia[1866]: Extract the NVIDIA Driver Installer 535.261.03 Oct 30 00:18:10.863914 setup-nvidia[1866]: /opt/nvidia/workdir/nvidia-workdir / Oct 30 00:18:10.937932 setup-nvidia[2445]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 30 00:18:11.257885 setup-nvidia[2445]: Verifying archive integrity... OK Oct 30 00:18:12.039188 setup-nvidia[2445]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 30 00:18:13.586748 systemd[1]: Started sshd@3-10.200.8.10:22-10.200.16.10:40472.service - OpenSSH per-connection server daemon (10.200.16.10:40472). Oct 30 00:18:13.821683 setup-nvidia[2471]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 30 00:18:13.965390 setup-nvidia[1866]: / Oct 30 00:18:13.965390 setup-nvidia[1866]: Spawn system-nspawn container to install the NVIDIA drivers Oct 30 00:18:14.194378 sudo[2482]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251029-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 Oct 30 00:18:14.196109 sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 30 00:18:14.201680 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 30 00:18:14.206414 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 30 00:18:14.215618 sshd[2476]: Accepted publickey for core from 10.200.16.10 port 40472 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:14.216702 sshd-session[2476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:14.223294 systemd-logind[1870]: New session 6 of user core. Oct 30 00:18:14.229157 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 30 00:18:14.241873 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 30 00:18:14.244487 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 30 00:18:14.250605 (systemd)[2486]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 30 00:18:14.252787 systemd-logind[1870]: New session c3 of user root. Oct 30 00:18:14.416396 systemd[2486]: Queued start job for default target default.target. Oct 30 00:18:14.433532 systemd[2486]: Created slice app.slice - User Application Slice. Oct 30 00:18:14.433562 systemd[2486]: Reached target paths.target - Paths. Oct 30 00:18:14.433607 systemd[2486]: Reached target timers.target - Timers. Oct 30 00:18:14.434718 systemd[2486]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 30 00:18:14.445342 systemd[2486]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:18:14.445399 systemd[2486]: Reached target sockets.target - Sockets. Oct 30 00:18:14.445450 systemd[2486]: Reached target basic.target - Basic System. Oct 30 00:18:14.445524 systemd[2486]: Reached target default.target - Main User Target. Oct 30 00:18:14.445555 systemd[2486]: Startup finished in 187ms. Oct 30 00:18:14.445608 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 30 00:18:14.447084 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 30 00:18:14.572491 kernel: loop6: detected capacity change from 0 to 12644352 Oct 30 00:18:14.582513 kernel: loop6: p9 Oct 30 00:18:14.662421 sshd[2484]: Connection closed by 10.200.16.10 port 40472 Oct 30 00:18:14.662795 sshd-session[2476]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:14.666645 systemd[1]: sshd@3-10.200.8.10:22-10.200.16.10:40472.service: Deactivated successfully. Oct 30 00:18:14.668299 systemd[1]: session-6.scope: Deactivated successfully. Oct 30 00:18:14.669122 systemd-logind[1870]: Session 6 logged out. Waiting for processes to exit. Oct 30 00:18:14.670102 systemd-logind[1870]: Removed session 6. Oct 30 00:18:14.714149 setup-nvidia[2495]: ░ Spawning container flatcardevelopercontainer-4459.1.0nightly-20251029-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251029-2100.bin. Oct 30 00:18:14.773701 systemd[1]: Started sshd@4-10.200.8.10:22-10.200.16.10:40476.service - OpenSSH per-connection server daemon (10.200.16.10:40476). Oct 30 00:18:15.402586 sshd[2504]: Accepted publickey for core from 10.200.16.10 port 40476 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:15.403827 sshd-session[2504]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:15.408053 systemd-logind[1870]: New session 7 of user core. Oct 30 00:18:15.422617 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 30 00:18:15.850637 sshd[2507]: Connection closed by 10.200.16.10 port 40476 Oct 30 00:18:15.850939 sshd-session[2504]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:15.854411 systemd[1]: sshd@4-10.200.8.10:22-10.200.16.10:40476.service: Deactivated successfully. Oct 30 00:18:15.856164 systemd[1]: session-7.scope: Deactivated successfully. Oct 30 00:18:15.856894 systemd-logind[1870]: Session 7 logged out. Waiting for processes to exit. Oct 30 00:18:15.857795 systemd-logind[1870]: Removed session 7. Oct 30 00:18:16.460223 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Oct 30 00:18:16.460344 kernel: EXT4-fs (loop6p9): mounted filesystem ff60a145-de44-4789-82a5-6d59a2c3f56a ro without journal. Quota mode: none. Oct 30 00:18:25.965561 systemd[1]: Started sshd@5-10.200.8.10:22-10.200.16.10:52626.service - OpenSSH per-connection server daemon (10.200.16.10:52626). Oct 30 00:18:26.592623 sshd[2574]: Accepted publickey for core from 10.200.16.10 port 52626 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:26.593908 sshd-session[2574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:26.598159 systemd-logind[1870]: New session 8 of user core. Oct 30 00:18:26.612614 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 30 00:18:27.037622 sshd[2619]: Connection closed by 10.200.16.10 port 52626 Oct 30 00:18:27.037992 sshd-session[2574]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:27.041524 systemd[1]: sshd@5-10.200.8.10:22-10.200.16.10:52626.service: Deactivated successfully. Oct 30 00:18:27.043282 systemd[1]: session-8.scope: Deactivated successfully. Oct 30 00:18:27.044030 systemd-logind[1870]: Session 8 logged out. Waiting for processes to exit. Oct 30 00:18:27.044975 systemd-logind[1870]: Removed session 8. Oct 30 00:18:27.149982 systemd[1]: Started sshd@6-10.200.8.10:22-10.200.16.10:52632.service - OpenSSH per-connection server daemon (10.200.16.10:52632). Oct 30 00:18:27.867874 sshd[2644]: Accepted publickey for core from 10.200.16.10 port 52632 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:27.869154 sshd-session[2644]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:27.873593 systemd-logind[1870]: New session 9 of user core. Oct 30 00:18:27.886584 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 30 00:18:28.316151 sshd[2721]: Connection closed by 10.200.16.10 port 52632 Oct 30 00:18:28.316559 sshd-session[2644]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:28.320093 systemd[1]: sshd@6-10.200.8.10:22-10.200.16.10:52632.service: Deactivated successfully. Oct 30 00:18:28.322210 systemd[1]: session-9.scope: Deactivated successfully. Oct 30 00:18:28.323835 systemd-logind[1870]: Session 9 logged out. Waiting for processes to exit. Oct 30 00:18:28.324806 systemd-logind[1870]: Removed session 9. Oct 30 00:18:38.427819 systemd[1]: Started sshd@7-10.200.8.10:22-10.200.16.10:46294.service - OpenSSH per-connection server daemon (10.200.16.10:46294). Oct 30 00:18:39.055581 sshd[3311]: Accepted publickey for core from 10.200.16.10 port 46294 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:39.056804 sshd-session[3311]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:39.063815 systemd-logind[1870]: New session 10 of user core. Oct 30 00:18:39.074593 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 30 00:18:39.500802 sshd[3314]: Connection closed by 10.200.16.10 port 46294 Oct 30 00:18:39.501164 sshd-session[3311]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:39.504678 systemd[1]: sshd@7-10.200.8.10:22-10.200.16.10:46294.service: Deactivated successfully. Oct 30 00:18:39.506299 systemd[1]: session-10.scope: Deactivated successfully. Oct 30 00:18:39.506989 systemd-logind[1870]: Session 10 logged out. Waiting for processes to exit. Oct 30 00:18:39.507915 systemd-logind[1870]: Removed session 10. Oct 30 00:18:39.615600 systemd[1]: Started sshd@8-10.200.8.10:22-10.200.16.10:46296.service - OpenSSH per-connection server daemon (10.200.16.10:46296). Oct 30 00:18:40.243346 sshd[3320]: Accepted publickey for core from 10.200.16.10 port 46296 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:40.244555 sshd-session[3320]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:40.248752 systemd-logind[1870]: New session 11 of user core. Oct 30 00:18:40.258590 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 30 00:18:40.689090 sshd[3323]: Connection closed by 10.200.16.10 port 46296 Oct 30 00:18:40.689438 sshd-session[3320]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:40.692949 systemd[1]: sshd@8-10.200.8.10:22-10.200.16.10:46296.service: Deactivated successfully. Oct 30 00:18:40.694553 systemd[1]: session-11.scope: Deactivated successfully. Oct 30 00:18:40.695213 systemd-logind[1870]: Session 11 logged out. Waiting for processes to exit. Oct 30 00:18:40.696099 systemd-logind[1870]: Removed session 11. Oct 30 00:18:50.805898 systemd[1]: Started sshd@9-10.200.8.10:22-10.200.16.10:53102.service - OpenSSH per-connection server daemon (10.200.16.10:53102). Oct 30 00:18:51.437097 sshd[3329]: Accepted publickey for core from 10.200.16.10 port 53102 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:51.438458 sshd-session[3329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:51.442697 systemd-logind[1870]: New session 12 of user core. Oct 30 00:18:51.464591 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 30 00:18:51.885386 sshd[3332]: Connection closed by 10.200.16.10 port 53102 Oct 30 00:18:51.885788 sshd-session[3329]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:51.889317 systemd[1]: sshd@9-10.200.8.10:22-10.200.16.10:53102.service: Deactivated successfully. Oct 30 00:18:51.891013 systemd[1]: session-12.scope: Deactivated successfully. Oct 30 00:18:51.891818 systemd-logind[1870]: Session 12 logged out. Waiting for processes to exit. Oct 30 00:18:51.892791 systemd-logind[1870]: Removed session 12. Oct 30 00:18:52.006062 systemd[1]: Started sshd@10-10.200.8.10:22-10.200.16.10:53108.service - OpenSSH per-connection server daemon (10.200.16.10:53108). Oct 30 00:18:52.640197 sshd[3338]: Accepted publickey for core from 10.200.16.10 port 53108 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:18:52.641495 sshd-session[3338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:18:52.649116 systemd-logind[1870]: New session 13 of user core. Oct 30 00:18:52.665605 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 30 00:18:53.087740 sshd[3341]: Connection closed by 10.200.16.10 port 53108 Oct 30 00:18:53.179921 sshd-session[3338]: pam_unix(sshd:session): session closed for user core Oct 30 00:18:53.183938 systemd[1]: sshd@10-10.200.8.10:22-10.200.16.10:53108.service: Deactivated successfully. Oct 30 00:18:53.185546 systemd[1]: session-13.scope: Deactivated successfully. Oct 30 00:18:53.186279 systemd-logind[1870]: Session 13 logged out. Waiting for processes to exit. Oct 30 00:18:53.187523 systemd-logind[1870]: Removed session 13. Oct 30 00:19:03.212880 systemd[1]: Started sshd@11-10.200.8.10:22-10.200.16.10:47618.service - OpenSSH per-connection server daemon (10.200.16.10:47618). Oct 30 00:19:03.850783 sshd[3403]: Accepted publickey for core from 10.200.16.10 port 47618 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:03.852029 sshd-session[3403]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:03.857917 systemd-logind[1870]: New session 14 of user core. Oct 30 00:19:03.868683 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 30 00:19:04.296068 sshd[3406]: Connection closed by 10.200.16.10 port 47618 Oct 30 00:19:04.296444 sshd-session[3403]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:04.299891 systemd[1]: sshd@11-10.200.8.10:22-10.200.16.10:47618.service: Deactivated successfully. Oct 30 00:19:04.301489 systemd[1]: session-14.scope: Deactivated successfully. Oct 30 00:19:04.302146 systemd-logind[1870]: Session 14 logged out. Waiting for processes to exit. Oct 30 00:19:04.303028 systemd-logind[1870]: Removed session 14. Oct 30 00:19:04.413653 systemd[1]: Started sshd@12-10.200.8.10:22-10.200.16.10:47632.service - OpenSSH per-connection server daemon (10.200.16.10:47632). Oct 30 00:19:05.047065 sshd[3412]: Accepted publickey for core from 10.200.16.10 port 47632 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:05.048255 sshd-session[3412]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:05.052547 systemd-logind[1870]: New session 15 of user core. Oct 30 00:19:05.062592 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 30 00:19:05.506888 sshd[3415]: Connection closed by 10.200.16.10 port 47632 Oct 30 00:19:05.507240 sshd-session[3412]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:05.510697 systemd[1]: sshd@12-10.200.8.10:22-10.200.16.10:47632.service: Deactivated successfully. Oct 30 00:19:05.512264 systemd[1]: session-15.scope: Deactivated successfully. Oct 30 00:19:05.512957 systemd-logind[1870]: Session 15 logged out. Waiting for processes to exit. Oct 30 00:19:05.513882 systemd-logind[1870]: Removed session 15. Oct 30 00:19:15.623752 systemd[1]: Started sshd@13-10.200.8.10:22-10.200.16.10:57270.service - OpenSSH per-connection server daemon (10.200.16.10:57270). Oct 30 00:19:16.261173 sshd[3542]: Accepted publickey for core from 10.200.16.10 port 57270 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:16.262415 sshd-session[3542]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:16.267964 systemd-logind[1870]: New session 16 of user core. Oct 30 00:19:16.287596 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 30 00:19:16.717024 sshd[3545]: Connection closed by 10.200.16.10 port 57270 Oct 30 00:19:16.717396 sshd-session[3542]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:16.720983 systemd[1]: sshd@13-10.200.8.10:22-10.200.16.10:57270.service: Deactivated successfully. Oct 30 00:19:16.722624 systemd[1]: session-16.scope: Deactivated successfully. Oct 30 00:19:16.723274 systemd-logind[1870]: Session 16 logged out. Waiting for processes to exit. Oct 30 00:19:16.724230 systemd-logind[1870]: Removed session 16. Oct 30 00:19:16.835912 systemd[1]: Started sshd@14-10.200.8.10:22-10.200.16.10:57282.service - OpenSSH per-connection server daemon (10.200.16.10:57282). Oct 30 00:19:17.463768 sshd[3551]: Accepted publickey for core from 10.200.16.10 port 57282 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:17.465007 sshd-session[3551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:17.469340 systemd-logind[1870]: New session 17 of user core. Oct 30 00:19:17.483610 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 30 00:19:17.908857 sshd[3554]: Connection closed by 10.200.16.10 port 57282 Oct 30 00:19:17.909209 sshd-session[3551]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:17.912782 systemd[1]: sshd@14-10.200.8.10:22-10.200.16.10:57282.service: Deactivated successfully. Oct 30 00:19:17.914368 systemd[1]: session-17.scope: Deactivated successfully. Oct 30 00:19:17.915094 systemd-logind[1870]: Session 17 logged out. Waiting for processes to exit. Oct 30 00:19:17.916039 systemd-logind[1870]: Removed session 17. Oct 30 00:19:28.032721 systemd[1]: Started sshd@15-10.200.8.10:22-10.200.16.10:47980.service - OpenSSH per-connection server daemon (10.200.16.10:47980). Oct 30 00:19:28.677719 sshd[7762]: Accepted publickey for core from 10.200.16.10 port 47980 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:28.678959 sshd-session[7762]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:28.683432 systemd-logind[1870]: New session 18 of user core. Oct 30 00:19:28.689613 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 30 00:19:29.128812 sshd[7976]: Connection closed by 10.200.16.10 port 47980 Oct 30 00:19:29.129600 sshd-session[7762]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:29.135120 systemd-logind[1870]: Session 18 logged out. Waiting for processes to exit. Oct 30 00:19:29.135979 systemd[1]: sshd@15-10.200.8.10:22-10.200.16.10:47980.service: Deactivated successfully. Oct 30 00:19:29.139528 systemd[1]: session-18.scope: Deactivated successfully. Oct 30 00:19:29.142380 systemd-logind[1870]: Removed session 18. Oct 30 00:19:29.243682 systemd[1]: Started sshd@16-10.200.8.10:22-10.200.16.10:47996.service - OpenSSH per-connection server daemon (10.200.16.10:47996). Oct 30 00:19:29.905117 sshd[8245]: Accepted publickey for core from 10.200.16.10 port 47996 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:29.907460 sshd-session[8245]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:29.914671 systemd-logind[1870]: New session 19 of user core. Oct 30 00:19:29.922115 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 30 00:19:30.357625 sshd[8466]: Connection closed by 10.200.16.10 port 47996 Oct 30 00:19:30.359272 sshd-session[8245]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:30.363065 systemd[1]: sshd@16-10.200.8.10:22-10.200.16.10:47996.service: Deactivated successfully. Oct 30 00:19:30.365749 systemd[1]: session-19.scope: Deactivated successfully. Oct 30 00:19:30.366578 systemd-logind[1870]: Session 19 logged out. Waiting for processes to exit. Oct 30 00:19:30.367903 systemd-logind[1870]: Removed session 19. Oct 30 00:19:40.475868 systemd[1]: Started sshd@17-10.200.8.10:22-10.200.16.10:37864.service - OpenSSH per-connection server daemon (10.200.16.10:37864). Oct 30 00:19:41.124403 sshd[12519]: Accepted publickey for core from 10.200.16.10 port 37864 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:41.125747 sshd-session[12519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:41.134538 systemd-logind[1870]: New session 20 of user core. Oct 30 00:19:41.145083 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 30 00:19:41.573503 sshd[12734]: Connection closed by 10.200.16.10 port 37864 Oct 30 00:19:41.573921 sshd-session[12519]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:41.577830 systemd[1]: sshd@17-10.200.8.10:22-10.200.16.10:37864.service: Deactivated successfully. Oct 30 00:19:41.579717 systemd[1]: session-20.scope: Deactivated successfully. Oct 30 00:19:41.583379 systemd-logind[1870]: Session 20 logged out. Waiting for processes to exit. Oct 30 00:19:41.586729 systemd-logind[1870]: Removed session 20. Oct 30 00:19:41.691437 systemd[1]: Started sshd@18-10.200.8.10:22-10.200.16.10:37878.service - OpenSSH per-connection server daemon (10.200.16.10:37878). Oct 30 00:19:42.340672 sshd[12931]: Accepted publickey for core from 10.200.16.10 port 37878 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:42.342316 sshd-session[12931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:42.349654 systemd-logind[1870]: New session 21 of user core. Oct 30 00:19:42.358826 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 30 00:19:42.790754 sshd[13229]: Connection closed by 10.200.16.10 port 37878 Oct 30 00:19:42.793263 sshd-session[12931]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:42.797236 systemd[1]: sshd@18-10.200.8.10:22-10.200.16.10:37878.service: Deactivated successfully. Oct 30 00:19:42.800256 systemd[1]: session-21.scope: Deactivated successfully. Oct 30 00:19:42.802132 systemd-logind[1870]: Session 21 logged out. Waiting for processes to exit. Oct 30 00:19:42.803885 systemd-logind[1870]: Removed session 21. Oct 30 00:19:52.913802 systemd[1]: Started sshd@19-10.200.8.10:22-10.200.16.10:58886.service - OpenSSH per-connection server daemon (10.200.16.10:58886). Oct 30 00:19:53.568124 sshd[14395]: Accepted publickey for core from 10.200.16.10 port 58886 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:53.569971 sshd-session[14395]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:53.577067 systemd-logind[1870]: New session 22 of user core. Oct 30 00:19:53.582643 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 30 00:19:54.019286 sshd[14416]: Connection closed by 10.200.16.10 port 58886 Oct 30 00:19:54.023862 sshd-session[14395]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:54.027896 systemd-logind[1870]: Session 22 logged out. Waiting for processes to exit. Oct 30 00:19:54.030579 systemd[1]: sshd@19-10.200.8.10:22-10.200.16.10:58886.service: Deactivated successfully. Oct 30 00:19:54.032792 systemd[1]: session-22.scope: Deactivated successfully. Oct 30 00:19:54.037383 systemd-logind[1870]: Removed session 22. Oct 30 00:19:54.142821 systemd[1]: Started sshd@20-10.200.8.10:22-10.200.16.10:58898.service - OpenSSH per-connection server daemon (10.200.16.10:58898). Oct 30 00:19:54.792421 sshd[14434]: Accepted publickey for core from 10.200.16.10 port 58898 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:19:54.796916 sshd-session[14434]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:19:54.804438 systemd-logind[1870]: New session 23 of user core. Oct 30 00:19:54.812653 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 30 00:19:55.246569 sshd[14455]: Connection closed by 10.200.16.10 port 58898 Oct 30 00:19:55.247370 sshd-session[14434]: pam_unix(sshd:session): session closed for user core Oct 30 00:19:55.250787 systemd-logind[1870]: Session 23 logged out. Waiting for processes to exit. Oct 30 00:19:55.252774 systemd[1]: sshd@20-10.200.8.10:22-10.200.16.10:58898.service: Deactivated successfully. Oct 30 00:19:55.256554 systemd[1]: session-23.scope: Deactivated successfully. Oct 30 00:19:55.259237 systemd-logind[1870]: Removed session 23. Oct 30 00:20:05.365880 systemd[1]: Started sshd@21-10.200.8.10:22-10.200.16.10:32830.service - OpenSSH per-connection server daemon (10.200.16.10:32830). Oct 30 00:20:06.023404 sshd[14803]: Accepted publickey for core from 10.200.16.10 port 32830 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:06.025562 sshd-session[14803]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:06.031827 systemd-logind[1870]: New session 24 of user core. Oct 30 00:20:06.041191 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 30 00:20:06.476957 sshd[14824]: Connection closed by 10.200.16.10 port 32830 Oct 30 00:20:06.477398 sshd-session[14803]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:06.482031 systemd-logind[1870]: Session 24 logged out. Waiting for processes to exit. Oct 30 00:20:06.483344 systemd[1]: sshd@21-10.200.8.10:22-10.200.16.10:32830.service: Deactivated successfully. Oct 30 00:20:06.486783 systemd[1]: session-24.scope: Deactivated successfully. Oct 30 00:20:06.489579 systemd-logind[1870]: Removed session 24. Oct 30 00:20:06.594554 systemd[1]: Started sshd@22-10.200.8.10:22-10.200.16.10:32842.service - OpenSSH per-connection server daemon (10.200.16.10:32842). Oct 30 00:20:07.240860 sshd[14836]: Accepted publickey for core from 10.200.16.10 port 32842 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:07.242276 sshd-session[14836]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:07.251519 systemd-logind[1870]: New session 25 of user core. Oct 30 00:20:07.257555 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 30 00:20:07.690232 sshd[14857]: Connection closed by 10.200.16.10 port 32842 Oct 30 00:20:07.689259 sshd-session[14836]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:07.693213 systemd-logind[1870]: Session 25 logged out. Waiting for processes to exit. Oct 30 00:20:07.697061 systemd[1]: sshd@22-10.200.8.10:22-10.200.16.10:32842.service: Deactivated successfully. Oct 30 00:20:07.699144 systemd[1]: session-25.scope: Deactivated successfully. Oct 30 00:20:07.702105 systemd-logind[1870]: Removed session 25. Oct 30 00:20:17.814150 systemd[1]: Started sshd@23-10.200.8.10:22-10.200.16.10:48088.service - OpenSSH per-connection server daemon (10.200.16.10:48088). Oct 30 00:20:18.455376 sshd[15208]: Accepted publickey for core from 10.200.16.10 port 48088 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:18.456612 sshd-session[15208]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:18.461376 systemd-logind[1870]: New session 26 of user core. Oct 30 00:20:18.472589 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 30 00:20:18.902364 sshd[15211]: Connection closed by 10.200.16.10 port 48088 Oct 30 00:20:18.902764 sshd-session[15208]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:18.906452 systemd[1]: sshd@23-10.200.8.10:22-10.200.16.10:48088.service: Deactivated successfully. Oct 30 00:20:18.908020 systemd[1]: session-26.scope: Deactivated successfully. Oct 30 00:20:18.908716 systemd-logind[1870]: Session 26 logged out. Waiting for processes to exit. Oct 30 00:20:18.909625 systemd-logind[1870]: Removed session 26. Oct 30 00:20:19.022803 systemd[1]: Started sshd@24-10.200.8.10:22-10.200.16.10:48094.service - OpenSSH per-connection server daemon (10.200.16.10:48094). Oct 30 00:20:19.652812 sshd[15217]: Accepted publickey for core from 10.200.16.10 port 48094 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:19.654086 sshd-session[15217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:19.658447 systemd-logind[1870]: New session 27 of user core. Oct 30 00:20:19.666775 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 30 00:20:20.101258 sshd[15220]: Connection closed by 10.200.16.10 port 48094 Oct 30 00:20:20.101569 sshd-session[15217]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:20.105155 systemd[1]: sshd@24-10.200.8.10:22-10.200.16.10:48094.service: Deactivated successfully. Oct 30 00:20:20.106722 systemd[1]: session-27.scope: Deactivated successfully. Oct 30 00:20:20.107398 systemd-logind[1870]: Session 27 logged out. Waiting for processes to exit. Oct 30 00:20:20.108299 systemd-logind[1870]: Removed session 27. Oct 30 00:20:26.241591 setup-nvidia[2495]: \u000d Oct 30 00:20:26.241591 setup-nvidia[2495]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 30 00:20:26.241591 setup-nvidia[2495]: frequently when this kernel module was built against the wrong or Oct 30 00:20:26.241591 setup-nvidia[2495]: improperly configured kernel sources, with a version of gcc that differs Oct 30 00:20:26.241591 setup-nvidia[2495]: from the one used to build the target kernel, or if another driver, such Oct 30 00:20:26.241591 setup-nvidia[2495]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 30 00:20:26.241591 setup-nvidia[2495]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 30 00:20:26.241591 setup-nvidia[2495]: installed in this system is supported by this NVIDIA Linux graphics Oct 30 00:20:26.241591 setup-nvidia[2495]: driver release. Oct 30 00:20:26.241591 setup-nvidia[2495]: \u000d Oct 30 00:20:26.241591 setup-nvidia[2495]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 30 00:20:26.241591 setup-nvidia[2495]: messages' at the end of the file Oct 30 00:20:26.241591 setup-nvidia[2495]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 30 00:20:26.241591 setup-nvidia[2495]: information. Oct 30 00:20:26.241591 setup-nvidia[2495]: \u000d Oct 30 00:20:26.256434 setup-nvidia[2495]: dmesg: read kernel buffer failed: Operation not permitted Oct 30 00:20:26.274948 setup-nvidia[2495]: \u000d Oct 30 00:20:26.274948 setup-nvidia[2495]: ERROR: Installation has failed. Please see the file Oct 30 00:20:26.274948 setup-nvidia[2495]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 30 00:20:26.274948 setup-nvidia[2495]: details. You may find suggestions on fixing installation problems in Oct 30 00:20:26.274948 setup-nvidia[2495]: the README available on the Linux driver download page at Oct 30 00:20:26.274948 setup-nvidia[2495]: www.nvidia.com. Oct 30 00:20:26.274948 setup-nvidia[2495]: \u000d Oct 30 00:20:26.275356 setup-nvidia[2495]: Last 50 lines of nvidia-installer.log: Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:20:26.276147 setup-nvidia[2495]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 30 00:20:26.276147 setup-nvidia[2495]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 30 00:20:26.276147 setup-nvidia[2495]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 30 00:20:26.276147 setup-nvidia[2495]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 30 00:20:26.276147 setup-nvidia[2495]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 30 00:20:26.276147 setup-nvidia[2495]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 30 00:20:26.276147 setup-nvidia[2495]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 30 00:20:26.276147 setup-nvidia[2495]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 30 00:20:26.276147 setup-nvidia[2495]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Oct 30 00:20:26.276147 setup-nvidia[2495]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Oct 30 00:20:26.276147 setup-nvidia[2495]: -> done. Oct 30 00:20:26.276147 setup-nvidia[2495]: -> Kernel module compilation complete. Oct 30 00:20:26.276147 setup-nvidia[2495]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 30 00:20:26.277291 setup-nvidia[2495]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Oct 30 00:20:26.277291 setup-nvidia[2495]: \u000d Oct 30 00:20:26.277291 setup-nvidia[2495]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Oct 30 00:20:26.277291 setup-nvidia[2495]: -> Kernel module load error: Operation not permitted Oct 30 00:20:26.277291 setup-nvidia[2495]: -> Kernel messages: Oct 30 00:20:26.277291 setup-nvidia[2495]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 30 00:20:30.213699 systemd[1]: Started sshd@25-10.200.8.10:22-10.200.16.10:52866.service - OpenSSH per-connection server daemon (10.200.16.10:52866). Oct 30 00:20:30.958656 sshd[15406]: Accepted publickey for core from 10.200.16.10 port 52866 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:30.959885 sshd-session[15406]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:30.964134 systemd-logind[1870]: New session 28 of user core. Oct 30 00:20:30.971600 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 30 00:20:31.488115 sshd[15409]: Connection closed by 10.200.16.10 port 52866 Oct 30 00:20:31.488520 sshd-session[15406]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:31.492060 systemd[1]: sshd@25-10.200.8.10:22-10.200.16.10:52866.service: Deactivated successfully. Oct 30 00:20:31.493705 systemd[1]: session-28.scope: Deactivated successfully. Oct 30 00:20:31.494396 systemd-logind[1870]: Session 28 logged out. Waiting for processes to exit. Oct 30 00:20:31.495506 systemd-logind[1870]: Removed session 28. Oct 30 00:20:31.626690 systemd[1]: Started sshd@26-10.200.8.10:22-10.200.16.10:52868.service - OpenSSH per-connection server daemon (10.200.16.10:52868). Oct 30 00:20:32.272491 sshd[15415]: Accepted publickey for core from 10.200.16.10 port 52868 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:32.273706 sshd-session[15415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:32.277984 systemd-logind[1870]: New session 29 of user core. Oct 30 00:20:32.289577 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 30 00:20:32.736488 sshd[15419]: Connection closed by 10.200.16.10 port 52868 Oct 30 00:20:32.736847 sshd-session[15415]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:32.740359 systemd[1]: sshd@26-10.200.8.10:22-10.200.16.10:52868.service: Deactivated successfully. Oct 30 00:20:32.742002 systemd[1]: session-29.scope: Deactivated successfully. Oct 30 00:20:32.742680 systemd-logind[1870]: Session 29 logged out. Waiting for processes to exit. Oct 30 00:20:32.743587 systemd-logind[1870]: Removed session 29. Oct 30 00:20:42.852521 systemd[1]: Started sshd@27-10.200.8.10:22-10.200.16.10:39286.service - OpenSSH per-connection server daemon (10.200.16.10:39286). Oct 30 00:20:43.484715 sshd[15425]: Accepted publickey for core from 10.200.16.10 port 39286 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:43.485871 sshd-session[15425]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:43.490074 systemd-logind[1870]: New session 30 of user core. Oct 30 00:20:43.507592 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 30 00:20:43.933188 sshd[15429]: Connection closed by 10.200.16.10 port 39286 Oct 30 00:20:43.933574 sshd-session[15425]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:43.937100 systemd[1]: sshd@27-10.200.8.10:22-10.200.16.10:39286.service: Deactivated successfully. Oct 30 00:20:43.938879 systemd[1]: session-30.scope: Deactivated successfully. Oct 30 00:20:43.939633 systemd-logind[1870]: Session 30 logged out. Waiting for processes to exit. Oct 30 00:20:43.940554 systemd-logind[1870]: Removed session 30. Oct 30 00:20:44.052818 systemd[1]: Started sshd@28-10.200.8.10:22-10.200.16.10:39292.service - OpenSSH per-connection server daemon (10.200.16.10:39292). Oct 30 00:20:44.699216 sshd[15436]: Accepted publickey for core from 10.200.16.10 port 39292 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:44.700446 sshd-session[15436]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:44.704928 systemd-logind[1870]: New session 31 of user core. Oct 30 00:20:44.727616 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 30 00:20:45.147771 sshd[15439]: Connection closed by 10.200.16.10 port 39292 Oct 30 00:20:45.148151 sshd-session[15436]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:45.151662 systemd[1]: sshd@28-10.200.8.10:22-10.200.16.10:39292.service: Deactivated successfully. Oct 30 00:20:45.153385 systemd[1]: session-31.scope: Deactivated successfully. Oct 30 00:20:45.154084 systemd-logind[1870]: Session 31 logged out. Waiting for processes to exit. Oct 30 00:20:45.154986 systemd-logind[1870]: Removed session 31. Oct 30 00:20:55.272948 systemd[1]: Started sshd@29-10.200.8.10:22-10.200.16.10:57292.service - OpenSSH per-connection server daemon (10.200.16.10:57292). Oct 30 00:20:59.562777 sshd[15446]: Accepted publickey for core from 10.200.16.10 port 57292 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:20:59.562893 sshd-session[15446]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:20:59.567302 systemd-logind[1870]: New session 32 of user core. Oct 30 00:20:59.591600 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 30 00:20:59.948221 sshd[15449]: Connection closed by 10.200.16.10 port 57292 Oct 30 00:20:59.948598 sshd-session[15446]: pam_unix(sshd:session): session closed for user core Oct 30 00:20:59.952053 systemd[1]: sshd@29-10.200.8.10:22-10.200.16.10:57292.service: Deactivated successfully. Oct 30 00:20:59.953732 systemd[1]: session-32.scope: Deactivated successfully. Oct 30 00:20:59.954409 systemd-logind[1870]: Session 32 logged out. Waiting for processes to exit. Oct 30 00:20:59.955324 systemd-logind[1870]: Removed session 32. Oct 30 00:21:00.059911 systemd[1]: Started sshd@30-10.200.8.10:22-10.200.16.10:54592.service - OpenSSH per-connection server daemon (10.200.16.10:54592). Oct 30 00:21:02.616626 sshd[15455]: Accepted publickey for core from 10.200.16.10 port 54592 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:02.617005 sshd-session[15455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:02.621653 systemd-logind[1870]: New session 33 of user core. Oct 30 00:21:02.631599 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 30 00:21:02.725496 kernel: EXT4-fs (loop6p9): unmounting filesystem ff60a145-de44-4789-82a5-6d59a2c3f56a. Oct 30 00:21:02.726430 setup-nvidia[2495]: Container flatcardevelopercontainer-4459.1.0nightly-20251029-2100.bin exited successfully. Oct 30 00:21:02.728861 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.0nightly\x2d20251029\x2d2100.bin.mount: Deactivated successfully. Oct 30 00:21:02.786271 sudo[2482]: pam_unix(sudo:session): session closed for user root Oct 30 00:21:02.788172 systemd[1]: session-c2.scope: Deactivated successfully. Oct 30 00:21:02.788424 systemd[1]: session-c2.scope: Consumed 5min 22.662s CPU time, 889.8M memory peak. Oct 30 00:21:02.992657 sshd[15458]: Connection closed by 10.200.16.10 port 54592 Oct 30 00:21:02.993042 sshd-session[15455]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:02.996691 systemd[1]: sshd@30-10.200.8.10:22-10.200.16.10:54592.service: Deactivated successfully. Oct 30 00:21:02.998350 systemd[1]: session-33.scope: Deactivated successfully. Oct 30 00:21:02.999035 systemd-logind[1870]: Session 33 logged out. Waiting for processes to exit. Oct 30 00:21:03.000012 systemd-logind[1870]: Removed session 33. Oct 30 00:21:03.218742 setup-nvidia[1866]: /opt/nvidia / Oct 30 00:21:03.219744 setup-nvidia[1866]: / Oct 30 00:21:03.229487 kernel: IPMI message handler: version 39.2 Oct 30 00:21:03.232489 kernel: ipmi device interface Oct 30 00:21:03.245198 setup-nvidia[1866]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Oct 30 00:21:03.303724 kernel: nvidia: loading out-of-tree module taints kernel. Oct 30 00:21:03.303858 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 30 00:21:03.303894 kernel: Disabling lock debugging due to kernel taint Oct 30 00:21:03.306500 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 30 00:21:03.313752 kernel: nvidia: module license taints kernel. Oct 30 00:21:03.712488 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 30 00:21:04.104979 kernel: Oct 30 00:21:04.105125 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 30 00:21:04.312493 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 30 00:21:04.333495 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 30 00:21:04.355497 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 30 00:21:04.408460 setup-nvidia[1866]: / Oct 30 00:21:04.408909 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 30 00:21:04.426503 setup-nvidia[15497]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 30 00:21:06.238669 setup-nvidia[15514]: Thu Oct 30 00:21:06 2025 Oct 30 00:21:06.238669 setup-nvidia[15514]: +---------------------------------------------------------------------------------------+ Oct 30 00:21:06.238669 setup-nvidia[15514]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 30 00:21:06.238669 setup-nvidia[15514]: |-----------------------------------------+----------------------+----------------------+ Oct 30 00:21:06.238669 setup-nvidia[15514]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 30 00:21:06.238669 setup-nvidia[15514]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 30 00:21:06.238669 setup-nvidia[15514]: | | | MIG M. | Oct 30 00:21:06.238669 setup-nvidia[15514]: |=========================================+======================+======================| Oct 30 00:21:06.242194 setup-nvidia[15514]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 30 00:21:06.242194 setup-nvidia[15514]: | N/A 25C P0 38W / 250W | 0MiB / 16384MiB | 0% Default | Oct 30 00:21:06.242194 setup-nvidia[15514]: | | | N/A | Oct 30 00:21:06.242194 setup-nvidia[15514]: +-----------------------------------------+----------------------+----------------------+ Oct 30 00:21:06.242305 setup-nvidia[15514]: Oct 30 00:21:06.242305 setup-nvidia[15514]: +---------------------------------------------------------------------------------------+ Oct 30 00:21:06.242305 setup-nvidia[15514]: | Processes: | Oct 30 00:21:06.242305 setup-nvidia[15514]: | GPU GI CI PID Type Process name GPU Memory | Oct 30 00:21:06.242305 setup-nvidia[15514]: | ID ID Usage | Oct 30 00:21:06.242305 setup-nvidia[15514]: |=======================================================================================| Oct 30 00:21:06.242430 setup-nvidia[15514]: | No running processes found | Oct 30 00:21:06.242430 setup-nvidia[15514]: +---------------------------------------------------------------------------------------+ Oct 30 00:21:06.613746 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 30 00:21:06.614479 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 30 00:21:06.619599 systemd[1]: Startup finished in 1.527s (firmware) + 32.913s (loader) + 3.692s (kernel) + 18.031s (initrd) + 4min 5.456s (userspace) = 5min 1.621s. Oct 30 00:21:12.883073 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 30 00:21:12.883877 systemd[2486]: Activating special unit exit.target... Oct 30 00:21:12.883964 systemd[2486]: Stopped target default.target - Main User Target. Oct 30 00:21:12.884004 systemd[2486]: Stopped target basic.target - Basic System. Oct 30 00:21:12.884033 systemd[2486]: Stopped target paths.target - Paths. Oct 30 00:21:12.884058 systemd[2486]: Stopped target sockets.target - Sockets. Oct 30 00:21:12.884085 systemd[2486]: Stopped target timers.target - Timers. Oct 30 00:21:12.884424 systemd[2486]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:21:12.884652 systemd[2486]: Removed slice app.slice - User Application Slice. Oct 30 00:21:12.884685 systemd[2486]: Reached target shutdown.target - Shutdown. Oct 30 00:21:12.884825 systemd[2486]: Finished systemd-exit.service - Exit the Session. Oct 30 00:21:12.884861 systemd[2486]: Reached target exit.target - Exit the Session. Oct 30 00:21:12.913407 (sd-pam)[2487]: pam_unix(systemd-user:session): session closed for user root Oct 30 00:21:12.914804 systemd[1]: user@0.service: Deactivated successfully. Oct 30 00:21:12.915173 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 30 00:21:12.917506 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 30 00:21:12.939023 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 30 00:21:12.939947 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 30 00:21:12.940200 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 30 00:21:12.941831 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 30 00:21:12.941915 systemd[1]: user-0.slice: Consumed 5min 22.882s CPU time, 895.8M memory peak. Oct 30 00:21:12.942322 systemd-logind[1870]: Removed session c3. Oct 30 00:21:13.109811 systemd[1]: Started sshd@31-10.200.8.10:22-10.200.16.10:32768.service - OpenSSH per-connection server daemon (10.200.16.10:32768). Oct 30 00:21:13.759176 sshd[15525]: Accepted publickey for core from 10.200.16.10 port 32768 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:13.760400 sshd-session[15525]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:13.765887 systemd-logind[1870]: New session 34 of user core. Oct 30 00:21:13.777218 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 30 00:21:14.210904 sshd[15528]: Connection closed by 10.200.16.10 port 32768 Oct 30 00:21:14.211292 sshd-session[15525]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:14.214864 systemd[1]: sshd@31-10.200.8.10:22-10.200.16.10:32768.service: Deactivated successfully. Oct 30 00:21:14.216518 systemd[1]: session-34.scope: Deactivated successfully. Oct 30 00:21:14.217179 systemd-logind[1870]: Session 34 logged out. Waiting for processes to exit. Oct 30 00:21:14.218090 systemd-logind[1870]: Removed session 34. Oct 30 00:21:14.323025 systemd[1]: Started sshd@32-10.200.8.10:22-10.200.16.10:32774.service - OpenSSH per-connection server daemon (10.200.16.10:32774). Oct 30 00:21:14.969946 sshd[15534]: Accepted publickey for core from 10.200.16.10 port 32774 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:14.971328 sshd-session[15534]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:14.975642 systemd-logind[1870]: New session 35 of user core. Oct 30 00:21:14.984579 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 30 00:21:15.413344 sshd[15537]: Connection closed by 10.200.16.10 port 32774 Oct 30 00:21:15.413738 sshd-session[15534]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:15.417212 systemd[1]: sshd@32-10.200.8.10:22-10.200.16.10:32774.service: Deactivated successfully. Oct 30 00:21:15.418787 systemd[1]: session-35.scope: Deactivated successfully. Oct 30 00:21:15.419445 systemd-logind[1870]: Session 35 logged out. Waiting for processes to exit. Oct 30 00:21:15.420338 systemd-logind[1870]: Removed session 35. Oct 30 00:21:15.527572 systemd[1]: Started sshd@33-10.200.8.10:22-10.200.16.10:32790.service - OpenSSH per-connection server daemon (10.200.16.10:32790). Oct 30 00:21:16.156824 sshd[15543]: Accepted publickey for core from 10.200.16.10 port 32790 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:16.158040 sshd-session[15543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:16.162460 systemd-logind[1870]: New session 36 of user core. Oct 30 00:21:16.172581 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 30 00:21:16.605578 sshd[15546]: Connection closed by 10.200.16.10 port 32790 Oct 30 00:21:16.605869 sshd-session[15543]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:16.609446 systemd[1]: sshd@33-10.200.8.10:22-10.200.16.10:32790.service: Deactivated successfully. Oct 30 00:21:16.611041 systemd[1]: session-36.scope: Deactivated successfully. Oct 30 00:21:16.611726 systemd-logind[1870]: Session 36 logged out. Waiting for processes to exit. Oct 30 00:21:16.612637 systemd-logind[1870]: Removed session 36. Oct 30 00:21:16.720634 systemd[1]: Started sshd@34-10.200.8.10:22-10.200.16.10:32796.service - OpenSSH per-connection server daemon (10.200.16.10:32796). Oct 30 00:21:17.370058 sshd[15552]: Accepted publickey for core from 10.200.16.10 port 32796 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:17.371302 sshd-session[15552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:17.375460 systemd-logind[1870]: New session 37 of user core. Oct 30 00:21:17.385602 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 30 00:21:17.823151 sshd[15555]: Connection closed by 10.200.16.10 port 32796 Oct 30 00:21:17.823546 sshd-session[15552]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:17.827020 systemd[1]: sshd@34-10.200.8.10:22-10.200.16.10:32796.service: Deactivated successfully. Oct 30 00:21:17.828646 systemd[1]: session-37.scope: Deactivated successfully. Oct 30 00:21:17.829292 systemd-logind[1870]: Session 37 logged out. Waiting for processes to exit. Oct 30 00:21:17.830180 systemd-logind[1870]: Removed session 37. Oct 30 00:21:17.946659 systemd[1]: Started sshd@35-10.200.8.10:22-10.200.16.10:32804.service - OpenSSH per-connection server daemon (10.200.16.10:32804). Oct 30 00:21:18.577566 sshd[15561]: Accepted publickey for core from 10.200.16.10 port 32804 ssh2: RSA SHA256:gtEEdhDlMBb3I4xcVv2MOUtq5JMC+ov0BcOcXC3BiL0 Oct 30 00:21:18.578809 sshd-session[15561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:21:18.583058 systemd-logind[1870]: New session 38 of user core. Oct 30 00:21:18.591587 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 30 00:21:20.519167 sshd[15564]: Connection closed by 10.200.16.10 port 32804 Oct 30 00:21:20.519593 sshd-session[15561]: pam_unix(sshd:session): session closed for user core Oct 30 00:21:20.523101 systemd[1]: sshd@35-10.200.8.10:22-10.200.16.10:32804.service: Deactivated successfully. Oct 30 00:21:20.524709 systemd[1]: session-38.scope: Deactivated successfully. Oct 30 00:21:20.524904 systemd[1]: session-38.scope: Consumed 1.434s CPU time, 14.2M memory peak. Oct 30 00:21:20.525386 systemd-logind[1870]: Session 38 logged out. Waiting for processes to exit. Oct 30 00:21:20.526338 systemd-logind[1870]: Removed session 38.