Oct 31 00:15:56.233386 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Thu Oct 30 22:22:41 -00 2025 Oct 31 00:15:56.233415 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.233426 kernel: BIOS-provided physical RAM map: Oct 31 00:15:56.233432 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 31 00:15:56.233439 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 31 00:15:56.233445 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 31 00:15:56.233454 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 31 00:15:56.233463 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 31 00:15:56.233470 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 31 00:15:56.233477 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 31 00:15:56.233484 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 31 00:15:56.233491 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 31 00:15:56.233498 kernel: printk: bootconsole [earlyser0] enabled Oct 31 00:15:56.233505 kernel: NX (Execute Disable) protection: active Oct 31 00:15:56.233516 kernel: APIC: Static calls initialized Oct 31 00:15:56.233523 kernel: efi: EFI v2.7 by Microsoft Oct 31 00:15:56.233531 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 31 00:15:56.233539 kernel: random: crng init done Oct 31 00:15:56.233546 kernel: secureboot: Secure boot disabled Oct 31 00:15:56.233554 kernel: SMBIOS 3.1.0 present. Oct 31 00:15:56.233561 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 31 00:15:56.233569 kernel: Hypervisor detected: Microsoft Hyper-V Oct 31 00:15:56.233576 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 31 00:15:56.233584 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 31 00:15:56.233593 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 31 00:15:56.233601 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 31 00:15:56.233608 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 31 00:15:56.233616 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 31 00:15:56.233624 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 31 00:15:56.233632 kernel: tsc: Detected 2593.993 MHz processor Oct 31 00:15:56.233640 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 31 00:15:56.233648 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 31 00:15:56.233656 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 31 00:15:56.233667 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 31 00:15:56.233674 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 31 00:15:56.233682 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 31 00:15:56.233690 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 31 00:15:56.233697 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 31 00:15:56.233705 kernel: Using GB pages for direct mapping Oct 31 00:15:56.233713 kernel: ACPI: Early table checksum verification disabled Oct 31 00:15:56.233724 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 31 00:15:56.233735 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233743 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233751 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 31 00:15:56.233759 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 31 00:15:56.233767 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233787 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233798 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233806 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233815 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233823 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233831 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233839 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 31 00:15:56.233848 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 31 00:15:56.233856 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 31 00:15:56.233866 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 31 00:15:56.233874 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 31 00:15:56.233882 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 31 00:15:56.233890 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 31 00:15:56.233898 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 31 00:15:56.233906 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 31 00:15:56.233914 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 31 00:15:56.233922 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 31 00:15:56.233930 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 31 00:15:56.233941 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Oct 31 00:15:56.233949 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Oct 31 00:15:56.233957 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Oct 31 00:15:56.233965 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Oct 31 00:15:56.233973 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 31 00:15:56.233981 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 31 00:15:56.233989 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 31 00:15:56.233997 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 31 00:15:56.234006 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 31 00:15:56.234017 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 31 00:15:56.234025 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 31 00:15:56.234033 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 31 00:15:56.234041 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Oct 31 00:15:56.234049 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Oct 31 00:15:56.234058 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Oct 31 00:15:56.234066 kernel: Zone ranges: Oct 31 00:15:56.234074 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 31 00:15:56.234082 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 31 00:15:56.234093 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 31 00:15:56.234101 kernel: Movable zone start for each node Oct 31 00:15:56.234109 kernel: Early memory node ranges Oct 31 00:15:56.234117 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 31 00:15:56.234125 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 31 00:15:56.234133 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 31 00:15:56.234141 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 31 00:15:56.234149 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 31 00:15:56.234157 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 31 00:15:56.234168 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 31 00:15:56.234176 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 31 00:15:56.234184 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 31 00:15:56.234192 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 31 00:15:56.234200 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 31 00:15:56.234208 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 31 00:15:56.234216 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 31 00:15:56.234224 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 31 00:15:56.234232 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 31 00:15:56.234243 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Oct 31 00:15:56.234251 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 31 00:15:56.234259 kernel: Booting paravirtualized kernel on Hyper-V Oct 31 00:15:56.234267 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 31 00:15:56.234276 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 31 00:15:56.234284 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u262144 Oct 31 00:15:56.234292 kernel: pcpu-alloc: s196712 r8192 d32664 u262144 alloc=1*2097152 Oct 31 00:15:56.234300 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 31 00:15:56.234308 kernel: Hyper-V: PV spinlocks enabled Oct 31 00:15:56.234319 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 31 00:15:56.234328 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.234337 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 31 00:15:56.234345 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 31 00:15:56.234353 kernel: Fallback order for Node 0: 0 Oct 31 00:15:56.234361 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Oct 31 00:15:56.234369 kernel: Policy zone: Normal Oct 31 00:15:56.234377 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 00:15:56.234388 kernel: software IO TLB: area num 8. Oct 31 00:15:56.234404 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2288K rwdata, 22872K rodata, 43520K init, 1560K bss, 2103120K reserved, 0K cma-reserved) Oct 31 00:15:56.234413 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 31 00:15:56.234424 kernel: Kernel/User page tables isolation: enabled Oct 31 00:15:56.234433 kernel: ftrace: allocating 37954 entries in 149 pages Oct 31 00:15:56.234441 kernel: ftrace: allocated 149 pages with 4 groups Oct 31 00:15:56.234449 kernel: Dynamic Preempt: voluntary Oct 31 00:15:56.234458 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 00:15:56.234467 kernel: rcu: RCU event tracing is enabled. Oct 31 00:15:56.234476 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 31 00:15:56.234485 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 00:15:56.234495 kernel: Rude variant of Tasks RCU enabled. Oct 31 00:15:56.234504 kernel: Tracing variant of Tasks RCU enabled. Oct 31 00:15:56.234513 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 00:15:56.234521 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 31 00:15:56.234530 kernel: Using NULL legacy PIC Oct 31 00:15:56.234538 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 31 00:15:56.234549 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 31 00:15:56.234558 kernel: Console: colour dummy device 80x25 Oct 31 00:15:56.234566 kernel: printk: console [tty1] enabled Oct 31 00:15:56.234575 kernel: printk: console [ttyS0] enabled Oct 31 00:15:56.234583 kernel: printk: bootconsole [earlyser0] disabled Oct 31 00:15:56.234592 kernel: ACPI: Core revision 20230628 Oct 31 00:15:56.234600 kernel: Failed to register legacy timer interrupt Oct 31 00:15:56.234609 kernel: APIC: Switch to symmetric I/O mode setup Oct 31 00:15:56.234617 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 31 00:15:56.234628 kernel: Hyper-V: Using IPI hypercalls Oct 31 00:15:56.234637 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 31 00:15:56.234645 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 31 00:15:56.234654 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 31 00:15:56.234662 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 31 00:15:56.234671 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 31 00:15:56.234679 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 31 00:15:56.234688 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 31 00:15:56.234696 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 31 00:15:56.234707 kernel: APIC: read() replaced with hv_apic_read() Oct 31 00:15:56.234716 kernel: APIC: write() replaced with hv_apic_write() Oct 31 00:15:56.234724 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 31 00:15:56.234733 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 31 00:15:56.234741 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 31 00:15:56.234750 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 31 00:15:56.234759 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 31 00:15:56.234767 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 31 00:15:56.234783 kernel: Spectre V2 : Mitigation: Retpolines Oct 31 00:15:56.234795 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 31 00:15:56.234803 kernel: Speculative Store Bypass: Vulnerable Oct 31 00:15:56.234812 kernel: MDS: Mitigation: Clear CPU buffers Oct 31 00:15:56.234820 kernel: TAA: Mitigation: Clear CPU buffers Oct 31 00:15:56.234829 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 31 00:15:56.234837 kernel: active return thunk: its_return_thunk Oct 31 00:15:56.234845 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 31 00:15:56.234854 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 31 00:15:56.234863 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 31 00:15:56.234871 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 31 00:15:56.234880 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 31 00:15:56.234891 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 31 00:15:56.234900 kernel: Freeing SMP alternatives memory: 32K Oct 31 00:15:56.234908 kernel: pid_max: default: 32768 minimum: 301 Oct 31 00:15:56.234916 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 31 00:15:56.234925 kernel: landlock: Up and running. Oct 31 00:15:56.234933 kernel: SELinux: Initializing. Oct 31 00:15:56.234942 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.234950 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.234959 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 31 00:15:56.234967 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.234976 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.234987 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.234996 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 31 00:15:56.235005 kernel: signal: max sigframe size: 1776 Oct 31 00:15:56.235013 kernel: rcu: Hierarchical SRCU implementation. Oct 31 00:15:56.235022 kernel: rcu: Max phase no-delay instances is 400. Oct 31 00:15:56.235030 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 31 00:15:56.235039 kernel: smp: Bringing up secondary CPUs ... Oct 31 00:15:56.235048 kernel: smpboot: x86: Booting SMP configuration: Oct 31 00:15:56.235056 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 31 00:15:56.235067 kernel: smp: Brought up 1 node, 6 CPUs Oct 31 00:15:56.235076 kernel: smpboot: Max logical packages: 1 Oct 31 00:15:56.235084 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 31 00:15:56.235093 kernel: devtmpfs: initialized Oct 31 00:15:56.235101 kernel: x86/mm: Memory block size: 1024MB Oct 31 00:15:56.235110 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 31 00:15:56.235118 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 00:15:56.235127 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 31 00:15:56.235136 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 00:15:56.235147 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 31 00:15:56.235155 kernel: audit: initializing netlink subsys (disabled) Oct 31 00:15:56.235164 kernel: audit: type=2000 audit(1761869754.029:1): state=initialized audit_enabled=0 res=1 Oct 31 00:15:56.235172 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 00:15:56.235181 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 31 00:15:56.235189 kernel: cpuidle: using governor menu Oct 31 00:15:56.235198 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 00:15:56.235206 kernel: dca service started, version 1.12.1 Oct 31 00:15:56.235215 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 31 00:15:56.235226 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 31 00:15:56.235234 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 00:15:56.235243 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 31 00:15:56.235251 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 00:15:56.235260 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 31 00:15:56.235268 kernel: ACPI: Added _OSI(Module Device) Oct 31 00:15:56.235277 kernel: ACPI: Added _OSI(Processor Device) Oct 31 00:15:56.235285 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 00:15:56.235294 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 00:15:56.235305 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 31 00:15:56.235313 kernel: ACPI: Interpreter enabled Oct 31 00:15:56.235322 kernel: ACPI: PM: (supports S0 S5) Oct 31 00:15:56.235330 kernel: ACPI: Using IOAPIC for interrupt routing Oct 31 00:15:56.235339 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 31 00:15:56.235348 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 31 00:15:56.235356 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 31 00:15:56.235365 kernel: iommu: Default domain type: Translated Oct 31 00:15:56.235373 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 31 00:15:56.235384 kernel: efivars: Registered efivars operations Oct 31 00:15:56.235393 kernel: PCI: Using ACPI for IRQ routing Oct 31 00:15:56.235401 kernel: PCI: System does not support PCI Oct 31 00:15:56.235410 kernel: vgaarb: loaded Oct 31 00:15:56.235418 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 31 00:15:56.235427 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 00:15:56.235435 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 00:15:56.235444 kernel: pnp: PnP ACPI init Oct 31 00:15:56.235452 kernel: pnp: PnP ACPI: found 3 devices Oct 31 00:15:56.235463 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 31 00:15:56.235471 kernel: NET: Registered PF_INET protocol family Oct 31 00:15:56.235480 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235489 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.235497 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 31 00:15:56.235506 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 00:15:56.235515 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235523 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 31 00:15:56.235532 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235543 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235552 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 31 00:15:56.235560 kernel: NET: Registered PF_XDP protocol family Oct 31 00:15:56.235569 kernel: PCI: CLS 0 bytes, default 64 Oct 31 00:15:56.235577 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 31 00:15:56.235586 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Oct 31 00:15:56.235594 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 31 00:15:56.235603 kernel: Initialise system trusted keyrings Oct 31 00:15:56.235611 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 31 00:15:56.235622 kernel: Key type asymmetric registered Oct 31 00:15:56.235631 kernel: Asymmetric key parser 'x509' registered Oct 31 00:15:56.235639 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 00:15:56.235648 kernel: io scheduler mq-deadline registered Oct 31 00:15:56.235656 kernel: io scheduler kyber registered Oct 31 00:15:56.235664 kernel: io scheduler bfq registered Oct 31 00:15:56.235673 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 31 00:15:56.235681 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 00:15:56.235690 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 31 00:15:56.235701 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 31 00:15:56.235709 kernel: i8042: PNP: No PS/2 controller found. Oct 31 00:15:56.235879 kernel: rtc_cmos 00:02: registered as rtc0 Oct 31 00:15:56.235993 kernel: rtc_cmos 00:02: setting system clock to 2025-10-31T00:15:55 UTC (1761869755) Oct 31 00:15:56.236104 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 31 00:15:56.236118 kernel: intel_pstate: CPU model not supported Oct 31 00:15:56.236127 kernel: efifb: probing for efifb Oct 31 00:15:56.236140 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 31 00:15:56.236149 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 31 00:15:56.236157 kernel: efifb: scrolling: redraw Oct 31 00:15:56.236166 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 31 00:15:56.236174 kernel: Console: switching to colour frame buffer device 128x48 Oct 31 00:15:56.236183 kernel: fb0: EFI VGA frame buffer device Oct 31 00:15:56.236192 kernel: pstore: Using crash dump compression: deflate Oct 31 00:15:56.236201 kernel: pstore: Registered efi_pstore as persistent store backend Oct 31 00:15:56.236209 kernel: NET: Registered PF_INET6 protocol family Oct 31 00:15:56.236220 kernel: Segment Routing with IPv6 Oct 31 00:15:56.236228 kernel: In-situ OAM (IOAM) with IPv6 Oct 31 00:15:56.236237 kernel: NET: Registered PF_PACKET protocol family Oct 31 00:15:56.236246 kernel: Key type dns_resolver registered Oct 31 00:15:56.236254 kernel: IPI shorthand broadcast: enabled Oct 31 00:15:56.236263 kernel: sched_clock: Marking stable (1641005900, 104818500)->(2089663100, -343838700) Oct 31 00:15:56.236271 kernel: registered taskstats version 1 Oct 31 00:15:56.236280 kernel: Loading compiled-in X.509 certificates Oct 31 00:15:56.236288 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: 8aa749fd6ae7d102bbcccd9368b753f4483252f4' Oct 31 00:15:56.236297 kernel: Key type .fscrypt registered Oct 31 00:15:56.236308 kernel: Key type fscrypt-provisioning registered Oct 31 00:15:56.236316 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 00:15:56.236325 kernel: ima: Allocated hash algorithm: sha1 Oct 31 00:15:56.236333 kernel: ima: No architecture policies found Oct 31 00:15:56.236342 kernel: clk: Disabling unused clocks Oct 31 00:15:56.236350 kernel: Freeing unused kernel image (initmem) memory: 43520K Oct 31 00:15:56.236359 kernel: Write protecting the kernel read-only data: 38912k Oct 31 00:15:56.236367 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Oct 31 00:15:56.236376 kernel: Run /init as init process Oct 31 00:15:56.236387 kernel: with arguments: Oct 31 00:15:56.236395 kernel: /init Oct 31 00:15:56.236404 kernel: with environment: Oct 31 00:15:56.236412 kernel: HOME=/ Oct 31 00:15:56.236420 kernel: TERM=linux Oct 31 00:15:56.236430 systemd[1]: Successfully made /usr/ read-only. Oct 31 00:15:56.236441 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 31 00:15:56.236453 systemd[1]: Detected virtualization microsoft. Oct 31 00:15:56.236462 systemd[1]: Detected architecture x86-64. Oct 31 00:15:56.236471 systemd[1]: Running in initrd. Oct 31 00:15:56.236480 systemd[1]: No hostname configured, using default hostname. Oct 31 00:15:56.236489 systemd[1]: Hostname set to . Oct 31 00:15:56.236498 systemd[1]: Initializing machine ID from random generator. Oct 31 00:15:56.236507 systemd[1]: Queued start job for default target initrd.target. Oct 31 00:15:56.236516 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:15:56.236525 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:15:56.236537 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 31 00:15:56.236546 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:15:56.236555 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 31 00:15:56.236565 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 31 00:15:56.236575 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 31 00:15:56.236584 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 31 00:15:56.236596 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:15:56.236605 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:15:56.236614 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:15:56.236623 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:15:56.236632 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:15:56.236641 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:15:56.236650 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:15:56.236659 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:15:56.236668 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 31 00:15:56.236680 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 31 00:15:56.236689 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:15:56.236698 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:15:56.236707 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:15:56.236716 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:15:56.236725 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 31 00:15:56.236734 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:15:56.236743 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 31 00:15:56.236755 systemd[1]: Starting systemd-fsck-usr.service... Oct 31 00:15:56.236764 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:15:56.236784 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:15:56.236813 systemd-journald[205]: Collecting audit messages is disabled. Oct 31 00:15:56.236840 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:56.236850 systemd-journald[205]: Journal started Oct 31 00:15:56.236870 systemd-journald[205]: Runtime Journal (/run/log/journal/eff1310ea45e47eba78f89af51fc989e) is 8M, max 2.2G, 2.1G free. Oct 31 00:15:56.246349 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:15:56.249212 systemd-modules-load[207]: Inserted module 'overlay' Oct 31 00:15:56.249858 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 31 00:15:56.251962 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:15:56.252669 systemd[1]: Finished systemd-fsck-usr.service. Oct 31 00:15:56.255903 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 00:15:56.257182 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:15:56.292415 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:15:56.293877 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:15:56.298283 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:56.318965 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:15:56.328737 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:56.353673 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:15:56.360031 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 31 00:15:56.370997 systemd-modules-load[207]: Inserted module 'br_netfilter' Oct 31 00:15:56.372555 kernel: Bridge firewalling registered Oct 31 00:15:56.371887 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:15:56.389958 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:15:56.391618 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:56.408861 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 31 00:15:56.422180 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:15:56.425014 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:15:56.448369 dracut-cmdline[240]: dracut-dracut-053 Oct 31 00:15:56.453649 dracut-cmdline[240]: Using kernel command line parameters: rd.driver.pre=btrfs 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.468587 systemd-resolved[247]: Positive Trust Anchors: Oct 31 00:15:56.468597 systemd-resolved[247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:15:56.468627 systemd-resolved[247]: 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 31 00:15:56.471242 systemd-resolved[247]: Defaulting to hostname 'linux'. Oct 31 00:15:56.472287 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:15:56.478917 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:15:56.609801 kernel: SCSI subsystem initialized Oct 31 00:15:56.620795 kernel: Loading iSCSI transport class v2.0-870. Oct 31 00:15:56.633793 kernel: iscsi: registered transport (tcp) Oct 31 00:15:56.657152 kernel: iscsi: registered transport (qla4xxx) Oct 31 00:15:56.657186 kernel: QLogic iSCSI HBA Driver Oct 31 00:15:56.701553 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 31 00:15:56.720056 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 31 00:15:56.754894 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 31 00:15:56.754955 kernel: device-mapper: uevent: version 1.0.3 Oct 31 00:15:56.759618 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 31 00:15:56.801798 kernel: raid6: avx2x4 gen() 31079 MB/s Oct 31 00:15:56.821799 kernel: raid6: avx2x2 gen() 30541 MB/s Oct 31 00:15:56.843064 kernel: raid6: avx2x1 gen() 27965 MB/s Oct 31 00:15:56.843097 kernel: raid6: using algorithm avx2x4 gen() 31079 MB/s Oct 31 00:15:56.865034 kernel: raid6: .... xor() 10273 MB/s, rmw enabled Oct 31 00:15:56.865058 kernel: raid6: using avx2x2 recovery algorithm Oct 31 00:15:56.889798 kernel: xor: automatically using best checksumming function avx Oct 31 00:15:57.039802 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 31 00:15:57.050347 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:15:57.068898 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:15:57.091021 systemd-udevd[428]: Using default interface naming scheme 'v255'. Oct 31 00:15:57.096468 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:15:57.099279 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 31 00:15:57.127809 dracut-pre-trigger[438]: rd.md=0: removing MD RAID activation Oct 31 00:15:57.156389 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:15:57.176933 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:15:57.236084 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:15:57.255924 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 31 00:15:57.272471 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 00:15:57.265472 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 31 00:15:57.272435 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:15:57.278068 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:15:57.287030 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:15:57.308916 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 31 00:15:57.327812 kernel: AVX2 version of gcm_enc/dec engaged. Oct 31 00:15:57.327876 kernel: hv_vmbus: Vmbus version:4.0 Oct 31 00:15:57.327895 kernel: AES CTR mode by8 optimization enabled Oct 31 00:15:57.333816 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 31 00:15:57.334012 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:15:57.351481 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 31 00:15:57.351501 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 31 00:15:57.351521 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 31 00:15:57.362993 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 00:15:57.363024 kernel: PTP clock support registered Oct 31 00:15:57.363038 kernel: hv_vmbus: registering driver hv_netvsc Oct 31 00:15:57.362783 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:15:57.805010 kernel: hv_vmbus: registering driver hv_storvsc Oct 31 00:15:57.805037 kernel: hv_vmbus: registering driver hid_hyperv Oct 31 00:15:57.805050 kernel: hv_vmbus: registering driver hv_pci Oct 31 00:15:57.805069 kernel: hv_utils: Registering HyperV Utility Driver Oct 31 00:15:57.805118 kernel: hv_vmbus: registering driver hv_utils Oct 31 00:15:57.805174 kernel: scsi host0: storvsc_host_t Oct 31 00:15:57.805521 kernel: hv_utils: Heartbeat IC version 3.0 Oct 31 00:15:57.805537 kernel: hv_utils: TimeSync IC version 4.0 Oct 31 00:15:57.805549 kernel: hv_utils: Shutdown IC version 3.2 Oct 31 00:15:57.805560 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 31 00:15:57.805572 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 31 00:15:57.805928 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 31 00:15:57.806148 kernel: scsi host1: storvsc_host_t Oct 31 00:15:57.806498 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 31 00:15:57.806719 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 31 00:15:57.807094 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 31 00:15:57.807297 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 31 00:15:57.807648 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 31 00:15:57.807882 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 31 00:15:57.808238 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 31 00:15:57.808446 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Oct 31 00:15:57.362960 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:57.837189 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Oct 31 00:15:57.837239 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 31 00:15:57.837260 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 31 00:15:57.837279 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 31 00:15:57.737264 systemd-resolved[247]: Clock change detected. Flushing caches. Oct 31 00:15:57.792752 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:57.858248 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 31 00:15:57.858414 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 31 00:15:57.798635 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:15:57.870007 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 31 00:15:57.870227 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Oct 31 00:15:57.798901 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:57.883828 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 31 00:15:57.884045 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 31 00:15:57.884063 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 31 00:15:57.833491 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:57.870366 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:57.896278 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:57.913424 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 31 00:15:57.913680 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 31 00:15:57.913855 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Oct 31 00:15:57.914002 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Oct 31 00:15:57.920089 kernel: sd 0:0:0:1: [sda] Write Protect is off Oct 31 00:15:57.922777 kernel: sd 0:0:0:0: [sdb] Write Protect is off Oct 31 00:15:57.922954 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Oct 31 00:15:57.928043 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 31 00:15:57.928295 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Oct 31 00:15:57.940524 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 31 00:15:57.942999 kernel: sda: sda1 Oct 31 00:15:57.943872 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:57.957313 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Oct 31 00:15:57.957489 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 31 00:15:57.957504 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Oct 31 00:15:57.963313 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:58.399756 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 scanned by (udev-worker) (495) Oct 31 00:15:58.421950 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 31 00:15:58.444020 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 31 00:15:58.488413 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 31 00:15:58.592760 kernel: BTRFS: device fsid 311007bd-ab96-441d-977a-c6c513017d1c devid 1 transid 37 /dev/sdb3 scanned by (udev-worker) (483) Oct 31 00:15:58.613213 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 31 00:15:58.618448 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 31 00:15:58.643858 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 31 00:15:58.657748 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 31 00:15:58.668752 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 31 00:15:59.679247 disk-uuid[640]: The operation has completed successfully. Oct 31 00:15:59.682995 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 31 00:16:00.341796 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 00:16:00.341914 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 31 00:16:00.359035 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 31 00:16:00.367874 sh[727]: Success Oct 31 00:16:00.407755 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 31 00:16:00.665593 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 31 00:16:00.689136 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 31 00:16:00.698187 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 31 00:16:00.723618 kernel: BTRFS info (device dm-0): first mount of filesystem 311007bd-ab96-441d-977a-c6c513017d1c Oct 31 00:16:00.723654 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:00.729737 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 31 00:16:00.733503 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 31 00:16:00.736691 kernel: BTRFS info (device dm-0): using free space tree Oct 31 00:16:01.297961 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 31 00:16:01.299248 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 31 00:16:01.318867 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 31 00:16:01.326548 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 31 00:16:01.364476 kernel: BTRFS info (device sdb6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:01.364517 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:01.367642 kernel: BTRFS info (device sdb6): using free space tree Oct 31 00:16:01.388764 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 31 00:16:01.397757 kernel: BTRFS info (device sdb6): last unmount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:01.404170 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 31 00:16:01.423875 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 31 00:16:01.431600 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:16:01.435908 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:16:01.476449 systemd-networkd[909]: lo: Link UP Oct 31 00:16:01.476460 systemd-networkd[909]: lo: Gained carrier Oct 31 00:16:01.478603 systemd-networkd[909]: Enumeration completed Oct 31 00:16:01.478711 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:16:01.479305 systemd-networkd[909]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:01.479313 systemd-networkd[909]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:01.480596 systemd-networkd[909]: eth0: Link UP Oct 31 00:16:01.480915 systemd[1]: Reached target network.target - Network. Oct 31 00:16:01.493401 systemd-networkd[909]: eth0: Gained carrier Oct 31 00:16:01.493410 systemd-networkd[909]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:01.544800 systemd-networkd[909]: eth0: DHCPv4 address 10.200.8.29/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:02.641862 systemd-networkd[909]: eth0: Gained IPv6LL Oct 31 00:16:02.896255 ignition[899]: Ignition 2.20.0 Oct 31 00:16:02.896268 ignition[899]: Stage: fetch-offline Oct 31 00:16:02.896310 ignition[899]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:02.896320 ignition[899]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:02.896437 ignition[899]: parsed url from cmdline: "" Oct 31 00:16:02.896442 ignition[899]: no config URL provided Oct 31 00:16:02.896448 ignition[899]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:16:02.896457 ignition[899]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:16:02.896463 ignition[899]: failed to fetch config: resource requires networking Oct 31 00:16:02.896759 ignition[899]: Ignition finished successfully Oct 31 00:16:02.924418 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:16:02.942982 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 31 00:16:02.954070 ignition[922]: Ignition 2.20.0 Oct 31 00:16:02.954082 ignition[922]: Stage: fetch Oct 31 00:16:02.954244 ignition[922]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:02.954257 ignition[922]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:02.954391 ignition[922]: parsed url from cmdline: "" Oct 31 00:16:02.954395 ignition[922]: no config URL provided Oct 31 00:16:02.954401 ignition[922]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:16:02.954413 ignition[922]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:16:02.954438 ignition[922]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 31 00:16:03.060312 ignition[922]: GET result: OK Oct 31 00:16:03.060379 ignition[922]: config has been read from IMDS userdata Oct 31 00:16:03.060393 ignition[922]: parsing config with SHA512: 0f4916dee65ba0cca419ccd3ef450791b5073c9421ec421c5b8643792f80a68b3219410a6f12da5f7e4ac3485825597645d22879852468c56d2d046e4a128e81 Oct 31 00:16:03.064177 unknown[922]: fetched base config from "system" Oct 31 00:16:03.064354 ignition[922]: fetch: fetch complete Oct 31 00:16:03.064185 unknown[922]: fetched base config from "system" Oct 31 00:16:03.064359 ignition[922]: fetch: fetch passed Oct 31 00:16:03.064190 unknown[922]: fetched user config from "azure" Oct 31 00:16:03.064403 ignition[922]: Ignition finished successfully Oct 31 00:16:03.075699 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 31 00:16:03.093903 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 31 00:16:03.114848 ignition[932]: Ignition 2.20.0 Oct 31 00:16:03.114860 ignition[932]: Stage: kargs Oct 31 00:16:03.115036 ignition[932]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.115050 ignition[932]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.115642 ignition[932]: kargs: kargs passed Oct 31 00:16:03.124035 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 31 00:16:03.115688 ignition[932]: Ignition finished successfully Oct 31 00:16:03.143866 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 31 00:16:03.161363 ignition[942]: Ignition 2.20.0 Oct 31 00:16:03.161375 ignition[942]: Stage: disks Oct 31 00:16:03.161555 ignition[942]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.161568 ignition[942]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.162156 ignition[942]: disks: disks passed Oct 31 00:16:03.170712 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 31 00:16:03.162202 ignition[942]: Ignition finished successfully Oct 31 00:16:03.178255 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 31 00:16:03.182692 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 31 00:16:03.191438 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:16:03.199361 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:16:03.207671 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:16:03.236881 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 31 00:16:03.340654 systemd-fsck[954]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Oct 31 00:16:03.346148 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 31 00:16:03.361826 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 31 00:16:03.470758 kernel: EXT4-fs (sdb9): mounted filesystem 7feab3bb-a335-45a4-937e-704f6ca28dd4 r/w with ordered data mode. Quota mode: none. Oct 31 00:16:03.471728 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 31 00:16:03.473781 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 31 00:16:03.512833 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:16:03.527953 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 31 00:16:03.535341 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sdb6 scanned by mount (965) Oct 31 00:16:03.531111 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 31 00:16:03.550313 kernel: BTRFS info (device sdb6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:03.550333 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:03.550345 kernel: BTRFS info (device sdb6): using free space tree Oct 31 00:16:03.546207 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 31 00:16:03.546238 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:16:03.562132 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 31 00:16:03.563171 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 31 00:16:03.598711 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 31 00:16:03.598449 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:16:04.644912 coreos-metadata[967]: Oct 31 00:16:04.644 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 31 00:16:04.650985 coreos-metadata[967]: Oct 31 00:16:04.650 INFO Fetch successful Oct 31 00:16:04.650985 coreos-metadata[967]: Oct 31 00:16:04.650 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 31 00:16:04.663416 coreos-metadata[967]: Oct 31 00:16:04.654 INFO Fetch successful Oct 31 00:16:04.667442 coreos-metadata[967]: Oct 31 00:16:04.667 INFO wrote hostname ci-4230.2.4-n-037541142d to /sysroot/etc/hostname Oct 31 00:16:04.668632 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 31 00:16:04.703722 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory Oct 31 00:16:04.750278 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory Oct 31 00:16:04.863217 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory Oct 31 00:16:04.918574 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory Oct 31 00:16:06.200524 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 31 00:16:06.226825 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 31 00:16:06.228305 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 31 00:16:06.246040 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 31 00:16:06.255035 kernel: BTRFS info (device sdb6): last unmount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:06.273556 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 31 00:16:06.285904 ignition[1087]: INFO : Ignition 2.20.0 Oct 31 00:16:06.285904 ignition[1087]: INFO : Stage: mount Oct 31 00:16:06.292056 ignition[1087]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:06.292056 ignition[1087]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:06.292056 ignition[1087]: INFO : mount: mount passed Oct 31 00:16:06.292056 ignition[1087]: INFO : Ignition finished successfully Oct 31 00:16:06.293091 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 31 00:16:06.323800 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 31 00:16:06.337626 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:16:06.361791 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sdb6 scanned by mount (1101) Oct 31 00:16:06.371286 kernel: BTRFS info (device sdb6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:06.371318 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:06.374411 kernel: BTRFS info (device sdb6): using free space tree Oct 31 00:16:06.381763 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 31 00:16:06.383667 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:16:06.407673 ignition[1118]: INFO : Ignition 2.20.0 Oct 31 00:16:06.407673 ignition[1118]: INFO : Stage: files Oct 31 00:16:06.413763 ignition[1118]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:06.413763 ignition[1118]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:06.413763 ignition[1118]: DEBUG : files: compiled without relabeling support, skipping Oct 31 00:16:06.413763 ignition[1118]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 31 00:16:06.413763 ignition[1118]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 31 00:16:06.517180 ignition[1118]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 31 00:16:06.522806 ignition[1118]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 31 00:16:06.522806 ignition[1118]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 00:16:06.517932 unknown[1118]: wrote ssh authorized keys file for user: core Oct 31 00:16:06.541853 ignition[1118]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:16:06.549421 ignition[1118]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:16:06.549421 ignition[1118]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:16:06.549421 ignition[1118]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:16:06.549421 ignition[1118]: INFO : files: files passed Oct 31 00:16:06.549421 ignition[1118]: INFO : Ignition finished successfully Oct 31 00:16:06.565312 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 31 00:16:06.591898 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 31 00:16:06.593471 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 31 00:16:06.602572 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 00:16:06.602669 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 31 00:16:06.630301 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.630301 initrd-setup-root-after-ignition[1150]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.643144 initrd-setup-root-after-ignition[1154]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.650271 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:16:06.651965 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 31 00:16:06.677854 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 31 00:16:06.704729 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 00:16:06.704851 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 31 00:16:06.707191 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 31 00:16:06.726623 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 31 00:16:06.728316 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 31 00:16:06.729111 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 31 00:16:06.755521 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:16:06.758229 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 31 00:16:06.777351 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:16:06.780209 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:16:06.789222 systemd[1]: Stopped target timers.target - Timer Units. Oct 31 00:16:06.798172 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 00:16:06.798295 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:16:06.812413 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 31 00:16:06.814602 systemd[1]: Stopped target basic.target - Basic System. Oct 31 00:16:06.826141 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 31 00:16:06.833135 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:16:06.841874 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 31 00:16:06.862721 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 31 00:16:06.870754 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:16:06.883266 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 31 00:16:06.892325 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 31 00:16:06.894491 systemd[1]: Stopped target swap.target - Swaps. Oct 31 00:16:06.911046 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 00:16:06.911187 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:16:06.928201 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:16:06.929876 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:16:06.937670 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 31 00:16:06.937817 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:16:06.951481 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 00:16:06.951613 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 31 00:16:06.964902 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 31 00:16:06.965039 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:16:06.973647 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 00:16:06.973786 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 31 00:16:06.981577 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 31 00:16:06.981695 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 31 00:16:07.003887 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 31 00:16:07.020722 ignition[1175]: INFO : Ignition 2.20.0 Oct 31 00:16:07.020722 ignition[1175]: INFO : Stage: umount Oct 31 00:16:07.020722 ignition[1175]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:07.020722 ignition[1175]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:07.020722 ignition[1175]: INFO : umount: umount passed Oct 31 00:16:07.020722 ignition[1175]: INFO : Ignition finished successfully Oct 31 00:16:07.011573 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 31 00:16:07.019078 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 00:16:07.019219 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:16:07.027835 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 00:16:07.027956 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:16:07.039661 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 31 00:16:07.039873 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 31 00:16:07.043901 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 00:16:07.044011 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 31 00:16:07.055710 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 31 00:16:07.055777 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 31 00:16:07.057916 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 31 00:16:07.057959 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 31 00:16:07.070465 systemd[1]: Stopped target network.target - Network. Oct 31 00:16:07.077498 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 31 00:16:07.077553 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:16:07.079693 systemd[1]: Stopped target paths.target - Path Units. Oct 31 00:16:07.091793 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:16:07.101786 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:16:07.103509 systemd[1]: Stopped target slices.target - Slice Units. Oct 31 00:16:07.112024 systemd[1]: Stopped target sockets.target - Socket Units. Oct 31 00:16:07.118585 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 00:16:07.118834 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:16:07.129042 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 00:16:07.129084 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:16:07.136752 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 00:16:07.136812 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 31 00:16:07.144479 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 31 00:16:07.144526 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 31 00:16:07.153488 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 31 00:16:07.161256 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 31 00:16:07.170028 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 31 00:16:07.170844 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 00:16:07.170948 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 31 00:16:07.181175 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 00:16:07.181286 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 31 00:16:07.192438 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 00:16:07.192540 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 31 00:16:07.194941 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 00:16:07.195041 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 31 00:16:07.205250 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 31 00:16:07.205309 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 31 00:16:07.208471 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 00:16:07.208531 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:16:07.213911 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 00:16:07.213967 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 31 00:16:07.250292 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 31 00:16:07.252118 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 31 00:16:07.252177 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:16:07.265220 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:16:07.265281 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:16:07.281797 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 31 00:16:07.281845 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 31 00:16:07.283296 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 00:16:07.283339 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:16:07.302451 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:16:07.311772 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 31 00:16:07.311842 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:07.338229 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 00:16:07.338371 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:16:07.345118 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 00:16:07.345291 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 31 00:16:07.353250 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 00:16:07.353290 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:16:07.361856 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 00:16:07.361906 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:16:07.376319 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 00:16:07.376370 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 31 00:16:07.390342 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:16:07.390396 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:16:07.422868 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 31 00:16:07.432536 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:16:07.432598 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:16:07.445992 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 31 00:16:07.446041 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:16:07.447593 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 00:16:07.447639 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:16:07.457304 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:16:07.457523 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:07.474659 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 31 00:16:07.475059 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:07.475601 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 00:16:07.475711 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 31 00:16:07.482123 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 00:16:07.482218 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 31 00:16:07.490995 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 31 00:16:07.513870 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 31 00:16:10.769695 systemd[1]: Switching root. Oct 31 00:16:10.831459 systemd-journald[205]: Journal stopped Oct 31 00:16:16.022098 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Oct 31 00:16:16.022131 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 00:16:16.022145 kernel: SELinux: policy capability open_perms=1 Oct 31 00:16:16.022154 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 00:16:16.022164 kernel: SELinux: policy capability always_check_network=0 Oct 31 00:16:16.022173 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 00:16:16.022183 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 00:16:16.022196 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 00:16:16.022205 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 31 00:16:16.022215 kernel: audit: type=1403 audit(1761869771.861:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 00:16:16.022226 systemd[1]: Successfully loaded SELinux policy in 251.916ms. Oct 31 00:16:16.022237 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.176ms. Oct 31 00:16:16.022248 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 31 00:16:16.022259 systemd[1]: Detected virtualization microsoft. Oct 31 00:16:16.022277 systemd[1]: Detected architecture x86-64. Oct 31 00:16:16.022288 systemd[1]: Detected first boot. Oct 31 00:16:16.022299 systemd[1]: Hostname set to . Oct 31 00:16:16.022310 systemd[1]: Initializing machine ID from random generator. Oct 31 00:16:16.022321 zram_generator::config[1221]: No configuration found. Oct 31 00:16:16.022334 kernel: Guest personality initialized and is inactive Oct 31 00:16:16.022345 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 31 00:16:16.022354 kernel: Initialized host personality Oct 31 00:16:16.022364 kernel: NET: Registered PF_VSOCK protocol family Oct 31 00:16:16.022375 systemd[1]: Populated /etc with preset unit settings. Oct 31 00:16:16.022386 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 31 00:16:16.022398 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 00:16:16.022408 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 31 00:16:16.022421 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 00:16:16.022432 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 31 00:16:16.022444 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 31 00:16:16.022455 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 31 00:16:16.022466 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 31 00:16:16.022476 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 31 00:16:16.022487 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 31 00:16:16.022501 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 31 00:16:16.022515 systemd[1]: Created slice user.slice - User and Session Slice. Oct 31 00:16:16.022526 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:16:16.022537 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:16:16.022548 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 31 00:16:16.022559 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 31 00:16:16.022574 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 31 00:16:16.022586 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:16:16.022600 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 31 00:16:16.022611 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:16:16.022622 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 31 00:16:16.022634 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 31 00:16:16.022645 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 31 00:16:16.022656 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 31 00:16:16.022667 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:16:16.022678 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:16:16.022692 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:16:16.022704 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:16:16.022715 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 31 00:16:16.022726 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 31 00:16:16.022748 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 31 00:16:16.022764 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:16:16.022779 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:16:16.022790 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:16:16.022802 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 31 00:16:16.022813 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 31 00:16:16.022824 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 31 00:16:16.022835 systemd[1]: Mounting media.mount - External Media Directory... Oct 31 00:16:16.022847 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:16.022861 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 31 00:16:16.022873 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 31 00:16:16.022884 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 31 00:16:16.022896 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 31 00:16:16.022907 systemd[1]: Reached target machines.target - Containers. Oct 31 00:16:16.022919 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 31 00:16:16.022930 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:16.022942 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:16:16.022956 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 31 00:16:16.022967 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:16.022978 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:16:16.022990 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:16.023001 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 31 00:16:16.023013 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:16.023027 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 31 00:16:16.023038 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 00:16:16.023052 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 31 00:16:16.023064 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 00:16:16.023075 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 00:16:16.023087 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 31 00:16:16.023099 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:16:16.023110 kernel: loop: module loaded Oct 31 00:16:16.023140 systemd-journald[1328]: Collecting audit messages is disabled. Oct 31 00:16:16.023174 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:16:16.023187 systemd-journald[1328]: Journal started Oct 31 00:16:16.023209 systemd-journald[1328]: Runtime Journal (/run/log/journal/49a4c26b7314481d89d9d7d164341b47) is 8M, max 2.2G, 2.1G free. Oct 31 00:16:15.300049 systemd[1]: Queued start job for default target multi-user.target. Oct 31 00:16:15.312200 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Oct 31 00:16:15.312683 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 00:16:16.035292 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 31 00:16:16.045812 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 31 00:16:16.045846 kernel: fuse: init (API version 7.39) Oct 31 00:16:16.060195 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 31 00:16:16.067750 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:16:16.079201 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 00:16:16.079245 systemd[1]: Stopped verity-setup.service. Oct 31 00:16:16.091755 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:16.099021 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:16:16.103556 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 31 00:16:16.107752 kernel: ACPI: bus type drm_connector registered Oct 31 00:16:16.112013 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 31 00:16:16.116727 systemd[1]: Mounted media.mount - External Media Directory. Oct 31 00:16:16.120810 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 31 00:16:16.125350 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 31 00:16:16.129963 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 31 00:16:16.134161 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 31 00:16:16.139282 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:16:16.144690 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 00:16:16.144905 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 31 00:16:16.150014 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:16.150199 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:16.155203 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:16:16.155392 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:16:16.159962 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:16.160152 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:16.165197 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 00:16:16.165386 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 31 00:16:16.170178 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:16.170363 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:16.175381 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:16:16.180191 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 00:16:16.185629 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 31 00:16:16.191026 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 31 00:16:16.206526 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 31 00:16:16.218849 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 31 00:16:16.224251 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 31 00:16:16.228808 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 31 00:16:16.228841 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:16:16.234061 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 31 00:16:16.240210 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 31 00:16:16.245792 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 31 00:16:16.249986 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:16.251183 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 31 00:16:16.256614 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 31 00:16:16.261360 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:16:16.262342 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 31 00:16:16.267637 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:16:16.268602 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:16:16.273721 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 31 00:16:16.279400 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 00:16:16.286701 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:16:16.291695 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 31 00:16:16.296543 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 31 00:16:16.302655 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 31 00:16:16.307664 systemd-journald[1328]: Time spent on flushing to /var/log/journal/49a4c26b7314481d89d9d7d164341b47 is 13.832ms for 938 entries. Oct 31 00:16:16.307664 systemd-journald[1328]: System Journal (/var/log/journal/49a4c26b7314481d89d9d7d164341b47) is 8M, max 2.6G, 2.6G free. Oct 31 00:16:16.345676 systemd-journald[1328]: Received client request to flush runtime journal. Oct 31 00:16:16.345718 kernel: loop0: detected capacity change from 0 to 138176 Oct 31 00:16:16.307889 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 31 00:16:16.320584 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 31 00:16:16.334924 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 31 00:16:16.340984 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 31 00:16:16.349822 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 31 00:16:16.359051 udevadm[1374]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 31 00:16:16.389147 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 00:16:16.389887 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:16:16.395514 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 31 00:16:16.445562 systemd-tmpfiles[1365]: ACLs are not supported, ignoring. Oct 31 00:16:16.445584 systemd-tmpfiles[1365]: ACLs are not supported, ignoring. Oct 31 00:16:16.462756 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:16:16.481984 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 31 00:16:16.588633 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 31 00:16:16.613175 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:16:16.633383 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Oct 31 00:16:16.633406 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Oct 31 00:16:16.638335 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:16:17.048269 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 31 00:16:17.071937 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:16:17.097239 systemd-udevd[1387]: Using default interface naming scheme 'v255'. Oct 31 00:16:17.121757 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 31 00:16:17.165890 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:16:17.187008 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:16:17.195163 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 31 00:16:17.202845 kernel: loop1: detected capacity change from 0 to 28272 Oct 31 00:16:17.256755 kernel: hv_vmbus: registering driver hv_balloon Oct 31 00:16:17.260805 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 31 00:16:17.279756 kernel: mousedev: PS/2 mouse device common for all mice Oct 31 00:16:17.294015 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 31 00:16:17.327755 kernel: hv_vmbus: registering driver hyperv_fb Oct 31 00:16:17.327848 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 31 00:16:17.330480 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 31 00:16:17.335424 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 31 00:16:17.343206 kernel: Console: switching to colour dummy device 80x25 Oct 31 00:16:17.347803 kernel: Console: switching to colour frame buffer device 128x48 Oct 31 00:16:17.358752 kernel: EDAC MC: Ver: 3.0.0 Oct 31 00:16:17.460963 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:16:17.469871 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:16:17.470143 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:17.476854 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:17.479311 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:16:17.569957 systemd-networkd[1405]: lo: Link UP Oct 31 00:16:17.569967 systemd-networkd[1405]: lo: Gained carrier Oct 31 00:16:17.571348 systemd-networkd[1405]: Enumeration completed Oct 31 00:16:17.571461 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:16:17.571905 systemd-networkd[1405]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:17.571916 systemd-networkd[1405]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:17.572576 systemd-networkd[1405]: eth0: Link UP Oct 31 00:16:17.572585 systemd-networkd[1405]: eth0: Gained carrier Oct 31 00:16:17.572598 systemd-networkd[1405]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:17.580866 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 31 00:16:17.583509 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 31 00:16:17.605914 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 37 scanned by (udev-worker) (1399) Oct 31 00:16:17.623854 systemd-networkd[1405]: eth0: DHCPv4 address 10.200.8.29/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:17.629672 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 31 00:16:17.648766 kernel: loop2: detected capacity change from 0 to 147912 Oct 31 00:16:17.653033 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 31 00:16:17.654864 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 31 00:16:17.672990 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 31 00:16:17.677923 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 31 00:16:17.761885 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 31 00:16:17.798552 lvm[1520]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:16:17.864338 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 31 00:16:17.866085 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:16:17.883887 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 31 00:16:17.892959 lvm[1525]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:16:17.929075 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 31 00:16:18.216097 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:18.568761 kernel: loop3: detected capacity change from 0 to 138176 Oct 31 00:16:18.593760 kernel: loop4: detected capacity change from 0 to 28272 Oct 31 00:16:18.607754 kernel: loop5: detected capacity change from 0 to 147912 Oct 31 00:16:18.625448 (sd-merge)[1531]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 31 00:16:18.625982 (sd-merge)[1531]: Merged extensions into '/usr'. Oct 31 00:16:18.629539 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 31 00:16:18.646961 systemd[1]: Starting ensure-sysext.service... Oct 31 00:16:18.651043 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:16:18.657964 systemd[1]: Reload requested from client PID 1533 ('systemctl') (unit ensure-sysext.service)... Oct 31 00:16:18.657979 systemd[1]: Reloading... Oct 31 00:16:18.698759 zram_generator::config[1565]: No configuration found. Oct 31 00:16:18.718484 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 31 00:16:18.718799 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 31 00:16:18.719696 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 00:16:18.719995 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Oct 31 00:16:18.720068 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Oct 31 00:16:18.772529 systemd-tmpfiles[1534]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:16:18.772545 systemd-tmpfiles[1534]: Skipping /boot Oct 31 00:16:18.784448 systemd-tmpfiles[1534]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:16:18.784463 systemd-tmpfiles[1534]: Skipping /boot Oct 31 00:16:18.851401 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:16:18.991521 systemd[1]: Reloading finished in 333 ms. Oct 31 00:16:19.031641 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:16:19.045357 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.055994 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 31 00:16:19.061231 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 31 00:16:19.066412 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.067554 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.072958 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.077483 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.081785 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.081915 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 31 00:16:19.083145 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 31 00:16:19.089921 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:16:19.095450 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 31 00:16:19.100282 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.102833 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.103020 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.108093 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.108273 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.113708 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.113904 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.124776 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.125022 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.138662 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.143925 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.149674 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.153791 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.153911 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 31 00:16:19.154017 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.158078 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 31 00:16:19.163989 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.164184 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.169291 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.169482 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.174896 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.175091 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.181389 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 31 00:16:19.192641 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.192920 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.203965 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.209123 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:16:19.214144 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.219834 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.224052 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.224176 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 31 00:16:19.224354 systemd[1]: Reached target time-set.target - System Time Set. Oct 31 00:16:19.228507 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.230274 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.230512 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.235505 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:16:19.235682 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:16:19.240384 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.240639 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.246088 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.246276 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.253251 systemd[1]: Finished ensure-sysext.service. Oct 31 00:16:19.259655 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:16:19.259714 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:16:19.298717 systemd-resolved[1638]: Positive Trust Anchors: Oct 31 00:16:19.298748 systemd-resolved[1638]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:16:19.298779 systemd-resolved[1638]: 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 31 00:16:19.368521 systemd-resolved[1638]: Using system hostname 'ci-4230.2.4-n-037541142d'. Oct 31 00:16:19.370644 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:16:19.375554 systemd[1]: Reached target network.target - Network. Oct 31 00:16:19.379376 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:16:19.394838 augenrules[1676]: No rules Oct 31 00:16:19.396103 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:16:19.396345 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 31 00:16:19.473844 systemd-networkd[1405]: eth0: Gained IPv6LL Oct 31 00:16:19.476118 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 31 00:16:19.481650 systemd[1]: Reached target network-online.target - Network is Online. Oct 31 00:16:20.069856 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 31 00:16:20.077305 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 31 00:16:23.900606 ldconfig[1358]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 00:16:23.914317 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 31 00:16:23.931875 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 31 00:16:23.940523 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 31 00:16:23.945198 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:16:23.949842 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 31 00:16:23.955039 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 31 00:16:23.960189 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 31 00:16:23.964679 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 31 00:16:23.969775 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 31 00:16:23.975098 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 31 00:16:23.975141 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:16:23.978955 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:16:23.984258 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 31 00:16:23.990154 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 31 00:16:23.996762 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 31 00:16:24.002361 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 31 00:16:24.007715 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 31 00:16:24.014200 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 31 00:16:24.018975 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 31 00:16:24.024489 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 31 00:16:24.029106 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:16:24.033264 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:16:24.037051 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:16:24.037083 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:16:24.061833 systemd[1]: Starting chronyd.service - NTP client/server... Oct 31 00:16:24.068474 systemd[1]: Starting containerd.service - containerd container runtime... Oct 31 00:16:24.073940 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 31 00:16:24.079086 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 31 00:16:24.083940 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 31 00:16:24.089287 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 31 00:16:24.093583 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 31 00:16:24.093639 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Oct 31 00:16:24.094745 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 31 00:16:24.098976 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 31 00:16:24.100348 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 31 00:16:24.101876 KVP[1698]: KVP starting; pid is:1698 Oct 31 00:16:24.104592 jq[1693]: false Oct 31 00:16:24.104775 KVP[1698]: KVP LIC Version: 3.1 Oct 31 00:16:24.106752 kernel: hv_utils: KVP IC version 4.0 Oct 31 00:16:24.107495 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 31 00:16:24.112454 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 31 00:16:24.113422 (chronyd)[1689]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Oct 31 00:16:24.119301 chronyd[1706]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Oct 31 00:16:24.120568 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 31 00:16:24.127499 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 31 00:16:24.132606 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 31 00:16:24.133128 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 00:16:24.133712 systemd[1]: Starting update-engine.service - Update Engine... Oct 31 00:16:24.138372 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 31 00:16:24.145336 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 31 00:16:24.145970 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 31 00:16:24.146356 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 00:16:24.146687 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 31 00:16:24.148861 extend-filesystems[1697]: Found loop3 Oct 31 00:16:24.152268 systemd[1]: Started chronyd.service - NTP client/server. Oct 31 00:16:24.151330 chronyd[1706]: Timezone right/UTC failed leap second check, ignoring Oct 31 00:16:24.153748 extend-filesystems[1697]: Found loop4 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found loop5 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sda Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sda1 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb1 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb2 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb3 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found usr Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb4 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb6 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb7 Oct 31 00:16:24.153748 extend-filesystems[1697]: Found sdb9 Oct 31 00:16:24.153748 extend-filesystems[1697]: Checking size of /dev/sdb9 Oct 31 00:16:24.151489 chronyd[1706]: Loaded seccomp filter (level 2) Oct 31 00:16:24.167264 (ntainerd)[1722]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 31 00:16:24.201087 jq[1712]: true Oct 31 00:16:24.176046 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 00:16:24.201303 jq[1719]: true Oct 31 00:16:24.177259 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 31 00:16:24.212775 extend-filesystems[1697]: Old size kept for /dev/sdb9 Oct 31 00:16:24.216142 extend-filesystems[1697]: Found sr0 Oct 31 00:16:24.214515 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 00:16:24.214753 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 31 00:16:24.228849 update_engine[1711]: I20251031 00:16:24.228771 1711 main.cc:92] Flatcar Update Engine starting Oct 31 00:16:24.252669 systemd-logind[1708]: New seat seat0. Oct 31 00:16:24.255756 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 37 scanned by (udev-worker) (1762) Oct 31 00:16:24.257158 systemd-logind[1708]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 31 00:16:24.257384 systemd[1]: Started systemd-logind.service - User Login Management. Oct 31 00:16:24.298322 dbus-daemon[1692]: [system] SELinux support is enabled Oct 31 00:16:24.298503 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 31 00:16:24.301562 update_engine[1711]: I20251031 00:16:24.301327 1711 update_check_scheduler.cc:74] Next update check in 10m28s Oct 31 00:16:24.313847 dbus-daemon[1692]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 31 00:16:24.315892 systemd[1]: Started update-engine.service - Update Engine. Oct 31 00:16:24.320536 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 31 00:16:24.320700 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 31 00:16:24.325949 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 31 00:16:24.326146 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 31 00:16:24.347950 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 31 00:16:24.363841 sshd_keygen[1713]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 31 00:16:24.380715 bash[1746]: Updated "/home/core/.ssh/authorized_keys" Oct 31 00:16:24.382091 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 31 00:16:24.387797 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 31 00:16:24.391417 coreos-metadata[1691]: Oct 31 00:16:24.391 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 31 00:16:24.393871 coreos-metadata[1691]: Oct 31 00:16:24.393 INFO Fetch successful Oct 31 00:16:24.394036 coreos-metadata[1691]: Oct 31 00:16:24.394 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 31 00:16:24.398429 coreos-metadata[1691]: Oct 31 00:16:24.398 INFO Fetch successful Oct 31 00:16:24.398498 coreos-metadata[1691]: Oct 31 00:16:24.398 INFO Fetching http://168.63.129.16/machine/9bc145b3-f41c-42ce-b3bd-9e70fb40cf83/51282c7f%2Dc2ae%2D4776%2D8516%2D412bea410316.%5Fci%2D4230.2.4%2Dn%2D037541142d?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 31 00:16:24.400157 coreos-metadata[1691]: Oct 31 00:16:24.400 INFO Fetch successful Oct 31 00:16:24.400310 coreos-metadata[1691]: Oct 31 00:16:24.400 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 31 00:16:24.403992 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 31 00:16:24.407278 coreos-metadata[1691]: Oct 31 00:16:24.407 INFO Fetch successful Oct 31 00:16:24.408078 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 31 00:16:24.409314 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 31 00:16:24.414807 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 00:16:24.415012 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 31 00:16:24.423131 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 31 00:16:24.431028 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 31 00:16:24.435920 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 31 00:16:24.437695 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 31 00:16:24.451564 setup-nvidia[1701]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.4+nightly-20251030-2100 Oct 31 00:16:24.471643 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 31 00:16:24.490030 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 31 00:16:24.495304 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 31 00:16:24.499988 systemd[1]: Reached target getty.target - Login Prompts. Oct 31 00:16:24.612553 locksmithd[1822]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 00:16:25.252355 setup-nvidia[1865]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 00:16:25.252673 setup-nvidia[1865]: Dload Upload Total Spent Left Speed Oct 31 00:16:25.405329 containerd[1722]: time="2025-10-31T00:16:25.405251400Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Oct 31 00:16:25.423601 containerd[1722]: time="2025-10-31T00:16:25.423558900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425184 containerd[1722]: time="2025-10-31T00:16:25.425145400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425184 containerd[1722]: time="2025-10-31T00:16:25.425173500Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 31 00:16:25.425275 containerd[1722]: time="2025-10-31T00:16:25.425188400Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 31 00:16:25.425353 containerd[1722]: time="2025-10-31T00:16:25.425329800Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 31 00:16:25.425395 containerd[1722]: time="2025-10-31T00:16:25.425352500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425471 containerd[1722]: time="2025-10-31T00:16:25.425451100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425510 containerd[1722]: time="2025-10-31T00:16:25.425470300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425676 containerd[1722]: time="2025-10-31T00:16:25.425653700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425706 containerd[1722]: time="2025-10-31T00:16:25.425674000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425706 containerd[1722]: time="2025-10-31T00:16:25.425687800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425706 containerd[1722]: time="2025-10-31T00:16:25.425696700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.425842 containerd[1722]: time="2025-10-31T00:16:25.425820500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.426095 containerd[1722]: time="2025-10-31T00:16:25.426074000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:25.426224 containerd[1722]: time="2025-10-31T00:16:25.426203100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:25.426253 containerd[1722]: time="2025-10-31T00:16:25.426223000Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 31 00:16:25.426322 containerd[1722]: time="2025-10-31T00:16:25.426304100Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 31 00:16:25.426378 containerd[1722]: time="2025-10-31T00:16:25.426360400Z" level=info msg="metadata content store policy set" policy=shared Oct 31 00:16:25.444993 containerd[1722]: time="2025-10-31T00:16:25.444949700Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 31 00:16:25.445071 containerd[1722]: time="2025-10-31T00:16:25.445002700Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 31 00:16:25.445071 containerd[1722]: time="2025-10-31T00:16:25.445020600Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 31 00:16:25.445071 containerd[1722]: time="2025-10-31T00:16:25.445039700Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 31 00:16:25.445071 containerd[1722]: time="2025-10-31T00:16:25.445055100Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 31 00:16:25.445225 containerd[1722]: time="2025-10-31T00:16:25.445200700Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 31 00:16:25.445497 containerd[1722]: time="2025-10-31T00:16:25.445464600Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 31 00:16:25.445642 containerd[1722]: time="2025-10-31T00:16:25.445617700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 31 00:16:25.445673 containerd[1722]: time="2025-10-31T00:16:25.445644700Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 31 00:16:25.445673 containerd[1722]: time="2025-10-31T00:16:25.445661100Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 31 00:16:25.445715 containerd[1722]: time="2025-10-31T00:16:25.445676700Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445715 containerd[1722]: time="2025-10-31T00:16:25.445689800Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445715 containerd[1722]: time="2025-10-31T00:16:25.445701900Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445799 containerd[1722]: time="2025-10-31T00:16:25.445715700Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445799 containerd[1722]: time="2025-10-31T00:16:25.445730700Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445799 containerd[1722]: time="2025-10-31T00:16:25.445760100Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445799 containerd[1722]: time="2025-10-31T00:16:25.445772400Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445799 containerd[1722]: time="2025-10-31T00:16:25.445784700Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445805300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445818800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445830500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445850500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445862900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445875400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445886900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.445910 containerd[1722]: time="2025-10-31T00:16:25.445898800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445911600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445926200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445937200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445948600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445959800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445972800Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.445992000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.446006400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.446017000Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 31 00:16:25.446077 containerd[1722]: time="2025-10-31T00:16:25.446072100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446090700Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446103000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446115300Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446125000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446137700Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446147800Z" level=info msg="NRI interface is disabled by configuration." Oct 31 00:16:25.446284 containerd[1722]: time="2025-10-31T00:16:25.446157800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 31 00:16:25.446921 containerd[1722]: time="2025-10-31T00:16:25.446511400Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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 31 00:16:25.446921 containerd[1722]: time="2025-10-31T00:16:25.446790900Z" level=info msg="Connect containerd service" Oct 31 00:16:25.446921 containerd[1722]: time="2025-10-31T00:16:25.446832100Z" level=info msg="using legacy CRI server" Oct 31 00:16:25.446921 containerd[1722]: time="2025-10-31T00:16:25.446840700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 31 00:16:25.447148 containerd[1722]: time="2025-10-31T00:16:25.447036500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 31 00:16:25.447932 containerd[1722]: time="2025-10-31T00:16:25.447903500Z" 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 31 00:16:25.448076 containerd[1722]: time="2025-10-31T00:16:25.448032500Z" level=info msg="Start subscribing containerd event" Oct 31 00:16:25.448149 containerd[1722]: time="2025-10-31T00:16:25.448085000Z" level=info msg="Start recovering state" Oct 31 00:16:25.448179 containerd[1722]: time="2025-10-31T00:16:25.448147100Z" level=info msg="Start event monitor" Oct 31 00:16:25.448179 containerd[1722]: time="2025-10-31T00:16:25.448169800Z" level=info msg="Start snapshots syncer" Oct 31 00:16:25.448234 containerd[1722]: time="2025-10-31T00:16:25.448181300Z" level=info msg="Start cni network conf syncer for default" Oct 31 00:16:25.448234 containerd[1722]: time="2025-10-31T00:16:25.448188500Z" level=info msg="Start streaming server" Oct 31 00:16:25.448282 containerd[1722]: time="2025-10-31T00:16:25.448233200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 31 00:16:25.448307 containerd[1722]: time="2025-10-31T00:16:25.448292900Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 31 00:16:25.448380 containerd[1722]: time="2025-10-31T00:16:25.448360400Z" level=info msg="containerd successfully booted in 0.044126s" Oct 31 00:16:25.448446 systemd[1]: Started containerd.service - containerd container runtime. Oct 31 00:16:26.874015 waagent[1848]: 2025-10-31T00:16:26.873925Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Oct 31 00:16:26.878278 waagent[1848]: 2025-10-31T00:16:26.878225Z INFO Daemon Daemon OS: flatcar 4230.2.4 Oct 31 00:16:26.881714 waagent[1848]: 2025-10-31T00:16:26.881662Z INFO Daemon Daemon Python: 3.11.11 Oct 31 00:16:26.885109 waagent[1848]: 2025-10-31T00:16:26.885055Z INFO Daemon Daemon Run daemon Oct 31 00:16:26.888141 waagent[1848]: 2025-10-31T00:16:26.888089Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Oct 31 00:16:26.894693 waagent[1848]: 2025-10-31T00:16:26.894635Z INFO Daemon Daemon Using waagent for provisioning Oct 31 00:16:26.898742 waagent[1848]: 2025-10-31T00:16:26.898686Z INFO Daemon Daemon Activate resource disk Oct 31 00:16:26.902320 waagent[1848]: 2025-10-31T00:16:26.902271Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 31 00:16:26.909845 waagent[1848]: 2025-10-31T00:16:26.909794Z INFO Daemon Daemon Found device: sda Oct 31 00:16:26.919270 waagent[1848]: 2025-10-31T00:16:26.919209Z INFO Daemon Daemon Examining partition table Oct 31 00:16:26.975641 waagent[1848]: 2025-10-31T00:16:26.975567Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 31 00:16:27.035508 waagent[1848]: 2025-10-31T00:16:27.035416Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Oct 31 00:16:27.040535 waagent[1848]: 2025-10-31T00:16:27.040481Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 31 00:16:27.475764 kernel: sda: sda1 Oct 31 00:16:27.482753 kernel: sda: sda1 Oct 31 00:16:27.506320 waagent[1848]: 2025-10-31T00:16:27.506227Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Oct 31 00:16:27.520778 kernel: sda: sda1 Oct 31 00:16:27.520835 waagent[1848]: 2025-10-31T00:16:27.520761Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Oct 31 00:16:32.545949 login[1855]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 31 00:16:32.592728 login[1854]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 31 00:16:32.603984 systemd-logind[1708]: New session 1 of user core. Oct 31 00:16:32.605053 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 31 00:16:32.612950 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 31 00:16:32.648209 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 31 00:16:32.660315 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 31 00:16:32.737284 (systemd)[1949]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:16:32.739790 systemd-logind[1708]: New session c1 of user core. Oct 31 00:16:33.190156 waagent[1848]: 2025-10-31T00:16:33.190056Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Oct 31 00:16:33.210520 waagent[1848]: 2025-10-31T00:16:33.210456Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Oct 31 00:16:33.210752 kernel: EXT4-fs (sda1): mounted filesystem 580fabf5-74f6-4083-904d-328778d0ba19 r/w with ordered data mode. Quota mode: none. Oct 31 00:16:33.213011 waagent[1848]: 2025-10-31T00:16:33.212653Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 31 00:16:33.213011 waagent[1848]: 2025-10-31T00:16:33.212975Z INFO Daemon Daemon Running default provisioning handler Oct 31 00:16:33.222090 waagent[1848]: 2025-10-31T00:16:33.221023Z 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 31 00:16:33.223170 waagent[1848]: 2025-10-31T00:16:33.223123Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 31 00:16:33.223508 waagent[1848]: 2025-10-31T00:16:33.223466Z INFO Daemon Daemon cloud-init is enabled: False Oct 31 00:16:33.225034 waagent[1848]: 2025-10-31T00:16:33.224987Z INFO Daemon Daemon Copying ovf-env.xml Oct 31 00:16:33.308200 waagent[1848]: 2025-10-31T00:16:33.308097Z INFO Daemon Daemon Successfully mounted dvd Oct 31 00:16:33.315425 systemd[1949]: Queued start job for default target default.target. Oct 31 00:16:33.322777 waagent[1848]: 2025-10-31T00:16:33.322699Z INFO Daemon Daemon Detect protocol endpoint Oct 31 00:16:33.322803 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 31 00:16:33.324404 waagent[1848]: 2025-10-31T00:16:33.324346Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 31 00:16:33.325968 waagent[1848]: 2025-10-31T00:16:33.325919Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 31 00:16:33.327430 waagent[1848]: 2025-10-31T00:16:33.327385Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 31 00:16:33.329441 waagent[1848]: 2025-10-31T00:16:33.329239Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 31 00:16:33.331093 waagent[1848]: 2025-10-31T00:16:33.331049Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 31 00:16:33.342953 systemd[1949]: Created slice app.slice - User Application Slice. Oct 31 00:16:33.343003 systemd[1949]: Reached target paths.target - Paths. Oct 31 00:16:33.343054 systemd[1949]: Reached target timers.target - Timers. Oct 31 00:16:33.348056 systemd[1949]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 00:16:33.359113 systemd[1949]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:16:33.359240 systemd[1949]: Reached target sockets.target - Sockets. Oct 31 00:16:33.359299 systemd[1949]: Reached target basic.target - Basic System. Oct 31 00:16:33.359347 systemd[1949]: Reached target default.target - Main User Target. Oct 31 00:16:33.359383 systemd[1949]: Startup finished in 613ms. Oct 31 00:16:33.359458 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 31 00:16:33.360920 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 31 00:16:33.381315 waagent[1848]: 2025-10-31T00:16:33.381264Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 31 00:16:33.393679 waagent[1848]: 2025-10-31T00:16:33.383124Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 31 00:16:33.393679 waagent[1848]: 2025-10-31T00:16:33.383673Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 31 00:16:33.547853 login[1855]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 31 00:16:33.552336 systemd-logind[1708]: New session 2 of user core. Oct 31 00:16:33.571875 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 31 00:16:33.613223 waagent[1848]: 2025-10-31T00:16:33.613127Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 31 00:16:33.627154 waagent[1848]: 2025-10-31T00:16:33.614825Z INFO Daemon Daemon Forcing an update of the goal state. Oct 31 00:16:33.627154 waagent[1848]: 2025-10-31T00:16:33.618502Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 31 00:16:33.666051 waagent[1848]: 2025-10-31T00:16:33.665996Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 31 00:16:33.691842 waagent[1848]: 2025-10-31T00:16:33.668090Z INFO Daemon Oct 31 00:16:33.691842 waagent[1848]: 2025-10-31T00:16:33.668419Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 426a2802-2b0a-4a8f-96e5-98fda1fc17ce eTag: 4246244119094930453 source: Fabric] Oct 31 00:16:33.691842 waagent[1848]: 2025-10-31T00:16:33.670156Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 31 00:16:33.691842 waagent[1848]: 2025-10-31T00:16:33.671212Z INFO Daemon Oct 31 00:16:33.691842 waagent[1848]: 2025-10-31T00:16:33.671399Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 31 00:16:33.694550 waagent[1848]: 2025-10-31T00:16:33.694506Z INFO Daemon Daemon Downloading artifacts profile blob Oct 31 00:16:33.816888 waagent[1848]: 2025-10-31T00:16:33.816778Z INFO Daemon Downloaded certificate {'thumbprint': '5C2BF6525AEA6F4D067259F72EF4001438FD97A8', 'hasPrivateKey': True} Oct 31 00:16:33.827171 waagent[1848]: 2025-10-31T00:16:33.818992Z INFO Daemon Fetch goal state completed Oct 31 00:16:33.849906 waagent[1848]: 2025-10-31T00:16:33.849851Z INFO Daemon Daemon Starting provisioning Oct 31 00:16:33.861537 waagent[1848]: 2025-10-31T00:16:33.851824Z INFO Daemon Daemon Handle ovf-env.xml. Oct 31 00:16:33.861537 waagent[1848]: 2025-10-31T00:16:33.852546Z INFO Daemon Daemon Set hostname [ci-4230.2.4-n-037541142d] Oct 31 00:16:33.880990 waagent[1848]: 2025-10-31T00:16:33.880940Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-n-037541142d] Oct 31 00:16:33.893783 waagent[1848]: 2025-10-31T00:16:33.882715Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 31 00:16:33.893783 waagent[1848]: 2025-10-31T00:16:33.884220Z INFO Daemon Daemon Primary interface is [eth0] Oct 31 00:16:33.893810 systemd-networkd[1405]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:33.893816 systemd-networkd[1405]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:33.893840 systemd-networkd[1405]: eth0: DHCP lease lost Oct 31 00:16:33.894276 waagent[1848]: 2025-10-31T00:16:33.894222Z INFO Daemon Daemon Create user account if not exists Oct 31 00:16:33.898771 waagent[1848]: 2025-10-31T00:16:33.898688Z INFO Daemon Daemon User core already exists, skip useradd Oct 31 00:16:33.900885 waagent[1848]: 2025-10-31T00:16:33.900829Z INFO Daemon Daemon Configure sudoer Oct 31 00:16:33.901904 waagent[1848]: 2025-10-31T00:16:33.901854Z INFO Daemon Daemon Configure sshd Oct 31 00:16:33.902563 waagent[1848]: 2025-10-31T00:16:33.902513Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 31 00:16:33.903819 waagent[1848]: 2025-10-31T00:16:33.903775Z INFO Daemon Daemon Deploy ssh public key. Oct 31 00:16:33.970778 systemd-networkd[1405]: eth0: DHCPv4 address 10.200.8.29/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:47.931183 chronyd[1706]: Selected source PHC0 Oct 31 00:16:49.269376 setup-nvidia[1865]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 535M 0 416k 0 0 342k 0 0:26:39 0:00:01 0:26:38 342k\u000d 3 535M 3 17.5M 0 0 8142k 0 0:01:07 0:00:02 0:01:05 8141k\u000d 7 535M 7 41.4M 0 0 12.9M 0 0:00:41 0:00:03 0:00:38 12.9M\u000d 12 535M 12 66.4M 0 0 15.8M 0 0:00:33 0:00:04 0:00:29 15.8M\u000d 17 535M 17 91.5M 0 0 17.2M 0 0:00:31 0:00:05 0:00:26 18.1M\u000d 21 535M 21 115M 0 0 18.2M 0 0:00:29 0:00:06 0:00:23 22.5M\u000d 26 535M 26 139M 0 0 19.1M 0 0:00:27 0:00:07 0:00:20 24.0M\u000d 30 535M 30 162M 0 0 19.7M 0 0:00:27 0:00:08 0:00:19 24.1M\u000d 34 535M 34 186M 0 0 20.0M 0 0:00:26 0:00:09 0:00:17 23.4M\u000d 39 535M 39 209M 0 0 20.3M 0 0:00:26 0:00:10 0:00:16 23.6M\u000d 43 535M 43 232M 0 0 20.6M 0 0:00:25 0:00:11 0:00:14 23.5M\u000d 47 535M 47 257M 0 0 20.9M 0 0:00:25 0:00:12 0:00:13 23.6M\u000d 52 535M 52 280M 0 0 21.1M 0 0:00:25 0:00:13 0:00:12 23.4M\u000d 56 535M 56 304M 0 0 21.3M 0 0:00:25 0:00:14 0:00:11 23.8M\u000d 61 535M 61 327M 0 0 21.4M 0 0:00:24 0:00:15 0:00:09 23.9M\u000d 65 535M 65 351M 0 0 21.6M 0 0:00:24 0:00:16 0:00:08 24.0M\u000d 69 535M 69 373M 0 0 21.7M 0 0:00:24 0:00:17 0:00:07 23.6M\u000d 73 535M 73 395M 0 0 21.7M 0 0:00:24 0:00:18 0:00:06 23.2M\u000d 78 535M 78 422M 0 0 21.8M 0 0:00:24 0:00:19 0:00:05 23.2M\u000d 83 535M 83 446M 0 0 21.9M 0 0:00:24 0:00:20 0:00:04 23.4M\u000d 87 535M 87 470M 0 0 22.1M 0 0:00:24 0:00:21 0:00:03 23.4M\u000d 92 535M 92 493M 0 0 22.1M 0 0:00:24 0:00:22 0:00:02 23.5M\u000d 96 535M 96 516M 0 0 22.1M 0 0:00:24 0:00:23 0:00:01 23.6M\u000d100 535M 100 535M 0 0 22.3M 0 0:00:24 0:00:24 --:--:-- 24.1M Oct 31 00:17:01.518049 setup-nvidia[1701]: Downloading NVIDIA 535.230.02 Driver Oct 31 00:17:01.522418 setup-nvidia[2017]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 00:17:01.522705 setup-nvidia[2017]: Dload Upload Total Spent Left Speed Oct 31 00:17:04.001270 waagent[1848]: 2025-10-31T00:17:04.001200Z INFO Daemon Daemon Provisioning complete Oct 31 00:17:04.011047 waagent[1848]: 2025-10-31T00:17:04.010999Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 31 00:17:04.012787 waagent[1848]: 2025-10-31T00:17:04.012723Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 31 00:17:04.013521 waagent[1848]: 2025-10-31T00:17:04.013478Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Oct 31 00:17:04.188863 waagent[2018]: 2025-10-31T00:17:04.188584Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Oct 31 00:17:04.189199 waagent[2018]: 2025-10-31T00:17:04.188986Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Oct 31 00:17:04.189199 waagent[2018]: 2025-10-31T00:17:04.189095Z INFO ExtHandler ExtHandler Python: 3.11.11 Oct 31 00:17:04.271021 waagent[2018]: 2025-10-31T00:17:04.270863Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Oct 31 00:17:04.271195 waagent[2018]: 2025-10-31T00:17:04.271148Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:04.271288 waagent[2018]: 2025-10-31T00:17:04.271249Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:04.279172 waagent[2018]: 2025-10-31T00:17:04.279115Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 31 00:17:04.293001 waagent[2018]: 2025-10-31T00:17:04.292951Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 31 00:17:04.293473 waagent[2018]: 2025-10-31T00:17:04.293425Z INFO ExtHandler Oct 31 00:17:04.293567 waagent[2018]: 2025-10-31T00:17:04.293527Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 863fe2a1-09ec-496a-a7f4-5f530c04a543 eTag: 4246244119094930453 source: Fabric] Oct 31 00:17:04.293939 waagent[2018]: 2025-10-31T00:17:04.293892Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 31 00:17:04.294623 waagent[2018]: 2025-10-31T00:17:04.294574Z INFO ExtHandler Oct 31 00:17:04.294708 waagent[2018]: 2025-10-31T00:17:04.294670Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 31 00:17:04.298122 waagent[2018]: 2025-10-31T00:17:04.298087Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 31 00:17:04.368184 waagent[2018]: 2025-10-31T00:17:04.368102Z INFO ExtHandler Downloaded certificate {'thumbprint': '5C2BF6525AEA6F4D067259F72EF4001438FD97A8', 'hasPrivateKey': True} Oct 31 00:17:04.368709 waagent[2018]: 2025-10-31T00:17:04.368659Z INFO ExtHandler Fetch goal state completed Oct 31 00:17:04.379453 waagent[2018]: 2025-10-31T00:17:04.379390Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2018 Oct 31 00:17:04.379596 waagent[2018]: 2025-10-31T00:17:04.379550Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 31 00:17:04.381421 waagent[2018]: 2025-10-31T00:17:04.381373Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Oct 31 00:17:04.382000 waagent[2018]: 2025-10-31T00:17:04.381948Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 31 00:17:04.433204 waagent[2018]: 2025-10-31T00:17:04.433159Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 31 00:17:04.433397 waagent[2018]: 2025-10-31T00:17:04.433352Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 31 00:17:04.439906 waagent[2018]: 2025-10-31T00:17:04.439865Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 31 00:17:04.447286 systemd[1]: Reload requested from client PID 2031 ('systemctl') (unit waagent.service)... Oct 31 00:17:04.447302 systemd[1]: Reloading... Oct 31 00:17:04.512766 zram_generator::config[2071]: No configuration found. Oct 31 00:17:04.650626 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:17:04.798410 systemd[1]: Reloading finished in 350 ms. Oct 31 00:17:04.812360 waagent[2018]: 2025-10-31T00:17:04.812261Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Oct 31 00:17:04.818265 systemd[1]: Reload requested from client PID 2128 ('systemctl') (unit waagent.service)... Oct 31 00:17:04.818280 systemd[1]: Reloading... Oct 31 00:17:04.887768 zram_generator::config[2168]: No configuration found. Oct 31 00:17:05.030388 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 31 00:17:05.206291 systemd[1]: Reloading finished in 387 ms. Oct 31 00:17:05.220534 waagent[2018]: 2025-10-31T00:17:05.220440Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 31 00:17:05.220859 waagent[2018]: 2025-10-31T00:17:05.220609Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 31 00:17:05.415157 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 31 00:17:06.022768 waagent[2018]: 2025-10-31T00:17:06.022647Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 31 00:17:06.023372 waagent[2018]: 2025-10-31T00:17:06.023313Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Oct 31 00:17:06.024195 waagent[2018]: 2025-10-31T00:17:06.024141Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 31 00:17:06.024573 waagent[2018]: 2025-10-31T00:17:06.024518Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:06.024779 waagent[2018]: 2025-10-31T00:17:06.024714Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 31 00:17:06.025146 waagent[2018]: 2025-10-31T00:17:06.025101Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:06.025299 waagent[2018]: 2025-10-31T00:17:06.025242Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:06.025707 waagent[2018]: 2025-10-31T00:17:06.025653Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 31 00:17:06.025834 waagent[2018]: 2025-10-31T00:17:06.025767Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 31 00:17:06.025962 waagent[2018]: 2025-10-31T00:17:06.025912Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 31 00:17:06.026095 waagent[2018]: 2025-10-31T00:17:06.026042Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:06.026435 waagent[2018]: 2025-10-31T00:17:06.026370Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 31 00:17:06.026602 waagent[2018]: 2025-10-31T00:17:06.026554Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 31 00:17:06.026821 waagent[2018]: 2025-10-31T00:17:06.026767Z INFO EnvHandler ExtHandler Configure routes Oct 31 00:17:06.027300 waagent[2018]: 2025-10-31T00:17:06.027259Z INFO EnvHandler ExtHandler Gateway:None Oct 31 00:17:06.027435 waagent[2018]: 2025-10-31T00:17:06.027389Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 31 00:17:06.027611 waagent[2018]: 2025-10-31T00:17:06.027560Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 31 00:17:06.027611 waagent[2018]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 31 00:17:06.027611 waagent[2018]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 31 00:17:06.027611 waagent[2018]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 31 00:17:06.027611 waagent[2018]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:06.027611 waagent[2018]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:06.027611 waagent[2018]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:06.028296 waagent[2018]: 2025-10-31T00:17:06.028252Z INFO EnvHandler ExtHandler Routes:None Oct 31 00:17:06.032748 waagent[2018]: 2025-10-31T00:17:06.032689Z INFO ExtHandler ExtHandler Oct 31 00:17:06.032966 waagent[2018]: 2025-10-31T00:17:06.032919Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 1cbe019b-3334-45b8-8a01-6cfa2beed208 correlation da64a97a-c261-4e5e-84c0-75a3505fe67a created: 2025-10-31T00:15:17.538765Z] Oct 31 00:17:06.034136 waagent[2018]: 2025-10-31T00:17:06.034080Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 31 00:17:06.036458 waagent[2018]: 2025-10-31T00:17:06.036407Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 31 00:17:06.086000 waagent[2018]: 2025-10-31T00:17:06.085941Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 2E1CB134-36FA-4735-BD3C-B9EDB8B7E398;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Oct 31 00:17:06.160202 waagent[2018]: 2025-10-31T00:17:06.160133Z INFO MonitorHandler ExtHandler Network interfaces: Oct 31 00:17:06.160202 waagent[2018]: Executing ['ip', '-a', '-o', 'link']: Oct 31 00:17:06.160202 waagent[2018]: 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 31 00:17:06.160202 waagent[2018]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:66:98:c7 brd ff:ff:ff:ff:ff:ff Oct 31 00:17:06.160202 waagent[2018]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 31 00:17:06.160202 waagent[2018]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 31 00:17:06.160202 waagent[2018]: 2: eth0 inet 10.200.8.29/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 31 00:17:06.160202 waagent[2018]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 31 00:17:06.160202 waagent[2018]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 31 00:17:06.160202 waagent[2018]: 2: eth0 inet6 fe80::20d:3aff:fe66:98c7/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 31 00:17:06.202174 waagent[2018]: 2025-10-31T00:17:06.202110Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Oct 31 00:17:06.202174 waagent[2018]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.202174 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.202174 waagent[2018]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.202174 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.202174 waagent[2018]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.202174 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.202174 waagent[2018]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 31 00:17:06.202174 waagent[2018]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 31 00:17:06.202174 waagent[2018]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 31 00:17:06.206113 waagent[2018]: 2025-10-31T00:17:06.206056Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 31 00:17:06.206113 waagent[2018]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.206113 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.206113 waagent[2018]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.206113 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.206113 waagent[2018]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:06.206113 waagent[2018]: pkts bytes target prot opt in out source destination Oct 31 00:17:06.206113 waagent[2018]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 31 00:17:06.206113 waagent[2018]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 31 00:17:06.206113 waagent[2018]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 31 00:17:06.206451 waagent[2018]: 2025-10-31T00:17:06.206346Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 31 00:17:06.206560 waagent[2018]: 2025-10-31T00:17:06.206520Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 31 00:17:09.341487 update_engine[1711]: I20251031 00:17:09.341405 1711 update_attempter.cc:509] Updating boot flags... Oct 31 00:17:09.418772 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 37 scanned by (udev-worker) (2273) Oct 31 00:17:09.986135 setup-nvidia[2017]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 5 326M 5 18.0M 0 0 19.3M 0 0:00:16 --:--:-- 0:00:16 19.3M\u000d 17 326M 17 56.1M 0 0 29.3M 0 0:00:11 0:00:01 0:00:10 29.3M\u000d 30 326M 30 98.0M 0 0 33.5M 0 0:00:09 0:00:02 0:00:07 33.5M\u000d 41 326M 41 134M 0 0 33.9M 0 0:00:09 0:00:03 0:00:06 33.9M\u000d 54 326M 54 178M 0 0 36.3M 0 0:00:08 0:00:04 0:00:04 36.3M\u000d 66 326M 66 216M 0 0 36.5M 0 0:00:08 0:00:05 0:00:03 39.6M\u000d 79 326M 79 260M 0 0 37.5M 0 0:00:08 0:00:06 0:00:02 40.6M\u000d 93 326M 93 304M 0 0 38.3M 0 0:00:08 0:00:07 0:00:01 41.1M\u000d100 326M 100 326M 0 0 38.5M 0 0:00:08 0:00:08 --:--:-- 42.4M Oct 31 00:17:10.063297 setup-nvidia[1701]: Extract the NVIDIA Driver Installer 535.230.02 Oct 31 00:17:10.063297 setup-nvidia[1701]: /opt/nvidia/workdir/nvidia-workdir / Oct 31 00:17:10.082519 setup-nvidia[2323]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Oct 31 00:17:10.351579 setup-nvidia[2323]: Verifying archive integrity... OK Oct 31 00:17:11.135234 setup-nvidia[2323]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Oct 31 00:17:13.021309 setup-nvidia[2349]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 31 00:17:13.070183 setup-nvidia[1701]: / Oct 31 00:17:13.070446 setup-nvidia[1701]: Spawn system-nspawn container to install the NVIDIA drivers Oct 31 00:17:13.328053 sudo[2356]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.4+nightly-20251030-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.230.02 Oct 31 00:17:13.329809 sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 31 00:17:13.337161 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 31 00:17:13.351057 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 31 00:17:13.361291 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 31 00:17:13.365598 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 31 00:17:13.371446 (systemd)[2359]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 31 00:17:13.373619 systemd-logind[1708]: New session c3 of user root. Oct 31 00:17:13.555820 systemd[2359]: Queued start job for default target default.target. Oct 31 00:17:13.562818 systemd[2359]: Created slice app.slice - User Application Slice. Oct 31 00:17:13.562849 systemd[2359]: Reached target paths.target - Paths. Oct 31 00:17:13.562899 systemd[2359]: Reached target timers.target - Timers. Oct 31 00:17:13.564190 systemd[2359]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 00:17:13.574755 systemd[2359]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:17:13.574985 systemd[2359]: Reached target sockets.target - Sockets. Oct 31 00:17:13.575043 systemd[2359]: Reached target basic.target - Basic System. Oct 31 00:17:13.575093 systemd[2359]: Reached target default.target - Main User Target. Oct 31 00:17:13.575127 systemd[2359]: Startup finished in 195ms. Oct 31 00:17:13.575201 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 31 00:17:13.592863 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 31 00:17:13.674765 kernel: loop6: detected capacity change from 0 to 12644352 Oct 31 00:17:13.716765 kernel: loop6: p9 Oct 31 00:17:13.992547 setup-nvidia[2368]: ░ Spawning container flatcardevelopercontainer-4230.2.4nightly-20251030-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.4+nightly-20251030-2100.bin. Oct 31 00:17:14.045755 kernel: EXT4-fs (loop6p9): mounted filesystem ef0d47f4-cb5a-40f9-985f-abe2ba1c953f ro without journal. Quota mode: none. Oct 31 00:17:34.118834 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 31 00:17:34.132011 systemd[1]: Started sshd@0-10.200.8.29:22-10.200.16.10:51352.service - OpenSSH per-connection server daemon (10.200.16.10:51352). Oct 31 00:17:34.890496 sshd[2889]: Accepted publickey for core from 10.200.16.10 port 51352 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:34.891864 sshd-session[2889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:34.896075 systemd-logind[1708]: New session 3 of user core. Oct 31 00:17:34.907862 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 31 00:17:35.441311 systemd[1]: Started sshd@1-10.200.8.29:22-10.200.16.10:51354.service - OpenSSH per-connection server daemon (10.200.16.10:51354). Oct 31 00:17:36.071200 sshd[3397]: Accepted publickey for core from 10.200.16.10 port 51354 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:36.072565 sshd-session[3397]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:36.076893 systemd-logind[1708]: New session 4 of user core. Oct 31 00:17:36.093857 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 31 00:17:36.517510 sshd[3399]: Connection closed by 10.200.16.10 port 51354 Oct 31 00:17:36.517924 sshd-session[3397]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:36.521198 systemd[1]: sshd@1-10.200.8.29:22-10.200.16.10:51354.service: Deactivated successfully. Oct 31 00:17:36.522820 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 00:17:36.523550 systemd-logind[1708]: Session 4 logged out. Waiting for processes to exit. Oct 31 00:17:36.524349 systemd-logind[1708]: Removed session 4. Oct 31 00:17:36.629002 systemd[1]: Started sshd@2-10.200.8.29:22-10.200.16.10:51366.service - OpenSSH per-connection server daemon (10.200.16.10:51366). Oct 31 00:17:37.259938 sshd[3405]: Accepted publickey for core from 10.200.16.10 port 51366 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:37.261382 sshd-session[3405]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:37.265689 systemd-logind[1708]: New session 5 of user core. Oct 31 00:17:37.276868 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 31 00:17:37.706242 sshd[3407]: Connection closed by 10.200.16.10 port 51366 Oct 31 00:17:37.706700 sshd-session[3405]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:37.709969 systemd[1]: sshd@2-10.200.8.29:22-10.200.16.10:51366.service: Deactivated successfully. Oct 31 00:17:37.711628 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 00:17:37.712281 systemd-logind[1708]: Session 5 logged out. Waiting for processes to exit. Oct 31 00:17:37.713097 systemd-logind[1708]: Removed session 5. Oct 31 00:17:47.825627 systemd[1]: Started sshd@3-10.200.8.29:22-10.200.16.10:57750.service - OpenSSH per-connection server daemon (10.200.16.10:57750). Oct 31 00:17:48.462470 sshd[5940]: Accepted publickey for core from 10.200.16.10 port 57750 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:48.463857 sshd-session[5940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:49.095078 sshd[6055]: Connection closed by 10.200.16.10 port 57750 Oct 31 00:17:48.468170 systemd-logind[1708]: New session 6 of user core. Oct 31 00:17:48.908311 sshd-session[5940]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:48.477887 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 31 00:17:48.911635 systemd[1]: sshd@3-10.200.8.29:22-10.200.16.10:57750.service: Deactivated successfully. Oct 31 00:17:48.913272 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 00:17:48.913930 systemd-logind[1708]: Session 6 logged out. Waiting for processes to exit. Oct 31 00:17:48.914722 systemd-logind[1708]: Removed session 6. Oct 31 00:17:49.024211 systemd[1]: Started sshd@4-10.200.8.29:22-10.200.16.10:57752.service - OpenSSH per-connection server daemon (10.200.16.10:57752). Oct 31 00:17:49.671507 sshd[6063]: Accepted publickey for core from 10.200.16.10 port 57752 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:49.671291 sshd-session[6063]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:49.675730 systemd-logind[1708]: New session 7 of user core. Oct 31 00:17:49.683910 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 31 00:17:50.115627 sshd[6228]: Connection closed by 10.200.16.10 port 57752 Oct 31 00:17:50.117215 sshd-session[6063]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:50.119607 systemd[1]: sshd@4-10.200.8.29:22-10.200.16.10:57752.service: Deactivated successfully. Oct 31 00:17:50.122222 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 00:17:50.123104 systemd-logind[1708]: Session 7 logged out. Waiting for processes to exit. Oct 31 00:17:50.123939 systemd-logind[1708]: Removed session 7. Oct 31 00:18:00.233346 systemd[1]: Started sshd@5-10.200.8.29:22-10.200.16.10:57676.service - OpenSSH per-connection server daemon (10.200.16.10:57676). Oct 31 00:18:07.033198 sshd[7982]: Accepted publickey for core from 10.200.16.10 port 57676 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:00.870702 sshd-session[7982]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:00.874995 systemd-logind[1708]: New session 8 of user core. Oct 31 00:18:07.033910 sshd[7984]: Connection closed by 10.200.16.10 port 57676 Oct 31 00:18:07.033997 sshd[7990]: Accepted publickey for core from 10.200.16.10 port 57684 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:01.314664 sshd-session[7982]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:00.889856 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 31 00:18:07.034467 sshd[7992]: Connection closed by 10.200.16.10 port 57684 Oct 31 00:18:02.061438 sshd-session[7990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:01.318060 systemd[1]: sshd@5-10.200.8.29:22-10.200.16.10:57676.service: Deactivated successfully. Oct 31 00:18:02.506099 sshd-session[7990]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:01.319685 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 00:18:01.320372 systemd-logind[1708]: Session 8 logged out. Waiting for processes to exit. Oct 31 00:18:01.321212 systemd-logind[1708]: Removed session 8. Oct 31 00:18:01.426262 systemd[1]: Started sshd@6-10.200.8.29:22-10.200.16.10:57684.service - OpenSSH per-connection server daemon (10.200.16.10:57684). Oct 31 00:18:02.066219 systemd-logind[1708]: New session 9 of user core. Oct 31 00:18:02.076868 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 31 00:18:02.508712 systemd[1]: sshd@6-10.200.8.29:22-10.200.16.10:57684.service: Deactivated successfully. Oct 31 00:18:02.510464 systemd[1]: session-9.scope: Deactivated successfully. Oct 31 00:18:02.511101 systemd-logind[1708]: Session 9 logged out. Waiting for processes to exit. Oct 31 00:18:02.511873 systemd-logind[1708]: Removed session 9. Oct 31 00:18:12.640041 systemd[1]: Started sshd@7-10.200.8.29:22-10.200.16.10:50254.service - OpenSSH per-connection server daemon (10.200.16.10:50254). Oct 31 00:18:13.280118 sshd[10292]: Accepted publickey for core from 10.200.16.10 port 50254 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:13.281516 sshd-session[10292]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:13.288991 systemd-logind[1708]: New session 10 of user core. Oct 31 00:18:13.301923 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 31 00:18:13.728690 sshd[10438]: Connection closed by 10.200.16.10 port 50254 Oct 31 00:18:13.730292 sshd-session[10292]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:13.733567 systemd[1]: sshd@7-10.200.8.29:22-10.200.16.10:50254.service: Deactivated successfully. Oct 31 00:18:13.735497 systemd[1]: session-10.scope: Deactivated successfully. Oct 31 00:18:13.737786 systemd-logind[1708]: Session 10 logged out. Waiting for processes to exit. Oct 31 00:18:13.739977 systemd-logind[1708]: Removed session 10. Oct 31 00:18:13.843716 systemd[1]: Started sshd@8-10.200.8.29:22-10.200.16.10:50256.service - OpenSSH per-connection server daemon (10.200.16.10:50256). Oct 31 00:18:14.482313 sshd[10617]: Accepted publickey for core from 10.200.16.10 port 50256 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:14.484074 sshd-session[10617]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:14.489294 systemd-logind[1708]: New session 11 of user core. Oct 31 00:18:14.503097 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 31 00:18:14.934587 sshd[10827]: Connection closed by 10.200.16.10 port 50256 Oct 31 00:18:14.935193 sshd-session[10617]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:14.938147 systemd-logind[1708]: Session 11 logged out. Waiting for processes to exit. Oct 31 00:18:14.941048 systemd[1]: sshd@8-10.200.8.29:22-10.200.16.10:50256.service: Deactivated successfully. Oct 31 00:18:14.945465 systemd[1]: session-11.scope: Deactivated successfully. Oct 31 00:18:14.947708 systemd-logind[1708]: Removed session 11. Oct 31 00:18:25.052674 systemd[1]: Started sshd@9-10.200.8.29:22-10.200.16.10:53534.service - OpenSSH per-connection server daemon (10.200.16.10:53534). Oct 31 00:18:25.704720 sshd[11856]: Accepted publickey for core from 10.200.16.10 port 53534 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:25.705756 sshd-session[11856]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:25.711905 systemd-logind[1708]: New session 12 of user core. Oct 31 00:18:25.720321 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 31 00:18:26.194146 sshd[11876]: Connection closed by 10.200.16.10 port 53534 Oct 31 00:18:26.196164 sshd-session[11856]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:26.199948 systemd-logind[1708]: Session 12 logged out. Waiting for processes to exit. Oct 31 00:18:26.201109 systemd[1]: sshd@9-10.200.8.29:22-10.200.16.10:53534.service: Deactivated successfully. Oct 31 00:18:26.202767 systemd[1]: session-12.scope: Deactivated successfully. Oct 31 00:18:26.204248 systemd-logind[1708]: Removed session 12. Oct 31 00:18:26.313604 systemd[1]: Started sshd@10-10.200.8.29:22-10.200.16.10:53546.service - OpenSSH per-connection server daemon (10.200.16.10:53546). Oct 31 00:18:26.950220 sshd[11889]: Accepted publickey for core from 10.200.16.10 port 53546 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:26.952342 sshd-session[11889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:26.959779 systemd-logind[1708]: New session 13 of user core. Oct 31 00:18:26.966901 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 31 00:18:27.400365 sshd[11909]: Connection closed by 10.200.16.10 port 53546 Oct 31 00:18:27.401176 sshd-session[11889]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:27.404851 systemd[1]: sshd@10-10.200.8.29:22-10.200.16.10:53546.service: Deactivated successfully. Oct 31 00:18:27.406452 systemd[1]: session-13.scope: Deactivated successfully. Oct 31 00:18:27.407563 systemd-logind[1708]: Session 13 logged out. Waiting for processes to exit. Oct 31 00:18:27.410240 systemd-logind[1708]: Removed session 13. Oct 31 00:18:37.518115 systemd[1]: Started sshd@11-10.200.8.29:22-10.200.16.10:55846.service - OpenSSH per-connection server daemon (10.200.16.10:55846). Oct 31 00:18:38.178825 sshd[12248]: Accepted publickey for core from 10.200.16.10 port 55846 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:38.180257 sshd-session[12248]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:38.185119 systemd-logind[1708]: New session 14 of user core. Oct 31 00:18:38.197327 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 31 00:18:38.626818 sshd[12271]: Connection closed by 10.200.16.10 port 55846 Oct 31 00:18:38.628572 sshd-session[12248]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:38.632154 systemd[1]: sshd@11-10.200.8.29:22-10.200.16.10:55846.service: Deactivated successfully. Oct 31 00:18:38.634427 systemd[1]: session-14.scope: Deactivated successfully. Oct 31 00:18:38.635469 systemd-logind[1708]: Session 14 logged out. Waiting for processes to exit. Oct 31 00:18:38.636697 systemd-logind[1708]: Removed session 14. Oct 31 00:18:38.759834 systemd[1]: Started sshd@12-10.200.8.29:22-10.200.16.10:55862.service - OpenSSH per-connection server daemon (10.200.16.10:55862). Oct 31 00:18:39.399611 sshd[12288]: Accepted publickey for core from 10.200.16.10 port 55862 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:39.400596 sshd-session[12288]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:39.407407 systemd-logind[1708]: New session 15 of user core. Oct 31 00:18:39.413322 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 31 00:18:39.850528 sshd[12308]: Connection closed by 10.200.16.10 port 55862 Oct 31 00:18:39.851317 sshd-session[12288]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:39.854476 systemd-logind[1708]: Session 15 logged out. Waiting for processes to exit. Oct 31 00:18:39.856131 systemd[1]: sshd@12-10.200.8.29:22-10.200.16.10:55862.service: Deactivated successfully. Oct 31 00:18:39.857720 systemd[1]: session-15.scope: Deactivated successfully. Oct 31 00:18:39.860278 systemd-logind[1708]: Removed session 15. Oct 31 00:18:49.962400 systemd[1]: Started sshd@13-10.200.8.29:22-10.200.16.10:44558.service - OpenSSH per-connection server daemon (10.200.16.10:44558). Oct 31 00:18:50.598808 sshd[12659]: Accepted publickey for core from 10.200.16.10 port 44558 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:50.600262 sshd-session[12659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:50.604446 systemd-logind[1708]: New session 16 of user core. Oct 31 00:18:50.615858 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 31 00:18:51.044610 sshd[12661]: Connection closed by 10.200.16.10 port 44558 Oct 31 00:18:51.044952 sshd-session[12659]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:51.048148 systemd[1]: sshd@13-10.200.8.29:22-10.200.16.10:44558.service: Deactivated successfully. Oct 31 00:18:51.049710 systemd[1]: session-16.scope: Deactivated successfully. Oct 31 00:18:51.050376 systemd-logind[1708]: Session 16 logged out. Waiting for processes to exit. Oct 31 00:18:51.051169 systemd-logind[1708]: Removed session 16. Oct 31 00:18:51.162219 systemd[1]: Started sshd@14-10.200.8.29:22-10.200.16.10:44560.service - OpenSSH per-connection server daemon (10.200.16.10:44560). Oct 31 00:18:51.794798 sshd[12667]: Accepted publickey for core from 10.200.16.10 port 44560 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:51.796155 sshd-session[12667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:51.800357 systemd-logind[1708]: New session 17 of user core. Oct 31 00:18:51.810869 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 31 00:18:52.244997 sshd[12669]: Connection closed by 10.200.16.10 port 44560 Oct 31 00:18:52.245387 sshd-session[12667]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:52.248563 systemd[1]: sshd@14-10.200.8.29:22-10.200.16.10:44560.service: Deactivated successfully. Oct 31 00:18:52.250188 systemd[1]: session-17.scope: Deactivated successfully. Oct 31 00:18:52.250823 systemd-logind[1708]: Session 17 logged out. Waiting for processes to exit. Oct 31 00:18:52.252719 systemd-logind[1708]: Removed session 17. Oct 31 00:18:57.536917 setup-nvidia[2368]: \u000d Oct 31 00:18:57.536917 setup-nvidia[2368]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 31 00:18:57.536917 setup-nvidia[2368]: frequently when this kernel module was built against the wrong or Oct 31 00:18:57.536917 setup-nvidia[2368]: improperly configured kernel sources, with a version of gcc that differs Oct 31 00:18:57.536917 setup-nvidia[2368]: from the one used to build the target kernel, or if another driver, such Oct 31 00:18:57.536917 setup-nvidia[2368]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 31 00:18:57.536917 setup-nvidia[2368]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 31 00:18:57.536917 setup-nvidia[2368]: installed in this system is supported by this NVIDIA Linux graphics Oct 31 00:18:57.536917 setup-nvidia[2368]: driver release. Oct 31 00:18:57.536917 setup-nvidia[2368]: \u000d Oct 31 00:18:57.536917 setup-nvidia[2368]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 31 00:18:57.536917 setup-nvidia[2368]: messages' at the end of the file Oct 31 00:18:57.536917 setup-nvidia[2368]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Oct 31 00:18:57.536917 setup-nvidia[2368]: information. Oct 31 00:18:57.536917 setup-nvidia[2368]: \u000d Oct 31 00:18:57.553402 setup-nvidia[2368]: dmesg: read kernel buffer failed: Operation not permitted Oct 31 00:18:57.578206 setup-nvidia[2368]: \u000d Oct 31 00:18:57.578206 setup-nvidia[2368]: ERROR: Installation has failed. Please see the file Oct 31 00:18:57.578206 setup-nvidia[2368]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Oct 31 00:18:57.578206 setup-nvidia[2368]: details. You may find suggestions on fixing installation problems in Oct 31 00:18:57.578206 setup-nvidia[2368]: the README available on the Linux driver download page at Oct 31 00:18:57.578206 setup-nvidia[2368]: www.nvidia.com. Oct 31 00:18:57.578206 setup-nvidia[2368]: \u000d Oct 31 00:18:57.578522 setup-nvidia[2368]: Last 50 lines of nvidia-installer.log: Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Oct 31 00:18:57.579343 setup-nvidia[2368]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Oct 31 00:18:57.579343 setup-nvidia[2368]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Oct 31 00:18:57.579343 setup-nvidia[2368]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Oct 31 00:18:57.579343 setup-nvidia[2368]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Oct 31 00:18:57.579343 setup-nvidia[2368]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Oct 31 00:18:57.579343 setup-nvidia[2368]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Oct 31 00:18:57.579343 setup-nvidia[2368]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 31 00:18:57.579343 setup-nvidia[2368]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 31 00:18:57.579343 setup-nvidia[2368]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 31 00:18:57.579343 setup-nvidia[2368]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 31 00:18:57.579343 setup-nvidia[2368]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 31 00:18:57.579343 setup-nvidia[2368]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Oct 31 00:18:57.579343 setup-nvidia[2368]: make[2]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/build' Oct 31 00:18:57.579343 setup-nvidia[2368]: make[1]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/source' Oct 31 00:18:57.579343 setup-nvidia[2368]: -> done. Oct 31 00:18:57.579343 setup-nvidia[2368]: -> Kernel module compilation complete. Oct 31 00:18:57.579343 setup-nvidia[2368]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 31 00:18:57.579343 setup-nvidia[2368]: 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 31 00:18:57.579343 setup-nvidia[2368]: \u000d Oct 31 00:18:57.580443 setup-nvidia[2368]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Oct 31 00:18:57.580443 setup-nvidia[2368]: -> Kernel module load error: Operation not permitted Oct 31 00:18:57.580443 setup-nvidia[2368]: -> Kernel messages: Oct 31 00:18:57.580443 setup-nvidia[2368]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 31 00:19:02.359288 systemd[1]: Started sshd@15-10.200.8.29:22-10.200.16.10:40086.service - OpenSSH per-connection server daemon (10.200.16.10:40086). Oct 31 00:19:02.994850 sshd[12884]: Accepted publickey for core from 10.200.16.10 port 40086 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:02.996167 sshd-session[12884]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:03.000408 systemd-logind[1708]: New session 18 of user core. Oct 31 00:19:03.019903 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 31 00:19:03.442188 sshd[12886]: Connection closed by 10.200.16.10 port 40086 Oct 31 00:19:03.442631 sshd-session[12884]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:03.445813 systemd[1]: sshd@15-10.200.8.29:22-10.200.16.10:40086.service: Deactivated successfully. Oct 31 00:19:03.447419 systemd[1]: session-18.scope: Deactivated successfully. Oct 31 00:19:03.448072 systemd-logind[1708]: Session 18 logged out. Waiting for processes to exit. Oct 31 00:19:03.448871 systemd-logind[1708]: Removed session 18. Oct 31 00:19:03.554490 systemd[1]: Started sshd@16-10.200.8.29:22-10.200.16.10:40102.service - OpenSSH per-connection server daemon (10.200.16.10:40102). Oct 31 00:19:04.186803 sshd[12892]: Accepted publickey for core from 10.200.16.10 port 40102 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:04.188113 sshd-session[12892]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:04.193765 systemd-logind[1708]: New session 19 of user core. Oct 31 00:19:04.203855 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 31 00:19:04.633268 sshd[12894]: Connection closed by 10.200.16.10 port 40102 Oct 31 00:19:04.633639 sshd-session[12892]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:04.636811 systemd[1]: sshd@16-10.200.8.29:22-10.200.16.10:40102.service: Deactivated successfully. Oct 31 00:19:04.638417 systemd[1]: session-19.scope: Deactivated successfully. Oct 31 00:19:04.639082 systemd-logind[1708]: Session 19 logged out. Waiting for processes to exit. Oct 31 00:19:04.639876 systemd-logind[1708]: Removed session 19. Oct 31 00:19:14.745347 systemd[1]: Started sshd@17-10.200.8.29:22-10.200.16.10:51664.service - OpenSSH per-connection server daemon (10.200.16.10:51664). Oct 31 00:19:15.380536 sshd[12900]: Accepted publickey for core from 10.200.16.10 port 51664 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:15.381903 sshd-session[12900]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:15.386157 systemd-logind[1708]: New session 20 of user core. Oct 31 00:19:15.392859 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 31 00:19:15.825688 sshd[12902]: Connection closed by 10.200.16.10 port 51664 Oct 31 00:19:15.826044 sshd-session[12900]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:15.829319 systemd[1]: sshd@17-10.200.8.29:22-10.200.16.10:51664.service: Deactivated successfully. Oct 31 00:19:15.830947 systemd[1]: session-20.scope: Deactivated successfully. Oct 31 00:19:15.831835 systemd-logind[1708]: Session 20 logged out. Waiting for processes to exit. Oct 31 00:19:15.832631 systemd-logind[1708]: Removed session 20. Oct 31 00:19:15.947227 systemd[1]: Started sshd@18-10.200.8.29:22-10.200.16.10:51668.service - OpenSSH per-connection server daemon (10.200.16.10:51668). Oct 31 00:19:16.583502 sshd[12909]: Accepted publickey for core from 10.200.16.10 port 51668 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:16.584918 sshd-session[12909]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:16.589268 systemd-logind[1708]: New session 21 of user core. Oct 31 00:19:16.598853 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 31 00:19:17.033024 sshd[12911]: Connection closed by 10.200.16.10 port 51668 Oct 31 00:19:17.033340 sshd-session[12909]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:17.037155 systemd[1]: sshd@18-10.200.8.29:22-10.200.16.10:51668.service: Deactivated successfully. Oct 31 00:19:17.038804 systemd[1]: session-21.scope: Deactivated successfully. Oct 31 00:19:17.039466 systemd-logind[1708]: Session 21 logged out. Waiting for processes to exit. Oct 31 00:19:17.040267 systemd-logind[1708]: Removed session 21. Oct 31 00:19:23.986804 kernel: EXT4-fs (loop6p9): unmounting filesystem ef0d47f4-cb5a-40f9-985f-abe2ba1c953f. Oct 31 00:19:23.986996 setup-nvidia[2368]: Container flatcardevelopercontainer-4230.2.4nightly-20251030-2100.bin exited successfully. Oct 31 00:19:23.989185 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.4nightly\x2d20251030\x2d2100.bin.mount: Deactivated successfully. Oct 31 00:19:24.048552 sudo[2356]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:24.050245 systemd[1]: session-c2.scope: Deactivated successfully. Oct 31 00:19:24.050512 systemd[1]: session-c2.scope: Consumed 5min 8.526s CPU time, 883.6M memory peak. Oct 31 00:19:24.479757 setup-nvidia[1701]: /opt/nvidia / Oct 31 00:19:24.480991 setup-nvidia[1701]: / Oct 31 00:19:24.496676 setup-nvidia[12933]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 00:19:24.529758 kernel: IPMI message handler: version 39.2 Oct 31 00:19:24.533751 kernel: ipmi device interface Oct 31 00:19:24.534445 setup-nvidia[1701]: /opt/nvidia/current/usr/lib/modules/6.6.113-flatcar/video / Oct 31 00:19:24.587893 kernel: nvidia: loading out-of-tree module taints kernel. Oct 31 00:19:24.588028 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 31 00:19:24.588051 kernel: Disabling lock debugging due to kernel taint Oct 31 00:19:24.595709 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 31 00:19:24.595769 kernel: nvidia: module license taints kernel. Oct 31 00:19:24.995763 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Oct 31 00:19:25.386611 kernel: Oct 31 00:19:25.386754 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 31 00:19:25.593764 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Oct 31 00:19:25.614755 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Oct 31 00:19:25.634767 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 31 00:19:25.678768 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Oct 31 00:19:25.678815 setup-nvidia[1701]: / Oct 31 00:19:27.162563 systemd[1]: Started sshd@19-10.200.8.29:22-10.200.16.10:33496.service - OpenSSH per-connection server daemon (10.200.16.10:33496). Oct 31 00:19:27.428278 setup-nvidia[12963]: Fri Oct 31 00:19:27 2025 Oct 31 00:19:27.428278 setup-nvidia[12963]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:27.428278 setup-nvidia[12963]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Oct 31 00:19:27.428278 setup-nvidia[12963]: |-----------------------------------------+----------------------+----------------------+ Oct 31 00:19:27.428278 setup-nvidia[12963]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 31 00:19:27.428278 setup-nvidia[12963]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 31 00:19:27.428278 setup-nvidia[12963]: | | | MIG M. | Oct 31 00:19:27.428278 setup-nvidia[12963]: |=========================================+======================+======================| Oct 31 00:19:27.431652 setup-nvidia[12963]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 31 00:19:27.431652 setup-nvidia[12963]: | N/A 26C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Oct 31 00:19:27.431652 setup-nvidia[12963]: | | | N/A | Oct 31 00:19:27.431652 setup-nvidia[12963]: +-----------------------------------------+----------------------+----------------------+ Oct 31 00:19:27.431785 setup-nvidia[12963]: Oct 31 00:19:27.431785 setup-nvidia[12963]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:27.431785 setup-nvidia[12963]: | Processes: | Oct 31 00:19:27.431785 setup-nvidia[12963]: | GPU GI CI PID Type Process name GPU Memory | Oct 31 00:19:27.431785 setup-nvidia[12963]: | ID ID Usage | Oct 31 00:19:27.431785 setup-nvidia[12963]: |=======================================================================================| Oct 31 00:19:27.431922 setup-nvidia[12963]: | No running processes found | Oct 31 00:19:27.431922 setup-nvidia[12963]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:27.797060 sshd[12968]: Accepted publickey for core from 10.200.16.10 port 33496 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:27.798338 sshd-session[12968]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:27.802462 systemd-logind[1708]: New session 22 of user core. Oct 31 00:19:27.810860 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 31 00:19:27.812853 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 31 00:19:27.814823 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 31 00:19:27.815471 systemd[1]: Startup finished in 1.095s (firmware) + 16.463s (loader) + 1.833s (kernel) + 15.471s (initrd) + 3min 16.205s (userspace) = 3min 51.068s. Oct 31 00:19:28.249304 sshd[12972]: Connection closed by 10.200.16.10 port 33496 Oct 31 00:19:28.249709 sshd-session[12968]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:28.253062 systemd[1]: sshd@19-10.200.8.29:22-10.200.16.10:33496.service: Deactivated successfully. Oct 31 00:19:28.254773 systemd[1]: session-22.scope: Deactivated successfully. Oct 31 00:19:28.255493 systemd-logind[1708]: Session 22 logged out. Waiting for processes to exit. Oct 31 00:19:28.256338 systemd-logind[1708]: Removed session 22. Oct 31 00:19:28.364199 systemd[1]: Started sshd@20-10.200.8.29:22-10.200.16.10:33502.service - OpenSSH per-connection server daemon (10.200.16.10:33502). Oct 31 00:19:28.996676 sshd[12978]: Accepted publickey for core from 10.200.16.10 port 33502 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:28.998024 sshd-session[12978]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:29.002207 systemd-logind[1708]: New session 23 of user core. Oct 31 00:19:29.013858 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 31 00:19:29.444559 sshd[12980]: Connection closed by 10.200.16.10 port 33502 Oct 31 00:19:29.444967 sshd-session[12978]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:29.448263 systemd[1]: sshd@20-10.200.8.29:22-10.200.16.10:33502.service: Deactivated successfully. Oct 31 00:19:29.449904 systemd[1]: session-23.scope: Deactivated successfully. Oct 31 00:19:29.450714 systemd-logind[1708]: Session 23 logged out. Waiting for processes to exit. Oct 31 00:19:29.451519 systemd-logind[1708]: Removed session 23. Oct 31 00:19:29.565485 systemd[1]: Started sshd@21-10.200.8.29:22-10.200.16.10:33512.service - OpenSSH per-connection server daemon (10.200.16.10:33512). Oct 31 00:19:31.524803 sshd[12986]: Accepted publickey for core from 10.200.16.10 port 33512 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:31.524993 sshd-session[12986]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:31.529322 systemd-logind[1708]: New session 24 of user core. Oct 31 00:19:31.541861 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 31 00:19:31.903856 sshd[12988]: Connection closed by 10.200.16.10 port 33512 Oct 31 00:19:31.904227 sshd-session[12986]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:31.907481 systemd[1]: sshd@21-10.200.8.29:22-10.200.16.10:33512.service: Deactivated successfully. Oct 31 00:19:31.909116 systemd[1]: session-24.scope: Deactivated successfully. Oct 31 00:19:31.909807 systemd-logind[1708]: Session 24 logged out. Waiting for processes to exit. Oct 31 00:19:31.910574 systemd-logind[1708]: Removed session 24. Oct 31 00:19:32.034008 systemd[1]: Started sshd@22-10.200.8.29:22-10.200.16.10:51876.service - OpenSSH per-connection server daemon (10.200.16.10:51876). Oct 31 00:19:32.660305 sshd[12994]: Accepted publickey for core from 10.200.16.10 port 51876 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:32.661638 sshd-session[12994]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:32.665972 systemd-logind[1708]: New session 25 of user core. Oct 31 00:19:32.686886 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 31 00:19:33.039242 sudo[12997]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 31 00:19:33.039562 sudo[12997]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 00:19:33.093120 sudo[12997]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:33.193493 sshd[12996]: Connection closed by 10.200.16.10 port 51876 Oct 31 00:19:33.193929 sshd-session[12994]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:33.197448 systemd[1]: sshd@22-10.200.8.29:22-10.200.16.10:51876.service: Deactivated successfully. Oct 31 00:19:33.199093 systemd[1]: session-25.scope: Deactivated successfully. Oct 31 00:19:33.199762 systemd-logind[1708]: Session 25 logged out. Waiting for processes to exit. Oct 31 00:19:33.200592 systemd-logind[1708]: Removed session 25. Oct 31 00:19:33.305416 systemd[1]: Started sshd@23-10.200.8.29:22-10.200.16.10:51888.service - OpenSSH per-connection server daemon (10.200.16.10:51888). Oct 31 00:19:33.937784 sshd[13003]: Accepted publickey for core from 10.200.16.10 port 51888 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:33.939169 sshd-session[13003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:33.943340 systemd-logind[1708]: New session 26 of user core. Oct 31 00:19:33.951884 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 31 00:19:34.084419 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 31 00:19:34.085281 systemd[2359]: Activating special unit exit.target... Oct 31 00:19:34.085365 systemd[2359]: Stopped target default.target - Main User Target. Oct 31 00:19:34.085407 systemd[2359]: Stopped target basic.target - Basic System. Oct 31 00:19:34.085440 systemd[2359]: Stopped target paths.target - Paths. Oct 31 00:19:34.085471 systemd[2359]: Stopped target sockets.target - Sockets. Oct 31 00:19:34.085502 systemd[2359]: Stopped target timers.target - Timers. Oct 31 00:19:34.085894 systemd[2359]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:19:34.086117 systemd[2359]: Removed slice app.slice - User Application Slice. Oct 31 00:19:34.086151 systemd[2359]: Reached target shutdown.target - Shutdown. Oct 31 00:19:34.086308 systemd[2359]: Finished systemd-exit.service - Exit the Session. Oct 31 00:19:34.086348 systemd[2359]: Reached target exit.target - Exit the Session. Oct 31 00:19:34.102034 (sd-pam)[2360]: pam_unix(systemd-user:session): session closed for user root Oct 31 00:19:34.103525 systemd[1]: user@0.service: Deactivated successfully. Oct 31 00:19:34.103929 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 31 00:19:34.118023 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 31 00:19:34.127636 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 31 00:19:34.128604 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 31 00:19:34.128812 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 31 00:19:34.130518 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 31 00:19:34.130611 systemd[1]: user-0.slice: Consumed 5min 8.749s CPU time, 893.1M memory peak. Oct 31 00:19:34.131020 systemd-logind[1708]: Removed session c3. Oct 31 00:19:34.284127 sudo[13011]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 31 00:19:34.284431 sudo[13011]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 00:19:34.287652 sudo[13011]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:34.293101 sudo[13010]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 31 00:19:34.293395 sudo[13010]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 00:19:34.314039 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 31 00:19:34.340287 augenrules[13033]: No rules Oct 31 00:19:34.341521 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:19:34.341767 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 31 00:19:34.342527 sudo[13010]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:34.443570 sshd[13005]: Connection closed by 10.200.16.10 port 51888 Oct 31 00:19:34.444005 sshd-session[13003]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:34.447580 systemd[1]: sshd@23-10.200.8.29:22-10.200.16.10:51888.service: Deactivated successfully. Oct 31 00:19:34.449228 systemd[1]: session-26.scope: Deactivated successfully. Oct 31 00:19:34.449876 systemd-logind[1708]: Session 26 logged out. Waiting for processes to exit. Oct 31 00:19:34.450662 systemd-logind[1708]: Removed session 26. Oct 31 00:19:34.559275 systemd[1]: Started sshd@24-10.200.8.29:22-10.200.16.10:51896.service - OpenSSH per-connection server daemon (10.200.16.10:51896). Oct 31 00:19:35.194193 sshd[13042]: Accepted publickey for core from 10.200.16.10 port 51896 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:35.195502 sshd-session[13042]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:35.199663 systemd-logind[1708]: New session 27 of user core. Oct 31 00:19:35.208876 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 31 00:19:35.541482 sudo[13045]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Oct 31 00:19:35.541805 sudo[13045]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 00:19:35.544935 sudo[13045]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:35.645241 sshd[13044]: Connection closed by 10.200.16.10 port 51896 Oct 31 00:19:35.645761 sshd-session[13042]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:35.648930 systemd[1]: sshd@24-10.200.8.29:22-10.200.16.10:51896.service: Deactivated successfully. Oct 31 00:19:35.650556 systemd[1]: session-27.scope: Deactivated successfully. Oct 31 00:19:35.651204 systemd-logind[1708]: Session 27 logged out. Waiting for processes to exit. Oct 31 00:19:35.651971 systemd-logind[1708]: Removed session 27. Oct 31 00:19:35.756292 systemd[1]: Started sshd@25-10.200.8.29:22-10.200.16.10:51904.service - OpenSSH per-connection server daemon (10.200.16.10:51904). Oct 31 00:19:36.387370 sshd[13051]: Accepted publickey for core from 10.200.16.10 port 51904 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:36.388777 sshd-session[13051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:36.392969 systemd-logind[1708]: New session 28 of user core. Oct 31 00:19:36.409870 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 31 00:19:36.734057 sudo[13055]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Oct 31 00:19:36.734358 sudo[13055]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 00:19:41.021743 sudo[13055]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:41.125017 sshd[13053]: Connection closed by 10.200.16.10 port 51904 Oct 31 00:19:41.124869 sshd-session[13051]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:41.128149 systemd[1]: sshd@25-10.200.8.29:22-10.200.16.10:51904.service: Deactivated successfully. Oct 31 00:19:41.129896 systemd[1]: session-28.scope: Deactivated successfully. Oct 31 00:19:41.130562 systemd-logind[1708]: Session 28 logged out. Waiting for processes to exit. Oct 31 00:19:41.131367 systemd-logind[1708]: Removed session 28. Oct 31 00:19:41.238168 systemd[1]: Started sshd@26-10.200.8.29:22-10.200.16.10:45948.service - OpenSSH per-connection server daemon (10.200.16.10:45948). Oct 31 00:19:41.867328 sshd[13062]: Accepted publickey for core from 10.200.16.10 port 45948 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:41.868662 sshd-session[13062]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:41.873459 systemd-logind[1708]: New session 29 of user core. Oct 31 00:19:41.883852 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 31 00:19:42.424196 sshd[13065]: Connection closed by 10.200.16.10 port 45948 Oct 31 00:19:42.424622 sshd-session[13062]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:42.428078 systemd[1]: sshd@26-10.200.8.29:22-10.200.16.10:45948.service: Deactivated successfully. Oct 31 00:19:42.429713 systemd[1]: session-29.scope: Deactivated successfully. Oct 31 00:19:42.430389 systemd-logind[1708]: Session 29 logged out. Waiting for processes to exit. Oct 31 00:19:42.431306 systemd-logind[1708]: Removed session 29. Oct 31 00:19:42.536408 systemd[1]: Started sshd@27-10.200.8.29:22-10.200.16.10:45962.service - OpenSSH per-connection server daemon (10.200.16.10:45962). Oct 31 00:19:43.166719 sshd[13081]: Accepted publickey for core from 10.200.16.10 port 45962 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:43.168106 sshd-session[13081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:43.172303 systemd-logind[1708]: New session 30 of user core. Oct 31 00:19:43.187862 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 31 00:19:44.036959 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 31 00:19:44.037131 (dockerd)[13105]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Oct 31 00:19:46.546253 dockerd[13105]: time="2025-10-31T00:19:46.546180847Z" level=info msg="Starting up" Oct 31 00:19:47.103156 dockerd[13105]: time="2025-10-31T00:19:47.103106749Z" level=info msg="Loading containers: start." Oct 31 00:19:47.318757 kernel: Initializing XFRM netlink socket Oct 31 00:19:47.408189 systemd-networkd[1405]: docker0: Link UP Oct 31 00:19:47.467643 dockerd[13105]: time="2025-10-31T00:19:47.467600463Z" level=info msg="Loading containers: done." Oct 31 00:19:47.481358 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1065582723-merged.mount: Deactivated successfully. Oct 31 00:19:47.503119 dockerd[13105]: time="2025-10-31T00:19:47.503051505Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 31 00:19:47.503240 dockerd[13105]: time="2025-10-31T00:19:47.503184300Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Oct 31 00:19:47.503404 dockerd[13105]: time="2025-10-31T00:19:47.503376692Z" level=info msg="Daemon has completed initialization" Oct 31 00:19:47.573234 dockerd[13105]: time="2025-10-31T00:19:47.573176222Z" level=info msg="API listen on /run/docker.sock" Oct 31 00:19:47.573346 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 31 00:19:48.707305 systemd[1]: var-lib-docker-overlay2-05ec7223db1b5b14405e9591e4dbc48fcbffd4071a8e4eb8856274614bca131c\x2dinit-merged.mount: Deactivated successfully. Oct 31 00:19:48.787022 kernel: docker0: port 1(veth96b713c) entered blocking state Oct 31 00:19:48.787140 kernel: docker0: port 1(veth96b713c) entered disabled state Oct 31 00:19:48.787166 kernel: veth96b713c: entered allmulticast mode Oct 31 00:19:48.792982 kernel: veth96b713c: entered promiscuous mode Oct 31 00:19:48.792956 systemd-networkd[1405]: veth96b713c: Link UP Oct 31 00:19:49.603112 containerd[1722]: time="2025-10-31T00:19:49.603026245Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 31 00:19:49.603112 containerd[1722]: time="2025-10-31T00:19:49.603084742Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 31 00:19:49.603112 containerd[1722]: time="2025-10-31T00:19:49.603097542Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:19:49.603579 containerd[1722]: time="2025-10-31T00:19:49.603171138Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:19:49.623865 systemd[1]: Started docker-39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935.scope - libcontainer container 39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935. Oct 31 00:19:49.675757 kernel: eth0: renamed from veth2a2deee Oct 31 00:19:49.690552 kernel: docker0: port 1(veth96b713c) entered blocking state Oct 31 00:19:49.690602 kernel: docker0: port 1(veth96b713c) entered forwarding state Oct 31 00:19:49.690647 systemd-networkd[1405]: veth96b713c: Gained carrier Oct 31 00:19:49.690929 systemd-networkd[1405]: docker0: Gained carrier Oct 31 00:19:50.865875 systemd-networkd[1405]: docker0: Gained IPv6LL Oct 31 00:19:51.313895 systemd-networkd[1405]: veth96b713c: Gained IPv6LL Oct 31 00:19:52.752424 systemd[1]: docker-39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935.scope: Deactivated successfully. Oct 31 00:19:52.764649 dockerd[13105]: time="2025-10-31T00:19:52.764602435Z" level=info msg="ignoring event" container=39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 31 00:19:52.764957 containerd[1722]: time="2025-10-31T00:19:52.764772130Z" level=info msg="shim disconnected" id=39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935 namespace=moby Oct 31 00:19:52.764957 containerd[1722]: time="2025-10-31T00:19:52.764824729Z" level=warning msg="cleaning up after shim disconnected" id=39f1f0e88be5236c42c3806c56989663601b4c28e1b7b8fb1d5a38cc212f1935 namespace=moby Oct 31 00:19:52.764957 containerd[1722]: time="2025-10-31T00:19:52.764833628Z" level=info msg="cleaning up dead shim" namespace=moby Oct 31 00:19:52.807459 systemd-networkd[1405]: veth96b713c: Lost carrier Oct 31 00:19:52.807812 kernel: docker0: port 1(veth96b713c) entered disabled state Oct 31 00:19:52.807846 kernel: veth2a2deee: renamed from eth0 Oct 31 00:19:52.840965 systemd-networkd[1405]: veth96b713c: Link DOWN Oct 31 00:19:52.844760 kernel: docker0: port 1(veth96b713c) entered disabled state Oct 31 00:19:52.844819 kernel: veth96b713c (unregistering): left allmulticast mode Oct 31 00:19:52.851921 kernel: veth96b713c (unregistering): left promiscuous mode Oct 31 00:19:52.851979 kernel: docker0: port 1(veth96b713c) entered disabled state Oct 31 00:19:52.887132 systemd[1]: run-docker-netns-953f692ce027.mount: Deactivated successfully. Oct 31 00:19:52.891984 systemd[1]: var-lib-docker-overlay2-05ec7223db1b5b14405e9591e4dbc48fcbffd4071a8e4eb8856274614bca131c-merged.mount: Deactivated successfully. Oct 31 00:19:53.046376 sshd[13083]: Connection closed by 10.200.16.10 port 45962 Oct 31 00:19:53.046710 sshd-session[13081]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:53.050256 systemd[1]: sshd@27-10.200.8.29:22-10.200.16.10:45962.service: Deactivated successfully. Oct 31 00:19:53.051918 systemd[1]: session-30.scope: Deactivated successfully. Oct 31 00:19:53.052548 systemd-logind[1708]: Session 30 logged out. Waiting for processes to exit. Oct 31 00:19:53.053383 systemd-logind[1708]: Removed session 30. Oct 31 00:19:53.160125 systemd[1]: Started sshd@28-10.200.8.29:22-10.200.16.10:45692.service - OpenSSH per-connection server daemon (10.200.16.10:45692). Oct 31 00:19:53.789950 sshd[13380]: Accepted publickey for core from 10.200.16.10 port 45692 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:53.791339 sshd-session[13380]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:53.795561 systemd-logind[1708]: New session 31 of user core. Oct 31 00:19:53.808863 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 31 00:19:53.809953 systemd-networkd[1405]: docker0: Lost carrier Oct 31 00:19:54.194623 systemd[1]: var-lib-docker-overlay2-5588c7376f3b06859bbaa78e914708fe5a2a58f99e871c96ed2dc4549da664ac\x2dinit-merged.mount: Deactivated successfully. Oct 31 00:19:54.306930 kernel: docker0: port 1(vetha7b3cc6) entered blocking state Oct 31 00:19:54.307152 kernel: docker0: port 1(vetha7b3cc6) entered disabled state Oct 31 00:19:54.309789 kernel: vetha7b3cc6: entered allmulticast mode Oct 31 00:19:54.312820 kernel: vetha7b3cc6: entered promiscuous mode Oct 31 00:19:54.316404 kernel: docker0: port 1(vetha7b3cc6) entered blocking state Oct 31 00:19:54.319877 kernel: docker0: port 1(vetha7b3cc6) entered forwarding state Oct 31 00:19:54.320759 kernel: docker0: port 1(vetha7b3cc6) entered disabled state Oct 31 00:19:54.324620 systemd-networkd[1405]: vetha7b3cc6: Link UP Oct 31 00:19:54.430236 containerd[1722]: time="2025-10-31T00:19:54.430111152Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 31 00:19:54.430236 containerd[1722]: time="2025-10-31T00:19:54.430218048Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 31 00:19:54.430236 containerd[1722]: time="2025-10-31T00:19:54.430239348Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:19:54.430667 containerd[1722]: time="2025-10-31T00:19:54.430330945Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 31 00:19:54.454865 systemd[1]: Started docker-93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92.scope - libcontainer container 93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92. Oct 31 00:19:54.512763 kernel: eth0: renamed from vethdb7994f Oct 31 00:19:54.535927 kernel: docker0: port 1(vetha7b3cc6) entered blocking state Oct 31 00:19:54.535995 kernel: docker0: port 1(vetha7b3cc6) entered forwarding state Oct 31 00:19:54.536337 systemd-networkd[1405]: vetha7b3cc6: Gained carrier Oct 31 00:19:54.536609 systemd-networkd[1405]: docker0: Gained carrier Oct 31 00:19:54.573632 systemd[1]: docker-93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92.scope: Deactivated successfully. Oct 31 00:19:54.594663 containerd[1722]: time="2025-10-31T00:19:54.594476520Z" level=info msg="shim disconnected" id=93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92 namespace=moby Oct 31 00:19:54.594663 containerd[1722]: time="2025-10-31T00:19:54.594527718Z" level=warning msg="cleaning up after shim disconnected" id=93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92 namespace=moby Oct 31 00:19:54.594663 containerd[1722]: time="2025-10-31T00:19:54.594539118Z" level=info msg="cleaning up dead shim" namespace=moby Oct 31 00:19:54.594831 dockerd[13105]: time="2025-10-31T00:19:54.594587917Z" level=info msg="ignoring event" container=93761e4b6c56b19cbb4d5b6948e846351f90df27efd171ef9bb1300fe4560b92 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 31 00:19:54.626278 systemd-networkd[1405]: vetha7b3cc6: Lost carrier Oct 31 00:19:54.626752 kernel: docker0: port 1(vetha7b3cc6) entered disabled state Oct 31 00:19:54.626815 kernel: vethdb7994f: renamed from eth0 Oct 31 00:19:54.654959 systemd-networkd[1405]: vetha7b3cc6: Link DOWN Oct 31 00:19:54.658750 kernel: docker0: port 1(vetha7b3cc6) entered disabled state Oct 31 00:19:54.665396 kernel: vetha7b3cc6 (unregistering): left allmulticast mode Oct 31 00:19:54.665445 kernel: vetha7b3cc6 (unregistering): left promiscuous mode Oct 31 00:19:54.668806 kernel: docker0: port 1(vetha7b3cc6) entered disabled state Oct 31 00:19:54.817930 systemd-networkd[1405]: docker0: Lost carrier Oct 31 00:19:54.830576 sshd[13382]: Connection closed by 10.200.16.10 port 45692 Oct 31 00:19:54.830985 sshd-session[13380]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:54.834191 systemd[1]: sshd@28-10.200.8.29:22-10.200.16.10:45692.service: Deactivated successfully. Oct 31 00:19:54.835837 systemd[1]: session-31.scope: Deactivated successfully. Oct 31 00:19:54.836473 systemd-logind[1708]: Session 31 logged out. Waiting for processes to exit. Oct 31 00:19:54.837247 systemd-logind[1708]: Removed session 31. Oct 31 00:19:54.952950 systemd[1]: Started sshd@29-10.200.8.29:22-10.200.16.10:45696.service - OpenSSH per-connection server daemon (10.200.16.10:45696). Oct 31 00:19:55.192271 systemd[1]: var-lib-docker-overlay2-5588c7376f3b06859bbaa78e914708fe5a2a58f99e871c96ed2dc4549da664ac-merged.mount: Deactivated successfully. Oct 31 00:19:55.580751 sshd[13486]: Accepted publickey for core from 10.200.16.10 port 45696 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:55.582042 sshd-session[13486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:55.586179 systemd-logind[1708]: New session 32 of user core. Oct 31 00:19:55.595870 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 31 00:19:55.987289 ntpdate[13498]: ntpdate 4.2.8p17@1.4004-o Thu Oct 30 21:34:39 UTC 2025 (1) Oct 31 00:20:02.738448 ntpdate[13498]: adjust time server 89.234.64.77 offset -0.021639 sec Oct 31 00:20:02.847135 sshd[13488]: Connection closed by 10.200.16.10 port 45696 Oct 31 00:20:02.847551 sshd-session[13486]: pam_unix(sshd:session): session closed for user core Oct 31 00:20:02.850086 systemd[1]: sshd@29-10.200.8.29:22-10.200.16.10:45696.service: Deactivated successfully. Oct 31 00:20:02.851846 systemd[1]: session-32.scope: Deactivated successfully. Oct 31 00:20:02.853113 systemd-logind[1708]: Session 32 logged out. Waiting for processes to exit. Oct 31 00:20:02.853921 systemd-logind[1708]: Removed session 32.