Aug 12 23:59:01.203469 kernel: Linux version 6.6.100-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 Tue Aug 12 21:47:31 -00 2025 Aug 12 23:59:01.203497 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=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:01.203508 kernel: BIOS-provided physical RAM map: Aug 12 23:59:01.203516 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Aug 12 23:59:01.203522 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Aug 12 23:59:01.203529 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Aug 12 23:59:01.203538 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Aug 12 23:59:01.203548 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Aug 12 23:59:01.203555 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Aug 12 23:59:01.203562 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Aug 12 23:59:01.203569 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Aug 12 23:59:01.203576 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Aug 12 23:59:01.203583 kernel: printk: bootconsole [earlyser0] enabled Aug 12 23:59:01.203590 kernel: NX (Execute Disable) protection: active Aug 12 23:59:01.203602 kernel: APIC: Static calls initialized Aug 12 23:59:01.203610 kernel: efi: EFI v2.7 by Microsoft Aug 12 23:59:01.203618 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Aug 12 23:59:01.203626 kernel: random: crng init done Aug 12 23:59:01.203634 kernel: secureboot: Secure boot disabled Aug 12 23:59:01.203642 kernel: SMBIOS 3.1.0 present. Aug 12 23:59:01.203650 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Aug 12 23:59:01.203657 kernel: Hypervisor detected: Microsoft Hyper-V Aug 12 23:59:01.203665 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Aug 12 23:59:01.203673 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Aug 12 23:59:01.203683 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Aug 12 23:59:01.203691 kernel: Hyper-V: Using hypercall for remote TLB flush Aug 12 23:59:01.203699 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 12 23:59:01.203707 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 12 23:59:01.203716 kernel: tsc: Marking TSC unstable due to running on Hyper-V Aug 12 23:59:01.203724 kernel: tsc: Detected 2593.993 MHz processor Aug 12 23:59:01.203732 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 12 23:59:01.203741 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 12 23:59:01.203749 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Aug 12 23:59:01.203759 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Aug 12 23:59:01.203767 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 12 23:59:01.203775 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Aug 12 23:59:01.203783 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Aug 12 23:59:01.203791 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Aug 12 23:59:01.203799 kernel: Using GB pages for direct mapping Aug 12 23:59:01.203807 kernel: ACPI: Early table checksum verification disabled Aug 12 23:59:01.203819 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Aug 12 23:59:01.203830 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203839 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203847 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Aug 12 23:59:01.203855 kernel: ACPI: FACS 0x000000003FFFE000 000040 Aug 12 23:59:01.203864 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203872 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203883 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203892 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203901 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203909 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203918 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:01.203926 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Aug 12 23:59:01.203934 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Aug 12 23:59:01.203943 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Aug 12 23:59:01.203954 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Aug 12 23:59:01.203962 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Aug 12 23:59:01.203971 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Aug 12 23:59:01.203979 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Aug 12 23:59:01.203988 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Aug 12 23:59:01.203996 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Aug 12 23:59:01.204004 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Aug 12 23:59:01.204013 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Aug 12 23:59:01.204021 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Aug 12 23:59:01.204032 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Aug 12 23:59:01.204040 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Aug 12 23:59:01.204049 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Aug 12 23:59:01.204057 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Aug 12 23:59:01.204066 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Aug 12 23:59:01.204074 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Aug 12 23:59:01.204083 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Aug 12 23:59:01.204091 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Aug 12 23:59:01.204100 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Aug 12 23:59:01.204111 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Aug 12 23:59:01.204120 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Aug 12 23:59:01.204128 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Aug 12 23:59:01.204137 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Aug 12 23:59:01.204145 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Aug 12 23:59:01.204154 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Aug 12 23:59:01.204162 kernel: Zone ranges: Aug 12 23:59:01.204176 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 12 23:59:01.204185 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 12 23:59:01.204196 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Aug 12 23:59:01.204207 kernel: Movable zone start for each node Aug 12 23:59:01.204216 kernel: Early memory node ranges Aug 12 23:59:01.204224 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Aug 12 23:59:01.204233 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Aug 12 23:59:01.204241 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Aug 12 23:59:01.204249 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Aug 12 23:59:01.204258 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Aug 12 23:59:01.204266 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Aug 12 23:59:01.204282 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 12 23:59:01.204296 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Aug 12 23:59:01.204309 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Aug 12 23:59:01.204320 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 12 23:59:01.204333 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Aug 12 23:59:01.204346 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Aug 12 23:59:01.204369 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 12 23:59:01.204383 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 12 23:59:01.204396 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Aug 12 23:59:01.204415 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Aug 12 23:59:01.204428 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Aug 12 23:59:01.204439 kernel: Booting paravirtualized kernel on Hyper-V Aug 12 23:59:01.204450 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 12 23:59:01.204459 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Aug 12 23:59:01.204467 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Aug 12 23:59:01.204525 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Aug 12 23:59:01.204541 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Aug 12 23:59:01.204549 kernel: Hyper-V: PV spinlocks enabled Aug 12 23:59:01.204563 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 12 23:59:01.204573 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=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:01.204583 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 12 23:59:01.204591 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Aug 12 23:59:01.204600 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Aug 12 23:59:01.204608 kernel: Fallback order for Node 0: 0 Aug 12 23:59:01.204617 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Aug 12 23:59:01.204625 kernel: Policy zone: Normal Aug 12 23:59:01.204636 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 12 23:59:01.204662 kernel: software IO TLB: area num 8. Aug 12 23:59:01.204672 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43504K init, 1572K bss, 2103120K reserved, 0K cma-reserved) Aug 12 23:59:01.204684 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Aug 12 23:59:01.204698 kernel: Kernel/User page tables isolation: enabled Aug 12 23:59:01.204707 kernel: ftrace: allocating 37942 entries in 149 pages Aug 12 23:59:01.204716 kernel: ftrace: allocated 149 pages with 4 groups Aug 12 23:59:01.204725 kernel: Dynamic Preempt: voluntary Aug 12 23:59:01.204737 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 12 23:59:01.204747 kernel: rcu: RCU event tracing is enabled. Aug 12 23:59:01.204756 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Aug 12 23:59:01.204766 kernel: Trampoline variant of Tasks RCU enabled. Aug 12 23:59:01.204778 kernel: Rude variant of Tasks RCU enabled. Aug 12 23:59:01.204788 kernel: Tracing variant of Tasks RCU enabled. Aug 12 23:59:01.204797 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 12 23:59:01.204806 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Aug 12 23:59:01.204817 kernel: Using NULL legacy PIC Aug 12 23:59:01.204826 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Aug 12 23:59:01.204835 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 12 23:59:01.204844 kernel: Console: colour dummy device 80x25 Aug 12 23:59:01.204853 kernel: printk: console [tty1] enabled Aug 12 23:59:01.204862 kernel: printk: console [ttyS0] enabled Aug 12 23:59:01.204871 kernel: printk: bootconsole [earlyser0] disabled Aug 12 23:59:01.204880 kernel: ACPI: Core revision 20230628 Aug 12 23:59:01.204889 kernel: Failed to register legacy timer interrupt Aug 12 23:59:01.204898 kernel: APIC: Switch to symmetric I/O mode setup Aug 12 23:59:01.204910 kernel: Hyper-V: enabling crash_kexec_post_notifiers Aug 12 23:59:01.204918 kernel: Hyper-V: Using IPI hypercalls Aug 12 23:59:01.204927 kernel: APIC: send_IPI() replaced with hv_send_ipi() Aug 12 23:59:01.204936 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Aug 12 23:59:01.204945 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Aug 12 23:59:01.204954 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Aug 12 23:59:01.204963 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Aug 12 23:59:01.204972 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Aug 12 23:59:01.204981 kernel: Hyper-V: Using enlightened APIC (xapic mode) Aug 12 23:59:01.204992 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Aug 12 23:59:01.205001 kernel: APIC: read() replaced with hv_apic_read() Aug 12 23:59:01.205010 kernel: APIC: write() replaced with hv_apic_write() Aug 12 23:59:01.205019 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Aug 12 23:59:01.205028 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Aug 12 23:59:01.205037 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Aug 12 23:59:01.205046 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Aug 12 23:59:01.205055 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Aug 12 23:59:01.205064 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 12 23:59:01.205076 kernel: Spectre V2 : Mitigation: Retpolines Aug 12 23:59:01.205084 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 12 23:59:01.205093 kernel: Speculative Store Bypass: Vulnerable Aug 12 23:59:01.205102 kernel: MDS: Mitigation: Clear CPU buffers Aug 12 23:59:01.205110 kernel: TAA: Mitigation: Clear CPU buffers Aug 12 23:59:01.205119 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 12 23:59:01.205128 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 12 23:59:01.205137 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 12 23:59:01.205145 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 12 23:59:01.205154 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 12 23:59:01.205163 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 12 23:59:01.205175 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 12 23:59:01.205184 kernel: Freeing SMP alternatives memory: 32K Aug 12 23:59:01.205193 kernel: pid_max: default: 32768 minimum: 301 Aug 12 23:59:01.205201 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Aug 12 23:59:01.205210 kernel: landlock: Up and running. Aug 12 23:59:01.205219 kernel: SELinux: Initializing. Aug 12 23:59:01.205227 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 12 23:59:01.205236 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 12 23:59:01.205245 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Aug 12 23:59:01.205254 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:01.205263 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:01.205275 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:01.205284 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 12 23:59:01.205293 kernel: signal: max sigframe size: 1776 Aug 12 23:59:01.205302 kernel: rcu: Hierarchical SRCU implementation. Aug 12 23:59:01.205311 kernel: rcu: Max phase no-delay instances is 400. Aug 12 23:59:01.205320 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 12 23:59:01.205329 kernel: smp: Bringing up secondary CPUs ... Aug 12 23:59:01.205338 kernel: smpboot: x86: Booting SMP configuration: Aug 12 23:59:01.205347 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Aug 12 23:59:01.205371 kernel: smp: Brought up 1 node, 6 CPUs Aug 12 23:59:01.205380 kernel: smpboot: Max logical packages: 1 Aug 12 23:59:01.205389 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Aug 12 23:59:01.205398 kernel: devtmpfs: initialized Aug 12 23:59:01.205407 kernel: x86/mm: Memory block size: 1024MB Aug 12 23:59:01.205416 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Aug 12 23:59:01.205425 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 12 23:59:01.205434 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Aug 12 23:59:01.205443 kernel: pinctrl core: initialized pinctrl subsystem Aug 12 23:59:01.205456 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 12 23:59:01.205465 kernel: audit: initializing netlink subsys (disabled) Aug 12 23:59:01.205474 kernel: audit: type=2000 audit(1755043140.029:1): state=initialized audit_enabled=0 res=1 Aug 12 23:59:01.205483 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 12 23:59:01.205491 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 12 23:59:01.205500 kernel: cpuidle: using governor menu Aug 12 23:59:01.205509 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 12 23:59:01.205518 kernel: dca service started, version 1.12.1 Aug 12 23:59:01.205527 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Aug 12 23:59:01.205539 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 12 23:59:01.205548 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 12 23:59:01.205557 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 12 23:59:01.205566 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 12 23:59:01.205575 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 12 23:59:01.205584 kernel: ACPI: Added _OSI(Module Device) Aug 12 23:59:01.205593 kernel: ACPI: Added _OSI(Processor Device) Aug 12 23:59:01.205602 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 12 23:59:01.205611 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 12 23:59:01.205623 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 12 23:59:01.205632 kernel: ACPI: Interpreter enabled Aug 12 23:59:01.205640 kernel: ACPI: PM: (supports S0 S5) Aug 12 23:59:01.205649 kernel: ACPI: Using IOAPIC for interrupt routing Aug 12 23:59:01.205658 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 12 23:59:01.205667 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 12 23:59:01.205676 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Aug 12 23:59:01.205685 kernel: iommu: Default domain type: Translated Aug 12 23:59:01.205694 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 12 23:59:01.205705 kernel: efivars: Registered efivars operations Aug 12 23:59:01.205714 kernel: PCI: Using ACPI for IRQ routing Aug 12 23:59:01.205723 kernel: PCI: System does not support PCI Aug 12 23:59:01.205732 kernel: vgaarb: loaded Aug 12 23:59:01.205741 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Aug 12 23:59:01.205755 kernel: VFS: Disk quotas dquot_6.6.0 Aug 12 23:59:01.205766 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 12 23:59:01.205775 kernel: pnp: PnP ACPI init Aug 12 23:59:01.205784 kernel: pnp: PnP ACPI: found 3 devices Aug 12 23:59:01.205797 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 12 23:59:01.205806 kernel: NET: Registered PF_INET protocol family Aug 12 23:59:01.205815 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 12 23:59:01.205824 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Aug 12 23:59:01.205833 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 12 23:59:01.205842 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 12 23:59:01.205851 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:01.205860 kernel: TCP: Hash tables configured (established 524288 bind 65536) Aug 12 23:59:01.205872 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:01.205881 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:01.205890 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 12 23:59:01.205899 kernel: NET: Registered PF_XDP protocol family Aug 12 23:59:01.205908 kernel: PCI: CLS 0 bytes, default 64 Aug 12 23:59:01.205917 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 12 23:59:01.205926 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Aug 12 23:59:01.205935 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 12 23:59:01.205944 kernel: Initialise system trusted keyrings Aug 12 23:59:01.205955 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Aug 12 23:59:01.205964 kernel: Key type asymmetric registered Aug 12 23:59:01.205973 kernel: Asymmetric key parser 'x509' registered Aug 12 23:59:01.205981 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 12 23:59:01.205990 kernel: io scheduler mq-deadline registered Aug 12 23:59:01.205999 kernel: io scheduler kyber registered Aug 12 23:59:01.206008 kernel: io scheduler bfq registered Aug 12 23:59:01.206017 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 12 23:59:01.206026 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 12 23:59:01.206035 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 12 23:59:01.206047 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 12 23:59:01.206056 kernel: i8042: PNP: No PS/2 controller found. Aug 12 23:59:01.206222 kernel: rtc_cmos 00:02: registered as rtc0 Aug 12 23:59:01.206333 kernel: rtc_cmos 00:02: setting system clock to 2025-08-12T23:59:00 UTC (1755043140) Aug 12 23:59:01.206503 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Aug 12 23:59:01.206519 kernel: intel_pstate: CPU model not supported Aug 12 23:59:01.206528 kernel: efifb: probing for efifb Aug 12 23:59:01.206542 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Aug 12 23:59:01.206552 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Aug 12 23:59:01.206560 kernel: efifb: scrolling: redraw Aug 12 23:59:01.206569 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 12 23:59:01.206578 kernel: Console: switching to colour frame buffer device 128x48 Aug 12 23:59:01.206587 kernel: fb0: EFI VGA frame buffer device Aug 12 23:59:01.206596 kernel: pstore: Using crash dump compression: deflate Aug 12 23:59:01.206605 kernel: pstore: Registered efi_pstore as persistent store backend Aug 12 23:59:01.206614 kernel: NET: Registered PF_INET6 protocol family Aug 12 23:59:01.206626 kernel: Segment Routing with IPv6 Aug 12 23:59:01.206635 kernel: In-situ OAM (IOAM) with IPv6 Aug 12 23:59:01.206644 kernel: NET: Registered PF_PACKET protocol family Aug 12 23:59:01.206652 kernel: Key type dns_resolver registered Aug 12 23:59:01.206661 kernel: IPI shorthand broadcast: enabled Aug 12 23:59:01.206670 kernel: sched_clock: Marking stable (1665005100, 100976700)->(2098418300, -332436500) Aug 12 23:59:01.206679 kernel: registered taskstats version 1 Aug 12 23:59:01.206688 kernel: Loading compiled-in X.509 certificates Aug 12 23:59:01.206697 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.100-flatcar: dfd2b306eb54324ea79eea0261f8d493924aeeeb' Aug 12 23:59:01.206708 kernel: Key type .fscrypt registered Aug 12 23:59:01.206717 kernel: Key type fscrypt-provisioning registered Aug 12 23:59:01.206726 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 12 23:59:01.206735 kernel: ima: Allocated hash algorithm: sha1 Aug 12 23:59:01.206744 kernel: ima: No architecture policies found Aug 12 23:59:01.206753 kernel: clk: Disabling unused clocks Aug 12 23:59:01.206762 kernel: Freeing unused kernel image (initmem) memory: 43504K Aug 12 23:59:01.206770 kernel: Write protecting the kernel read-only data: 38912k Aug 12 23:59:01.206779 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Aug 12 23:59:01.206791 kernel: Run /init as init process Aug 12 23:59:01.206800 kernel: with arguments: Aug 12 23:59:01.206809 kernel: /init Aug 12 23:59:01.206817 kernel: with environment: Aug 12 23:59:01.206826 kernel: HOME=/ Aug 12 23:59:01.206835 kernel: TERM=linux Aug 12 23:59:01.206843 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 12 23:59:01.206853 systemd[1]: Successfully made /usr/ read-only. Aug 12 23:59:01.206865 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) Aug 12 23:59:01.206878 systemd[1]: Detected virtualization microsoft. Aug 12 23:59:01.206887 systemd[1]: Detected architecture x86-64. Aug 12 23:59:01.206896 systemd[1]: Running in initrd. Aug 12 23:59:01.206906 systemd[1]: No hostname configured, using default hostname. Aug 12 23:59:01.206915 systemd[1]: Hostname set to . Aug 12 23:59:01.206925 systemd[1]: Initializing machine ID from random generator. Aug 12 23:59:01.206934 systemd[1]: Queued start job for default target initrd.target. Aug 12 23:59:01.206946 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:01.206956 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:01.206966 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 12 23:59:01.206976 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:59:01.206985 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 12 23:59:01.206995 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 12 23:59:01.207006 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 12 23:59:01.207018 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 12 23:59:01.207028 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:01.207037 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:01.207047 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:59:01.207056 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:59:01.207066 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:59:01.207075 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:59:01.207085 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:59:01.207097 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:59:01.207107 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 12 23:59:01.207116 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 12 23:59:01.207125 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:01.207135 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:01.207144 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:01.207154 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:59:01.207163 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 12 23:59:01.207173 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:59:01.207185 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 12 23:59:01.207194 systemd[1]: Starting systemd-fsck-usr.service... Aug 12 23:59:01.207204 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:59:01.207213 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:59:01.207222 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:01.207253 systemd-journald[202]: Collecting audit messages is disabled. Aug 12 23:59:01.207279 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 12 23:59:01.207290 systemd-journald[202]: Journal started Aug 12 23:59:01.207310 systemd-journald[202]: Runtime Journal (/run/log/journal/7a70f032c91049e2b13ed0693e353441) is 8M, max 2.2G, 2.1G free. Aug 12 23:59:01.204711 systemd-modules-load[205]: Inserted module 'overlay' Aug 12 23:59:01.215498 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:59:01.215868 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:01.216392 systemd[1]: Finished systemd-fsck-usr.service. Aug 12 23:59:01.222022 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 12 23:59:01.222701 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:59:01.230539 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 12 23:59:01.231784 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:59:01.284228 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:01.286064 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:01.287344 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:01.318506 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:01.326972 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 12 23:59:01.337525 systemd-modules-load[205]: Inserted module 'br_netfilter' Aug 12 23:59:01.338979 kernel: Bridge firewalling registered Aug 12 23:59:01.338429 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:01.339893 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:59:01.350713 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:01.364465 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 12 23:59:01.373781 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:01.376577 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:59:01.390126 dracut-cmdline[240]: dracut-dracut-053 Aug 12 23:59:01.390126 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=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:01.442439 systemd-resolved[245]: Positive Trust Anchors: Aug 12 23:59:01.442454 systemd-resolved[245]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:59:01.442485 systemd-resolved[245]: 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 Aug 12 23:59:01.445076 systemd-resolved[245]: Defaulting to hostname 'linux'. Aug 12 23:59:01.446140 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:59:01.455769 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:01.538385 kernel: SCSI subsystem initialized Aug 12 23:59:01.549376 kernel: Loading iSCSI transport class v2.0-870. Aug 12 23:59:01.564384 kernel: iscsi: registered transport (tcp) Aug 12 23:59:01.586659 kernel: iscsi: registered transport (qla4xxx) Aug 12 23:59:01.586691 kernel: QLogic iSCSI HBA Driver Aug 12 23:59:01.628414 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 12 23:59:01.647494 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 12 23:59:01.681840 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 12 23:59:01.681881 kernel: device-mapper: uevent: version 1.0.3 Aug 12 23:59:01.686331 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 12 23:59:01.733383 kernel: raid6: avx2x4 gen() 31104 MB/s Aug 12 23:59:01.752389 kernel: raid6: avx2x2 gen() 30688 MB/s Aug 12 23:59:01.773457 kernel: raid6: avx2x1 gen() 27958 MB/s Aug 12 23:59:01.773488 kernel: raid6: using algorithm avx2x4 gen() 31104 MB/s Aug 12 23:59:01.796555 kernel: raid6: .... xor() 9635 MB/s, rmw enabled Aug 12 23:59:01.796586 kernel: raid6: using avx2x2 recovery algorithm Aug 12 23:59:01.821379 kernel: xor: automatically using best checksumming function avx Aug 12 23:59:01.975384 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 12 23:59:01.985626 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:59:02.004489 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:02.027072 systemd-udevd[427]: Using default interface naming scheme 'v255'. Aug 12 23:59:02.032635 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:02.055507 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 12 23:59:02.067268 dracut-pre-trigger[435]: rd.md=0: removing MD RAID activation Aug 12 23:59:02.098022 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:59:02.116468 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:59:02.175478 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:02.192593 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 12 23:59:02.202552 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 12 23:59:02.207304 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:59:02.217796 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:02.230690 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:59:02.245108 kernel: cryptd: max_cpu_qlen set to 1000 Aug 12 23:59:02.253597 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 12 23:59:02.257149 kernel: AVX2 version of gcm_enc/dec engaged. Aug 12 23:59:02.263283 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:59:02.276850 kernel: AES CTR mode by8 optimization enabled Aug 12 23:59:02.276874 kernel: hv_vmbus: Vmbus version:4.0 Aug 12 23:59:02.271283 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:59:02.271412 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:02.291567 kernel: hv_vmbus: registering driver hyperv_keyboard Aug 12 23:59:02.294398 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:02.303372 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 Aug 12 23:59:02.310499 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:02.311508 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:02.323003 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:02.333091 kernel: pps_core: LinuxPPS API ver. 1 registered Aug 12 23:59:02.333111 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Aug 12 23:59:02.344836 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 12 23:59:02.344866 kernel: hv_vmbus: registering driver hv_pci Aug 12 23:59:02.344880 kernel: PTP clock support registered Aug 12 23:59:02.344892 kernel: hv_vmbus: registering driver hv_netvsc Aug 12 23:59:02.355145 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Aug 12 23:59:02.355486 kernel: hv_vmbus: registering driver hid_hyperv Aug 12 23:59:02.357869 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:02.397178 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Aug 12 23:59:02.397383 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Aug 12 23:59:02.397400 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Aug 12 23:59:02.397569 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Aug 12 23:59:02.397708 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Aug 12 23:59:02.397847 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Aug 12 23:59:02.397979 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Aug 12 23:59:02.398008 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Aug 12 23:59:02.392718 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:02.420785 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 12 23:59:02.420824 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 12 23:59:02.420844 kernel: pci 0001:00:00.0: Enabling HDA controller Aug 12 23:59:02.392819 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:02.427469 kernel: hv_vmbus: registering driver hv_storvsc Aug 12 23:59:02.421205 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:02.438955 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Aug 12 23:59:02.439142 kernel: hv_utils: Registering HyperV Utility Driver Aug 12 23:59:02.439165 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 12 23:59:02.439336 kernel: hv_vmbus: registering driver hv_utils Aug 12 23:59:02.449230 kernel: hv_utils: Heartbeat IC version 3.0 Aug 12 23:59:02.449260 kernel: hv_utils: Shutdown IC version 3.2 Aug 12 23:59:02.449274 kernel: hv_utils: TimeSync IC version 4.0 Aug 12 23:59:02.449286 kernel: scsi host1: storvsc_host_t Aug 12 23:59:02.452374 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 12 23:59:02.452552 kernel: scsi host0: storvsc_host_t Aug 12 23:59:02.455561 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Aug 12 23:59:03.279953 systemd-resolved[245]: Clock change detected. Flushing caches. Aug 12 23:59:03.293701 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 12 23:59:03.292278 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:03.298974 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 12 23:59:03.304606 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Aug 12 23:59:03.324597 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Aug 12 23:59:03.324806 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 12 23:59:03.327602 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Aug 12 23:59:03.328119 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:03.342758 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:03.382706 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Aug 12 23:59:03.382918 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Aug 12 23:59:03.383083 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Aug 12 23:59:03.383234 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Aug 12 23:59:03.383375 kernel: sd 0:0:0:0: [sdb] Write Protect is off Aug 12 23:59:03.383518 kernel: sd 0:0:0:1: [sda] Write Protect is off Aug 12 23:59:03.383679 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Aug 12 23:59:03.383828 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 12 23:59:03.383973 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Aug 12 23:59:03.384122 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 12 23:59:03.384137 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 12 23:59:03.384284 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Aug 12 23:59:03.384425 kernel: sda: sda1 Aug 12 23:59:03.384439 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Aug 12 23:59:03.405041 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:03.934108 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Aug 12 23:59:03.978673 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 scanned by (udev-worker) (493) Aug 12 23:59:04.000699 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 12 23:59:04.025778 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Aug 12 23:59:04.094618 kernel: BTRFS: device fsid 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 devid 1 transid 45 /dev/sdb3 scanned by (udev-worker) (483) Aug 12 23:59:04.114969 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Aug 12 23:59:04.116540 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Aug 12 23:59:04.146706 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 12 23:59:04.165611 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 12 23:59:04.173603 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 12 23:59:05.180413 disk-uuid[645]: The operation has completed successfully. Aug 12 23:59:05.184247 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 12 23:59:05.238484 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 12 23:59:05.238606 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 12 23:59:05.298725 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 12 23:59:05.307216 sh[732]: Success Aug 12 23:59:05.342603 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Aug 12 23:59:05.514120 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 12 23:59:05.534018 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 12 23:59:05.536124 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 12 23:59:05.573469 kernel: BTRFS info (device dm-0): first mount of filesystem 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 Aug 12 23:59:05.573506 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:05.578274 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 12 23:59:05.581828 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 12 23:59:05.584855 kernel: BTRFS info (device dm-0): using free space tree Aug 12 23:59:05.958834 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 12 23:59:05.960905 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 12 23:59:05.980714 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 12 23:59:05.988302 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 12 23:59:06.019233 kernel: BTRFS info (device sdb6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:06.019269 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:06.022315 kernel: BTRFS info (device sdb6): using free space tree Aug 12 23:59:06.038626 kernel: BTRFS info (device sdb6): auto enabling async discard Aug 12 23:59:06.046608 kernel: BTRFS info (device sdb6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:06.051761 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 12 23:59:06.068718 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 12 23:59:06.096886 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:59:06.116719 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:59:06.146270 systemd-networkd[913]: lo: Link UP Aug 12 23:59:06.146281 systemd-networkd[913]: lo: Gained carrier Aug 12 23:59:06.147438 systemd-networkd[913]: Enumeration completed Aug 12 23:59:06.147510 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:59:06.147832 systemd-networkd[913]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:06.147836 systemd-networkd[913]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:06.148611 systemd-networkd[913]: eth0: Link UP Aug 12 23:59:06.152956 systemd[1]: Reached target network.target - Network. Aug 12 23:59:06.159108 systemd-networkd[913]: eth0: Gained carrier Aug 12 23:59:06.159120 systemd-networkd[913]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:06.210593 systemd-networkd[913]: eth0: DHCPv4 address 10.200.8.15/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:07.808746 ignition[870]: Ignition 2.20.0 Aug 12 23:59:07.808759 ignition[870]: Stage: fetch-offline Aug 12 23:59:07.808815 ignition[870]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:07.808829 ignition[870]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:07.808938 ignition[870]: parsed url from cmdline: "" Aug 12 23:59:07.808943 ignition[870]: no config URL provided Aug 12 23:59:07.808949 ignition[870]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:59:07.808958 ignition[870]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:59:07.808964 ignition[870]: failed to fetch config: resource requires networking Aug 12 23:59:07.809144 ignition[870]: Ignition finished successfully Aug 12 23:59:07.833024 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:59:07.853713 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 12 23:59:07.869017 ignition[926]: Ignition 2.20.0 Aug 12 23:59:07.869029 ignition[926]: Stage: fetch Aug 12 23:59:07.869192 ignition[926]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:07.869204 ignition[926]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:07.869296 ignition[926]: parsed url from cmdline: "" Aug 12 23:59:07.869300 ignition[926]: no config URL provided Aug 12 23:59:07.869305 ignition[926]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:59:07.869315 ignition[926]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:59:07.869338 ignition[926]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Aug 12 23:59:07.983363 ignition[926]: GET result: OK Aug 12 23:59:07.983405 ignition[926]: config has been read from IMDS userdata Aug 12 23:59:07.983416 ignition[926]: parsing config with SHA512: 30606cce05ea18e9287b760c6c21a341fb4ffd3f1461b72e6399a585bcfa402aea9297214dcdb4b131bb98eb9564778cc6fceb68c04207263b87b5252c0d13e1 Aug 12 23:59:07.987671 unknown[926]: fetched base config from "system" Aug 12 23:59:07.987883 ignition[926]: fetch: fetch complete Aug 12 23:59:07.987678 unknown[926]: fetched base config from "system" Aug 12 23:59:07.987888 ignition[926]: fetch: fetch passed Aug 12 23:59:07.987684 unknown[926]: fetched user config from "azure" Aug 12 23:59:07.987931 ignition[926]: Ignition finished successfully Aug 12 23:59:07.996534 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 12 23:59:08.017757 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 12 23:59:08.038206 ignition[936]: Ignition 2.20.0 Aug 12 23:59:08.038218 ignition[936]: Stage: kargs Aug 12 23:59:08.038371 ignition[936]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.038382 ignition[936]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.038953 ignition[936]: kargs: kargs passed Aug 12 23:59:08.051148 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 12 23:59:08.038998 ignition[936]: Ignition finished successfully Aug 12 23:59:08.071770 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 12 23:59:08.083796 ignition[946]: Ignition 2.20.0 Aug 12 23:59:08.083808 ignition[946]: Stage: disks Aug 12 23:59:08.083980 ignition[946]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.083993 ignition[946]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.089413 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 12 23:59:08.084559 ignition[946]: disks: disks passed Aug 12 23:59:08.090699 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 12 23:59:08.084621 ignition[946]: Ignition finished successfully Aug 12 23:59:08.096947 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 12 23:59:08.105520 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:59:08.114579 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:59:08.120945 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:59:08.151713 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 12 23:59:08.173652 systemd-networkd[913]: eth0: Gained IPv6LL Aug 12 23:59:08.244852 systemd-fsck[958]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Aug 12 23:59:08.251318 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 12 23:59:08.278673 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 12 23:59:08.375601 kernel: EXT4-fs (sdb9): mounted filesystem 27db109b-2440-48a3-909e-fd8973275523 r/w with ordered data mode. Quota mode: none. Aug 12 23:59:08.375980 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 12 23:59:08.378014 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 12 23:59:08.420652 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:59:08.431117 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 12 23:59:08.446213 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sdb6 scanned by mount (970) Aug 12 23:59:08.446240 kernel: BTRFS info (device sdb6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:08.446254 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:08.446267 kernel: BTRFS info (device sdb6): using free space tree Aug 12 23:59:08.443032 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 12 23:59:08.466932 kernel: BTRFS info (device sdb6): auto enabling async discard Aug 12 23:59:08.446222 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 12 23:59:08.446254 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:59:08.460086 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:59:08.468445 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 12 23:59:08.471270 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 12 23:59:09.690620 coreos-metadata[972]: Aug 12 23:59:09.690 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 12 23:59:09.696792 coreos-metadata[972]: Aug 12 23:59:09.693 INFO Fetch successful Aug 12 23:59:09.696792 coreos-metadata[972]: Aug 12 23:59:09.693 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Aug 12 23:59:09.708815 coreos-metadata[972]: Aug 12 23:59:09.701 INFO Fetch successful Aug 12 23:59:09.714166 coreos-metadata[972]: Aug 12 23:59:09.714 INFO wrote hostname ci-4230.2.2-a-1b13430f3f to /sysroot/etc/hostname Aug 12 23:59:09.716119 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 12 23:59:09.729877 initrd-setup-root[999]: cut: /sysroot/etc/passwd: No such file or directory Aug 12 23:59:09.734813 initrd-setup-root[1007]: cut: /sysroot/etc/group: No such file or directory Aug 12 23:59:09.739112 initrd-setup-root[1014]: cut: /sysroot/etc/shadow: No such file or directory Aug 12 23:59:09.744682 initrd-setup-root[1021]: cut: /sysroot/etc/gshadow: No such file or directory Aug 12 23:59:11.075457 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 12 23:59:11.094666 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 12 23:59:11.096793 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 12 23:59:11.113380 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 12 23:59:11.122463 kernel: BTRFS info (device sdb6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:11.138370 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 12 23:59:11.150665 ignition[1091]: INFO : Ignition 2.20.0 Aug 12 23:59:11.150665 ignition[1091]: INFO : Stage: mount Aug 12 23:59:11.156792 ignition[1091]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.156792 ignition[1091]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.156792 ignition[1091]: INFO : mount: mount passed Aug 12 23:59:11.156792 ignition[1091]: INFO : Ignition finished successfully Aug 12 23:59:11.157728 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 12 23:59:11.185690 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 12 23:59:11.193326 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:59:11.212599 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sdb6 scanned by mount (1103) Aug 12 23:59:11.212636 kernel: BTRFS info (device sdb6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:11.221232 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:11.224304 kernel: BTRFS info (device sdb6): using free space tree Aug 12 23:59:11.230598 kernel: BTRFS info (device sdb6): auto enabling async discard Aug 12 23:59:11.231710 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:59:11.263142 ignition[1120]: INFO : Ignition 2.20.0 Aug 12 23:59:11.263142 ignition[1120]: INFO : Stage: files Aug 12 23:59:11.269480 ignition[1120]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.269480 ignition[1120]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.269480 ignition[1120]: DEBUG : files: compiled without relabeling support, skipping Aug 12 23:59:11.288155 ignition[1120]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 12 23:59:11.288155 ignition[1120]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 12 23:59:11.381970 ignition[1120]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 12 23:59:11.387658 ignition[1120]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 12 23:59:11.387658 ignition[1120]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 12 23:59:11.382658 unknown[1120]: wrote ssh authorized keys file for user: core Aug 12 23:59:11.444172 ignition[1120]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:59:11.451192 ignition[1120]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:59:11.451192 ignition[1120]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:59:11.451192 ignition[1120]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:59:11.451192 ignition[1120]: INFO : files: files passed Aug 12 23:59:11.451192 ignition[1120]: INFO : Ignition finished successfully Aug 12 23:59:11.459445 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 12 23:59:11.481977 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 12 23:59:11.485095 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 12 23:59:11.492472 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 12 23:59:11.492567 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 12 23:59:11.522123 initrd-setup-root-after-ignition[1152]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.522123 initrd-setup-root-after-ignition[1152]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.534344 initrd-setup-root-after-ignition[1156]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.535374 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:59:11.546336 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 12 23:59:11.569703 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 12 23:59:11.593152 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 12 23:59:11.593256 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 12 23:59:11.601881 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 12 23:59:11.610534 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 12 23:59:11.618067 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 12 23:59:11.632686 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 12 23:59:11.652501 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:59:11.655205 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 12 23:59:11.671690 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:11.672551 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:11.687904 systemd[1]: Stopped target timers.target - Timer Units. Aug 12 23:59:11.698874 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 12 23:59:11.699016 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:59:11.710525 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 12 23:59:11.719029 systemd[1]: Stopped target basic.target - Basic System. Aug 12 23:59:11.726389 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 12 23:59:11.734041 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:59:11.742409 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 12 23:59:11.751126 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 12 23:59:11.759295 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:59:11.768007 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 12 23:59:11.776678 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 12 23:59:11.784813 systemd[1]: Stopped target swap.target - Swaps. Aug 12 23:59:11.791798 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 12 23:59:11.791926 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:59:11.802718 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:11.811249 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:11.819988 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 12 23:59:11.824619 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:11.826087 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 12 23:59:11.826214 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 12 23:59:11.846842 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 12 23:59:11.846971 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:59:11.855619 systemd[1]: ignition-files.service: Deactivated successfully. Aug 12 23:59:11.855732 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 12 23:59:11.863901 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 12 23:59:11.864017 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 12 23:59:11.893751 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 12 23:59:11.894518 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 12 23:59:11.894661 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:11.914461 ignition[1176]: INFO : Ignition 2.20.0 Aug 12 23:59:11.914461 ignition[1176]: INFO : Stage: umount Aug 12 23:59:11.914461 ignition[1176]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.914461 ignition[1176]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.914461 ignition[1176]: INFO : umount: umount passed Aug 12 23:59:11.914461 ignition[1176]: INFO : Ignition finished successfully Aug 12 23:59:11.914521 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 12 23:59:11.916034 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 12 23:59:11.916164 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:11.925185 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 12 23:59:11.925303 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:59:11.937674 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 12 23:59:11.937772 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 12 23:59:11.944674 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 12 23:59:11.944769 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 12 23:59:11.949410 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 12 23:59:11.949476 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 12 23:59:11.950372 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 12 23:59:11.950417 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 12 23:59:11.964436 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 12 23:59:11.964488 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 12 23:59:11.971623 systemd[1]: Stopped target network.target - Network. Aug 12 23:59:11.982352 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 12 23:59:11.982411 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:59:11.990819 systemd[1]: Stopped target paths.target - Path Units. Aug 12 23:59:11.992214 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 12 23:59:12.007643 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:12.010838 systemd[1]: Stopped target slices.target - Slice Units. Aug 12 23:59:12.020299 systemd[1]: Stopped target sockets.target - Socket Units. Aug 12 23:59:12.027483 systemd[1]: iscsid.socket: Deactivated successfully. Aug 12 23:59:12.027535 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:59:12.034762 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 12 23:59:12.034813 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:59:12.042462 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 12 23:59:12.042512 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 12 23:59:12.049421 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 12 23:59:12.049471 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 12 23:59:12.062173 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 12 23:59:12.069519 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 12 23:59:12.077492 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 12 23:59:12.078117 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 12 23:59:12.078217 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 12 23:59:12.082304 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 12 23:59:12.082383 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 12 23:59:12.098330 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 12 23:59:12.098750 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 12 23:59:12.112056 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 12 23:59:12.112269 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 12 23:59:12.112379 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 12 23:59:12.122819 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 12 23:59:12.123478 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 12 23:59:12.123525 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:12.152670 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 12 23:59:12.153360 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 12 23:59:12.153418 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:59:12.161248 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 12 23:59:12.161298 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:12.177549 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 12 23:59:12.177615 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:12.185320 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 12 23:59:12.185369 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:12.194603 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:12.202518 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 12 23:59:12.202598 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:12.239212 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 12 23:59:12.239320 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 12 23:59:12.250022 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 12 23:59:12.250165 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:12.251979 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 12 23:59:12.252023 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:12.264601 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 12 23:59:12.264641 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:12.272365 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 12 23:59:12.272415 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:59:12.286956 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 12 23:59:12.287005 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 12 23:59:12.298881 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:59:12.298931 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:12.337849 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 12 23:59:12.340570 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 12 23:59:12.340647 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:12.350914 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:12.350964 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:12.365279 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 12 23:59:12.365343 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:12.365714 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 12 23:59:12.365807 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 12 23:59:12.373596 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 12 23:59:12.405727 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 12 23:59:12.419238 systemd[1]: Switching root. Aug 12 23:59:12.503906 systemd-journald[202]: Journal stopped Aug 12 23:59:17.691700 systemd-journald[202]: Received SIGTERM from PID 1 (systemd). Aug 12 23:59:17.691752 kernel: SELinux: policy capability network_peer_controls=1 Aug 12 23:59:17.691768 kernel: SELinux: policy capability open_perms=1 Aug 12 23:59:17.691785 kernel: SELinux: policy capability extended_socket_class=1 Aug 12 23:59:17.691795 kernel: SELinux: policy capability always_check_network=0 Aug 12 23:59:17.691804 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 12 23:59:17.691814 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 12 23:59:17.691827 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 12 23:59:17.691837 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 12 23:59:17.691846 kernel: audit: type=1403 audit(1755043153.521:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 12 23:59:17.691857 systemd[1]: Successfully loaded SELinux policy in 235.625ms. Aug 12 23:59:17.691869 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.853ms. Aug 12 23:59:17.691880 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) Aug 12 23:59:17.691891 systemd[1]: Detected virtualization microsoft. Aug 12 23:59:17.691905 systemd[1]: Detected architecture x86-64. Aug 12 23:59:17.691916 systemd[1]: Detected first boot. Aug 12 23:59:17.691927 systemd[1]: Hostname set to . Aug 12 23:59:17.691937 systemd[1]: Initializing machine ID from random generator. Aug 12 23:59:17.691948 zram_generator::config[1223]: No configuration found. Aug 12 23:59:17.691962 kernel: Guest personality initialized and is inactive Aug 12 23:59:17.691972 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Aug 12 23:59:17.691982 kernel: Initialized host personality Aug 12 23:59:17.691992 kernel: NET: Registered PF_VSOCK protocol family Aug 12 23:59:17.692002 systemd[1]: Populated /etc with preset unit settings. Aug 12 23:59:17.692016 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 12 23:59:17.692027 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 12 23:59:17.692038 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 12 23:59:17.692052 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 12 23:59:17.692063 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 12 23:59:17.692074 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 12 23:59:17.692085 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 12 23:59:17.692096 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 12 23:59:17.692107 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 12 23:59:17.692118 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 12 23:59:17.692131 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 12 23:59:17.692142 systemd[1]: Created slice user.slice - User and Session Slice. Aug 12 23:59:17.692153 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:17.692164 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:17.692175 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 12 23:59:17.692186 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 12 23:59:17.692201 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 12 23:59:17.692213 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:59:17.692227 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 12 23:59:17.692238 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:17.692249 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 12 23:59:17.692263 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 12 23:59:17.692274 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 12 23:59:17.692285 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 12 23:59:17.692297 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:17.692308 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:59:17.692322 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:59:17.692333 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:59:17.692345 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 12 23:59:17.692356 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 12 23:59:17.692367 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 12 23:59:17.692381 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:17.692392 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:17.692403 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:17.692415 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 12 23:59:17.692426 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 12 23:59:17.692438 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 12 23:59:17.692449 systemd[1]: Mounting media.mount - External Media Directory... Aug 12 23:59:17.692460 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:17.692474 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 12 23:59:17.692486 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 12 23:59:17.692497 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 12 23:59:17.692512 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). Aug 12 23:59:17.692571 systemd[1]: Reached target machines.target - Containers. Aug 12 23:59:17.692646 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 12 23:59:17.692661 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:59:17.692674 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:59:17.692690 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 12 23:59:17.692702 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:59:17.692714 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:59:17.692725 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:59:17.692736 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 12 23:59:17.692748 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:59:17.692759 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 12 23:59:17.692771 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 12 23:59:17.692785 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 12 23:59:17.692797 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 12 23:59:17.692809 systemd[1]: Stopped systemd-fsck-usr.service. Aug 12 23:59:17.692821 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). Aug 12 23:59:17.692832 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:59:17.692847 kernel: fuse: init (API version 7.39) Aug 12 23:59:17.692858 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:59:17.692870 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 12 23:59:17.692889 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 12 23:59:17.692901 kernel: loop: module loaded Aug 12 23:59:17.692912 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 12 23:59:17.692923 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:59:17.692935 systemd[1]: verity-setup.service: Deactivated successfully. Aug 12 23:59:17.692946 systemd[1]: Stopped verity-setup.service. Aug 12 23:59:17.692958 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:17.692969 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 12 23:59:17.692984 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 12 23:59:17.692995 systemd[1]: Mounted media.mount - External Media Directory. Aug 12 23:59:17.693007 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 12 23:59:17.693018 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 12 23:59:17.693029 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 12 23:59:17.693041 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 12 23:59:17.693079 systemd-journald[1330]: Collecting audit messages is disabled. Aug 12 23:59:17.693109 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:17.693121 kernel: ACPI: bus type drm_connector registered Aug 12 23:59:17.693133 systemd-journald[1330]: Journal started Aug 12 23:59:17.693156 systemd-journald[1330]: Runtime Journal (/run/log/journal/b9ce40a3dd184189b51c7eb5f727f2e7) is 8M, max 2.2G, 2.1G free. Aug 12 23:59:16.852423 systemd[1]: Queued start job for default target multi-user.target. Aug 12 23:59:16.864964 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Aug 12 23:59:16.865431 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 12 23:59:17.708958 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:59:17.713166 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 12 23:59:17.713362 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 12 23:59:17.718394 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:59:17.718597 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:59:17.723431 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:59:17.723636 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:59:17.728019 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:59:17.728204 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:59:17.733335 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 12 23:59:17.733519 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 12 23:59:17.738069 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:59:17.738246 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:59:17.742866 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:17.747592 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 12 23:59:17.753209 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 12 23:59:17.763636 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 12 23:59:17.773099 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 12 23:59:17.787665 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 12 23:59:17.792911 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 12 23:59:17.797529 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 12 23:59:17.797568 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:59:17.802966 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 12 23:59:17.808993 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 12 23:59:17.814687 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 12 23:59:17.819863 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:59:17.821186 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 12 23:59:17.826358 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 12 23:59:17.830921 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:59:17.831937 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 12 23:59:17.836169 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:59:17.837210 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:59:17.842042 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 12 23:59:17.847933 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 12 23:59:17.854389 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:17.859402 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 12 23:59:17.859997 systemd-journald[1330]: Time spent on flushing to /var/log/journal/b9ce40a3dd184189b51c7eb5f727f2e7 is 16.130ms for 939 entries. Aug 12 23:59:17.859997 systemd-journald[1330]: System Journal (/var/log/journal/b9ce40a3dd184189b51c7eb5f727f2e7) is 8M, max 2.6G, 2.6G free. Aug 12 23:59:17.911221 systemd-journald[1330]: Received client request to flush runtime journal. Aug 12 23:59:17.868883 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 12 23:59:17.875383 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 12 23:59:17.881441 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 12 23:59:17.889634 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 12 23:59:17.900765 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 12 23:59:17.906510 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 12 23:59:17.912019 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 12 23:59:17.922598 udevadm[1377]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 12 23:59:17.942548 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:17.961600 kernel: loop0: detected capacity change from 0 to 147912 Aug 12 23:59:17.967381 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 12 23:59:17.968084 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 12 23:59:18.349157 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 12 23:59:18.365725 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:59:18.446366 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Aug 12 23:59:18.446388 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Aug 12 23:59:18.451677 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:18.869608 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 12 23:59:18.914614 kernel: loop1: detected capacity change from 0 to 28272 Aug 12 23:59:19.211616 kernel: loop2: detected capacity change from 0 to 138176 Aug 12 23:59:19.326054 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 12 23:59:19.342847 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:19.366521 systemd-udevd[1389]: Using default interface naming scheme 'v255'. Aug 12 23:59:19.575607 kernel: loop3: detected capacity change from 0 to 147912 Aug 12 23:59:19.589600 kernel: loop4: detected capacity change from 0 to 28272 Aug 12 23:59:19.598604 kernel: loop5: detected capacity change from 0 to 138176 Aug 12 23:59:19.609882 (sd-merge)[1391]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Aug 12 23:59:19.610369 (sd-merge)[1391]: Merged extensions into '/usr'. Aug 12 23:59:19.613870 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 12 23:59:19.634760 systemd[1]: Starting ensure-sysext.service... Aug 12 23:59:19.640733 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:59:19.645537 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:19.673628 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 12 23:59:19.685990 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:59:19.698730 systemd[1]: Reload requested from client PID 1393 ('systemctl') (unit ensure-sysext.service)... Aug 12 23:59:19.698747 systemd[1]: Reloading... Aug 12 23:59:19.725278 systemd-tmpfiles[1399]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 12 23:59:19.725704 systemd-tmpfiles[1399]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 12 23:59:19.726617 systemd-tmpfiles[1399]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 12 23:59:19.726903 systemd-tmpfiles[1399]: ACLs are not supported, ignoring. Aug 12 23:59:19.726972 systemd-tmpfiles[1399]: ACLs are not supported, ignoring. Aug 12 23:59:19.750607 zram_generator::config[1466]: No configuration found. Aug 12 23:59:19.762623 kernel: mousedev: PS/2 mouse device common for all mice Aug 12 23:59:19.770750 kernel: hv_vmbus: registering driver hv_balloon Aug 12 23:59:19.770808 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Aug 12 23:59:19.779602 kernel: hv_vmbus: registering driver hyperv_fb Aug 12 23:59:19.788599 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Aug 12 23:59:19.788652 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Aug 12 23:59:19.788676 kernel: Console: switching to colour dummy device 80x25 Aug 12 23:59:19.786737 systemd-tmpfiles[1399]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:59:19.786751 systemd-tmpfiles[1399]: Skipping /boot Aug 12 23:59:19.795388 kernel: Console: switching to colour frame buffer device 128x48 Aug 12 23:59:19.798973 systemd-tmpfiles[1399]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:59:19.798989 systemd-tmpfiles[1399]: Skipping /boot Aug 12 23:59:19.823602 kernel: EDAC MC: Ver: 3.0.0 Aug 12 23:59:19.889798 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. Aug 12 23:59:19.946638 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 45 scanned by (udev-worker) (1412) Aug 12 23:59:19.996714 systemd[1]: Reloading finished in 297 ms. Aug 12 23:59:20.037199 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:20.081367 systemd[1]: Finished ensure-sysext.service. Aug 12 23:59:20.100063 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 12 23:59:20.121269 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 12 23:59:20.129773 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:20.151750 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 12 23:59:20.156407 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 12 23:59:20.161681 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:59:20.162966 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 12 23:59:20.168609 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:59:20.174158 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:59:20.179428 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:59:20.184925 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:59:20.188836 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:59:20.189746 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 12 23:59:20.194318 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). Aug 12 23:59:20.195374 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 12 23:59:20.202368 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:59:20.206393 systemd[1]: Reached target time-set.target - System Time Set. Aug 12 23:59:20.211297 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 12 23:59:20.217173 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 12 23:59:20.222077 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:20.226466 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:20.227716 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:59:20.227913 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:59:20.232922 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:59:20.233128 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:59:20.239139 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:59:20.239437 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:59:20.244606 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:59:20.245164 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:59:20.251692 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:59:20.251755 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:59:20.287928 lvm[1600]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:59:20.292442 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 12 23:59:20.310948 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 12 23:59:20.326004 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 12 23:59:20.359226 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 12 23:59:20.361848 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:20.377803 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 12 23:59:20.382489 lvm[1635]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:59:20.382532 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 12 23:59:20.428247 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 12 23:59:20.438003 systemd-networkd[1425]: lo: Link UP Aug 12 23:59:20.438013 systemd-networkd[1425]: lo: Gained carrier Aug 12 23:59:20.439436 systemd-networkd[1425]: Enumeration completed Aug 12 23:59:20.439574 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:59:20.439863 systemd-networkd[1425]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:20.439874 systemd-networkd[1425]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:20.440418 systemd-networkd[1425]: eth0: Link UP Aug 12 23:59:20.440428 systemd-networkd[1425]: eth0: Gained carrier Aug 12 23:59:20.440441 systemd-networkd[1425]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:20.451733 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 12 23:59:20.453028 systemd-resolved[1607]: Positive Trust Anchors: Aug 12 23:59:20.453047 systemd-resolved[1607]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:59:20.453077 systemd-resolved[1607]: 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 Aug 12 23:59:20.454321 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 12 23:59:20.477851 augenrules[1654]: No rules Aug 12 23:59:20.479050 systemd[1]: audit-rules.service: Deactivated successfully. Aug 12 23:59:20.479292 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 12 23:59:20.492633 systemd-networkd[1425]: eth0: DHCPv4 address 10.200.8.15/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:20.496554 systemd-resolved[1607]: Using system hostname 'ci-4230.2.2-a-1b13430f3f'. Aug 12 23:59:20.498404 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:59:20.499925 systemd[1]: Reached target network.target - Network. Aug 12 23:59:20.500577 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:20.548857 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 12 23:59:21.112458 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:21.283973 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 12 23:59:21.292625 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). Aug 12 23:59:21.740703 systemd-networkd[1425]: eth0: Gained IPv6LL Aug 12 23:59:21.743299 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 12 23:59:21.748772 systemd[1]: Reached target network-online.target - Network is Online. Aug 12 23:59:24.920091 ldconfig[1360]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 12 23:59:24.935118 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 12 23:59:24.950797 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 12 23:59:24.963193 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 12 23:59:24.967997 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:59:24.972793 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 12 23:59:24.977764 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 12 23:59:24.983165 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 12 23:59:24.987616 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 12 23:59:24.993853 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 12 23:59:24.998909 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 12 23:59:24.998947 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:59:25.002719 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:59:25.007397 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 12 23:59:25.013020 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 12 23:59:25.019428 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 12 23:59:25.024637 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 12 23:59:25.029558 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 12 23:59:25.035535 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 12 23:59:25.039938 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 12 23:59:25.045251 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 12 23:59:25.049621 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:59:25.053505 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:59:25.058336 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:59:25.058369 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:59:25.072680 systemd[1]: Starting chronyd.service - NTP client/server... Aug 12 23:59:25.077024 systemd[1]: Starting containerd.service - containerd container runtime... Aug 12 23:59:25.082434 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 12 23:59:25.087390 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 12 23:59:25.092026 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 12 23:59:25.097063 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 12 23:59:25.101000 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). Aug 12 23:59:25.101053 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Aug 12 23:59:25.102475 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Aug 12 23:59:25.106843 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Aug 12 23:59:25.107907 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 12 23:59:25.108851 KVP[1680]: KVP starting; pid is:1680 Aug 12 23:59:25.111159 KVP[1680]: KVP LIC Version: 3.1 Aug 12 23:59:25.113602 kernel: hv_utils: KVP IC version 4.0 Aug 12 23:59:25.115081 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 12 23:59:25.119702 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 12 23:59:25.125285 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 12 23:59:25.125911 (chronyd)[1671]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Aug 12 23:59:25.130498 jq[1675]: false Aug 12 23:59:25.131858 chronyd[1687]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Aug 12 23:59:25.131902 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 12 23:59:25.134324 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 12 23:59:25.134816 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 12 23:59:25.135459 systemd[1]: Starting update-engine.service - Update Engine... Aug 12 23:59:25.135848 chronyd[1687]: Timezone right/UTC failed leap second check, ignoring Aug 12 23:59:25.135999 chronyd[1687]: Loaded seccomp filter (level 2) Aug 12 23:59:25.138267 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 12 23:59:25.145100 systemd[1]: Started chronyd.service - NTP client/server. Aug 12 23:59:25.148887 jq[1690]: true Aug 12 23:59:25.150253 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 12 23:59:25.150479 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 12 23:59:25.150817 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 12 23:59:25.151022 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 12 23:59:25.163402 jq[1695]: true Aug 12 23:59:25.177497 update_engine[1689]: I20250812 23:59:25.177126 1689 main.cc:92] Flatcar Update Engine starting Aug 12 23:59:25.187239 extend-filesystems[1679]: Found loop3 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found loop4 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found loop5 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sda Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sda1 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb1 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb2 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb3 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found usr Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb4 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb6 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb7 Aug 12 23:59:25.190577 extend-filesystems[1679]: Found sdb9 Aug 12 23:59:25.190577 extend-filesystems[1679]: Checking size of /dev/sdb9 Aug 12 23:59:25.187951 (ntainerd)[1722]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 12 23:59:25.241885 extend-filesystems[1679]: Old size kept for /dev/sdb9 Aug 12 23:59:25.241885 extend-filesystems[1679]: Found sr0 Aug 12 23:59:25.188053 systemd[1]: motdgen.service: Deactivated successfully. Aug 12 23:59:25.188282 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 12 23:59:25.201931 systemd-logind[1688]: New seat seat0. Aug 12 23:59:25.203655 systemd-logind[1688]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 12 23:59:25.205672 systemd[1]: Started systemd-logind.service - User Login Management. Aug 12 23:59:25.210942 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 12 23:59:25.211178 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 12 23:59:25.263612 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 45 scanned by (udev-worker) (1740) Aug 12 23:59:25.301690 dbus-daemon[1674]: [system] SELinux support is enabled Aug 12 23:59:25.301942 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 12 23:59:25.304431 update_engine[1689]: I20250812 23:59:25.304378 1689 update_check_scheduler.cc:74] Next update check in 10m35s Aug 12 23:59:25.313564 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). Aug 12 23:59:25.313613 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 12 23:59:25.313957 dbus-daemon[1674]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 12 23:59:25.318625 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). Aug 12 23:59:25.318654 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 12 23:59:25.323556 systemd[1]: Started update-engine.service - Update Engine. Aug 12 23:59:25.327493 sshd_keygen[1694]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 12 23:59:25.336355 bash[1729]: Updated "/home/core/.ssh/authorized_keys" Aug 12 23:59:25.337815 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 12 23:59:25.343241 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 12 23:59:25.348630 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 12 23:59:25.350155 coreos-metadata[1673]: Aug 12 23:59:25.350 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 12 23:59:25.352837 coreos-metadata[1673]: Aug 12 23:59:25.352 INFO Fetch successful Aug 12 23:59:25.353011 coreos-metadata[1673]: Aug 12 23:59:25.352 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Aug 12 23:59:25.356545 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 12 23:59:25.360270 coreos-metadata[1673]: Aug 12 23:59:25.360 INFO Fetch successful Aug 12 23:59:25.360270 coreos-metadata[1673]: Aug 12 23:59:25.360 INFO Fetching http://168.63.129.16/machine/d03a49bf-5817-4d64-97b3-068036ed53ae/6379fd65%2Dd31d%2D430a%2D9676%2De9d4b407904a.%5Fci%2D4230.2.2%2Da%2D1b13430f3f?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Aug 12 23:59:25.360350 coreos-metadata[1673]: Aug 12 23:59:25.360 INFO Fetch successful Aug 12 23:59:25.360350 coreos-metadata[1673]: Aug 12 23:59:25.360 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Aug 12 23:59:25.360642 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 12 23:59:25.361918 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Aug 12 23:59:25.365017 setup-nvidia[1683]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2 Aug 12 23:59:25.366750 coreos-metadata[1673]: Aug 12 23:59:25.366 INFO Fetch successful Aug 12 23:59:25.367140 systemd[1]: issuegen.service: Deactivated successfully. Aug 12 23:59:25.367357 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 12 23:59:25.375335 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 12 23:59:25.389147 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Aug 12 23:59:25.393833 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 12 23:59:25.399226 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 12 23:59:25.420810 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 12 23:59:25.436850 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 12 23:59:25.441867 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 12 23:59:25.446422 systemd[1]: Reached target getty.target - Login Prompts. Aug 12 23:59:25.523850 locksmithd[1800]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 12 23:59:25.977195 setup-nvidia[1840]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 12 23:59:25.977571 setup-nvidia[1840]: Dload Upload Total Spent Left Speed Aug 12 23:59:26.402560 containerd[1722]: time="2025-08-12T23:59:26.402436200Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Aug 12 23:59:26.420368 containerd[1722]: time="2025-08-12T23:59:26.420333800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.421933 containerd[1722]: time="2025-08-12T23:59:26.421894200Z" 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.100-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.421933 containerd[1722]: time="2025-08-12T23:59:26.421922700Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 12 23:59:26.422011 containerd[1722]: time="2025-08-12T23:59:26.421938900Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.422098 containerd[1722]: time="2025-08-12T23:59:26.422075700Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 12 23:59:26.422129 containerd[1722]: time="2025-08-12T23:59:26.422097800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422180 containerd[1722]: time="2025-08-12T23:59:26.422157900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422180 containerd[1722]: time="2025-08-12T23:59:26.422176600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422360 containerd[1722]: time="2025-08-12T23:59:26.422336200Z" 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 Aug 12 23:59:26.422360 containerd[1722]: time="2025-08-12T23:59:26.422356500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422413 containerd[1722]: time="2025-08-12T23:59:26.422369000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422413 containerd[1722]: time="2025-08-12T23:59:26.422378200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422467 containerd[1722]: time="2025-08-12T23:59:26.422458400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422711 containerd[1722]: time="2025-08-12T23:59:26.422689000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.422840 containerd[1722]: time="2025-08-12T23:59:26.422817400Z" 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 Aug 12 23:59:26.422840 containerd[1722]: time="2025-08-12T23:59:26.422837000Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 12 23:59:26.422935 containerd[1722]: time="2025-08-12T23:59:26.422915400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 12 23:59:26.423005 containerd[1722]: time="2025-08-12T23:59:26.422985700Z" level=info msg="metadata content store policy set" policy=shared Aug 12 23:59:26.444768 containerd[1722]: time="2025-08-12T23:59:26.444735500Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 12 23:59:26.444818 containerd[1722]: time="2025-08-12T23:59:26.444781000Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 12 23:59:26.444818 containerd[1722]: time="2025-08-12T23:59:26.444799400Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 12 23:59:26.444818 containerd[1722]: time="2025-08-12T23:59:26.444814600Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 12 23:59:26.444900 containerd[1722]: time="2025-08-12T23:59:26.444828900Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 12 23:59:26.444971 containerd[1722]: time="2025-08-12T23:59:26.444948200Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 12 23:59:26.445196 containerd[1722]: time="2025-08-12T23:59:26.445173100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 12 23:59:26.445308 containerd[1722]: time="2025-08-12T23:59:26.445286200Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 12 23:59:26.445342 containerd[1722]: time="2025-08-12T23:59:26.445308500Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 12 23:59:26.445342 containerd[1722]: time="2025-08-12T23:59:26.445323500Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 12 23:59:26.445342 containerd[1722]: time="2025-08-12T23:59:26.445337400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445349600Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445360900Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445373800Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445387000Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445398400Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445411 containerd[1722]: time="2025-08-12T23:59:26.445410200Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445421200Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445438500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445451400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445463500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445474600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445485500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445498400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445508900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445520000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445534 containerd[1722]: time="2025-08-12T23:59:26.445531700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445560600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445575500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445608800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445622900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445636300Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445670100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445686600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445696400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445740500Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445757100Z" 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 Aug 12 23:59:26.445772 containerd[1722]: time="2025-08-12T23:59:26.445766500Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.445995 containerd[1722]: time="2025-08-12T23:59:26.445777600Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 12 23:59:26.445995 containerd[1722]: time="2025-08-12T23:59:26.445787200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.445995 containerd[1722]: time="2025-08-12T23:59:26.445810000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 12 23:59:26.445995 containerd[1722]: time="2025-08-12T23:59:26.445823800Z" level=info msg="NRI interface is disabled by configuration." Aug 12 23:59:26.445995 containerd[1722]: time="2025-08-12T23:59:26.445834100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.446109 containerd[1722]: time="2025-08-12T23:59:26.446065000Z" 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}" Aug 12 23:59:26.446109 containerd[1722]: time="2025-08-12T23:59:26.446109100Z" level=info msg="Connect containerd service" Aug 12 23:59:26.446277 containerd[1722]: time="2025-08-12T23:59:26.446146400Z" level=info msg="using legacy CRI server" Aug 12 23:59:26.446277 containerd[1722]: time="2025-08-12T23:59:26.446157500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 12 23:59:26.446277 containerd[1722]: time="2025-08-12T23:59:26.446251500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 12 23:59:26.446910 containerd[1722]: time="2025-08-12T23:59:26.446881100Z" 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" Aug 12 23:59:26.447050 containerd[1722]: time="2025-08-12T23:59:26.447011300Z" level=info msg="Start subscribing containerd event" Aug 12 23:59:26.447088 containerd[1722]: time="2025-08-12T23:59:26.447065700Z" level=info msg="Start recovering state" Aug 12 23:59:26.447144 containerd[1722]: time="2025-08-12T23:59:26.447124900Z" level=info msg="Start event monitor" Aug 12 23:59:26.447144 containerd[1722]: time="2025-08-12T23:59:26.447141600Z" level=info msg="Start snapshots syncer" Aug 12 23:59:26.447197 containerd[1722]: time="2025-08-12T23:59:26.447151200Z" level=info msg="Start cni network conf syncer for default" Aug 12 23:59:26.447197 containerd[1722]: time="2025-08-12T23:59:26.447159800Z" level=info msg="Start streaming server" Aug 12 23:59:26.447197 containerd[1722]: time="2025-08-12T23:59:26.447164600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 12 23:59:26.447268 containerd[1722]: time="2025-08-12T23:59:26.447227200Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 12 23:59:26.447295 containerd[1722]: time="2025-08-12T23:59:26.447286700Z" level=info msg="containerd successfully booted in 0.045858s" Aug 12 23:59:26.447390 systemd[1]: Started containerd.service - containerd container runtime. Aug 12 23:59:27.665909 waagent[1825]: 2025-08-12T23:59:27.665820Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Aug 12 23:59:27.670188 waagent[1825]: 2025-08-12T23:59:27.670123Z INFO Daemon Daemon OS: flatcar 4230.2.2 Aug 12 23:59:27.673696 waagent[1825]: 2025-08-12T23:59:27.673642Z INFO Daemon Daemon Python: 3.11.11 Aug 12 23:59:27.677152 waagent[1825]: 2025-08-12T23:59:27.677074Z INFO Daemon Daemon Run daemon Aug 12 23:59:27.680158 waagent[1825]: 2025-08-12T23:59:27.680102Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Aug 12 23:59:27.686654 waagent[1825]: 2025-08-12T23:59:27.686597Z INFO Daemon Daemon Using waagent for provisioning Aug 12 23:59:27.690534 waagent[1825]: 2025-08-12T23:59:27.690485Z INFO Daemon Daemon Activate resource disk Aug 12 23:59:27.693983 waagent[1825]: 2025-08-12T23:59:27.693933Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Aug 12 23:59:27.701227 waagent[1825]: 2025-08-12T23:59:27.701172Z INFO Daemon Daemon Found device: sda Aug 12 23:59:27.710315 waagent[1825]: 2025-08-12T23:59:27.710257Z INFO Daemon Daemon Examining partition table Aug 12 23:59:27.819912 waagent[1825]: 2025-08-12T23:59:27.819819Z INFO Daemon Daemon GPT not detected, determining filesystem Aug 12 23:59:27.887228 waagent[1825]: 2025-08-12T23:59:27.886785Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Aug 12 23:59:27.891116 waagent[1825]: 2025-08-12T23:59:27.891061Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Aug 12 23:59:28.361344 kernel: sda: sda1 Aug 12 23:59:28.361467 kernel: sda: sda1 Aug 12 23:59:28.379113 waagent[1825]: 2025-08-12T23:59:28.379023Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Aug 12 23:59:28.391208 waagent[1825]: 2025-08-12T23:59:28.391145Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Aug 12 23:59:32.742048 login[1828]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 12 23:59:32.743218 login[1829]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 12 23:59:32.754369 systemd-logind[1688]: New session 1 of user core. Aug 12 23:59:32.755428 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 12 23:59:32.764785 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 12 23:59:32.767074 systemd-logind[1688]: New session 2 of user core. Aug 12 23:59:32.944431 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 12 23:59:32.959925 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 12 23:59:33.151777 (systemd)[1909]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 12 23:59:33.154198 systemd-logind[1688]: New session c1 of user core. Aug 12 23:59:34.008225 waagent[1825]: 2025-08-12T23:59:34.008117Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Aug 12 23:59:34.029114 kernel: EXT4-fs (sda1): mounted filesystem 96e7acc9-cd64-482c-b5f8-9ee30947b93e r/w with ordered data mode. Quota mode: none. Aug 12 23:59:34.029187 waagent[1825]: 2025-08-12T23:59:34.027449Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Aug 12 23:59:34.029670 waagent[1825]: 2025-08-12T23:59:34.029613Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 12 23:59:34.029897 waagent[1825]: 2025-08-12T23:59:34.029854Z INFO Daemon Daemon Running default provisioning handler Aug 12 23:59:34.038715 waagent[1825]: 2025-08-12T23:59:34.037638Z 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. Aug 12 23:59:34.040597 waagent[1825]: 2025-08-12T23:59:34.040537Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Aug 12 23:59:34.040916 waagent[1825]: 2025-08-12T23:59:34.040868Z INFO Daemon Daemon cloud-init is enabled: False Aug 12 23:59:34.041638 waagent[1825]: 2025-08-12T23:59:34.041597Z INFO Daemon Daemon Copying ovf-env.xml Aug 12 23:59:34.043980 systemd[1909]: Queued start job for default target default.target. Aug 12 23:59:34.080667 systemd[1909]: Created slice app.slice - User Application Slice. Aug 12 23:59:34.080713 systemd[1909]: Reached target paths.target - Paths. Aug 12 23:59:34.080769 systemd[1909]: Reached target timers.target - Timers. Aug 12 23:59:34.082072 systemd[1909]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 12 23:59:34.092445 systemd[1909]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 12 23:59:34.092507 systemd[1909]: Reached target sockets.target - Sockets. Aug 12 23:59:34.092555 systemd[1909]: Reached target basic.target - Basic System. Aug 12 23:59:34.092621 systemd[1909]: Reached target default.target - Main User Target. Aug 12 23:59:34.092657 systemd[1909]: Startup finished in 931ms. Aug 12 23:59:34.092776 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 12 23:59:34.094287 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 12 23:59:34.095005 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 12 23:59:34.108162 waagent[1825]: 2025-08-12T23:59:34.108072Z INFO Daemon Daemon Successfully mounted dvd Aug 12 23:59:34.146887 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Aug 12 23:59:34.147024 waagent[1825]: 2025-08-12T23:59:34.146890Z INFO Daemon Daemon Detect protocol endpoint Aug 12 23:59:34.170074 waagent[1825]: 2025-08-12T23:59:34.148457Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 12 23:59:34.170074 waagent[1825]: 2025-08-12T23:59:34.149938Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Aug 12 23:59:34.170074 waagent[1825]: 2025-08-12T23:59:34.151364Z INFO Daemon Daemon Test for route to 168.63.129.16 Aug 12 23:59:34.170074 waagent[1825]: 2025-08-12T23:59:34.152945Z INFO Daemon Daemon Route to 168.63.129.16 exists Aug 12 23:59:34.170074 waagent[1825]: 2025-08-12T23:59:34.153571Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Aug 12 23:59:34.187602 waagent[1825]: 2025-08-12T23:59:34.187548Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Aug 12 23:59:34.199667 waagent[1825]: 2025-08-12T23:59:34.189351Z INFO Daemon Daemon Wire protocol version:2012-11-30 Aug 12 23:59:34.199667 waagent[1825]: 2025-08-12T23:59:34.190484Z INFO Daemon Daemon Server preferred version:2015-04-05 Aug 12 23:59:34.449455 waagent[1825]: 2025-08-12T23:59:34.449296Z INFO Daemon Daemon Initializing goal state during protocol detection Aug 12 23:59:34.463271 waagent[1825]: 2025-08-12T23:59:34.451030Z INFO Daemon Daemon Forcing an update of the goal state. Aug 12 23:59:34.463271 waagent[1825]: 2025-08-12T23:59:34.455554Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 12 23:59:34.489306 waagent[1825]: 2025-08-12T23:59:34.489257Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.491244Z INFO Daemon Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.491561Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 0d5d87ca-bda1-4267-ba91-0e6611057df9 eTag: 1169807572981350972 source: Fabric] Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.492570Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.494315Z INFO Daemon Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.495832Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Aug 12 23:59:34.517451 waagent[1825]: 2025-08-12T23:59:34.500195Z INFO Daemon Daemon Downloading artifacts profile blob Aug 12 23:59:34.631490 waagent[1825]: 2025-08-12T23:59:34.631415Z INFO Daemon Downloaded certificate {'thumbprint': '20FC19E4C31AA83208035CA7120EED50864BAF0B', 'hasPrivateKey': True} Aug 12 23:59:34.641778 waagent[1825]: 2025-08-12T23:59:34.633550Z INFO Daemon Fetch goal state completed Aug 12 23:59:34.677997 waagent[1825]: 2025-08-12T23:59:34.677940Z INFO Daemon Daemon Starting provisioning Aug 12 23:59:34.688502 waagent[1825]: 2025-08-12T23:59:34.679522Z INFO Daemon Daemon Handle ovf-env.xml. Aug 12 23:59:34.688502 waagent[1825]: 2025-08-12T23:59:34.680253Z INFO Daemon Daemon Set hostname [ci-4230.2.2-a-1b13430f3f] Aug 12 23:59:34.704280 waagent[1825]: 2025-08-12T23:59:34.704230Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-a-1b13430f3f] Aug 12 23:59:34.705980 waagent[1825]: 2025-08-12T23:59:34.705930Z INFO Daemon Daemon Examine /proc/net/route for primary interface Aug 12 23:59:34.706723 waagent[1825]: 2025-08-12T23:59:34.706677Z INFO Daemon Daemon Primary interface is [eth0] Aug 12 23:59:34.715372 systemd-networkd[1425]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:34.715382 systemd-networkd[1425]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:34.715407 systemd-networkd[1425]: eth0: DHCP lease lost Aug 12 23:59:34.715801 waagent[1825]: 2025-08-12T23:59:34.715748Z INFO Daemon Daemon Create user account if not exists Aug 12 23:59:34.716111 waagent[1825]: 2025-08-12T23:59:34.716068Z INFO Daemon Daemon User core already exists, skip useradd Aug 12 23:59:34.717510 waagent[1825]: 2025-08-12T23:59:34.717464Z INFO Daemon Daemon Configure sudoer Aug 12 23:59:34.719100 waagent[1825]: 2025-08-12T23:59:34.719054Z INFO Daemon Daemon Configure sshd Aug 12 23:59:34.719819 waagent[1825]: 2025-08-12T23:59:34.719775Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Aug 12 23:59:34.720328 waagent[1825]: 2025-08-12T23:59:34.720290Z INFO Daemon Daemon Deploy ssh public key. Aug 12 23:59:34.790617 systemd-networkd[1425]: eth0: DHCPv4 address 10.200.8.15/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:35.878941 waagent[1825]: 2025-08-12T23:59:35.878876Z INFO Daemon Daemon Provisioning complete Aug 12 23:59:35.888527 waagent[1825]: 2025-08-12T23:59:35.888477Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Aug 12 23:59:35.890193 waagent[1825]: 2025-08-12T23:59:35.890143Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Aug 12 23:59:35.891666 waagent[1825]: 2025-08-12T23:59:35.891613Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Aug 12 23:59:36.030185 waagent[1966]: 2025-08-12T23:59:36.030118Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Aug 12 23:59:36.030489 waagent[1966]: 2025-08-12T23:59:36.030250Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Aug 12 23:59:36.030489 waagent[1966]: 2025-08-12T23:59:36.030327Z INFO ExtHandler ExtHandler Python: 3.11.11 Aug 12 23:59:36.085511 waagent[1966]: 2025-08-12T23:59:36.085439Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Aug 12 23:59:36.085720 waagent[1966]: 2025-08-12T23:59:36.085673Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:36.085807 waagent[1966]: 2025-08-12T23:59:36.085768Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:36.093045 waagent[1966]: 2025-08-12T23:59:36.092975Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 12 23:59:36.098075 waagent[1966]: 2025-08-12T23:59:36.098022Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Aug 12 23:59:36.098525 waagent[1966]: 2025-08-12T23:59:36.098469Z INFO ExtHandler Aug 12 23:59:36.100746 waagent[1966]: 2025-08-12T23:59:36.100688Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 2b9ae2b8-91d9-4547-8963-92ee792ca0c4 eTag: 1169807572981350972 source: Fabric] Aug 12 23:59:36.101102 waagent[1966]: 2025-08-12T23:59:36.101055Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Aug 12 23:59:36.101785 waagent[1966]: 2025-08-12T23:59:36.101737Z INFO ExtHandler Aug 12 23:59:36.101870 waagent[1966]: 2025-08-12T23:59:36.101832Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Aug 12 23:59:36.105298 waagent[1966]: 2025-08-12T23:59:36.105256Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Aug 12 23:59:36.174539 waagent[1966]: 2025-08-12T23:59:36.174441Z INFO ExtHandler Downloaded certificate {'thumbprint': '20FC19E4C31AA83208035CA7120EED50864BAF0B', 'hasPrivateKey': True} Aug 12 23:59:36.174990 waagent[1966]: 2025-08-12T23:59:36.174940Z INFO ExtHandler Fetch goal state completed Aug 12 23:59:36.185386 waagent[1966]: 2025-08-12T23:59:36.185332Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1966 Aug 12 23:59:36.185530 waagent[1966]: 2025-08-12T23:59:36.185485Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Aug 12 23:59:36.187297 waagent[1966]: 2025-08-12T23:59:36.187250Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Aug 12 23:59:36.187686 waagent[1966]: 2025-08-12T23:59:36.187639Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Aug 12 23:59:36.230590 waagent[1966]: 2025-08-12T23:59:36.230550Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Aug 12 23:59:36.230778 waagent[1966]: 2025-08-12T23:59:36.230734Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Aug 12 23:59:36.237073 waagent[1966]: 2025-08-12T23:59:36.237028Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Aug 12 23:59:36.244623 systemd[1]: Reload requested from client PID 1979 ('systemctl') (unit waagent.service)... Aug 12 23:59:36.244638 systemd[1]: Reloading... Aug 12 23:59:36.312652 zram_generator::config[2019]: No configuration found. Aug 12 23:59:36.445993 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. Aug 12 23:59:36.595242 systemd[1]: Reloading finished in 350 ms. Aug 12 23:59:36.607187 waagent[1966]: 2025-08-12T23:59:36.607107Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Aug 12 23:59:36.612976 systemd[1]: Reload requested from client PID 2076 ('systemctl') (unit waagent.service)... Aug 12 23:59:36.612991 systemd[1]: Reloading... Aug 12 23:59:36.676612 zram_generator::config[2118]: No configuration found. Aug 12 23:59:36.805020 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. Aug 12 23:59:36.951315 systemd[1]: Reloading finished in 337 ms. Aug 12 23:59:36.967302 waagent[1966]: 2025-08-12T23:59:36.967224Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Aug 12 23:59:36.967428 waagent[1966]: 2025-08-12T23:59:36.967387Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Aug 12 23:59:37.679499 waagent[1966]: 2025-08-12T23:59:37.679407Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Aug 12 23:59:37.680069 waagent[1966]: 2025-08-12T23:59:37.680011Z 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] Aug 12 23:59:37.680846 waagent[1966]: 2025-08-12T23:59:37.680794Z INFO ExtHandler ExtHandler Starting env monitor service. Aug 12 23:59:37.681229 waagent[1966]: 2025-08-12T23:59:37.681176Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:37.681421 waagent[1966]: 2025-08-12T23:59:37.681373Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Aug 12 23:59:37.681887 waagent[1966]: 2025-08-12T23:59:37.681821Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Aug 12 23:59:37.681953 waagent[1966]: 2025-08-12T23:59:37.681905Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Aug 12 23:59:37.682162 waagent[1966]: 2025-08-12T23:59:37.682114Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:37.682670 waagent[1966]: 2025-08-12T23:59:37.682611Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Aug 12 23:59:37.682811 waagent[1966]: 2025-08-12T23:59:37.682758Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:37.683414 waagent[1966]: 2025-08-12T23:59:37.682933Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Aug 12 23:59:37.683530 waagent[1966]: 2025-08-12T23:59:37.683489Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Aug 12 23:59:37.683984 waagent[1966]: 2025-08-12T23:59:37.683937Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:37.684080 waagent[1966]: 2025-08-12T23:59:37.684035Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Aug 12 23:59:37.684675 waagent[1966]: 2025-08-12T23:59:37.684619Z INFO EnvHandler ExtHandler Configure routes Aug 12 23:59:37.685196 waagent[1966]: 2025-08-12T23:59:37.685136Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Aug 12 23:59:37.685196 waagent[1966]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Aug 12 23:59:37.685196 waagent[1966]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Aug 12 23:59:37.685196 waagent[1966]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Aug 12 23:59:37.685196 waagent[1966]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.685196 waagent[1966]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.685196 waagent[1966]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.685720 waagent[1966]: 2025-08-12T23:59:37.685674Z INFO EnvHandler ExtHandler Gateway:None Aug 12 23:59:37.686881 waagent[1966]: 2025-08-12T23:59:37.686552Z INFO EnvHandler ExtHandler Routes:None Aug 12 23:59:37.690843 waagent[1966]: 2025-08-12T23:59:37.690794Z INFO ExtHandler ExtHandler Aug 12 23:59:37.691208 waagent[1966]: 2025-08-12T23:59:37.691161Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 90645da9-b500-4d45-bcd8-44679a6c35e9 correlation e370cc59-5406-4a72-885f-ecdba276fb1b created: 2025-08-12T23:58:12.247452Z] Aug 12 23:59:37.692250 waagent[1966]: 2025-08-12T23:59:37.692198Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Aug 12 23:59:37.694091 waagent[1966]: 2025-08-12T23:59:37.694032Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Aug 12 23:59:37.727177 waagent[1966]: 2025-08-12T23:59:37.727120Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 16DA5627-B0E2-4372-B57B-DD4438BD111F;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Aug 12 23:59:37.850542 waagent[1966]: 2025-08-12T23:59:37.850463Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Aug 12 23:59:37.850542 waagent[1966]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.850542 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.850542 waagent[1966]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.850542 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.850542 waagent[1966]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.850542 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.850542 waagent[1966]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 12 23:59:37.850542 waagent[1966]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 12 23:59:37.850542 waagent[1966]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 12 23:59:37.854289 waagent[1966]: 2025-08-12T23:59:37.854212Z INFO EnvHandler ExtHandler Current Firewall rules: Aug 12 23:59:37.854289 waagent[1966]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.854289 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.854289 waagent[1966]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.854289 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.854289 waagent[1966]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.854289 waagent[1966]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.854289 waagent[1966]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 12 23:59:37.854289 waagent[1966]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 12 23:59:37.854289 waagent[1966]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 12 23:59:37.854905 waagent[1966]: 2025-08-12T23:59:37.854499Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Aug 12 23:59:37.854905 waagent[1966]: 2025-08-12T23:59:37.854678Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Aug 12 23:59:37.984117 waagent[1966]: 2025-08-12T23:59:37.984039Z INFO MonitorHandler ExtHandler Network interfaces: Aug 12 23:59:37.984117 waagent[1966]: Executing ['ip', '-a', '-o', 'link']: Aug 12 23:59:37.984117 waagent[1966]: 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 Aug 12 23:59:37.984117 waagent[1966]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:74:a2:53 brd ff:ff:ff:ff:ff:ff Aug 12 23:59:37.984117 waagent[1966]: Executing ['ip', '-4', '-a', '-o', 'address']: Aug 12 23:59:37.984117 waagent[1966]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Aug 12 23:59:37.984117 waagent[1966]: 2: eth0 inet 10.200.8.15/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Aug 12 23:59:37.984117 waagent[1966]: Executing ['ip', '-6', '-a', '-o', 'address']: Aug 12 23:59:37.984117 waagent[1966]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Aug 12 23:59:37.984117 waagent[1966]: 2: eth0 inet6 fe80::7eed:8dff:fe74:a253/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Aug 12 23:59:48.915546 chronyd[1687]: Selected source PHC0 Aug 12 23:59:50.624197 setup-nvidia[1840]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 482M 0 192k 0 0 192k 0 0:42:46 --:--:-- 0:42:46 192k\u000d 2 482M 2 14.0M 0 0 7278k 0 0:01:07 0:00:01 0:01:06 7278k\u000d 8 482M 8 39.4M 0 0 13.2M 0 0:00:36 0:00:02 0:00:34 13.2M\u000d 13 482M 13 64.8M 0 0 16.3M 0 0:00:29 0:00:03 0:00:26 16.3M\u000d 18 482M 18 89.5M 0 0 18.0M 0 0:00:26 0:00:04 0:00:22 18.0M\u000d 23 482M 23 112M 0 0 18.8M 0 0:00:25 0:00:05 0:00:20 22.5M\u000d 27 482M 27 134M 0 0 19.3M 0 0:00:24 0:00:06 0:00:18 24.2M\u000d 32 482M 32 157M 0 0 19.8M 0 0:00:24 0:00:07 0:00:17 23.8M\u000d 37 482M 37 179M 0 0 19.2M 0 0:00:25 0:00:09 0:00:16 21.4M\u000d 37 482M 37 180M 0 0 17.9M 0 0:00:26 0:00:10 0:00:16 17.8M\u000d 37 482M 37 180M 0 0 16.3M 0 0:00:29 0:00:10 0:00:19 13.5M\u000d 37 482M 37 182M 0 0 15.2M 0 0:00:31 0:00:11 0:00:20 9752k\u000d 40 482M 40 194M 0 0 15.0M 0 0:00:32 0:00:12 0:00:20 7463k\u000d 45 482M 45 218M 0 0 15.6M 0 0:00:30 0:00:13 0:00:17 8598k\u000d 50 482M 50 243M 0 0 16.3M 0 0:00:29 0:00:14 0:00:15 13.0M\u000d 56 482M 56 270M 0 0 16.9M 0 0:00:28 0:00:15 0:00:13 18.2M\u000d 60 482M 60 292M 0 0 17.1M 0 0:00:28 0:00:17 0:00:11 21.6M\u000d 64 482M 64 311M 0 0 17.3M 0 0:00:27 0:00:17 0:00:10 23.5M\u000d 70 482M 70 338M 0 0 17.8M 0 0:00:27 0:00:18 0:00:09 23.9M\u000d 75 482M 75 364M 0 0 18.2M 0 0:00:26 0:00:19 0:00:07 24.0M\u000d 80 482M 80 389M 0 0 18.5M 0 0:00:25 0:00:20 0:00:05 23.8M\u000d 86 482M 86 415M 0 0 18.9M 0 0:00:25 0:00:21 0:00:04 25.1M\u000d 90 482M 90 438M 0 0 19.1M 0 0:00:25 0:00:22 0:00:03 25.3M\u000d 96 482M 96 463M 0 0 19.3M 0 0:00:24 0:00:23 0:00:01 25.0M\u000d100 482M 100 482M 0 0 19.5M 0 0:00:24 0:00:24 --:--:-- 25.0M Aug 12 23:59:59.572237 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 12 23:59:59.586896 systemd[1]: Started sshd@0-10.200.8.15:22-10.200.16.10:55980.service - OpenSSH per-connection server daemon (10.200.16.10:55980). Aug 13 00:00:01.649427 setup-nvidia[1683]: Downloading NVIDIA 535.230.02 Driver Aug 13 00:00:01.654283 setup-nvidia[2220]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 13 00:00:01.654672 setup-nvidia[2220]: Dload Upload Total Spent Left Speed Aug 13 00:00:01.698836 systemd[1]: Started logrotate.service - Rotate and Compress System Logs. Aug 13 00:00:01.979400 systemd[1]: logrotate.service: Deactivated successfully. Aug 13 00:00:02.070130 sshd[2217]: Accepted publickey for core from 10.200.16.10 port 55980 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:02.071439 sshd-session[2217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:02.076034 systemd-logind[1688]: New session 3 of user core. Aug 13 00:00:02.097715 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 00:00:02.623527 systemd[1]: Started sshd@1-10.200.8.15:22-10.200.16.10:52976.service - OpenSSH per-connection server daemon (10.200.16.10:52976). Aug 13 00:00:03.219774 setup-nvidia[2220]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 1 326M 1 3828k 0 0 14.2M 0 0:00:22 --:--:-- 0:00:22 14.2M\u000d 74 326M 74 244M 0 0 195M 0 0:00:01 0:00:01 --:--:-- 194M\u000d100 326M 100 326M 0 0 208M 0 0:00:01 0:00:01 --:--:-- 208M Aug 13 00:00:03.251033 sshd[2226]: Accepted publickey for core from 10.200.16.10 port 52976 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:03.252326 sshd-session[2226]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:03.256504 systemd-logind[1688]: New session 4 of user core. Aug 13 00:00:03.265716 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 00:00:03.297454 setup-nvidia[1683]: Extract the NVIDIA Driver Installer 535.230.02 Aug 13 00:00:03.297666 setup-nvidia[1683]: /opt/nvidia/workdir/nvidia-workdir / Aug 13 00:00:03.314985 setup-nvidia[2231]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Aug 13 00:00:03.588743 setup-nvidia[2231]: Verifying archive integrity... OK Aug 13 00:00:03.699335 sshd[2229]: Connection closed by 10.200.16.10 port 52976 Aug 13 00:00:03.699759 sshd-session[2226]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:03.703070 systemd[1]: sshd@1-10.200.8.15:22-10.200.16.10:52976.service: Deactivated successfully. Aug 13 00:00:03.704773 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 00:00:03.705394 systemd-logind[1688]: Session 4 logged out. Waiting for processes to exit. Aug 13 00:00:03.706273 systemd-logind[1688]: Removed session 4. Aug 13 00:00:03.822175 systemd[1]: Started sshd@2-10.200.8.15:22-10.200.16.10:52988.service - OpenSSH per-connection server daemon (10.200.16.10:52988). Aug 13 00:00:04.449952 sshd[2259]: Accepted publickey for core from 10.200.16.10 port 52988 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:04.451359 sshd-session[2259]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:04.455539 systemd-logind[1688]: New session 5 of user core. Aug 13 00:00:04.468720 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 00:00:04.497102 setup-nvidia[2231]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Aug 13 00:00:04.908406 sshd[2266]: Connection closed by 10.200.16.10 port 52988 Aug 13 00:00:04.908841 sshd-session[2259]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:04.912205 systemd[1]: sshd@2-10.200.8.15:22-10.200.16.10:52988.service: Deactivated successfully. Aug 13 00:00:04.913817 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 00:00:04.914440 systemd-logind[1688]: Session 5 logged out. Waiting for processes to exit. Aug 13 00:00:04.915260 systemd-logind[1688]: Removed session 5. Aug 13 00:00:06.317043 setup-nvidia[2264]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Aug 13 00:00:06.413516 setup-nvidia[1683]: / Aug 13 00:00:06.413763 setup-nvidia[1683]: Spawn system-nspawn container to install the NVIDIA drivers Aug 13 00:00:06.594995 sudo[2276]: 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.2.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 Aug 13 00:00:06.596957 sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:00:06.607703 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Aug 13 00:00:06.620798 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:00:06.630838 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:00:06.634646 systemd[1]: Starting user@0.service - User Manager for UID 0... Aug 13 00:00:06.640931 (systemd)[2279]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:00:06.643059 systemd-logind[1688]: New session c3 of user root. Aug 13 00:00:06.813711 systemd[2279]: Queued start job for default target default.target. Aug 13 00:00:06.822688 systemd[2279]: Created slice app.slice - User Application Slice. Aug 13 00:00:06.822720 systemd[2279]: Reached target paths.target - Paths. Aug 13 00:00:06.822770 systemd[2279]: Reached target timers.target - Timers. Aug 13 00:00:06.823949 systemd[2279]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:00:06.834350 systemd[2279]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:00:06.834411 systemd[2279]: Reached target sockets.target - Sockets. Aug 13 00:00:06.834458 systemd[2279]: Reached target basic.target - Basic System. Aug 13 00:00:06.834505 systemd[2279]: Reached target default.target - Main User Target. Aug 13 00:00:06.834537 systemd[2279]: Startup finished in 185ms. Aug 13 00:00:06.834711 systemd[1]: Started user@0.service - User Manager for UID 0. Aug 13 00:00:06.837726 systemd[1]: Started session-c2.scope - Session c2 of User root. Aug 13 00:00:06.977604 kernel: loop6: detected capacity change from 0 to 12644352 Aug 13 00:00:06.994600 kernel: loop6: p9 Aug 13 00:00:07.181069 setup-nvidia[2288]: ░ Spawning container flatcardevelopercontainer-4230.2.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.2.bin. Aug 13 00:00:07.231607 kernel: EXT4-fs (loop6p9): mounted filesystem f88d1834-7666-4561-9c55-bbe37350df98 ro without journal. Quota mode: none. Aug 13 00:00:07.865138 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Aug 13 00:00:10.748208 update_engine[1689]: I20250813 00:00:10.748131 1689 update_attempter.cc:509] Updating boot flags... Aug 13 00:00:10.864610 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 45 scanned by (udev-worker) (2365) Aug 13 00:00:10.936646 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 45 scanned by (udev-worker) (2368) Aug 13 00:00:15.020137 systemd[1]: Started sshd@3-10.200.8.15:22-10.200.16.10:58096.service - OpenSSH per-connection server daemon (10.200.16.10:58096). Aug 13 00:00:15.647623 sshd[2510]: Accepted publickey for core from 10.200.16.10 port 58096 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:15.648870 sshd-session[2510]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:15.653036 systemd-logind[1688]: New session 6 of user core. Aug 13 00:00:15.669707 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 13 00:00:16.095176 sshd[2564]: Connection closed by 10.200.16.10 port 58096 Aug 13 00:00:16.095558 sshd-session[2510]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:16.098715 systemd[1]: sshd@3-10.200.8.15:22-10.200.16.10:58096.service: Deactivated successfully. Aug 13 00:00:16.100359 systemd[1]: session-6.scope: Deactivated successfully. Aug 13 00:00:16.101029 systemd-logind[1688]: Session 6 logged out. Waiting for processes to exit. Aug 13 00:00:16.101850 systemd-logind[1688]: Removed session 6. Aug 13 00:00:16.210064 systemd[1]: Started sshd@4-10.200.8.15:22-10.200.16.10:58102.service - OpenSSH per-connection server daemon (10.200.16.10:58102). Aug 13 00:00:16.836601 sshd[2788]: Accepted publickey for core from 10.200.16.10 port 58102 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:16.837926 sshd-session[2788]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:16.842374 systemd-logind[1688]: New session 7 of user core. Aug 13 00:00:16.858708 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 13 00:00:17.287223 sshd[3087]: Connection closed by 10.200.16.10 port 58102 Aug 13 00:00:17.287592 sshd-session[2788]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:17.290059 systemd[1]: sshd@4-10.200.8.15:22-10.200.16.10:58102.service: Deactivated successfully. Aug 13 00:00:17.291788 systemd[1]: session-7.scope: Deactivated successfully. Aug 13 00:00:17.293269 systemd-logind[1688]: Session 7 logged out. Waiting for processes to exit. Aug 13 00:00:17.294102 systemd-logind[1688]: Removed session 7. Aug 13 00:00:27.411456 systemd[1]: Started sshd@5-10.200.8.15:22-10.200.16.10:44674.service - OpenSSH per-connection server daemon (10.200.16.10:44674). Aug 13 00:00:28.046554 sshd[3486]: Accepted publickey for core from 10.200.16.10 port 44674 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:28.047912 sshd-session[3486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:28.052138 systemd-logind[1688]: New session 8 of user core. Aug 13 00:00:28.069719 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 13 00:00:28.495964 sshd[3488]: Connection closed by 10.200.16.10 port 44674 Aug 13 00:00:28.496399 sshd-session[3486]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:28.499705 systemd[1]: sshd@5-10.200.8.15:22-10.200.16.10:44674.service: Deactivated successfully. Aug 13 00:00:28.501303 systemd[1]: session-8.scope: Deactivated successfully. Aug 13 00:00:28.501948 systemd-logind[1688]: Session 8 logged out. Waiting for processes to exit. Aug 13 00:00:28.502737 systemd-logind[1688]: Removed session 8. Aug 13 00:00:28.608049 systemd[1]: Started sshd@6-10.200.8.15:22-10.200.16.10:44690.service - OpenSSH per-connection server daemon (10.200.16.10:44690). Aug 13 00:00:29.238061 sshd[3494]: Accepted publickey for core from 10.200.16.10 port 44690 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:29.239430 sshd-session[3494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:29.243802 systemd-logind[1688]: New session 9 of user core. Aug 13 00:00:29.254706 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 13 00:00:29.687312 sshd[3496]: Connection closed by 10.200.16.10 port 44690 Aug 13 00:00:29.687640 sshd-session[3494]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:29.691411 systemd[1]: sshd@6-10.200.8.15:22-10.200.16.10:44690.service: Deactivated successfully. Aug 13 00:00:29.693053 systemd[1]: session-9.scope: Deactivated successfully. Aug 13 00:00:29.693704 systemd-logind[1688]: Session 9 logged out. Waiting for processes to exit. Aug 13 00:00:29.694540 systemd-logind[1688]: Removed session 9. Aug 13 00:00:39.800099 systemd[1]: Started sshd@7-10.200.8.15:22-10.200.16.10:41970.service - OpenSSH per-connection server daemon (10.200.16.10:41970). Aug 13 00:00:40.436345 sshd[3624]: Accepted publickey for core from 10.200.16.10 port 41970 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:40.437694 sshd-session[3624]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:40.443177 systemd-logind[1688]: New session 10 of user core. Aug 13 00:00:40.454718 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 13 00:00:40.896346 sshd[3626]: Connection closed by 10.200.16.10 port 41970 Aug 13 00:00:40.896744 sshd-session[3624]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:40.899929 systemd[1]: sshd@7-10.200.8.15:22-10.200.16.10:41970.service: Deactivated successfully. Aug 13 00:00:40.901553 systemd[1]: session-10.scope: Deactivated successfully. Aug 13 00:00:40.902330 systemd-logind[1688]: Session 10 logged out. Waiting for processes to exit. Aug 13 00:00:40.903128 systemd-logind[1688]: Removed session 10. Aug 13 00:00:41.015185 systemd[1]: Started sshd@8-10.200.8.15:22-10.200.16.10:43282.service - OpenSSH per-connection server daemon (10.200.16.10:43282). Aug 13 00:00:41.649059 sshd[3638]: Accepted publickey for core from 10.200.16.10 port 43282 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:41.650463 sshd-session[3638]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:41.655383 systemd-logind[1688]: New session 11 of user core. Aug 13 00:00:41.662105 systemd[1]: Started session-11.scope - Session 11 of User core. Aug 13 00:00:42.108721 sshd[3760]: Connection closed by 10.200.16.10 port 43282 Aug 13 00:00:42.109087 sshd-session[3638]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:42.112302 systemd[1]: sshd@8-10.200.8.15:22-10.200.16.10:43282.service: Deactivated successfully. Aug 13 00:00:42.113959 systemd[1]: session-11.scope: Deactivated successfully. Aug 13 00:00:42.114603 systemd-logind[1688]: Session 11 logged out. Waiting for processes to exit. Aug 13 00:00:42.115390 systemd-logind[1688]: Removed session 11. Aug 13 00:00:52.225705 systemd[1]: Started sshd@9-10.200.8.15:22-10.200.16.10:40782.service - OpenSSH per-connection server daemon (10.200.16.10:40782). Aug 13 00:00:52.856292 sshd[7651]: Accepted publickey for core from 10.200.16.10 port 40782 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:52.857746 sshd-session[7651]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:52.864119 systemd-logind[1688]: New session 12 of user core. Aug 13 00:00:52.868299 systemd[1]: Started session-12.scope - Session 12 of User core. Aug 13 00:00:53.317130 sshd[7885]: Connection closed by 10.200.16.10 port 40782 Aug 13 00:00:53.318004 sshd-session[7651]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:53.321912 systemd[1]: sshd@9-10.200.8.15:22-10.200.16.10:40782.service: Deactivated successfully. Aug 13 00:00:53.322539 systemd-logind[1688]: Session 12 logged out. Waiting for processes to exit. Aug 13 00:00:53.326095 systemd[1]: session-12.scope: Deactivated successfully. Aug 13 00:00:53.327512 systemd-logind[1688]: Removed session 12. Aug 13 00:00:53.437957 systemd[1]: Started sshd@10-10.200.8.15:22-10.200.16.10:40786.service - OpenSSH per-connection server daemon (10.200.16.10:40786). Aug 13 00:00:54.074401 sshd[7951]: Accepted publickey for core from 10.200.16.10 port 40786 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:54.076219 sshd-session[7951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:54.081431 systemd-logind[1688]: New session 13 of user core. Aug 13 00:00:54.090805 systemd[1]: Started session-13.scope - Session 13 of User core. Aug 13 00:00:54.534646 sshd[8080]: Connection closed by 10.200.16.10 port 40786 Aug 13 00:00:54.536952 sshd-session[7951]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:54.540042 systemd-logind[1688]: Session 13 logged out. Waiting for processes to exit. Aug 13 00:00:54.540971 systemd[1]: sshd@10-10.200.8.15:22-10.200.16.10:40786.service: Deactivated successfully. Aug 13 00:00:54.543738 systemd[1]: session-13.scope: Deactivated successfully. Aug 13 00:00:54.546021 systemd-logind[1688]: Removed session 13. Aug 13 00:01:04.652200 systemd[1]: Started sshd@11-10.200.8.15:22-10.200.16.10:40062.service - OpenSSH per-connection server daemon (10.200.16.10:40062). Aug 13 00:01:05.288817 sshd[11548]: Accepted publickey for core from 10.200.16.10 port 40062 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:05.290668 sshd-session[11548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:05.297155 systemd-logind[1688]: New session 14 of user core. Aug 13 00:01:05.302991 systemd[1]: Started session-14.scope - Session 14 of User core. Aug 13 00:01:05.739421 sshd[11562]: Connection closed by 10.200.16.10 port 40062 Aug 13 00:01:05.740200 sshd-session[11548]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:05.745107 systemd-logind[1688]: Session 14 logged out. Waiting for processes to exit. Aug 13 00:01:05.747040 systemd[1]: sshd@11-10.200.8.15:22-10.200.16.10:40062.service: Deactivated successfully. Aug 13 00:01:05.750747 systemd[1]: session-14.scope: Deactivated successfully. Aug 13 00:01:05.752269 systemd-logind[1688]: Removed session 14. Aug 13 00:01:05.864839 systemd[1]: Started sshd@12-10.200.8.15:22-10.200.16.10:40078.service - OpenSSH per-connection server daemon (10.200.16.10:40078). Aug 13 00:01:06.500598 sshd[11595]: Accepted publickey for core from 10.200.16.10 port 40078 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:06.502051 sshd-session[11595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:06.508910 systemd-logind[1688]: New session 15 of user core. Aug 13 00:01:06.517239 systemd[1]: Started session-15.scope - Session 15 of User core. Aug 13 00:01:06.951279 sshd[11608]: Connection closed by 10.200.16.10 port 40078 Aug 13 00:01:06.951692 sshd-session[11595]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:06.954634 systemd-logind[1688]: Session 15 logged out. Waiting for processes to exit. Aug 13 00:01:06.956382 systemd[1]: sshd@12-10.200.8.15:22-10.200.16.10:40078.service: Deactivated successfully. Aug 13 00:01:06.958426 systemd[1]: session-15.scope: Deactivated successfully. Aug 13 00:01:06.964065 systemd-logind[1688]: Removed session 15. Aug 13 00:01:17.069579 systemd[1]: Started sshd@13-10.200.8.15:22-10.200.16.10:53434.service - OpenSSH per-connection server daemon (10.200.16.10:53434). Aug 13 00:01:17.707131 sshd[12021]: Accepted publickey for core from 10.200.16.10 port 53434 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:17.708543 sshd-session[12021]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:17.716261 systemd-logind[1688]: New session 16 of user core. Aug 13 00:01:17.721772 systemd[1]: Started session-16.scope - Session 16 of User core. Aug 13 00:01:18.165445 sshd[12047]: Connection closed by 10.200.16.10 port 53434 Aug 13 00:01:18.168821 sshd-session[12021]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:18.171846 systemd[1]: sshd@13-10.200.8.15:22-10.200.16.10:53434.service: Deactivated successfully. Aug 13 00:01:18.173709 systemd[1]: session-16.scope: Deactivated successfully. Aug 13 00:01:18.175134 systemd-logind[1688]: Session 16 logged out. Waiting for processes to exit. Aug 13 00:01:18.176875 systemd-logind[1688]: Removed session 16. Aug 13 00:01:18.277185 systemd[1]: Started sshd@14-10.200.8.15:22-10.200.16.10:53446.service - OpenSSH per-connection server daemon (10.200.16.10:53446). Aug 13 00:01:18.916460 sshd[12054]: Accepted publickey for core from 10.200.16.10 port 53446 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:18.918240 sshd-session[12054]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:18.926507 systemd-logind[1688]: New session 17 of user core. Aug 13 00:01:18.932730 systemd[1]: Started session-17.scope - Session 17 of User core. Aug 13 00:01:19.373882 sshd[12074]: Connection closed by 10.200.16.10 port 53446 Aug 13 00:01:19.374260 sshd-session[12054]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:19.377137 systemd-logind[1688]: Session 17 logged out. Waiting for processes to exit. Aug 13 00:01:19.378877 systemd[1]: sshd@14-10.200.8.15:22-10.200.16.10:53446.service: Deactivated successfully. Aug 13 00:01:19.381330 systemd[1]: session-17.scope: Deactivated successfully. Aug 13 00:01:19.384076 systemd-logind[1688]: Removed session 17. Aug 13 00:01:22.072567 kernel: hrtimer: interrupt took 5617714 ns Aug 13 00:01:29.503829 systemd[1]: Started sshd@15-10.200.8.15:22-10.200.16.10:38426.service - OpenSSH per-connection server daemon (10.200.16.10:38426). Aug 13 00:01:30.136811 sshd[12416]: Accepted publickey for core from 10.200.16.10 port 38426 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:30.138205 sshd-session[12416]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:30.145781 systemd-logind[1688]: New session 18 of user core. Aug 13 00:01:30.153907 systemd[1]: Started session-18.scope - Session 18 of User core. Aug 13 00:01:30.591474 sshd[12430]: Connection closed by 10.200.16.10 port 38426 Aug 13 00:01:30.592792 sshd-session[12416]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:30.595916 systemd-logind[1688]: Session 18 logged out. Waiting for processes to exit. Aug 13 00:01:30.596635 systemd[1]: sshd@15-10.200.8.15:22-10.200.16.10:38426.service: Deactivated successfully. Aug 13 00:01:30.601251 systemd[1]: session-18.scope: Deactivated successfully. Aug 13 00:01:30.603323 systemd-logind[1688]: Removed session 18. Aug 13 00:01:30.707400 systemd[1]: Started sshd@16-10.200.8.15:22-10.200.16.10:54828.service - OpenSSH per-connection server daemon (10.200.16.10:54828). Aug 13 00:01:31.346790 sshd[12453]: Accepted publickey for core from 10.200.16.10 port 54828 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:31.348620 sshd-session[12453]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:31.354905 systemd-logind[1688]: New session 19 of user core. Aug 13 00:01:31.359135 systemd[1]: Started session-19.scope - Session 19 of User core. Aug 13 00:01:31.793168 sshd[12462]: Connection closed by 10.200.16.10 port 54828 Aug 13 00:01:31.793788 sshd-session[12453]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:31.796840 systemd-logind[1688]: Session 19 logged out. Waiting for processes to exit. Aug 13 00:01:31.798489 systemd[1]: sshd@16-10.200.8.15:22-10.200.16.10:54828.service: Deactivated successfully. Aug 13 00:01:31.800306 systemd[1]: session-19.scope: Deactivated successfully. Aug 13 00:01:31.801758 systemd-logind[1688]: Removed session 19. Aug 13 00:01:41.906274 systemd[1]: Started sshd@17-10.200.8.15:22-10.200.16.10:40516.service - OpenSSH per-connection server daemon (10.200.16.10:40516). Aug 13 00:01:42.535753 sshd[12704]: Accepted publickey for core from 10.200.16.10 port 40516 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:42.537150 sshd-session[12704]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:42.541298 systemd-logind[1688]: New session 20 of user core. Aug 13 00:01:42.548719 systemd[1]: Started session-20.scope - Session 20 of User core. Aug 13 00:01:42.979418 sshd[12706]: Connection closed by 10.200.16.10 port 40516 Aug 13 00:01:42.979821 sshd-session[12704]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:42.982997 systemd[1]: sshd@17-10.200.8.15:22-10.200.16.10:40516.service: Deactivated successfully. Aug 13 00:01:42.984645 systemd[1]: session-20.scope: Deactivated successfully. Aug 13 00:01:42.985493 systemd-logind[1688]: Session 20 logged out. Waiting for processes to exit. Aug 13 00:01:42.986276 systemd-logind[1688]: Removed session 20. Aug 13 00:01:43.095961 systemd[1]: Started sshd@18-10.200.8.15:22-10.200.16.10:40520.service - OpenSSH per-connection server daemon (10.200.16.10:40520). Aug 13 00:01:43.725036 sshd[12758]: Accepted publickey for core from 10.200.16.10 port 40520 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:43.726327 sshd-session[12758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:43.730557 systemd-logind[1688]: New session 21 of user core. Aug 13 00:01:43.741708 systemd[1]: Started session-21.scope - Session 21 of User core. Aug 13 00:01:44.170790 sshd[12760]: Connection closed by 10.200.16.10 port 40520 Aug 13 00:01:44.171223 sshd-session[12758]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:44.177059 systemd[1]: sshd@18-10.200.8.15:22-10.200.16.10:40520.service: Deactivated successfully. Aug 13 00:01:44.179492 systemd[1]: session-21.scope: Deactivated successfully. Aug 13 00:01:44.180438 systemd-logind[1688]: Session 21 logged out. Waiting for processes to exit. Aug 13 00:01:44.182732 systemd-logind[1688]: Removed session 21. Aug 13 00:01:45.175395 setup-nvidia[2288]: \u000d Aug 13 00:01:45.175395 setup-nvidia[2288]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Aug 13 00:01:45.175395 setup-nvidia[2288]: frequently when this kernel module was built against the wrong or Aug 13 00:01:45.175395 setup-nvidia[2288]: improperly configured kernel sources, with a version of gcc that differs Aug 13 00:01:45.175395 setup-nvidia[2288]: from the one used to build the target kernel, or if another driver, such Aug 13 00:01:45.175395 setup-nvidia[2288]: as nouveau, is present and prevents the NVIDIA kernel module from Aug 13 00:01:45.175395 setup-nvidia[2288]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Aug 13 00:01:45.175395 setup-nvidia[2288]: installed in this system is supported by this NVIDIA Linux graphics Aug 13 00:01:45.175395 setup-nvidia[2288]: driver release. Aug 13 00:01:45.175395 setup-nvidia[2288]: \u000d Aug 13 00:01:45.175395 setup-nvidia[2288]: Please see the log entries 'Kernel module load error' and 'Kernel Aug 13 00:01:45.175395 setup-nvidia[2288]: messages' at the end of the file Aug 13 00:01:45.175395 setup-nvidia[2288]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Aug 13 00:01:45.175395 setup-nvidia[2288]: information. Aug 13 00:01:45.175395 setup-nvidia[2288]: \u000d Aug 13 00:01:45.190799 setup-nvidia[2288]: dmesg: read kernel buffer failed: Operation not permitted Aug 13 00:01:45.212964 setup-nvidia[2288]: \u000d Aug 13 00:01:45.212964 setup-nvidia[2288]: ERROR: Installation has failed. Please see the file Aug 13 00:01:45.212964 setup-nvidia[2288]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Aug 13 00:01:45.212964 setup-nvidia[2288]: details. You may find suggestions on fixing installation problems in Aug 13 00:01:45.212964 setup-nvidia[2288]: the README available on the Linux driver download page at Aug 13 00:01:45.212964 setup-nvidia[2288]: www.nvidia.com. Aug 13 00:01:45.212964 setup-nvidia[2288]: \u000d Aug 13 00:01:45.213279 setup-nvidia[2288]: Last 50 lines of nvidia-installer.log: Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Aug 13 00:01:45.214594 setup-nvidia[2288]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Aug 13 00:01:45.214594 setup-nvidia[2288]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Aug 13 00:01:45.214594 setup-nvidia[2288]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Aug 13 00:01:45.214594 setup-nvidia[2288]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Aug 13 00:01:45.214594 setup-nvidia[2288]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Aug 13 00:01:45.214594 setup-nvidia[2288]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Aug 13 00:01:45.214594 setup-nvidia[2288]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Aug 13 00:01:45.214594 setup-nvidia[2288]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Aug 13 00:01:45.214594 setup-nvidia[2288]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Aug 13 00:01:45.214594 setup-nvidia[2288]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Aug 13 00:01:45.214594 setup-nvidia[2288]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:01:45.214594 setup-nvidia[2288]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Aug 13 00:01:45.214594 setup-nvidia[2288]: make[2]: Leaving directory '/usr/lib/modules/6.6.100-flatcar/build' Aug 13 00:01:45.214594 setup-nvidia[2288]: make[1]: Leaving directory '/usr/lib/modules/6.6.100-flatcar/source' Aug 13 00:01:45.214594 setup-nvidia[2288]: -> done. Aug 13 00:01:45.214594 setup-nvidia[2288]: -> Kernel module compilation complete. Aug 13 00:01:45.214594 setup-nvidia[2288]: -> Unable to determine if Secure Boot is enabled: No such file or directory Aug 13 00:01:45.214594 setup-nvidia[2288]: 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. Aug 13 00:01:45.214594 setup-nvidia[2288]: \u000d Aug 13 00:01:45.215694 setup-nvidia[2288]: 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. Aug 13 00:01:45.215694 setup-nvidia[2288]: -> Kernel module load error: Operation not permitted Aug 13 00:01:45.215694 setup-nvidia[2288]: -> Kernel messages: Aug 13 00:01:45.215694 setup-nvidia[2288]: 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. Aug 13 00:01:54.281177 systemd[1]: Started sshd@19-10.200.8.15:22-10.200.16.10:52430.service - OpenSSH per-connection server daemon (10.200.16.10:52430). Aug 13 00:01:54.916950 sshd[12930]: Accepted publickey for core from 10.200.16.10 port 52430 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:54.918417 sshd-session[12930]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:54.922611 systemd-logind[1688]: New session 22 of user core. Aug 13 00:01:54.934745 systemd[1]: Started session-22.scope - Session 22 of User core. Aug 13 00:01:55.359947 sshd[12932]: Connection closed by 10.200.16.10 port 52430 Aug 13 00:01:55.360362 sshd-session[12930]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:55.363571 systemd[1]: sshd@19-10.200.8.15:22-10.200.16.10:52430.service: Deactivated successfully. Aug 13 00:01:55.365231 systemd[1]: session-22.scope: Deactivated successfully. Aug 13 00:01:55.365951 systemd-logind[1688]: Session 22 logged out. Waiting for processes to exit. Aug 13 00:01:55.366821 systemd-logind[1688]: Removed session 22. Aug 13 00:01:55.471260 systemd[1]: Started sshd@20-10.200.8.15:22-10.200.16.10:52442.service - OpenSSH per-connection server daemon (10.200.16.10:52442). Aug 13 00:01:56.100531 sshd[12938]: Accepted publickey for core from 10.200.16.10 port 52442 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:56.101880 sshd-session[12938]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:56.106167 systemd-logind[1688]: New session 23 of user core. Aug 13 00:01:56.115704 systemd[1]: Started session-23.scope - Session 23 of User core. Aug 13 00:01:56.544485 sshd[12940]: Connection closed by 10.200.16.10 port 52442 Aug 13 00:01:56.544903 sshd-session[12938]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:56.548115 systemd[1]: sshd@20-10.200.8.15:22-10.200.16.10:52442.service: Deactivated successfully. Aug 13 00:01:56.549731 systemd[1]: session-23.scope: Deactivated successfully. Aug 13 00:01:56.550375 systemd-logind[1688]: Session 23 logged out. Waiting for processes to exit. Aug 13 00:01:56.553529 systemd-logind[1688]: Removed session 23. Aug 13 00:01:56.696610 kernel: EXT4-fs (loop6p9): unmounting filesystem f88d1834-7666-4561-9c55-bbe37350df98. Aug 13 00:01:56.696719 setup-nvidia[2288]: Container flatcardevelopercontainer-4230.2.2.bin exited successfully. Aug 13 00:01:56.699909 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2.bin.mount: Deactivated successfully. Aug 13 00:01:56.762436 sudo[2276]: pam_unix(sudo:session): session closed for user root Aug 13 00:01:56.764157 systemd[1]: session-c2.scope: Deactivated successfully. Aug 13 00:01:56.764423 systemd[1]: session-c2.scope: Consumed 5min 15.695s CPU time, 882.6M memory peak. Aug 13 00:01:57.171218 setup-nvidia[1683]: /opt/nvidia / Aug 13 00:01:57.172206 setup-nvidia[1683]: / Aug 13 00:01:57.187547 setup-nvidia[12962]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:01:57.216609 kernel: IPMI message handler: version 39.2 Aug 13 00:01:57.219602 kernel: ipmi device interface Aug 13 00:01:57.220373 setup-nvidia[1683]: /opt/nvidia/current/usr/lib/modules/6.6.100-flatcar/video / Aug 13 00:01:57.275892 kernel: nvidia: loading out-of-tree module taints kernel. Aug 13 00:01:57.276027 kernel: nvidia: module license 'NVIDIA' taints kernel. Aug 13 00:01:57.276052 kernel: Disabling lock debugging due to kernel taint Aug 13 00:01:57.282958 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Aug 13 00:01:57.282991 kernel: nvidia: module license taints kernel. Aug 13 00:01:57.682611 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Aug 13 00:01:58.085606 kernel: Aug 13 00:01:58.085762 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Aug 13 00:01:58.292606 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Aug 13 00:01:58.314608 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Aug 13 00:01:58.333612 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Aug 13 00:01:58.378051 setup-nvidia[1683]: / Aug 13 00:01:58.378729 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Aug 13 00:02:00.087319 setup-nvidia[12992]: Wed Aug 13 00:02:00 2025 Aug 13 00:02:00.087319 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Aug 13 00:02:00.087319 setup-nvidia[12992]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Aug 13 00:02:00.087319 setup-nvidia[12992]: |-----------------------------------------+----------------------+----------------------+ Aug 13 00:02:00.087319 setup-nvidia[12992]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Aug 13 00:02:00.087319 setup-nvidia[12992]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Aug 13 00:02:00.087319 setup-nvidia[12992]: | | | MIG M. | Aug 13 00:02:00.087319 setup-nvidia[12992]: |=========================================+======================+======================| Aug 13 00:02:00.090941 setup-nvidia[12992]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Aug 13 00:02:00.090941 setup-nvidia[12992]: | N/A 28C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Aug 13 00:02:00.090941 setup-nvidia[12992]: | | | N/A | Aug 13 00:02:00.090941 setup-nvidia[12992]: +-----------------------------------------+----------------------+----------------------+ Aug 13 00:02:00.091047 setup-nvidia[12992]: Aug 13 00:02:00.091047 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Aug 13 00:02:00.091047 setup-nvidia[12992]: | Processes: | Aug 13 00:02:00.091047 setup-nvidia[12992]: | GPU GI CI PID Type Process name GPU Memory | Aug 13 00:02:00.091047 setup-nvidia[12992]: | ID ID Usage | Aug 13 00:02:00.091047 setup-nvidia[12992]: |=======================================================================================| Aug 13 00:02:00.091188 setup-nvidia[12992]: | No running processes found | Aug 13 00:02:00.091188 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Aug 13 00:02:00.465047 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 00:02:00.467707 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 00:02:00.468073 systemd[1]: Startup finished in 818ms (firmware) + 27.999s (loader) + 1.847s (kernel) + 11.696s (initrd) + 2min 47.179s (userspace) = 3min 29.541s. Aug 13 00:02:06.656650 systemd[1]: Started sshd@21-10.200.8.15:22-10.200.16.10:39648.service - OpenSSH per-connection server daemon (10.200.16.10:39648). Aug 13 00:02:06.940783 systemd[1]: Stopping user@0.service - User Manager for UID 0... Aug 13 00:02:06.941547 systemd[2279]: Activating special unit exit.target... Aug 13 00:02:06.941648 systemd[2279]: Stopped target default.target - Main User Target. Aug 13 00:02:06.941689 systemd[2279]: Stopped target basic.target - Basic System. Aug 13 00:02:06.941720 systemd[2279]: Stopped target paths.target - Paths. Aug 13 00:02:06.941750 systemd[2279]: Stopped target sockets.target - Sockets. Aug 13 00:02:06.941778 systemd[2279]: Stopped target timers.target - Timers. Aug 13 00:02:06.942135 systemd[2279]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:02:06.942353 systemd[2279]: Removed slice app.slice - User Application Slice. Aug 13 00:02:06.942386 systemd[2279]: Reached target shutdown.target - Shutdown. Aug 13 00:02:06.942536 systemd[2279]: Finished systemd-exit.service - Exit the Session. Aug 13 00:02:06.942577 systemd[2279]: Reached target exit.target - Exit the Session. Aug 13 00:02:06.956155 (sd-pam)[2280]: pam_unix(systemd-user:session): session closed for user root Aug 13 00:02:06.957624 systemd[1]: user@0.service: Deactivated successfully. Aug 13 00:02:06.958012 systemd[1]: Stopped user@0.service - User Manager for UID 0. Aug 13 00:02:06.967840 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:02:06.976761 systemd[1]: run-user-0.mount: Deactivated successfully. Aug 13 00:02:06.977682 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Aug 13 00:02:06.977868 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:02:06.979439 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Aug 13 00:02:06.979530 systemd[1]: user-0.slice: Consumed 5min 15.967s CPU time, 892.3M memory peak. Aug 13 00:02:06.979993 systemd-logind[1688]: Removed session c3. Aug 13 00:02:07.287378 sshd[12999]: Accepted publickey for core from 10.200.16.10 port 39648 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:07.292845 sshd-session[12999]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:07.297314 systemd-logind[1688]: New session 24 of user core. Aug 13 00:02:07.306715 systemd[1]: Started session-24.scope - Session 24 of User core. Aug 13 00:02:07.730509 sshd[13006]: Connection closed by 10.200.16.10 port 39648 Aug 13 00:02:07.730985 sshd-session[12999]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:07.734102 systemd[1]: sshd@21-10.200.8.15:22-10.200.16.10:39648.service: Deactivated successfully. Aug 13 00:02:07.735738 systemd[1]: session-24.scope: Deactivated successfully. Aug 13 00:02:07.736354 systemd-logind[1688]: Session 24 logged out. Waiting for processes to exit. Aug 13 00:02:07.737188 systemd-logind[1688]: Removed session 24. Aug 13 00:02:07.845188 systemd[1]: Started sshd@22-10.200.8.15:22-10.200.16.10:39650.service - OpenSSH per-connection server daemon (10.200.16.10:39650). Aug 13 00:02:08.473357 sshd[13012]: Accepted publickey for core from 10.200.16.10 port 39650 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:08.474737 sshd-session[13012]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:08.479636 systemd-logind[1688]: New session 25 of user core. Aug 13 00:02:08.490707 systemd[1]: Started session-25.scope - Session 25 of User core. Aug 13 00:02:08.913113 sshd[13014]: Connection closed by 10.200.16.10 port 39650 Aug 13 00:02:08.913518 sshd-session[13012]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:08.916737 systemd[1]: sshd@22-10.200.8.15:22-10.200.16.10:39650.service: Deactivated successfully. Aug 13 00:02:08.918337 systemd[1]: session-25.scope: Deactivated successfully. Aug 13 00:02:08.919014 systemd-logind[1688]: Session 25 logged out. Waiting for processes to exit. Aug 13 00:02:08.919794 systemd-logind[1688]: Removed session 25. Aug 13 00:02:09.024453 systemd[1]: Started sshd@23-10.200.8.15:22-10.200.16.10:39654.service - OpenSSH per-connection server daemon (10.200.16.10:39654). Aug 13 00:02:09.651575 sshd[13020]: Accepted publickey for core from 10.200.16.10 port 39654 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:09.652928 sshd-session[13020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:09.657068 systemd-logind[1688]: New session 26 of user core. Aug 13 00:02:09.668711 systemd[1]: Started session-26.scope - Session 26 of User core. Aug 13 00:02:10.096562 sshd[13022]: Connection closed by 10.200.16.10 port 39654 Aug 13 00:02:10.096999 sshd-session[13020]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:10.100215 systemd[1]: sshd@23-10.200.8.15:22-10.200.16.10:39654.service: Deactivated successfully. Aug 13 00:02:10.102011 systemd[1]: session-26.scope: Deactivated successfully. Aug 13 00:02:10.102650 systemd-logind[1688]: Session 26 logged out. Waiting for processes to exit. Aug 13 00:02:10.103447 systemd-logind[1688]: Removed session 26. Aug 13 00:02:10.208080 systemd[1]: Started sshd@24-10.200.8.15:22-10.200.16.10:45166.service - OpenSSH per-connection server daemon (10.200.16.10:45166). Aug 13 00:02:10.836378 sshd[13028]: Accepted publickey for core from 10.200.16.10 port 45166 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:10.837777 sshd-session[13028]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:10.841961 systemd-logind[1688]: New session 27 of user core. Aug 13 00:02:10.855721 systemd[1]: Started session-27.scope - Session 27 of User core. Aug 13 00:02:11.482489 sudo[13031]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Aug 13 00:02:11.482840 sudo[13031]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:11.496847 sudo[13031]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:11.606213 sshd[13030]: Connection closed by 10.200.16.10 port 45166 Aug 13 00:02:11.606622 sshd-session[13028]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:11.609771 systemd[1]: sshd@24-10.200.8.15:22-10.200.16.10:45166.service: Deactivated successfully. Aug 13 00:02:11.611358 systemd[1]: session-27.scope: Deactivated successfully. Aug 13 00:02:11.612018 systemd-logind[1688]: Session 27 logged out. Waiting for processes to exit. Aug 13 00:02:11.612823 systemd-logind[1688]: Removed session 27. Aug 13 00:02:11.717369 systemd[1]: Started sshd@25-10.200.8.15:22-10.200.16.10:45172.service - OpenSSH per-connection server daemon (10.200.16.10:45172). Aug 13 00:02:12.345090 sshd[13037]: Accepted publickey for core from 10.200.16.10 port 45172 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:12.348352 sshd-session[13037]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:12.352486 systemd-logind[1688]: New session 28 of user core. Aug 13 00:02:12.364707 systemd[1]: Started session-28.scope - Session 28 of User core. Aug 13 00:02:12.691034 sudo[13041]: 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 Aug 13 00:02:12.691340 sudo[13041]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:12.694609 sudo[13041]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:12.700009 sudo[13040]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Aug 13 00:02:12.700300 sudo[13040]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:12.714866 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 13 00:02:12.739967 augenrules[13063]: No rules Aug 13 00:02:12.741167 systemd[1]: audit-rules.service: Deactivated successfully. Aug 13 00:02:12.741416 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 13 00:02:12.742187 sudo[13040]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:12.851284 sshd[13039]: Connection closed by 10.200.16.10 port 45172 Aug 13 00:02:12.851645 sshd-session[13037]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:12.854922 systemd[1]: sshd@25-10.200.8.15:22-10.200.16.10:45172.service: Deactivated successfully. Aug 13 00:02:12.856499 systemd[1]: session-28.scope: Deactivated successfully. Aug 13 00:02:12.857271 systemd-logind[1688]: Session 28 logged out. Waiting for processes to exit. Aug 13 00:02:12.858046 systemd-logind[1688]: Removed session 28. Aug 13 00:02:12.966335 systemd[1]: Started sshd@26-10.200.8.15:22-10.200.16.10:45184.service - OpenSSH per-connection server daemon (10.200.16.10:45184). Aug 13 00:02:13.595613 sshd[13072]: Accepted publickey for core from 10.200.16.10 port 45184 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:13.596955 sshd-session[13072]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:13.601085 systemd-logind[1688]: New session 29 of user core. Aug 13 00:02:13.613714 systemd[1]: Started session-29.scope - Session 29 of User core. Aug 13 00:02:13.941387 sudo[13075]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Aug 13 00:02:13.941717 sudo[13075]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:13.948846 sudo[13075]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:14.048665 sshd[13074]: Connection closed by 10.200.16.10 port 45184 Aug 13 00:02:14.049015 sshd-session[13072]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:14.052066 systemd[1]: sshd@26-10.200.8.15:22-10.200.16.10:45184.service: Deactivated successfully. Aug 13 00:02:14.053721 systemd[1]: session-29.scope: Deactivated successfully. Aug 13 00:02:14.054349 systemd-logind[1688]: Session 29 logged out. Waiting for processes to exit. Aug 13 00:02:14.057543 systemd-logind[1688]: Removed session 29. Aug 13 00:02:14.160045 systemd[1]: Started sshd@27-10.200.8.15:22-10.200.16.10:45192.service - OpenSSH per-connection server daemon (10.200.16.10:45192). Aug 13 00:02:14.787737 sshd[13081]: Accepted publickey for core from 10.200.16.10 port 45192 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:14.789070 sshd-session[13081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:14.793239 systemd-logind[1688]: New session 30 of user core. Aug 13 00:02:14.805702 systemd[1]: Started session-30.scope - Session 30 of User core. Aug 13 00:02:15.135430 sudo[13084]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Aug 13 00:02:15.135753 sudo[13084]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:18.460920 sudo[13084]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:18.560940 sshd[13083]: Connection closed by 10.200.16.10 port 45192 Aug 13 00:02:18.561232 sshd-session[13081]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:18.564521 systemd[1]: sshd@27-10.200.8.15:22-10.200.16.10:45192.service: Deactivated successfully. Aug 13 00:02:18.566205 systemd[1]: session-30.scope: Deactivated successfully. Aug 13 00:02:18.567533 systemd-logind[1688]: Session 30 logged out. Waiting for processes to exit. Aug 13 00:02:18.568340 systemd-logind[1688]: Removed session 30. Aug 13 00:02:18.672909 systemd[1]: Started sshd@28-10.200.8.15:22-10.200.16.10:45204.service - OpenSSH per-connection server daemon (10.200.16.10:45204). Aug 13 00:02:19.301697 sshd[13090]: Accepted publickey for core from 10.200.16.10 port 45204 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:19.303050 sshd-session[13090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:19.307194 systemd-logind[1688]: New session 31 of user core. Aug 13 00:02:19.316721 systemd[1]: Started session-31.scope - Session 31 of User core. Aug 13 00:02:19.797196 sshd[13092]: Connection closed by 10.200.16.10 port 45204 Aug 13 00:02:19.797619 sshd-session[13090]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:19.800783 systemd[1]: sshd@28-10.200.8.15:22-10.200.16.10:45204.service: Deactivated successfully. Aug 13 00:02:19.802431 systemd[1]: session-31.scope: Deactivated successfully. Aug 13 00:02:19.803071 systemd-logind[1688]: Session 31 logged out. Waiting for processes to exit. Aug 13 00:02:19.803843 systemd-logind[1688]: Removed session 31. Aug 13 00:02:19.907914 systemd[1]: Started sshd@29-10.200.8.15:22-10.200.16.10:45210.service - OpenSSH per-connection server daemon (10.200.16.10:45210). Aug 13 00:02:20.536176 sshd[13107]: Accepted publickey for core from 10.200.16.10 port 45210 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:20.537560 sshd-session[13107]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:20.541951 systemd-logind[1688]: New session 32 of user core. Aug 13 00:02:20.553703 systemd[1]: Started session-32.scope - Session 32 of User core. Aug 13 00:02:21.723807 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 13 00:02:21.723971 (dockerd)[13129]: 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 Aug 13 00:02:24.251463 dockerd[13129]: time="2025-08-13T00:02:24.251400714Z" level=info msg="Starting up" Aug 13 00:02:24.866720 dockerd[13129]: time="2025-08-13T00:02:24.866681788Z" level=info msg="Loading containers: start." Aug 13 00:02:25.063607 kernel: Initializing XFRM netlink socket Aug 13 00:02:25.120646 systemd-networkd[1425]: docker0: Link UP Aug 13 00:02:25.176868 dockerd[13129]: time="2025-08-13T00:02:25.176827045Z" level=info msg="Loading containers: done." Aug 13 00:02:25.190991 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1219532246-merged.mount: Deactivated successfully. Aug 13 00:02:25.207954 dockerd[13129]: time="2025-08-13T00:02:25.207908749Z" 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 Aug 13 00:02:25.208041 dockerd[13129]: time="2025-08-13T00:02:25.207988046Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Aug 13 00:02:25.208118 dockerd[13129]: time="2025-08-13T00:02:25.208094243Z" level=info msg="Daemon has completed initialization" Aug 13 00:02:25.277270 dockerd[13129]: time="2025-08-13T00:02:25.277225326Z" level=info msg="API listen on /run/docker.sock" Aug 13 00:02:25.277374 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 13 00:02:26.354488 systemd[1]: var-lib-docker-overlay2-3f9f0e89cd670c3272d9fa141b69b33fc802cbe0689381ed1690cbff2961c356\x2dinit-merged.mount: Deactivated successfully. Aug 13 00:02:26.440622 kernel: docker0: port 1(vethf9488ee) entered blocking state Aug 13 00:02:26.440740 kernel: docker0: port 1(vethf9488ee) entered disabled state Aug 13 00:02:26.440766 kernel: vethf9488ee: entered allmulticast mode Aug 13 00:02:26.445590 kernel: vethf9488ee: entered promiscuous mode Aug 13 00:02:26.446213 systemd-networkd[1425]: vethf9488ee: Link UP Aug 13 00:02:27.458826 containerd[1722]: time="2025-08-13T00:02:27.458725488Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:02:27.458826 containerd[1722]: time="2025-08-13T00:02:27.458792086Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:02:27.458826 containerd[1722]: time="2025-08-13T00:02:27.458805185Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:02:27.459303 containerd[1722]: time="2025-08-13T00:02:27.458884383Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:02:27.482713 systemd[1]: Started docker-d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d.scope - libcontainer container d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d. Aug 13 00:02:27.540614 kernel: eth0: renamed from veth7dff3d0 Aug 13 00:02:27.554837 kernel: docker0: port 1(vethf9488ee) entered blocking state Aug 13 00:02:27.554886 kernel: docker0: port 1(vethf9488ee) entered forwarding state Aug 13 00:02:27.554928 systemd-networkd[1425]: vethf9488ee: Gained carrier Aug 13 00:02:27.555189 systemd-networkd[1425]: docker0: Gained carrier Aug 13 00:02:28.620816 systemd-networkd[1425]: docker0: Gained IPv6LL Aug 13 00:02:29.260717 systemd-networkd[1425]: vethf9488ee: Gained IPv6LL Aug 13 00:02:30.831322 systemd[1]: docker-d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d.scope: Deactivated successfully. Aug 13 00:02:30.843557 containerd[1722]: time="2025-08-13T00:02:30.843497013Z" level=info msg="shim disconnected" id=d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d namespace=moby Aug 13 00:02:30.843897 dockerd[13129]: time="2025-08-13T00:02:30.843513513Z" level=info msg="ignoring event" container=d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Aug 13 00:02:30.844145 containerd[1722]: time="2025-08-13T00:02:30.844114794Z" level=warning msg="cleaning up after shim disconnected" id=d330962b9889c0d0799d889d16b195507c4d50d1e00d18568bf47446f8c80c2d namespace=moby Aug 13 00:02:30.844145 containerd[1722]: time="2025-08-13T00:02:30.844138293Z" level=info msg="cleaning up dead shim" namespace=moby Aug 13 00:02:30.879922 systemd-networkd[1425]: vethf9488ee: Lost carrier Aug 13 00:02:30.880604 kernel: docker0: port 1(vethf9488ee) entered disabled state Aug 13 00:02:30.880646 kernel: veth7dff3d0: renamed from eth0 Aug 13 00:02:30.916640 systemd-networkd[1425]: vethf9488ee: Link DOWN Aug 13 00:02:30.920601 kernel: docker0: port 1(vethf9488ee) entered disabled state Aug 13 00:02:30.920657 kernel: vethf9488ee (unregistering): left allmulticast mode Aug 13 00:02:30.926628 kernel: vethf9488ee (unregistering): left promiscuous mode Aug 13 00:02:30.929893 kernel: docker0: port 1(vethf9488ee) entered disabled state Aug 13 00:02:30.960332 systemd[1]: run-docker-netns-918dfa214bf2.mount: Deactivated successfully. Aug 13 00:02:30.965085 systemd[1]: var-lib-docker-overlay2-3f9f0e89cd670c3272d9fa141b69b33fc802cbe0689381ed1690cbff2961c356-merged.mount: Deactivated successfully. Aug 13 00:02:31.118151 sshd[13109]: Connection closed by 10.200.16.10 port 45210 Aug 13 00:02:31.118492 sshd-session[13107]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:31.121878 systemd[1]: sshd@29-10.200.8.15:22-10.200.16.10:45210.service: Deactivated successfully. Aug 13 00:02:31.123508 systemd[1]: session-32.scope: Deactivated successfully. Aug 13 00:02:31.124178 systemd-logind[1688]: Session 32 logged out. Waiting for processes to exit. Aug 13 00:02:31.124973 systemd-logind[1688]: Removed session 32. Aug 13 00:02:31.227904 systemd[1]: Started sshd@30-10.200.8.15:22-10.200.16.10:50754.service - OpenSSH per-connection server daemon (10.200.16.10:50754). Aug 13 00:02:31.855917 sshd[13400]: Accepted publickey for core from 10.200.16.10 port 50754 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:31.857293 sshd-session[13400]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:31.861455 systemd-logind[1688]: New session 33 of user core. Aug 13 00:02:31.869708 systemd[1]: Started session-33.scope - Session 33 of User core. Aug 13 00:02:31.884773 systemd-networkd[1425]: docker0: Lost carrier Aug 13 00:02:32.255431 systemd[1]: var-lib-docker-overlay2-11cc766db56764332b9e3ed0007996457354627331bae3c9672789bff89e32d6\x2dinit-merged.mount: Deactivated successfully. Aug 13 00:02:32.366213 kernel: docker0: port 1(veth2e4c515) entered blocking state Aug 13 00:02:32.366316 kernel: docker0: port 1(veth2e4c515) entered disabled state Aug 13 00:02:32.369077 kernel: veth2e4c515: entered allmulticast mode Aug 13 00:02:32.371878 kernel: veth2e4c515: entered promiscuous mode Aug 13 00:02:32.375317 kernel: docker0: port 1(veth2e4c515) entered blocking state Aug 13 00:02:32.378742 kernel: docker0: port 1(veth2e4c515) entered forwarding state Aug 13 00:02:32.379606 kernel: docker0: port 1(veth2e4c515) entered disabled state Aug 13 00:02:32.379612 systemd-networkd[1425]: veth2e4c515: Link UP Aug 13 00:02:32.457772 containerd[1722]: time="2025-08-13T00:02:32.457681334Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:02:32.457772 containerd[1722]: time="2025-08-13T00:02:32.457730533Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:02:32.457772 containerd[1722]: time="2025-08-13T00:02:32.457741932Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:02:32.458311 containerd[1722]: time="2025-08-13T00:02:32.457824430Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:02:32.482731 systemd[1]: Started docker-542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287.scope - libcontainer container 542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287. Aug 13 00:02:32.536609 kernel: eth0: renamed from veth2a0fd14 Aug 13 00:02:32.553767 kernel: docker0: port 1(veth2e4c515) entered blocking state Aug 13 00:02:32.553837 kernel: docker0: port 1(veth2e4c515) entered forwarding state Aug 13 00:02:32.554172 systemd-networkd[1425]: veth2e4c515: Gained carrier Aug 13 00:02:32.554427 systemd-networkd[1425]: docker0: Gained carrier Aug 13 00:02:32.595819 systemd[1]: docker-542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287.scope: Deactivated successfully. Aug 13 00:02:32.616404 dockerd[13129]: time="2025-08-13T00:02:32.616269575Z" level=info msg="ignoring event" container=542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Aug 13 00:02:32.616720 containerd[1722]: time="2025-08-13T00:02:32.616384771Z" level=info msg="shim disconnected" id=542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287 namespace=moby Aug 13 00:02:32.616720 containerd[1722]: time="2025-08-13T00:02:32.616527867Z" level=warning msg="cleaning up after shim disconnected" id=542aefbaf72d8e8ac9b1bc663b11590d35d6692f75b52043d6a73aa4ec4b4287 namespace=moby Aug 13 00:02:32.616720 containerd[1722]: time="2025-08-13T00:02:32.616537367Z" level=info msg="cleaning up dead shim" namespace=moby Aug 13 00:02:32.645606 kernel: docker0: port 1(veth2e4c515) entered disabled state Aug 13 00:02:32.645681 kernel: veth2a0fd14: renamed from eth0 Aug 13 00:02:32.645699 systemd-networkd[1425]: veth2e4c515: Lost carrier Aug 13 00:02:32.679073 systemd-networkd[1425]: veth2e4c515: Link DOWN Aug 13 00:02:32.682600 kernel: docker0: port 1(veth2e4c515) entered disabled state Aug 13 00:02:32.689089 kernel: veth2e4c515 (unregistering): left allmulticast mode Aug 13 00:02:32.689141 kernel: veth2e4c515 (unregistering): left promiscuous mode Aug 13 00:02:32.692383 kernel: docker0: port 1(veth2e4c515) entered disabled state Aug 13 00:02:32.842922 sshd[13402]: Connection closed by 10.200.16.10 port 50754 Aug 13 00:02:32.843236 sshd-session[13400]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:32.846570 systemd[1]: sshd@30-10.200.8.15:22-10.200.16.10:50754.service: Deactivated successfully. Aug 13 00:02:32.848248 systemd[1]: session-33.scope: Deactivated successfully. Aug 13 00:02:32.848924 systemd-logind[1688]: Session 33 logged out. Waiting for processes to exit. Aug 13 00:02:32.849713 systemd-logind[1688]: Removed session 33. Aug 13 00:02:32.892802 systemd-networkd[1425]: docker0: Lost carrier Aug 13 00:02:32.954049 systemd[1]: Started sshd@31-10.200.8.15:22-10.200.16.10:50766.service - OpenSSH per-connection server daemon (10.200.16.10:50766). Aug 13 00:02:33.252571 systemd[1]: var-lib-docker-overlay2-11cc766db56764332b9e3ed0007996457354627331bae3c9672789bff89e32d6-merged.mount: Deactivated successfully. Aug 13 00:02:33.583476 sshd[13507]: Accepted publickey for core from 10.200.16.10 port 50766 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:02:33.584796 sshd-session[13507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:33.588948 systemd-logind[1688]: New session 34 of user core. Aug 13 00:02:33.610714 systemd[1]: Started session-34.scope - Session 34 of User core. Aug 13 00:02:33.982304 ntpdate[13520]: ntpdate 4.2.8p17@1.4004-o Tue Aug 12 20:59:44 UTC 2025 (1) Aug 13 00:02:40.700462 ntpdate[13520]: adjust time server 193.1.8.98 offset -0.003320 sec Aug 13 00:02:40.801647 sshd[13509]: Connection closed by 10.200.16.10 port 50766 Aug 13 00:02:40.802071 sshd-session[13507]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:40.805341 systemd[1]: sshd@31-10.200.8.15:22-10.200.16.10:50766.service: Deactivated successfully. Aug 13 00:02:40.806981 systemd[1]: session-34.scope: Deactivated successfully. Aug 13 00:02:40.807616 systemd-logind[1688]: Session 34 logged out. Waiting for processes to exit. Aug 13 00:02:40.808390 systemd-logind[1688]: Removed session 34.