Jul 7 00:13:01.214060 kernel: Linux version 6.12.35-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.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Jul 6 21:58:13 -00 2025 Jul 7 00:13:01.214088 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=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.214098 kernel: BIOS-provided physical RAM map: Jul 7 00:13:01.214105 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 7 00:13:01.214111 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 7 00:13:01.214118 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 7 00:13:01.214126 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 7 00:13:01.214135 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 7 00:13:01.214142 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 7 00:13:01.214149 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 7 00:13:01.214156 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 7 00:13:01.214163 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 7 00:13:01.214170 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 7 00:13:01.214177 kernel: NX (Execute Disable) protection: active Jul 7 00:13:01.214187 kernel: APIC: Static calls initialized Jul 7 00:13:01.214195 kernel: efi: EFI v2.7 by Microsoft Jul 7 00:13:01.214203 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 7 00:13:01.214211 kernel: random: crng init done Jul 7 00:13:01.214218 kernel: secureboot: Secure boot disabled Jul 7 00:13:01.214226 kernel: SMBIOS 3.1.0 present. Jul 7 00:13:01.214234 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 7 00:13:01.214241 kernel: DMI: Memory slots populated: 3/3 Jul 7 00:13:01.214249 kernel: Hypervisor detected: Microsoft Hyper-V Jul 7 00:13:01.214257 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 7 00:13:01.214266 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 7 00:13:01.214274 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 7 00:13:01.214282 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 00:13:01.214289 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 00:13:01.214298 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 7 00:13:01.214306 kernel: tsc: Detected 2593.994 MHz processor Jul 7 00:13:01.214314 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 7 00:13:01.214322 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 7 00:13:01.214329 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 7 00:13:01.214340 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 7 00:13:01.214347 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 7 00:13:01.214355 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 7 00:13:01.214363 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 7 00:13:01.214370 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 7 00:13:01.214378 kernel: Using GB pages for direct mapping Jul 7 00:13:01.214386 kernel: ACPI: Early table checksum verification disabled Jul 7 00:13:01.214397 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 7 00:13:01.214408 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214416 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214424 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 7 00:13:01.214432 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 7 00:13:01.214440 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214449 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214459 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214467 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214476 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214484 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214492 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.214500 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 7 00:13:01.214508 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 7 00:13:01.214516 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 7 00:13:01.214527 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 7 00:13:01.214535 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 7 00:13:01.214543 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 7 00:13:01.214551 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 7 00:13:01.214559 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 7 00:13:01.214567 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 7 00:13:01.214576 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 7 00:13:01.214584 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 7 00:13:01.214592 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 7 00:13:01.214602 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 7 00:13:01.214610 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 7 00:13:01.214618 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 7 00:13:01.214626 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 7 00:13:01.214635 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 7 00:13:01.214663 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 7 00:13:01.214671 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 7 00:13:01.214680 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 7 00:13:01.214688 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 7 00:13:01.214700 kernel: Zone ranges: Jul 7 00:13:01.214708 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 7 00:13:01.214716 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 7 00:13:01.214724 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 7 00:13:01.214732 kernel: Device empty Jul 7 00:13:01.214740 kernel: Movable zone start for each node Jul 7 00:13:01.214748 kernel: Early memory node ranges Jul 7 00:13:01.214757 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 7 00:13:01.214765 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 7 00:13:01.214775 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 7 00:13:01.214783 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 7 00:13:01.214792 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 7 00:13:01.214800 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 7 00:13:01.214808 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 7 00:13:01.214816 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 7 00:13:01.214824 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 7 00:13:01.214832 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 7 00:13:01.214840 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 7 00:13:01.214851 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 7 00:13:01.214859 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 7 00:13:01.214867 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 7 00:13:01.214875 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 7 00:13:01.214883 kernel: CPU topo: Max. logical packages: 1 Jul 7 00:13:01.214891 kernel: CPU topo: Max. logical dies: 1 Jul 7 00:13:01.214899 kernel: CPU topo: Max. dies per package: 1 Jul 7 00:13:01.214907 kernel: CPU topo: Max. threads per core: 1 Jul 7 00:13:01.214916 kernel: CPU topo: Num. cores per package: 6 Jul 7 00:13:01.214926 kernel: CPU topo: Num. threads per package: 6 Jul 7 00:13:01.214934 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 7 00:13:01.214942 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 7 00:13:01.214950 kernel: Booting paravirtualized kernel on Hyper-V Jul 7 00:13:01.214959 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 7 00:13:01.214967 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 7 00:13:01.214975 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 7 00:13:01.214983 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 7 00:13:01.214991 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 7 00:13:01.215001 kernel: Hyper-V: PV spinlocks enabled Jul 7 00:13:01.215010 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 7 00:13:01.215019 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=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.215027 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 7 00:13:01.215036 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 7 00:13:01.215044 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 7 00:13:01.215052 kernel: Fallback order for Node 0: 0 Jul 7 00:13:01.215060 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 7 00:13:01.215071 kernel: Policy zone: Normal Jul 7 00:13:01.215086 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 7 00:13:01.215095 kernel: software IO TLB: area num 8. Jul 7 00:13:01.215106 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 7 00:13:01.215114 kernel: Kernel/User page tables isolation: enabled Jul 7 00:13:01.215123 kernel: ftrace: allocating 40095 entries in 157 pages Jul 7 00:13:01.215132 kernel: ftrace: allocated 157 pages with 5 groups Jul 7 00:13:01.215140 kernel: Dynamic Preempt: voluntary Jul 7 00:13:01.215149 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 7 00:13:01.215158 kernel: rcu: RCU event tracing is enabled. Jul 7 00:13:01.215167 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 7 00:13:01.215175 kernel: Trampoline variant of Tasks RCU enabled. Jul 7 00:13:01.215186 kernel: Rude variant of Tasks RCU enabled. Jul 7 00:13:01.215195 kernel: Tracing variant of Tasks RCU enabled. Jul 7 00:13:01.215204 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 7 00:13:01.215212 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 7 00:13:01.215221 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.215232 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.215241 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.215250 kernel: Using NULL legacy PIC Jul 7 00:13:01.215258 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 7 00:13:01.215267 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 7 00:13:01.215276 kernel: Console: colour dummy device 80x25 Jul 7 00:13:01.215284 kernel: printk: legacy console [tty1] enabled Jul 7 00:13:01.215293 kernel: printk: legacy console [ttyS0] enabled Jul 7 00:13:01.215301 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 7 00:13:01.215313 kernel: ACPI: Core revision 20240827 Jul 7 00:13:01.215321 kernel: Failed to register legacy timer interrupt Jul 7 00:13:01.215330 kernel: APIC: Switch to symmetric I/O mode setup Jul 7 00:13:01.215338 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 7 00:13:01.215347 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 7 00:13:01.215355 kernel: Hyper-V: Using IPI hypercalls Jul 7 00:13:01.215364 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 7 00:13:01.215373 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 7 00:13:01.215381 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 7 00:13:01.215392 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 7 00:13:01.215401 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 7 00:13:01.215410 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 7 00:13:01.215418 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 7 00:13:01.215427 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 7 00:13:01.215435 kernel: APIC: read() replaced with hv_apic_read() Jul 7 00:13:01.215444 kernel: APIC: write() replaced with hv_apic_write() Jul 7 00:13:01.215453 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 7 00:13:01.215461 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 7 00:13:01.215472 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593994) Jul 7 00:13:01.215481 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 7 00:13:01.215490 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 7 00:13:01.215498 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 7 00:13:01.215507 kernel: Spectre V2 : Mitigation: Retpolines Jul 7 00:13:01.215515 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 7 00:13:01.215524 kernel: Speculative Store Bypass: Vulnerable Jul 7 00:13:01.215532 kernel: MDS: Mitigation: Clear CPU buffers Jul 7 00:13:01.215540 kernel: TAA: Mitigation: Clear CPU buffers Jul 7 00:13:01.215549 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 7 00:13:01.215560 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 7 00:13:01.215568 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 7 00:13:01.215576 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 7 00:13:01.215585 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 7 00:13:01.215593 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 7 00:13:01.215602 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 7 00:13:01.215611 kernel: Freeing SMP alternatives memory: 32K Jul 7 00:13:01.215619 kernel: pid_max: default: 32768 minimum: 301 Jul 7 00:13:01.215627 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 7 00:13:01.215636 kernel: landlock: Up and running. Jul 7 00:13:01.215654 kernel: SELinux: Initializing. Jul 7 00:13:01.215665 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.215674 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.215683 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 7 00:13:01.215691 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 7 00:13:01.215700 kernel: signal: max sigframe size: 1776 Jul 7 00:13:01.215709 kernel: rcu: Hierarchical SRCU implementation. Jul 7 00:13:01.215717 kernel: rcu: Max phase no-delay instances is 400. Jul 7 00:13:01.215727 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 7 00:13:01.215735 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 7 00:13:01.215744 kernel: smp: Bringing up secondary CPUs ... Jul 7 00:13:01.215755 kernel: smpboot: x86: Booting SMP configuration: Jul 7 00:13:01.215763 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 7 00:13:01.215772 kernel: smp: Brought up 1 node, 6 CPUs Jul 7 00:13:01.215781 kernel: smpboot: Total of 6 processors activated (31127.92 BogoMIPS) Jul 7 00:13:01.215790 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54432K init, 2536K bss, 2090000K reserved, 0K cma-reserved) Jul 7 00:13:01.215798 kernel: devtmpfs: initialized Jul 7 00:13:01.215807 kernel: x86/mm: Memory block size: 1024MB Jul 7 00:13:01.215816 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 7 00:13:01.215824 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 7 00:13:01.215835 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 7 00:13:01.215844 kernel: pinctrl core: initialized pinctrl subsystem Jul 7 00:13:01.215853 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 7 00:13:01.215861 kernel: audit: initializing netlink subsys (disabled) Jul 7 00:13:01.215870 kernel: audit: type=2000 audit(1751847177.028:1): state=initialized audit_enabled=0 res=1 Jul 7 00:13:01.215878 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 7 00:13:01.215887 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 7 00:13:01.215896 kernel: cpuidle: using governor menu Jul 7 00:13:01.215904 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 7 00:13:01.215915 kernel: dca service started, version 1.12.1 Jul 7 00:13:01.215924 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 7 00:13:01.215932 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 7 00:13:01.215941 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 7 00:13:01.215950 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 7 00:13:01.215958 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 7 00:13:01.215967 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 7 00:13:01.215975 kernel: ACPI: Added _OSI(Module Device) Jul 7 00:13:01.215984 kernel: ACPI: Added _OSI(Processor Device) Jul 7 00:13:01.215995 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 7 00:13:01.216004 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 7 00:13:01.216012 kernel: ACPI: Interpreter enabled Jul 7 00:13:01.216021 kernel: ACPI: PM: (supports S0 S5) Jul 7 00:13:01.216029 kernel: ACPI: Using IOAPIC for interrupt routing Jul 7 00:13:01.216038 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 7 00:13:01.216047 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 7 00:13:01.216055 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 7 00:13:01.216064 kernel: iommu: Default domain type: Translated Jul 7 00:13:01.216075 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 7 00:13:01.216083 kernel: efivars: Registered efivars operations Jul 7 00:13:01.216092 kernel: PCI: Using ACPI for IRQ routing Jul 7 00:13:01.216100 kernel: PCI: System does not support PCI Jul 7 00:13:01.216109 kernel: vgaarb: loaded Jul 7 00:13:01.216118 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 7 00:13:01.216127 kernel: VFS: Disk quotas dquot_6.6.0 Jul 7 00:13:01.216135 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 7 00:13:01.216144 kernel: pnp: PnP ACPI init Jul 7 00:13:01.216155 kernel: pnp: PnP ACPI: found 3 devices Jul 7 00:13:01.216163 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 7 00:13:01.216172 kernel: NET: Registered PF_INET protocol family Jul 7 00:13:01.216181 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.216189 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.216198 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 7 00:13:01.216207 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 7 00:13:01.216215 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.216226 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 7 00:13:01.216235 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.216243 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.216252 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 7 00:13:01.216261 kernel: NET: Registered PF_XDP protocol family Jul 7 00:13:01.216269 kernel: PCI: CLS 0 bytes, default 64 Jul 7 00:13:01.216278 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 7 00:13:01.216286 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jul 7 00:13:01.216295 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 7 00:13:01.216306 kernel: Initialise system trusted keyrings Jul 7 00:13:01.216314 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 7 00:13:01.216323 kernel: Key type asymmetric registered Jul 7 00:13:01.216331 kernel: Asymmetric key parser 'x509' registered Jul 7 00:13:01.216340 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 7 00:13:01.216348 kernel: io scheduler mq-deadline registered Jul 7 00:13:01.216357 kernel: io scheduler kyber registered Jul 7 00:13:01.216365 kernel: io scheduler bfq registered Jul 7 00:13:01.216374 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 7 00:13:01.216385 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 7 00:13:01.216394 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 7 00:13:01.216402 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 7 00:13:01.216411 kernel: i8042: PNP: No PS/2 controller found. Jul 7 00:13:01.216558 kernel: rtc_cmos 00:02: registered as rtc0 Jul 7 00:13:01.216719 kernel: rtc_cmos 00:02: setting system clock to 2025-07-07T00:13:00 UTC (1751847180) Jul 7 00:13:01.216820 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 7 00:13:01.216833 kernel: intel_pstate: CPU model not supported Jul 7 00:13:01.216847 kernel: efifb: probing for efifb Jul 7 00:13:01.216856 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 7 00:13:01.216864 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 7 00:13:01.216873 kernel: efifb: scrolling: redraw Jul 7 00:13:01.216881 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 7 00:13:01.216890 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 00:13:01.216899 kernel: fb0: EFI VGA frame buffer device Jul 7 00:13:01.216907 kernel: pstore: Using crash dump compression: deflate Jul 7 00:13:01.216916 kernel: pstore: Registered efi_pstore as persistent store backend Jul 7 00:13:01.216927 kernel: NET: Registered PF_INET6 protocol family Jul 7 00:13:01.216936 kernel: Segment Routing with IPv6 Jul 7 00:13:01.216944 kernel: In-situ OAM (IOAM) with IPv6 Jul 7 00:13:01.216953 kernel: NET: Registered PF_PACKET protocol family Jul 7 00:13:01.216962 kernel: Key type dns_resolver registered Jul 7 00:13:01.216970 kernel: IPI shorthand broadcast: enabled Jul 7 00:13:01.216979 kernel: sched_clock: Marking stable (3672005300, 122963800)->(4137524600, -342555500) Jul 7 00:13:01.216987 kernel: registered taskstats version 1 Jul 7 00:13:01.216996 kernel: Loading compiled-in X.509 certificates Jul 7 00:13:01.217007 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.35-flatcar: 025c05e23c9778f7a70ff09fb369dd949499fb06' Jul 7 00:13:01.217015 kernel: Demotion targets for Node 0: null Jul 7 00:13:01.217024 kernel: Key type .fscrypt registered Jul 7 00:13:01.217032 kernel: Key type fscrypt-provisioning registered Jul 7 00:13:01.217041 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 7 00:13:01.217050 kernel: ima: Allocated hash algorithm: sha1 Jul 7 00:13:01.217058 kernel: ima: No architecture policies found Jul 7 00:13:01.217067 kernel: clk: Disabling unused clocks Jul 7 00:13:01.217075 kernel: Warning: unable to open an initial console. Jul 7 00:13:01.217087 kernel: Freeing unused kernel image (initmem) memory: 54432K Jul 7 00:13:01.217096 kernel: Write protecting the kernel read-only data: 24576k Jul 7 00:13:01.217104 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 7 00:13:01.217113 kernel: Run /init as init process Jul 7 00:13:01.217122 kernel: with arguments: Jul 7 00:13:01.217130 kernel: /init Jul 7 00:13:01.217139 kernel: with environment: Jul 7 00:13:01.217147 kernel: HOME=/ Jul 7 00:13:01.217155 kernel: TERM=linux Jul 7 00:13:01.217166 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 7 00:13:01.217176 systemd[1]: Successfully made /usr/ read-only. Jul 7 00:13:01.217188 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) Jul 7 00:13:01.217197 systemd[1]: Detected virtualization microsoft. Jul 7 00:13:01.217206 systemd[1]: Detected architecture x86-64. Jul 7 00:13:01.217215 systemd[1]: Running in initrd. Jul 7 00:13:01.217224 systemd[1]: No hostname configured, using default hostname. Jul 7 00:13:01.217236 systemd[1]: Hostname set to . Jul 7 00:13:01.217245 systemd[1]: Initializing machine ID from random generator. Jul 7 00:13:01.217254 systemd[1]: Queued start job for default target initrd.target. Jul 7 00:13:01.217263 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:01.217272 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:01.217282 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 7 00:13:01.217291 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 00:13:01.217300 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 7 00:13:01.217312 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 7 00:13:01.217322 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 7 00:13:01.217332 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 7 00:13:01.217341 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:01.217350 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:01.217359 systemd[1]: Reached target paths.target - Path Units. Jul 7 00:13:01.217368 systemd[1]: Reached target slices.target - Slice Units. Jul 7 00:13:01.217380 systemd[1]: Reached target swap.target - Swaps. Jul 7 00:13:01.217389 systemd[1]: Reached target timers.target - Timer Units. Jul 7 00:13:01.217398 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 00:13:01.217408 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 00:13:01.217417 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 7 00:13:01.217426 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 7 00:13:01.217435 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:01.217444 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:01.217454 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:01.217465 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 00:13:01.217474 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 7 00:13:01.217483 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 00:13:01.217492 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 7 00:13:01.217502 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Jul 7 00:13:01.217511 systemd[1]: Starting systemd-fsck-usr.service... Jul 7 00:13:01.217520 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 00:13:01.217529 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 00:13:01.217561 systemd-journald[231]: Collecting audit messages is disabled. Jul 7 00:13:01.217584 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:01.217594 systemd-journald[231]: Journal started Jul 7 00:13:01.217617 systemd-journald[231]: Runtime Journal (/run/log/journal/249ff26c17f24e31b61b254a2b488af6) is 8M, max 2.2G, 2.1G free. Jul 7 00:13:01.221759 systemd-modules-load[233]: Inserted module 'overlay' Jul 7 00:13:01.227250 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 00:13:01.227574 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 7 00:13:01.228090 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:01.228822 systemd[1]: Finished systemd-fsck-usr.service. Jul 7 00:13:01.259157 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 00:13:01.269993 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 00:13:01.272020 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:01.281461 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 00:13:01.301834 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 7 00:13:01.310596 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 7 00:13:01.308227 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 7 00:13:01.319146 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 00:13:01.337980 systemd-modules-load[233]: Inserted module 'br_netfilter' Jul 7 00:13:01.341635 kernel: Bridge firewalling registered Jul 7 00:13:01.343760 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:01.345410 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:01.347284 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 00:13:01.360242 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 00:13:01.362762 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 7 00:13:01.383334 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:01.388823 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:01.402521 dracut-cmdline[269]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.400047 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 00:13:01.472802 systemd-resolved[290]: Positive Trust Anchors: Jul 7 00:13:01.472819 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 00:13:01.472849 systemd-resolved[290]: 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 Jul 7 00:13:01.475800 systemd-resolved[290]: Defaulting to hostname 'linux'. Jul 7 00:13:01.476805 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 00:13:01.486394 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:01.556666 kernel: SCSI subsystem initialized Jul 7 00:13:01.566656 kernel: Loading iSCSI transport class v2.0-870. Jul 7 00:13:01.579657 kernel: iscsi: registered transport (tcp) Jul 7 00:13:01.602176 kernel: iscsi: registered transport (qla4xxx) Jul 7 00:13:01.602203 kernel: QLogic iSCSI HBA Driver Jul 7 00:13:01.619889 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 00:13:01.645991 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:01.648487 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 00:13:01.704802 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 7 00:13:01.707751 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 7 00:13:01.784664 kernel: raid6: avx2x4 gen() 30540 MB/s Jul 7 00:13:01.803653 kernel: raid6: avx2x2 gen() 31067 MB/s Jul 7 00:13:01.825115 kernel: raid6: avx2x1 gen() 26983 MB/s Jul 7 00:13:01.825145 kernel: raid6: using algorithm avx2x2 gen() 31067 MB/s Jul 7 00:13:01.848051 kernel: raid6: .... xor() 18503 MB/s, rmw enabled Jul 7 00:13:01.848078 kernel: raid6: using avx2x2 recovery algorithm Jul 7 00:13:01.871663 kernel: xor: automatically using best checksumming function avx Jul 7 00:13:02.040661 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 7 00:13:02.047197 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 7 00:13:02.057051 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:02.095127 systemd-udevd[483]: Using default interface naming scheme 'v255'. Jul 7 00:13:02.101187 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:02.104749 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 7 00:13:02.144598 dracut-pre-trigger[491]: rd.md=0: removing MD RAID activation Jul 7 00:13:02.167394 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 00:13:02.169983 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 00:13:02.241865 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:02.247937 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 7 00:13:02.269656 kernel: cryptd: max_cpu_qlen set to 1000 Jul 7 00:13:02.278665 kernel: AES CTR mode by8 optimization enabled Jul 7 00:13:02.284659 kernel: hv_vmbus: Vmbus version:4.0 Jul 7 00:13:02.302850 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 00:13:02.321180 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 7 00:13:02.321202 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 Jul 7 00:13:02.303027 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:02.321218 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:02.330144 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:02.354444 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 7 00:13:02.354481 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 7 00:13:02.354507 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 7 00:13:02.339118 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:02.363654 kernel: hv_vmbus: registering driver hv_pci Jul 7 00:13:02.363682 kernel: hv_vmbus: registering driver hv_netvsc Jul 7 00:13:02.365853 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 7 00:13:02.374119 kernel: PTP clock support registered Jul 7 00:13:02.374150 kernel: hv_vmbus: registering driver hid_hyperv Jul 7 00:13:02.374164 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 7 00:13:02.381651 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 7 00:13:02.386571 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 7 00:13:02.386768 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 7 00:13:02.394361 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 7 00:13:02.401501 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 7 00:13:02.404612 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 7 00:13:02.410741 kernel: hv_vmbus: registering driver hv_storvsc Jul 7 00:13:02.410769 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 7 00:13:02.413694 kernel: scsi host1: storvsc_host_t Jul 7 00:13:02.413737 kernel: hv_utils: Registering HyperV Utility Driver Jul 7 00:13:02.414659 kernel: scsi host0: storvsc_host_t Jul 7 00:13:02.414696 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 7 00:13:02.415683 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 7 00:13:02.415770 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 00:13:02.416747 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 7 00:13:02.420112 kernel: hv_vmbus: registering driver hv_utils Jul 7 00:13:02.424657 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 00:13:02.424698 kernel: hv_utils: Heartbeat IC version 3.0 Jul 7 00:13:02.431653 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 7 00:13:02.431698 kernel: hv_utils: Shutdown IC version 3.2 Jul 7 00:13:02.440662 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 7 00:13:02.459733 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 7 00:13:02.459925 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 7 00:13:02.471926 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 7 00:13:02.478565 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:02.508656 kernel: hv_utils: TimeSync IC version 4.0 Jul 7 00:13:02.871097 systemd-resolved[290]: Clock change detected. Flushing caches. Jul 7 00:13:02.882490 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 7 00:13:02.882683 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 7 00:13:02.882833 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jul 7 00:13:02.882967 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jul 7 00:13:02.889031 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jul 7 00:13:02.889217 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 7 00:13:02.894305 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jul 7 00:13:02.894482 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jul 7 00:13:02.894614 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 00:13:02.902172 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 00:13:02.912127 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#133 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.919189 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#392 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.920168 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#220 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.932615 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#227 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.941164 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 00:13:02.944170 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 7 00:13:02.944351 kernel: sdb: sdb1 Jul 7 00:13:02.948109 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jul 7 00:13:02.949210 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 7 00:13:02.953860 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 7 00:13:02.956173 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 7 00:13:03.532811 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 00:13:03.559525 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 7 00:13:03.572694 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 7 00:13:03.650036 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 7 00:13:03.651607 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 7 00:13:03.672091 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 7 00:13:03.711171 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#567 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:03.722168 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 00:13:03.732171 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#208 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:03.743167 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 00:13:04.750950 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#156 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:04.762098 disk-uuid[722]: The operation has completed successfully. Jul 7 00:13:04.765966 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 00:13:06.009037 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 7 00:13:06.009179 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 7 00:13:06.018864 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 7 00:13:06.044275 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 7 00:13:06.046330 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 00:13:06.054004 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:06.068448 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 00:13:06.080784 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 7 00:13:06.089263 sh[885]: Success Jul 7 00:13:06.115632 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 7 00:13:06.138767 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 7 00:13:06.138793 kernel: device-mapper: uevent: version 1.0.3 Jul 7 00:13:06.138805 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 7 00:13:06.150172 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 7 00:13:06.332629 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 7 00:13:06.336101 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 7 00:13:06.356509 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 7 00:13:06.377451 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 7 00:13:06.377483 kernel: BTRFS: device fsid 9d729180-1373-4e9f-840c-4db0e9220239 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (912) Jul 7 00:13:06.388772 kernel: BTRFS info (device dm-0): first mount of filesystem 9d729180-1373-4e9f-840c-4db0e9220239 Jul 7 00:13:06.388800 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:06.391892 kernel: BTRFS info (device dm-0): using free-space-tree Jul 7 00:13:06.708871 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 7 00:13:06.710786 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 7 00:13:06.717176 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 7 00:13:06.717942 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 7 00:13:06.744384 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 7 00:13:06.767736 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (935) Jul 7 00:13:06.767769 kernel: BTRFS info (device sda6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:06.772090 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:06.775284 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 00:13:06.801170 kernel: BTRFS info (device sda6): last unmount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:06.801684 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 7 00:13:06.804032 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 7 00:13:06.847210 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 00:13:06.849551 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 00:13:06.900388 systemd-networkd[1081]: lo: Link UP Jul 7 00:13:06.900398 systemd-networkd[1081]: lo: Gained carrier Jul 7 00:13:06.901513 systemd-networkd[1081]: Enumeration completed Jul 7 00:13:06.901587 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 00:13:06.901869 systemd-networkd[1081]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:06.901874 systemd-networkd[1081]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:06.902627 systemd-networkd[1081]: eth0: Link UP Jul 7 00:13:06.902790 systemd-networkd[1081]: eth0: Gained carrier Jul 7 00:13:06.902800 systemd-networkd[1081]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:06.907693 systemd[1]: Reached target network.target - Network. Jul 7 00:13:06.977539 systemd-networkd[1081]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:08.125718 ignition[1027]: Ignition 2.21.0 Jul 7 00:13:08.125731 ignition[1027]: Stage: fetch-offline Jul 7 00:13:08.125822 ignition[1027]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.125832 ignition[1027]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.125934 ignition[1027]: parsed url from cmdline: "" Jul 7 00:13:08.125938 ignition[1027]: no config URL provided Jul 7 00:13:08.125943 ignition[1027]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 00:13:08.125952 ignition[1027]: no config at "/usr/lib/ignition/user.ign" Jul 7 00:13:08.125957 ignition[1027]: failed to fetch config: resource requires networking Jul 7 00:13:08.126116 ignition[1027]: Ignition finished successfully Jul 7 00:13:08.157324 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 00:13:08.159858 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 7 00:13:08.202750 ignition[1094]: Ignition 2.21.0 Jul 7 00:13:08.203315 ignition[1094]: Stage: fetch Jul 7 00:13:08.203608 ignition[1094]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.203627 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.203722 ignition[1094]: parsed url from cmdline: "" Jul 7 00:13:08.203726 ignition[1094]: no config URL provided Jul 7 00:13:08.203732 ignition[1094]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 00:13:08.203740 ignition[1094]: no config at "/usr/lib/ignition/user.ign" Jul 7 00:13:08.203788 ignition[1094]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 7 00:13:08.305827 ignition[1094]: GET result: OK Jul 7 00:13:08.305872 ignition[1094]: config has been read from IMDS userdata Jul 7 00:13:08.305884 ignition[1094]: parsing config with SHA512: 84d035067fda5e74ac847be105d6d5caa269703c863c365dfdaf95cc217b092bb63aace6b8f6278e8c646ab9640ef0ff071c6af072187358e34a132e869f9d6d Jul 7 00:13:08.309661 unknown[1094]: fetched base config from "system" Jul 7 00:13:08.309861 ignition[1094]: fetch: fetch complete Jul 7 00:13:08.309668 unknown[1094]: fetched base config from "system" Jul 7 00:13:08.309866 ignition[1094]: fetch: fetch passed Jul 7 00:13:08.309673 unknown[1094]: fetched user config from "azure" Jul 7 00:13:08.309909 ignition[1094]: Ignition finished successfully Jul 7 00:13:08.317331 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 7 00:13:08.320238 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 7 00:13:08.364396 ignition[1104]: Ignition 2.21.0 Jul 7 00:13:08.364408 ignition[1104]: Stage: kargs Jul 7 00:13:08.364666 ignition[1104]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.364678 ignition[1104]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.365438 ignition[1104]: kargs: kargs passed Jul 7 00:13:08.373190 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 7 00:13:08.365480 ignition[1104]: Ignition finished successfully Jul 7 00:13:08.381846 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 7 00:13:08.424840 ignition[1113]: Ignition 2.21.0 Jul 7 00:13:08.424853 ignition[1113]: Stage: disks Jul 7 00:13:08.425081 ignition[1113]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.425093 ignition[1113]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.430794 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 7 00:13:08.425743 ignition[1113]: disks: disks passed Jul 7 00:13:08.436986 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 7 00:13:08.425787 ignition[1113]: Ignition finished successfully Jul 7 00:13:08.445621 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 7 00:13:08.447078 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 00:13:08.458422 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 00:13:08.466127 systemd[1]: Reached target basic.target - Basic System. Jul 7 00:13:08.481774 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 7 00:13:08.530237 systemd-networkd[1081]: eth0: Gained IPv6LL Jul 7 00:13:08.550856 systemd-fsck[1126]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 7 00:13:08.556073 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 7 00:13:08.558591 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 7 00:13:08.802182 kernel: EXT4-fs (sda9): mounted filesystem 98c55dfc-aac4-4fdd-8ec0-1f5587b3aa36 r/w with ordered data mode. Quota mode: none. Jul 7 00:13:08.803204 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 7 00:13:08.805105 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 7 00:13:08.822081 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 00:13:08.824430 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 7 00:13:08.851684 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 7 00:13:08.868268 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1140) Jul 7 00:13:08.852465 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 7 00:13:08.895362 kernel: BTRFS info (device sda6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:08.895383 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:08.895396 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 00:13:08.852496 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 00:13:08.870170 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 7 00:13:08.897932 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 00:13:08.912536 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 7 00:13:09.544087 coreos-metadata[1142]: Jul 07 00:13:09.544 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 00:13:09.551227 coreos-metadata[1142]: Jul 07 00:13:09.551 INFO Fetch successful Jul 7 00:13:09.551227 coreos-metadata[1142]: Jul 07 00:13:09.551 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 7 00:13:09.563979 coreos-metadata[1142]: Jul 07 00:13:09.555 INFO Fetch successful Jul 7 00:13:09.570062 coreos-metadata[1142]: Jul 07 00:13:09.570 INFO wrote hostname ci-4344.1.1-a-fe0cf7fe19 to /sysroot/etc/hostname Jul 7 00:13:09.577616 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 00:13:09.711399 initrd-setup-root[1171]: cut: /sysroot/etc/passwd: No such file or directory Jul 7 00:13:09.749206 initrd-setup-root[1178]: cut: /sysroot/etc/group: No such file or directory Jul 7 00:13:09.769748 initrd-setup-root[1185]: cut: /sysroot/etc/shadow: No such file or directory Jul 7 00:13:09.777168 initrd-setup-root[1192]: cut: /sysroot/etc/gshadow: No such file or directory Jul 7 00:13:10.964670 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 7 00:13:10.967463 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 7 00:13:10.997822 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 7 00:13:11.009300 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 7 00:13:11.018608 kernel: BTRFS info (device sda6): last unmount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:11.033711 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 7 00:13:11.046586 ignition[1264]: INFO : Ignition 2.21.0 Jul 7 00:13:11.046586 ignition[1264]: INFO : Stage: mount Jul 7 00:13:11.057670 ignition[1264]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.057670 ignition[1264]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.057670 ignition[1264]: INFO : mount: mount passed Jul 7 00:13:11.057670 ignition[1264]: INFO : Ignition finished successfully Jul 7 00:13:11.051555 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 7 00:13:11.052684 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 7 00:13:11.091769 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 00:13:11.122185 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1278) Jul 7 00:13:11.122232 kernel: BTRFS info (device sda6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:11.131064 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:11.134407 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 00:13:11.138278 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 00:13:11.185714 ignition[1295]: INFO : Ignition 2.21.0 Jul 7 00:13:11.185714 ignition[1295]: INFO : Stage: files Jul 7 00:13:11.191717 ignition[1295]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.191717 ignition[1295]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.191717 ignition[1295]: DEBUG : files: compiled without relabeling support, skipping Jul 7 00:13:11.205471 ignition[1295]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 7 00:13:11.205471 ignition[1295]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 7 00:13:11.260264 ignition[1295]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 7 00:13:11.265993 ignition[1295]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 7 00:13:11.271345 ignition[1295]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 7 00:13:11.266472 unknown[1295]: wrote ssh authorized keys file for user: core Jul 7 00:13:11.323815 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 00:13:11.331353 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 00:13:11.331353 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 7 00:13:11.331353 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 7 00:13:11.331353 ignition[1295]: INFO : files: files passed Jul 7 00:13:11.331353 ignition[1295]: INFO : Ignition finished successfully Jul 7 00:13:11.340271 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 7 00:13:11.348074 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 7 00:13:11.378646 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 7 00:13:11.380959 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 7 00:13:11.381048 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 7 00:13:11.431954 initrd-setup-root-after-ignition[1328]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.431954 initrd-setup-root-after-ignition[1328]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.449978 initrd-setup-root-after-ignition[1332]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.433736 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 00:13:11.440538 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 7 00:13:11.452104 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 7 00:13:11.503871 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 7 00:13:11.503990 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 7 00:13:11.506336 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 7 00:13:11.519207 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 7 00:13:11.531394 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 7 00:13:11.532138 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 7 00:13:11.562232 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 00:13:11.566069 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 7 00:13:11.581490 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:11.583067 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:11.592174 systemd[1]: Stopped target timers.target - Timer Units. Jul 7 00:13:11.601625 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 7 00:13:11.601740 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 00:13:11.615943 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 7 00:13:11.624747 systemd[1]: Stopped target basic.target - Basic System. Jul 7 00:13:11.632455 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 7 00:13:11.640370 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 00:13:11.649415 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 7 00:13:11.651636 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 7 00:13:11.667259 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 7 00:13:11.676537 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 00:13:11.685604 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 7 00:13:11.690066 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 7 00:13:11.701638 systemd[1]: Stopped target swap.target - Swaps. Jul 7 00:13:11.709524 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 7 00:13:11.709665 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 7 00:13:11.722554 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:11.731334 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:11.740524 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 7 00:13:11.740649 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:11.750288 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 7 00:13:11.750408 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 7 00:13:11.763973 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 7 00:13:11.764100 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 00:13:11.772715 systemd[1]: ignition-files.service: Deactivated successfully. Jul 7 00:13:11.772840 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 7 00:13:11.780744 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 7 00:13:11.780856 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 00:13:11.791195 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 7 00:13:11.798888 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 7 00:13:11.799014 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:11.823716 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 7 00:13:11.849911 ignition[1352]: INFO : Ignition 2.21.0 Jul 7 00:13:11.849911 ignition[1352]: INFO : Stage: umount Jul 7 00:13:11.849911 ignition[1352]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.849911 ignition[1352]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.849911 ignition[1352]: INFO : umount: umount passed Jul 7 00:13:11.849911 ignition[1352]: INFO : Ignition finished successfully Jul 7 00:13:11.826272 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 7 00:13:11.826398 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:11.833755 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 7 00:13:11.834049 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 00:13:11.852681 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 7 00:13:11.853736 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 7 00:13:11.853831 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 7 00:13:11.862428 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 7 00:13:11.862520 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 7 00:13:11.865660 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 7 00:13:11.865732 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 7 00:13:11.875039 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 7 00:13:11.875086 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 7 00:13:11.882207 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 7 00:13:11.882252 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 7 00:13:11.896195 systemd[1]: Stopped target network.target - Network. Jul 7 00:13:11.904124 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 7 00:13:11.904192 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 00:13:11.912909 systemd[1]: Stopped target paths.target - Path Units. Jul 7 00:13:11.920644 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 7 00:13:11.925199 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:11.932930 systemd[1]: Stopped target slices.target - Slice Units. Jul 7 00:13:11.940274 systemd[1]: Stopped target sockets.target - Socket Units. Jul 7 00:13:11.941799 systemd[1]: iscsid.socket: Deactivated successfully. Jul 7 00:13:11.941839 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 00:13:11.953864 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 7 00:13:11.953906 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 00:13:11.961576 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 7 00:13:11.961630 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 7 00:13:11.969541 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 7 00:13:11.969589 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 7 00:13:11.977657 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 7 00:13:12.060471 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 7 00:13:12.065232 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 7 00:13:12.065330 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 7 00:13:12.073304 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 7 00:13:12.073408 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 7 00:13:12.085937 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 7 00:13:12.086189 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 7 00:13:12.086295 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 7 00:13:12.098021 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 7 00:13:12.099353 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 7 00:13:12.103625 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 7 00:13:12.103669 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:12.111127 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 7 00:13:12.111197 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 7 00:13:12.124043 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 7 00:13:12.125448 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 7 00:13:12.125497 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 00:13:12.137289 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 7 00:13:12.137335 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:12.153515 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 7 00:13:12.153561 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:12.161834 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 7 00:13:12.161884 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:12.171740 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:12.181742 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 7 00:13:12.181804 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:12.187765 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 7 00:13:12.187911 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:12.191397 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 7 00:13:12.191440 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:12.203629 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 7 00:13:12.203665 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:12.212033 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 7 00:13:12.212084 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 7 00:13:12.228471 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 7 00:13:12.228517 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 7 00:13:12.239949 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 7 00:13:12.239994 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 00:13:12.259120 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 7 00:13:12.266533 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 7 00:13:12.266584 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:12.277555 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 7 00:13:12.277605 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:12.286597 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 00:13:12.286648 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:12.296586 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 7 00:13:12.296641 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 7 00:13:12.296686 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:12.398582 systemd-journald[231]: Received SIGTERM from PID 1 (systemd). Jul 7 00:13:12.297031 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 7 00:13:12.297246 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 7 00:13:12.302826 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 7 00:13:12.302919 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 7 00:13:12.308260 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 7 00:13:12.317366 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 7 00:13:12.330610 systemd[1]: Switching root. Jul 7 00:13:12.402572 systemd-journald[231]: Journal stopped Jul 7 00:13:17.265841 kernel: SELinux: policy capability network_peer_controls=1 Jul 7 00:13:17.265870 kernel: SELinux: policy capability open_perms=1 Jul 7 00:13:17.265883 kernel: SELinux: policy capability extended_socket_class=1 Jul 7 00:13:17.265892 kernel: SELinux: policy capability always_check_network=0 Jul 7 00:13:17.265901 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 7 00:13:17.265910 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 7 00:13:17.265920 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 7 00:13:17.265932 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 7 00:13:17.265941 kernel: SELinux: policy capability userspace_initial_context=0 Jul 7 00:13:17.265951 kernel: audit: type=1403 audit(1751847193.440:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 7 00:13:17.265962 systemd[1]: Successfully loaded SELinux policy in 237.890ms. Jul 7 00:13:17.265974 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.491ms. Jul 7 00:13:17.265986 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) Jul 7 00:13:17.265996 systemd[1]: Detected virtualization microsoft. Jul 7 00:13:17.266010 systemd[1]: Detected architecture x86-64. Jul 7 00:13:17.266021 systemd[1]: Detected first boot. Jul 7 00:13:17.266032 systemd[1]: Hostname set to . Jul 7 00:13:17.266043 systemd[1]: Initializing machine ID from random generator. Jul 7 00:13:17.266053 zram_generator::config[1398]: No configuration found. Jul 7 00:13:17.266067 kernel: Guest personality initialized and is inactive Jul 7 00:13:17.266077 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 7 00:13:17.266087 kernel: Initialized host personality Jul 7 00:13:17.266096 kernel: NET: Registered PF_VSOCK protocol family Jul 7 00:13:17.266107 systemd[1]: Populated /etc with preset unit settings. Jul 7 00:13:17.266118 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 7 00:13:17.266129 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 7 00:13:17.266142 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 7 00:13:17.266163 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 7 00:13:17.266176 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 7 00:13:17.266187 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 7 00:13:17.266198 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 7 00:13:17.266208 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 7 00:13:17.266219 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 7 00:13:17.266230 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 7 00:13:17.266245 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 7 00:13:17.266256 systemd[1]: Created slice user.slice - User and Session Slice. Jul 7 00:13:17.266266 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:17.266277 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:17.266288 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 7 00:13:17.266299 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 7 00:13:17.266314 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 7 00:13:17.266326 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 00:13:17.266340 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 7 00:13:17.266352 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:17.266363 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:17.266374 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 7 00:13:17.266385 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 7 00:13:17.266397 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 7 00:13:17.266408 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 7 00:13:17.266426 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:17.266437 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 00:13:17.266448 systemd[1]: Reached target slices.target - Slice Units. Jul 7 00:13:17.266459 systemd[1]: Reached target swap.target - Swaps. Jul 7 00:13:17.266470 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 7 00:13:17.266481 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 7 00:13:17.266495 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 7 00:13:17.266507 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:17.266518 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:17.266529 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:17.266540 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 7 00:13:17.266551 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 7 00:13:17.266562 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 7 00:13:17.266576 systemd[1]: Mounting media.mount - External Media Directory... Jul 7 00:13:17.266588 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:17.266599 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 7 00:13:17.266610 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 7 00:13:17.266621 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 7 00:13:17.266632 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). Jul 7 00:13:17.266643 systemd[1]: Reached target machines.target - Containers. Jul 7 00:13:17.266654 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 7 00:13:17.266669 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 00:13:17.266684 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 00:13:17.266695 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 7 00:13:17.266707 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 00:13:17.266718 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 00:13:17.266729 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 00:13:17.266740 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 7 00:13:17.266751 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 00:13:17.266763 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 7 00:13:17.266777 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 7 00:13:17.266788 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 7 00:13:17.266799 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 7 00:13:17.266810 systemd[1]: Stopped systemd-fsck-usr.service. Jul 7 00:13:17.266821 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). Jul 7 00:13:17.266833 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 00:13:17.266844 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 00:13:17.266855 kernel: fuse: init (API version 7.41) Jul 7 00:13:17.266869 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 00:13:17.266880 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 7 00:13:17.266892 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 7 00:13:17.266903 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 00:13:17.266916 systemd[1]: verity-setup.service: Deactivated successfully. Jul 7 00:13:17.266927 systemd[1]: Stopped verity-setup.service. Jul 7 00:13:17.266938 kernel: loop: module loaded Jul 7 00:13:17.266949 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:17.266960 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 7 00:13:17.266974 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 7 00:13:17.266985 systemd[1]: Mounted media.mount - External Media Directory. Jul 7 00:13:17.267018 systemd-journald[1481]: Collecting audit messages is disabled. Jul 7 00:13:17.267046 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 7 00:13:17.267059 systemd-journald[1481]: Journal started Jul 7 00:13:17.267081 systemd-journald[1481]: Runtime Journal (/run/log/journal/0b9f8133ca7a400daeadeb08fdc9bbb2) is 8M, max 2.2G, 2.1G free. Jul 7 00:13:16.482965 systemd[1]: Queued start job for default target multi-user.target. Jul 7 00:13:16.507502 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 7 00:13:16.507974 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 7 00:13:17.274711 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 00:13:17.278875 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 7 00:13:17.283629 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 7 00:13:17.287851 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 7 00:13:17.292956 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:17.298371 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 7 00:13:17.298557 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 7 00:13:17.303586 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 00:13:17.303778 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 00:13:17.308747 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 00:13:17.308933 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 00:13:17.314345 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 7 00:13:17.314536 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 7 00:13:17.319400 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 00:13:17.319602 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 00:13:17.324458 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:17.329435 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:17.334953 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 7 00:13:17.340510 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 7 00:13:17.345888 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:17.360093 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 00:13:17.366024 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 7 00:13:17.385405 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 7 00:13:17.389898 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 7 00:13:17.389931 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 00:13:17.395313 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 7 00:13:17.401471 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 7 00:13:17.405667 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 00:13:17.425170 kernel: ACPI: bus type drm_connector registered Jul 7 00:13:17.444772 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 7 00:13:17.461426 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 7 00:13:17.466272 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 00:13:17.467200 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 7 00:13:17.471575 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 00:13:17.472518 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 00:13:17.477634 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 7 00:13:17.483295 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 7 00:13:17.489305 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 00:13:17.489498 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 00:13:17.494128 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 7 00:13:17.499192 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 7 00:13:17.504508 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 7 00:13:17.506965 systemd-journald[1481]: Time spent on flushing to /var/log/journal/0b9f8133ca7a400daeadeb08fdc9bbb2 is 16.431ms for 949 entries. Jul 7 00:13:17.506965 systemd-journald[1481]: System Journal (/var/log/journal/0b9f8133ca7a400daeadeb08fdc9bbb2) is 8M, max 2.6G, 2.6G free. Jul 7 00:13:17.568935 systemd-journald[1481]: Received client request to flush runtime journal. Jul 7 00:13:17.516215 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 7 00:13:17.522355 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 7 00:13:17.570292 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 7 00:13:17.587176 kernel: loop0: detected capacity change from 0 to 28496 Jul 7 00:13:17.588043 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 7 00:13:17.588710 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 7 00:13:17.630116 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:18.052992 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 7 00:13:18.058991 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 00:13:18.140488 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Jul 7 00:13:18.140509 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Jul 7 00:13:18.145756 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:18.363182 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 7 00:13:18.435180 kernel: loop1: detected capacity change from 0 to 113872 Jul 7 00:13:18.775185 kernel: loop2: detected capacity change from 0 to 146240 Jul 7 00:13:19.256187 kernel: loop3: detected capacity change from 0 to 28496 Jul 7 00:13:19.268190 kernel: loop4: detected capacity change from 0 to 113872 Jul 7 00:13:19.284298 kernel: loop5: detected capacity change from 0 to 146240 Jul 7 00:13:19.349829 (sd-merge)[1561]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 7 00:13:19.350323 (sd-merge)[1561]: Merged extensions into '/usr'. Jul 7 00:13:19.353855 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 7 00:13:19.360870 systemd[1]: Starting ensure-sysext.service... Jul 7 00:13:19.364860 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 00:13:19.390198 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 7 00:13:19.397372 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:19.428769 systemd-udevd[1567]: Using default interface naming scheme 'v255'. Jul 7 00:13:19.435556 systemd-tmpfiles[1564]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 7 00:13:19.435593 systemd-tmpfiles[1564]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 7 00:13:19.435888 systemd-tmpfiles[1564]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 7 00:13:19.436000 systemd[1]: Reload requested from client PID 1563 ('systemctl') (unit ensure-sysext.service)... Jul 7 00:13:19.436015 systemd[1]: Reloading... Jul 7 00:13:19.436194 systemd-tmpfiles[1564]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 7 00:13:19.437310 systemd-tmpfiles[1564]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 7 00:13:19.437647 systemd-tmpfiles[1564]: ACLs are not supported, ignoring. Jul 7 00:13:19.437714 systemd-tmpfiles[1564]: ACLs are not supported, ignoring. Jul 7 00:13:19.483186 zram_generator::config[1593]: No configuration found. Jul 7 00:13:19.498317 systemd-tmpfiles[1564]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 00:13:19.498333 systemd-tmpfiles[1564]: Skipping /boot Jul 7 00:13:19.510539 systemd-tmpfiles[1564]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 00:13:19.510554 systemd-tmpfiles[1564]: Skipping /boot Jul 7 00:13:19.599262 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. Jul 7 00:13:19.691251 kernel: hv_vmbus: registering driver hv_balloon Jul 7 00:13:19.699187 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 7 00:13:19.699236 kernel: hv_vmbus: registering driver hyperv_fb Jul 7 00:13:19.710904 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 7 00:13:19.710962 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 7 00:13:19.714653 kernel: Console: switching to colour dummy device 80x25 Jul 7 00:13:19.720832 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 00:13:19.725411 kernel: mousedev: PS/2 mouse device common for all mice Jul 7 00:13:19.737558 systemd[1]: Reloading finished in 301 ms. Jul 7 00:13:19.752534 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:19.766176 kernel: EDAC MC: Ver: 3.0.0 Jul 7 00:13:19.771633 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:19.803762 systemd[1]: Finished ensure-sysext.service. Jul 7 00:13:19.810487 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 7 00:13:19.811563 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Jul 7 00:13:19.815962 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:19.817182 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 00:13:19.841646 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 7 00:13:19.846657 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 00:13:19.859551 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 00:13:19.864946 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 00:13:19.870057 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 00:13:19.875762 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 00:13:19.880241 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 00:13:19.880306 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). Jul 7 00:13:19.881398 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 7 00:13:19.893345 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 00:13:19.900011 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 00:13:19.904621 systemd[1]: Reached target time-set.target - System Time Set. Jul 7 00:13:19.909900 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 7 00:13:19.921466 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:19.925765 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:19.926748 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 00:13:19.926951 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 00:13:19.932078 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 00:13:19.932283 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 00:13:19.938426 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 00:13:19.938614 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 00:13:19.943935 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 00:13:19.944126 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 00:13:19.971577 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 7 00:13:19.993837 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 00:13:19.996760 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 7 00:13:19.998011 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 00:13:19.998067 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 00:13:19.999790 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 7 00:13:20.016355 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 7 00:13:20.053738 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 7 00:13:20.080939 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 7 00:13:20.098558 augenrules[1839]: No rules Jul 7 00:13:20.099920 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 00:13:20.100221 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 00:13:20.177347 systemd-resolved[1794]: Positive Trust Anchors: Jul 7 00:13:20.177361 systemd-resolved[1794]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 00:13:20.177392 systemd-resolved[1794]: 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 Jul 7 00:13:20.182325 systemd-resolved[1794]: Using system hostname 'ci-4344.1.1-a-fe0cf7fe19'. Jul 7 00:13:20.184125 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 00:13:20.185662 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:20.247186 systemd-networkd[1788]: lo: Link UP Jul 7 00:13:20.247196 systemd-networkd[1788]: lo: Gained carrier Jul 7 00:13:20.248583 systemd-networkd[1788]: Enumeration completed Jul 7 00:13:20.248703 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 00:13:20.248975 systemd-networkd[1788]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:20.248985 systemd-networkd[1788]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:20.249573 systemd-networkd[1788]: eth0: Link UP Jul 7 00:13:20.249582 systemd-networkd[1788]: eth0: Gained carrier Jul 7 00:13:20.249596 systemd-networkd[1788]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:20.250332 systemd[1]: Reached target network.target - Network. Jul 7 00:13:20.252372 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 7 00:13:20.253605 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 7 00:13:20.298206 systemd-networkd[1788]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:20.322521 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 7 00:13:20.334692 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 7 00:13:20.336420 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). Jul 7 00:13:20.512148 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:22.290299 systemd-networkd[1788]: eth0: Gained IPv6LL Jul 7 00:13:22.292692 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 7 00:13:22.300468 systemd[1]: Reached target network-online.target - Network is Online. Jul 7 00:13:23.923311 ldconfig[1534]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 00:13:23.933746 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 7 00:13:23.939725 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 7 00:13:23.981462 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 7 00:13:23.986322 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 00:13:23.990843 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 7 00:13:23.996150 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 7 00:13:24.001337 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 7 00:13:24.006253 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 7 00:13:24.010653 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 7 00:13:24.015845 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 7 00:13:24.021014 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 7 00:13:24.021050 systemd[1]: Reached target paths.target - Path Units. Jul 7 00:13:24.024802 systemd[1]: Reached target timers.target - Timer Units. Jul 7 00:13:24.040396 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 7 00:13:24.046168 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 7 00:13:24.052720 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 7 00:13:24.058088 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 7 00:13:24.063313 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 7 00:13:24.069383 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 7 00:13:24.073989 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 7 00:13:24.079542 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 7 00:13:24.084811 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 00:13:24.088840 systemd[1]: Reached target basic.target - Basic System. Jul 7 00:13:24.092590 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 7 00:13:24.092625 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 7 00:13:24.094881 systemd[1]: Starting chronyd.service - NTP client/server... Jul 7 00:13:24.099339 systemd[1]: Starting containerd.service - containerd container runtime... Jul 7 00:13:24.108967 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 7 00:13:24.114067 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 7 00:13:24.118762 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 7 00:13:24.124416 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 7 00:13:24.129414 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 7 00:13:24.133651 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). Jul 7 00:13:24.134719 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 7 00:13:24.138876 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Jul 7 00:13:24.139877 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 7 00:13:24.144125 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 7 00:13:24.145124 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 7 00:13:24.147744 KVP[1870]: KVP starting; pid is:1870 Jul 7 00:13:24.150211 KVP[1870]: KVP LIC Version: 3.1 Jul 7 00:13:24.150497 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 7 00:13:24.152203 kernel: hv_utils: KVP IC version 4.0 Jul 7 00:13:24.154262 jq[1867]: false Jul 7 00:13:24.156774 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 7 00:13:24.157341 (chronyd)[1859]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 7 00:13:24.160853 extend-filesystems[1868]: Found /dev/sda6 Jul 7 00:13:24.163077 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 7 00:13:24.165196 chronyd[1881]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 7 00:13:24.171086 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 7 00:13:24.175989 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 7 00:13:24.176426 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 7 00:13:24.178280 systemd[1]: Starting update-engine.service - Update Engine... Jul 7 00:13:24.182833 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 7 00:13:24.190073 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 7 00:13:24.191107 jq[1888]: true Jul 7 00:13:24.190962 chronyd[1881]: Timezone right/UTC failed leap second check, ignoring Jul 7 00:13:24.191138 chronyd[1881]: Loaded seccomp filter (level 2) Jul 7 00:13:24.195435 systemd[1]: Started chronyd.service - NTP client/server. Jul 7 00:13:24.199355 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Refreshing passwd entry cache Jul 7 00:13:24.199366 oslogin_cache_refresh[1869]: Refreshing passwd entry cache Jul 7 00:13:24.199608 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 7 00:13:24.199822 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 7 00:13:24.200113 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 7 00:13:24.200458 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 7 00:13:24.209177 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Failure getting users, quitting Jul 7 00:13:24.209177 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 00:13:24.209177 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Refreshing group entry cache Jul 7 00:13:24.208923 oslogin_cache_refresh[1869]: Failure getting users, quitting Jul 7 00:13:24.208940 oslogin_cache_refresh[1869]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 00:13:24.208978 oslogin_cache_refresh[1869]: Refreshing group entry cache Jul 7 00:13:24.213248 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Failure getting groups, quitting Jul 7 00:13:24.213248 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 00:13:24.213237 oslogin_cache_refresh[1869]: Failure getting groups, quitting Jul 7 00:13:24.213248 oslogin_cache_refresh[1869]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 00:13:24.214327 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 7 00:13:24.218945 extend-filesystems[1868]: Found /dev/sda9 Jul 7 00:13:24.221981 extend-filesystems[1868]: Checking size of /dev/sda9 Jul 7 00:13:24.221932 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 7 00:13:24.227145 systemd[1]: motdgen.service: Deactivated successfully. Jul 7 00:13:24.234383 jq[1893]: true Jul 7 00:13:24.227371 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 7 00:13:24.240910 (ntainerd)[1905]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 7 00:13:24.241145 extend-filesystems[1868]: Old size kept for /dev/sda9 Jul 7 00:13:24.245058 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 7 00:13:24.245318 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 7 00:13:24.277397 systemd-logind[1886]: New seat seat0. Jul 7 00:13:24.281093 systemd-logind[1886]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 7 00:13:24.281326 systemd[1]: Started systemd-logind.service - User Login Management. Jul 7 00:13:24.282687 update_engine[1887]: I20250707 00:13:24.282142 1887 main.cc:92] Flatcar Update Engine starting Jul 7 00:13:24.322779 bash[1934]: Updated "/home/core/.ssh/authorized_keys" Jul 7 00:13:24.324380 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 7 00:13:24.330296 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 7 00:13:24.358671 dbus-daemon[1862]: [system] SELinux support is enabled Jul 7 00:13:24.358836 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 7 00:13:24.361558 update_engine[1887]: I20250707 00:13:24.361505 1887 update_check_scheduler.cc:74] Next update check in 5m39s Jul 7 00:13:24.365055 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). Jul 7 00:13:24.365090 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 7 00:13:24.365856 dbus-daemon[1862]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 7 00:13:24.370323 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). Jul 7 00:13:24.370344 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 7 00:13:24.375501 systemd[1]: Started update-engine.service - Update Engine. Jul 7 00:13:24.381336 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 7 00:13:24.403433 setup-nvidia[1875]: Downloading Flatcar Container Linux Developer Container for version: 4344.1.1 Jul 7 00:13:24.444122 coreos-metadata[1861]: Jul 07 00:13:24.444 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 00:13:24.446616 coreos-metadata[1861]: Jul 07 00:13:24.446 INFO Fetch successful Jul 7 00:13:24.446765 coreos-metadata[1861]: Jul 07 00:13:24.446 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 7 00:13:24.451043 coreos-metadata[1861]: Jul 07 00:13:24.451 INFO Fetch successful Jul 7 00:13:24.451322 coreos-metadata[1861]: Jul 07 00:13:24.451 INFO Fetching http://168.63.129.16/machine/873d3cbf-a952-47ac-a44d-192fb5537d9f/6243d491%2D06d1%2D41bd%2Da37b%2D875a78957a47.%5Fci%2D4344.1.1%2Da%2Dfe0cf7fe19?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 7 00:13:24.452989 coreos-metadata[1861]: Jul 07 00:13:24.452 INFO Fetch successful Jul 7 00:13:24.453137 coreos-metadata[1861]: Jul 07 00:13:24.453 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 7 00:13:24.460017 sshd_keygen[1892]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 7 00:13:24.460270 coreos-metadata[1861]: Jul 07 00:13:24.460 INFO Fetch successful Jul 7 00:13:24.482765 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 7 00:13:24.485962 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 7 00:13:24.491788 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 7 00:13:24.496672 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 7 00:13:24.501869 systemd[1]: issuegen.service: Deactivated successfully. Jul 7 00:13:24.502077 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 7 00:13:24.508742 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 7 00:13:24.510451 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 7 00:13:24.525398 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 7 00:13:24.558549 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 7 00:13:24.565064 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 7 00:13:24.570118 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 7 00:13:24.576549 systemd[1]: Reached target getty.target - Login Prompts. Jul 7 00:13:24.592123 locksmithd[2004]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 7 00:13:24.978760 setup-nvidia[2049]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 00:13:24.979073 setup-nvidia[2049]: Dload Upload Total Spent Left Speed Jul 7 00:13:25.257366 containerd[1905]: time="2025-07-07T00:13:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jul 7 00:13:25.257960 containerd[1905]: time="2025-07-07T00:13:25.257925000Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 7 00:13:25.267253 containerd[1905]: time="2025-07-07T00:13:25.267218700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.1µs" Jul 7 00:13:25.267253 containerd[1905]: time="2025-07-07T00:13:25.267247300Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 7 00:13:25.267320 containerd[1905]: time="2025-07-07T00:13:25.267265600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 7 00:13:25.267430 containerd[1905]: time="2025-07-07T00:13:25.267407600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 7 00:13:25.267473 containerd[1905]: time="2025-07-07T00:13:25.267430400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 7 00:13:25.267473 containerd[1905]: time="2025-07-07T00:13:25.267453600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 00:13:25.267535 containerd[1905]: time="2025-07-07T00:13:25.267512500Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 00:13:25.267535 containerd[1905]: time="2025-07-07T00:13:25.267532600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.267743 containerd[1905]: time="2025-07-07T00:13:25.267720700Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268011 containerd[1905]: time="2025-07-07T00:13:25.267741900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268011 containerd[1905]: time="2025-07-07T00:13:25.267752600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268011 containerd[1905]: time="2025-07-07T00:13:25.267763000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268011 containerd[1905]: time="2025-07-07T00:13:25.267841900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268112 containerd[1905]: time="2025-07-07T00:13:25.268060200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268112 containerd[1905]: time="2025-07-07T00:13:25.268090800Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.268112 containerd[1905]: time="2025-07-07T00:13:25.268101100Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 7 00:13:25.268197 containerd[1905]: time="2025-07-07T00:13:25.268133600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 7 00:13:25.268351 containerd[1905]: time="2025-07-07T00:13:25.268327500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 7 00:13:25.268418 containerd[1905]: time="2025-07-07T00:13:25.268399300Z" level=info msg="metadata content store policy set" policy=shared Jul 7 00:13:25.283473 containerd[1905]: time="2025-07-07T00:13:25.283445300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 7 00:13:25.283504 containerd[1905]: time="2025-07-07T00:13:25.283483800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 7 00:13:25.283504 containerd[1905]: time="2025-07-07T00:13:25.283497600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 7 00:13:25.283543 containerd[1905]: time="2025-07-07T00:13:25.283510000Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 7 00:13:25.283543 containerd[1905]: time="2025-07-07T00:13:25.283520900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 7 00:13:25.283543 containerd[1905]: time="2025-07-07T00:13:25.283530800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283554200Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283568600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283578300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283587500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283595800Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 7 00:13:25.283613 containerd[1905]: time="2025-07-07T00:13:25.283606600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 7 00:13:25.283729 containerd[1905]: time="2025-07-07T00:13:25.283707700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 7 00:13:25.283757 containerd[1905]: time="2025-07-07T00:13:25.283728300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 7 00:13:25.283757 containerd[1905]: time="2025-07-07T00:13:25.283742700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 7 00:13:25.283757 containerd[1905]: time="2025-07-07T00:13:25.283752700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283761500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283770500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283779900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283788500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283798700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283808100Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 7 00:13:25.283819 containerd[1905]: time="2025-07-07T00:13:25.283817000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 7 00:13:25.284016 containerd[1905]: time="2025-07-07T00:13:25.283869700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 7 00:13:25.284016 containerd[1905]: time="2025-07-07T00:13:25.283886700Z" level=info msg="Start snapshots syncer" Jul 7 00:13:25.284016 containerd[1905]: time="2025-07-07T00:13:25.283913000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 7 00:13:25.284227 containerd[1905]: time="2025-07-07T00:13:25.284183700Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Jul 7 00:13:25.284351 containerd[1905]: time="2025-07-07T00:13:25.284248600Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 7 00:13:25.284379 containerd[1905]: time="2025-07-07T00:13:25.284351100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 7 00:13:25.284501 containerd[1905]: time="2025-07-07T00:13:25.284472300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 7 00:13:25.284530 containerd[1905]: time="2025-07-07T00:13:25.284503400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 7 00:13:25.284530 containerd[1905]: time="2025-07-07T00:13:25.284515600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 7 00:13:25.284530 containerd[1905]: time="2025-07-07T00:13:25.284526800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 7 00:13:25.284595 containerd[1905]: time="2025-07-07T00:13:25.284537800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 7 00:13:25.284595 containerd[1905]: time="2025-07-07T00:13:25.284548500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 7 00:13:25.284595 containerd[1905]: time="2025-07-07T00:13:25.284557900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 7 00:13:25.284595 containerd[1905]: time="2025-07-07T00:13:25.284577400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 7 00:13:25.284595 containerd[1905]: time="2025-07-07T00:13:25.284589400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284598200Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284629600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284643700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284651800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284660200Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284667300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284675300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 7 00:13:25.284698 containerd[1905]: time="2025-07-07T00:13:25.284684300Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 7 00:13:25.284864 containerd[1905]: time="2025-07-07T00:13:25.284699700Z" level=info msg="runtime interface created" Jul 7 00:13:25.284864 containerd[1905]: time="2025-07-07T00:13:25.284706000Z" level=info msg="created NRI interface" Jul 7 00:13:25.284864 containerd[1905]: time="2025-07-07T00:13:25.284725100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 7 00:13:25.284864 containerd[1905]: time="2025-07-07T00:13:25.284739900Z" level=info msg="Connect containerd service" Jul 7 00:13:25.284864 containerd[1905]: time="2025-07-07T00:13:25.284764400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 7 00:13:25.285477 containerd[1905]: time="2025-07-07T00:13:25.285448400Z" 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" Jul 7 00:13:26.328883 containerd[1905]: time="2025-07-07T00:13:26.328820400Z" level=info msg="Start subscribing containerd event" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.328883000Z" level=info msg="Start recovering state" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.328978400Z" level=info msg="Start event monitor" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.328993600Z" level=info msg="Start cni network conf syncer for default" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329004500Z" level=info msg="Start streaming server" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329014000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329021600Z" level=info msg="runtime interface starting up..." Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329027100Z" level=info msg="starting plugins..." Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329043700Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329028400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 7 00:13:26.329254 containerd[1905]: time="2025-07-07T00:13:26.329186200Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 7 00:13:26.329469 containerd[1905]: time="2025-07-07T00:13:26.329264900Z" level=info msg="containerd successfully booted in 1.072434s" Jul 7 00:13:26.329373 systemd[1]: Started containerd.service - containerd container runtime. Jul 7 00:13:26.696102 waagent[2036]: 2025-07-07T00:13:26.695961Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 7 00:13:26.700493 waagent[2036]: 2025-07-07T00:13:26.700447Z INFO Daemon Daemon OS: flatcar 4344.1.1 Jul 7 00:13:26.704090 waagent[2036]: 2025-07-07T00:13:26.704044Z INFO Daemon Daemon Python: 3.11.12 Jul 7 00:13:26.707473 waagent[2036]: 2025-07-07T00:13:26.707427Z INFO Daemon Daemon Run daemon Jul 7 00:13:26.710473 waagent[2036]: 2025-07-07T00:13:26.710432Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.1.1' Jul 7 00:13:26.717559 waagent[2036]: 2025-07-07T00:13:26.717510Z INFO Daemon Daemon Using waagent for provisioning Jul 7 00:13:26.721604 waagent[2036]: 2025-07-07T00:13:26.721552Z INFO Daemon Daemon Activate resource disk Jul 7 00:13:26.725590 waagent[2036]: 2025-07-07T00:13:26.725539Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 7 00:13:26.733145 waagent[2036]: 2025-07-07T00:13:26.733100Z INFO Daemon Daemon Found device: sdb Jul 7 00:13:26.742260 waagent[2036]: 2025-07-07T00:13:26.742216Z INFO Daemon Daemon Examining partition table Jul 7 00:13:26.778331 waagent[2036]: 2025-07-07T00:13:26.778266Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 7 00:13:26.816000 waagent[2036]: 2025-07-07T00:13:26.815937Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jul 7 00:13:26.820739 waagent[2036]: 2025-07-07T00:13:26.820693Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 7 00:13:27.272796 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#86 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.284178 kernel: sdb: sdb1 Jul 7 00:13:27.291193 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#211 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.298170 kernel: sdb: sdb1 Jul 7 00:13:27.302345 waagent[2036]: 2025-07-07T00:13:27.302274Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jul 7 00:13:27.321705 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#157 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.339172 kernel: sdb: sdb1 Jul 7 00:13:27.339533 waagent[2036]: 2025-07-07T00:13:27.339481Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jul 7 00:13:32.985638 login[2040]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 7 00:13:32.985917 login[2039]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 00:13:32.992424 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 7 00:13:32.993533 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 7 00:13:32.999948 systemd-logind[1886]: New session 1 of user core. Jul 7 00:13:33.106563 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 7 00:13:33.115935 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 7 00:13:33.161505 (systemd)[2145]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 7 00:13:33.164018 systemd-logind[1886]: New session c1 of user core. Jul 7 00:13:33.196463 waagent[2036]: 2025-07-07T00:13:33.196388Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jul 7 00:13:33.310889 waagent[2036]: 2025-07-07T00:13:33.310775Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jul 7 00:13:33.311177 kernel: EXT4-fs (sdb1): mounted filesystem 0bfeae22-d311-4774-a9e6-bfc6918d20ea r/w with ordered data mode. Quota mode: none. Jul 7 00:13:33.313016 waagent[2036]: 2025-07-07T00:13:33.312975Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 00:13:33.314100 waagent[2036]: 2025-07-07T00:13:33.314065Z INFO Daemon Daemon Running default provisioning handler Jul 7 00:13:33.328842 waagent[2036]: 2025-07-07T00:13:33.328793Z 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. Jul 7 00:13:33.330816 waagent[2036]: 2025-07-07T00:13:33.330778Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 7 00:13:33.331988 waagent[2036]: 2025-07-07T00:13:33.331953Z INFO Daemon Daemon cloud-init is enabled: False Jul 7 00:13:33.333508 waagent[2036]: 2025-07-07T00:13:33.333471Z INFO Daemon Daemon Copying ovf-env.xml Jul 7 00:13:33.373447 systemd[2145]: Queued start job for default target default.target. Jul 7 00:13:33.386926 systemd[2145]: Created slice app.slice - User Application Slice. Jul 7 00:13:33.386957 systemd[2145]: Reached target paths.target - Paths. Jul 7 00:13:33.387001 systemd[2145]: Reached target timers.target - Timers. Jul 7 00:13:33.388220 systemd[2145]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 00:13:33.398622 systemd[2145]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:13:33.398737 systemd[2145]: Reached target sockets.target - Sockets. Jul 7 00:13:33.398790 systemd[2145]: Reached target basic.target - Basic System. Jul 7 00:13:33.398844 systemd[2145]: Reached target default.target - Main User Target. Jul 7 00:13:33.398873 systemd[2145]: Startup finished in 227ms. Jul 7 00:13:33.398946 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 7 00:13:33.400567 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 7 00:13:33.404857 waagent[2036]: 2025-07-07T00:13:33.404791Z INFO Daemon Daemon Successfully mounted dvd Jul 7 00:13:33.440821 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 7 00:13:33.440931 waagent[2036]: 2025-07-07T00:13:33.440854Z INFO Daemon Daemon Detect protocol endpoint Jul 7 00:13:33.442549 waagent[2036]: 2025-07-07T00:13:33.442502Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 00:13:33.444051 waagent[2036]: 2025-07-07T00:13:33.444012Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 7 00:13:33.445559 waagent[2036]: 2025-07-07T00:13:33.445516Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 7 00:13:33.447278 waagent[2036]: 2025-07-07T00:13:33.447187Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 7 00:13:33.448462 waagent[2036]: 2025-07-07T00:13:33.448425Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 7 00:13:33.467569 waagent[2036]: 2025-07-07T00:13:33.467529Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 7 00:13:33.469736 waagent[2036]: 2025-07-07T00:13:33.469642Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 7 00:13:33.471400 waagent[2036]: 2025-07-07T00:13:33.471361Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 7 00:13:33.574251 waagent[2036]: 2025-07-07T00:13:33.574089Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 7 00:13:33.575878 waagent[2036]: 2025-07-07T00:13:33.575833Z INFO Daemon Daemon Forcing an update of the goal state. Jul 7 00:13:33.581537 waagent[2036]: 2025-07-07T00:13:33.581456Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 00:13:33.598251 waagent[2036]: 2025-07-07T00:13:33.598210Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 7 00:13:33.600299 waagent[2036]: 2025-07-07T00:13:33.600255Z INFO Daemon Jul 7 00:13:33.600696 waagent[2036]: 2025-07-07T00:13:33.600641Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 62b56e65-5eee-4350-928b-0497766885c4 eTag: 17191476906196788318 source: Fabric] Jul 7 00:13:33.602467 waagent[2036]: 2025-07-07T00:13:33.602428Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 7 00:13:33.603086 waagent[2036]: 2025-07-07T00:13:33.603049Z INFO Daemon Jul 7 00:13:33.604364 waagent[2036]: 2025-07-07T00:13:33.604319Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 7 00:13:33.626522 waagent[2036]: 2025-07-07T00:13:33.626485Z INFO Daemon Daemon Downloading artifacts profile blob Jul 7 00:13:33.748091 waagent[2036]: 2025-07-07T00:13:33.748036Z INFO Daemon Downloaded certificate {'thumbprint': '2A6644C9F626C8889831A1F0A15D852D4FF5C997', 'hasPrivateKey': True} Jul 7 00:13:33.750162 waagent[2036]: 2025-07-07T00:13:33.750114Z INFO Daemon Fetch goal state completed Jul 7 00:13:33.793944 waagent[2036]: 2025-07-07T00:13:33.793901Z INFO Daemon Daemon Starting provisioning Jul 7 00:13:33.797325 waagent[2036]: 2025-07-07T00:13:33.797270Z INFO Daemon Daemon Handle ovf-env.xml. Jul 7 00:13:33.800985 waagent[2036]: 2025-07-07T00:13:33.800935Z INFO Daemon Daemon Set hostname [ci-4344.1.1-a-fe0cf7fe19] Jul 7 00:13:33.838634 waagent[2036]: 2025-07-07T00:13:33.838592Z INFO Daemon Daemon Publish hostname [ci-4344.1.1-a-fe0cf7fe19] Jul 7 00:13:33.840306 waagent[2036]: 2025-07-07T00:13:33.840264Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 7 00:13:33.841044 waagent[2036]: 2025-07-07T00:13:33.841008Z INFO Daemon Daemon Primary interface is [eth0] Jul 7 00:13:33.850517 systemd-networkd[1788]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:33.850527 systemd-networkd[1788]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:33.850553 systemd-networkd[1788]: eth0: DHCP lease lost Jul 7 00:13:33.850987 waagent[2036]: 2025-07-07T00:13:33.850943Z INFO Daemon Daemon Create user account if not exists Jul 7 00:13:33.852697 waagent[2036]: 2025-07-07T00:13:33.852654Z INFO Daemon Daemon User core already exists, skip useradd Jul 7 00:13:33.854147 waagent[2036]: 2025-07-07T00:13:33.854113Z INFO Daemon Daemon Configure sudoer Jul 7 00:13:33.858319 waagent[2036]: 2025-07-07T00:13:33.858278Z INFO Daemon Daemon Configure sshd Jul 7 00:13:33.861386 waagent[2036]: 2025-07-07T00:13:33.861341Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 7 00:13:33.861732 waagent[2036]: 2025-07-07T00:13:33.861699Z INFO Daemon Daemon Deploy ssh public key. Jul 7 00:13:33.937197 systemd-networkd[1788]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:33.987451 login[2040]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 00:13:33.992120 systemd-logind[1886]: New session 2 of user core. Jul 7 00:13:34.001280 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 7 00:13:35.025725 waagent[2036]: 2025-07-07T00:13:35.025664Z INFO Daemon Daemon Provisioning complete Jul 7 00:13:35.039014 waagent[2036]: 2025-07-07T00:13:35.038972Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 7 00:13:35.043780 waagent[2036]: 2025-07-07T00:13:35.043670Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 7 00:13:35.051172 waagent[2036]: 2025-07-07T00:13:35.051121Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 7 00:13:35.195698 waagent[2201]: 2025-07-07T00:13:35.195614Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 7 00:13:35.196028 waagent[2201]: 2025-07-07T00:13:35.195752Z INFO ExtHandler ExtHandler OS: flatcar 4344.1.1 Jul 7 00:13:35.196028 waagent[2201]: 2025-07-07T00:13:35.195820Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 7 00:13:35.196028 waagent[2201]: 2025-07-07T00:13:35.195887Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 7 00:13:35.225642 waagent[2201]: 2025-07-07T00:13:35.225591Z INFO ExtHandler ExtHandler Distro: flatcar-4344.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 7 00:13:35.225822 waagent[2201]: 2025-07-07T00:13:35.225785Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:35.225898 waagent[2201]: 2025-07-07T00:13:35.225867Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:35.232624 waagent[2201]: 2025-07-07T00:13:35.232572Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 00:13:35.237360 waagent[2201]: 2025-07-07T00:13:35.237317Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 7 00:13:35.237783 waagent[2201]: 2025-07-07T00:13:35.237745Z INFO ExtHandler Jul 7 00:13:35.237857 waagent[2201]: 2025-07-07T00:13:35.237827Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 82695fac-12b6-4ff2-b338-9e633f01b01e eTag: 17191476906196788318 source: Fabric] Jul 7 00:13:35.238163 waagent[2201]: 2025-07-07T00:13:35.238126Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 7 00:13:35.238768 waagent[2201]: 2025-07-07T00:13:35.238733Z INFO ExtHandler Jul 7 00:13:35.238836 waagent[2201]: 2025-07-07T00:13:35.238807Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 7 00:13:35.242020 waagent[2201]: 2025-07-07T00:13:35.241977Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 7 00:13:35.302784 waagent[2201]: 2025-07-07T00:13:35.302681Z INFO ExtHandler Downloaded certificate {'thumbprint': '2A6644C9F626C8889831A1F0A15D852D4FF5C997', 'hasPrivateKey': True} Jul 7 00:13:35.303233 waagent[2201]: 2025-07-07T00:13:35.303193Z INFO ExtHandler Fetch goal state completed Jul 7 00:13:35.314219 waagent[2201]: 2025-07-07T00:13:35.314177Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 7 00:13:35.319534 waagent[2201]: 2025-07-07T00:13:35.319489Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2201 Jul 7 00:13:35.319666 waagent[2201]: 2025-07-07T00:13:35.319632Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 7 00:13:35.319990 waagent[2201]: 2025-07-07T00:13:35.319954Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 7 00:13:35.321558 waagent[2201]: 2025-07-07T00:13:35.321519Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 7 00:13:35.321975 waagent[2201]: 2025-07-07T00:13:35.321937Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 7 00:13:35.322120 waagent[2201]: 2025-07-07T00:13:35.322085Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 7 00:13:35.322737 waagent[2201]: 2025-07-07T00:13:35.322700Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 7 00:13:35.364359 waagent[2201]: 2025-07-07T00:13:35.364317Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 7 00:13:35.364513 waagent[2201]: 2025-07-07T00:13:35.364477Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 7 00:13:35.370934 waagent[2201]: 2025-07-07T00:13:35.370903Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 7 00:13:35.377863 systemd[1]: Reload requested from client PID 2216 ('systemctl') (unit waagent.service)... Jul 7 00:13:35.377880 systemd[1]: Reloading... Jul 7 00:13:35.439188 zram_generator::config[2258]: No configuration found. Jul 7 00:13:35.557837 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. Jul 7 00:13:35.721224 systemd[1]: Reloading finished in 342 ms. Jul 7 00:13:35.745995 waagent[2201]: 2025-07-07T00:13:35.745932Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 7 00:13:35.746114 waagent[2201]: 2025-07-07T00:13:35.746077Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 7 00:13:36.318502 waagent[2201]: 2025-07-07T00:13:36.318425Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 7 00:13:36.318874 waagent[2201]: 2025-07-07T00:13:36.318748Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Jul 7 00:13:36.319568 waagent[2201]: 2025-07-07T00:13:36.319524Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 7 00:13:36.319817 waagent[2201]: 2025-07-07T00:13:36.319772Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:36.320127 waagent[2201]: 2025-07-07T00:13:36.320089Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 7 00:13:36.320214 waagent[2201]: 2025-07-07T00:13:36.320177Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:36.320577 waagent[2201]: 2025-07-07T00:13:36.320536Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:36.320651 waagent[2201]: 2025-07-07T00:13:36.320609Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 7 00:13:36.320731 waagent[2201]: 2025-07-07T00:13:36.320692Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 7 00:13:36.321132 waagent[2201]: 2025-07-07T00:13:36.321096Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:36.321559 waagent[2201]: 2025-07-07T00:13:36.321512Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 7 00:13:36.321675 waagent[2201]: 2025-07-07T00:13:36.321621Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 7 00:13:36.321769 waagent[2201]: 2025-07-07T00:13:36.321731Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 7 00:13:36.321982 waagent[2201]: 2025-07-07T00:13:36.321945Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 7 00:13:36.322198 waagent[2201]: 2025-07-07T00:13:36.322139Z INFO EnvHandler ExtHandler Configure routes Jul 7 00:13:36.322758 waagent[2201]: 2025-07-07T00:13:36.322723Z INFO EnvHandler ExtHandler Gateway:None Jul 7 00:13:36.322812 waagent[2201]: 2025-07-07T00:13:36.322771Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 7 00:13:36.322812 waagent[2201]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 7 00:13:36.322812 waagent[2201]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jul 7 00:13:36.322812 waagent[2201]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 7 00:13:36.322812 waagent[2201]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.322812 waagent[2201]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.322812 waagent[2201]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.323563 waagent[2201]: 2025-07-07T00:13:36.323526Z INFO EnvHandler ExtHandler Routes:None Jul 7 00:13:36.328811 waagent[2201]: 2025-07-07T00:13:36.328767Z INFO ExtHandler ExtHandler Jul 7 00:13:36.329199 waagent[2201]: 2025-07-07T00:13:36.329150Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 1301f6a8-82d7-4924-a7a1-31a67c78f28f correlation 2c01c7a9-f351-47ec-8c18-85d0c53f22e2 created: 2025-07-07T00:12:06.539421Z] Jul 7 00:13:36.330303 waagent[2201]: 2025-07-07T00:13:36.330256Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 7 00:13:36.332291 waagent[2201]: 2025-07-07T00:13:36.332252Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 7 00:13:36.366336 waagent[2201]: 2025-07-07T00:13:36.366291Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Jul 7 00:13:36.366336 waagent[2201]: Try `iptables -h' or 'iptables --help' for more information.) Jul 7 00:13:36.366719 waagent[2201]: 2025-07-07T00:13:36.366682Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D3F74444-BFD1-4694-8257-C6ADBDA77CF6;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 7 00:13:36.413698 waagent[2201]: 2025-07-07T00:13:36.413623Z INFO MonitorHandler ExtHandler Network interfaces: Jul 7 00:13:36.413698 waagent[2201]: Executing ['ip', '-a', '-o', 'link']: Jul 7 00:13:36.413698 waagent[2201]: 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 Jul 7 00:13:36.413698 waagent[2201]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:04:5c:d5 brd ff:ff:ff:ff:ff:ff Jul 7 00:13:36.413698 waagent[2201]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 7 00:13:36.413698 waagent[2201]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 7 00:13:36.413698 waagent[2201]: 2: eth0 inet 10.200.4.18/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 7 00:13:36.413698 waagent[2201]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 7 00:13:36.413698 waagent[2201]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 7 00:13:36.413698 waagent[2201]: 2: eth0 inet6 fe80::7e1e:52ff:fe04:5cd5/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 7 00:13:36.452221 waagent[2201]: 2025-07-07T00:13:36.452170Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 7 00:13:36.452221 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.452221 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.452221 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.452221 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.452221 waagent[2201]: Chain OUTPUT (policy ACCEPT 3 packets, 164 bytes) Jul 7 00:13:36.452221 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.452221 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 00:13:36.452221 waagent[2201]: 11 1299 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 00:13:36.452221 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 00:13:36.456077 waagent[2201]: 2025-07-07T00:13:36.456036Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 7 00:13:36.456077 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.456077 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.456077 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.456077 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.456077 waagent[2201]: Chain OUTPUT (policy ACCEPT 6 packets, 513 bytes) Jul 7 00:13:36.456077 waagent[2201]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.456077 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 00:13:36.456077 waagent[2201]: 12 1351 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 00:13:36.456077 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 00:13:36.456330 waagent[2201]: 2025-07-07T00:13:36.456304Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 7 00:13:36.456485 waagent[2201]: 2025-07-07T00:13:36.456454Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 7 00:13:47.970777 chronyd[1881]: Selected source PHC0 Jul 7 00:13:49.833115 setup-nvidia[2049]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 485M 0 10665 0 0 17410 0 8:07:24 --:--:-- 8:07:24 17398\u000d 0 485M 0 1680k 0 0 1130k 0 0:07:19 0:00:01 0:07:18 1130k\u000d 4 485M 4 21.2M 0 0 8625k 0 0:00:57 0:00:02 0:00:55 8624k\u000d 9 485M 9 44.0M 0 0 12.3M 0 0:00:39 0:00:03 0:00:36 12.3M\u000d 13 485M 13 65.9M 0 0 14.6M 0 0:00:33 0:00:04 0:00:29 14.6M\u000d 18 485M 18 87.4M 0 0 15.8M 0 0:00:30 0:00:05 0:00:25 17.7M\u000d 22 485M 22 109M 0 0 16.6M 0 0:00:29 0:00:06 0:00:23 21.1M\u000d 25 485M 25 125M 0 0 16.4M 0 0:00:29 0:00:07 0:00:22 20.4M\u000d 30 485M 30 146M 0 0 17.1M 0 0:00:28 0:00:08 0:00:20 20.6M\u000d 34 485M 34 169M 0 0 17.7M 0 0:00:27 0:00:09 0:00:18 20.4M\u000d 39 485M 39 191M 0 0 18.2M 0 0:00:26 0:00:10 0:00:16 21.0M\u000d 44 485M 44 216M 0 0 18.7M 0 0:00:25 0:00:11 0:00:14 21.6M\u000d 48 485M 48 235M 0 0 18.6M 0 0:00:25 0:00:12 0:00:13 22.0M\u000d 52 485M 52 253M 0 0 18.7M 0 0:00:25 0:00:13 0:00:12 21.5M\u000d 56 485M 56 276M 0 0 18.9M 0 0:00:25 0:00:14 0:00:11 21.3M\u000d 61 485M 61 297M 0 0 19.0M 0 0:00:25 0:00:15 0:00:10 20.6M\u000d 64 485M 64 313M 0 0 18.9M 0 0:00:25 0:00:16 0:00:09 19.5M\u000d 69 485M 69 335M 0 0 19.1M 0 0:00:25 0:00:17 0:00:08 20.2M\u000d 73 485M 73 358M 0 0 19.2M 0 0:00:25 0:00:18 0:00:07 20.5M\u000d 77 485M 77 378M 0 0 19.4M 0 0:00:25 0:00:19 0:00:06 20.5M\u000d 82 485M 82 398M 0 0 19.3M 0 0:00:25 0:00:20 0:00:05 20.3M\u000d 86 485M 86 418M 0 0 19.3M 0 0:00:25 0:00:21 0:00:04 20.6M\u000d 89 485M 89 436M 0 0 19.4M 0 0:00:24 0:00:22 0:00:02 20.4M\u000d 93 485M 93 455M 0 0 19.3M 0 0:00:25 0:00:23 0:00:02 19.8M\u000d 98 485M 98 477M 0 0 19.4M 0 0:00:24 0:00:24 --:--:-- 19.5M\u000d100 485M 100 485M 0 0 19.5M 0 0:00:24 0:00:24 --:--:-- 20.2M Jul 7 00:13:53.676203 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 7 00:13:53.681472 systemd[1]: Started sshd@0-10.200.4.18:22-10.200.16.10:34248.service - OpenSSH per-connection server daemon (10.200.16.10:34248). Jul 7 00:13:54.503173 sshd[2356]: Accepted publickey for core from 10.200.16.10 port 34248 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:54.504228 sshd-session[2356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:54.511505 systemd-logind[1886]: New session 3 of user core. Jul 7 00:13:54.523325 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 7 00:13:55.032414 systemd[1]: Started sshd@1-10.200.4.18:22-10.200.16.10:34260.service - OpenSSH per-connection server daemon (10.200.16.10:34260). Jul 7 00:13:55.651598 sshd[2361]: Accepted publickey for core from 10.200.16.10 port 34260 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:55.653085 sshd-session[2361]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:55.660464 systemd-logind[1886]: New session 4 of user core. Jul 7 00:13:55.667500 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 7 00:13:56.085764 sshd[2363]: Connection closed by 10.200.16.10 port 34260 Jul 7 00:13:56.087262 sshd-session[2361]: pam_unix(sshd:session): session closed for user core Jul 7 00:13:56.091870 systemd[1]: sshd@1-10.200.4.18:22-10.200.16.10:34260.service: Deactivated successfully. Jul 7 00:13:56.093513 systemd[1]: session-4.scope: Deactivated successfully. Jul 7 00:13:56.094459 systemd-logind[1886]: Session 4 logged out. Waiting for processes to exit. Jul 7 00:13:56.099044 systemd-logind[1886]: Removed session 4. Jul 7 00:13:56.194783 systemd[1]: Started sshd@2-10.200.4.18:22-10.200.16.10:34268.service - OpenSSH per-connection server daemon (10.200.16.10:34268). Jul 7 00:13:56.806146 sshd[2369]: Accepted publickey for core from 10.200.16.10 port 34268 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:56.808752 sshd-session[2369]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:56.815752 systemd-logind[1886]: New session 5 of user core. Jul 7 00:13:56.822386 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 7 00:13:57.230280 sshd[2371]: Connection closed by 10.200.16.10 port 34268 Jul 7 00:13:57.230651 sshd-session[2369]: pam_unix(sshd:session): session closed for user core Jul 7 00:13:57.235850 systemd[1]: sshd@2-10.200.4.18:22-10.200.16.10:34268.service: Deactivated successfully. Jul 7 00:13:57.237758 systemd[1]: session-5.scope: Deactivated successfully. Jul 7 00:13:57.238810 systemd-logind[1886]: Session 5 logged out. Waiting for processes to exit. Jul 7 00:13:57.244896 systemd-logind[1886]: Removed session 5. Jul 7 00:14:00.575947 setup-nvidia[1875]: Downloading NVIDIA 535.230.02 Driver Jul 7 00:14:00.580312 setup-nvidia[2377]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 00:14:00.580547 setup-nvidia[2377]: Dload Upload Total Spent Left Speed Jul 7 00:14:02.613138 setup-nvidia[2377]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 38 326M 38 126M 0 0 145M 0 0:00:02 --:--:-- 0:00:02 145M\u000d 90 326M 90 296M 0 0 158M 0 0:00:02 0:00:01 0:00:01 158M\u000d100 326M 100 326M 0 0 160M 0 0:00:02 0:00:02 --:--:-- 160M Jul 7 00:14:02.686542 setup-nvidia[1875]: Extract the NVIDIA Driver Installer 535.230.02 Jul 7 00:14:02.686542 setup-nvidia[1875]: /opt/nvidia/workdir/nvidia-workdir / Jul 7 00:14:02.703903 setup-nvidia[2380]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 7 00:14:02.956587 setup-nvidia[2380]: Verifying archive integrity... OK Jul 7 00:14:03.673707 setup-nvidia[2380]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 7 00:14:05.397983 setup-nvidia[2406]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 7 00:14:05.412342 setup-nvidia[1875]: / Jul 7 00:14:05.412511 setup-nvidia[1875]: Spawn system-nspawn container to install the NVIDIA drivers Jul 7 00:14:05.624868 sudo[2413]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.1.1.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 Jul 7 00:14:05.626687 sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 7 00:14:05.632239 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 7 00:14:05.635219 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 00:14:05.657836 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 00:14:05.663881 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 7 00:14:05.690063 (systemd)[2416]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 7 00:14:05.692273 systemd-logind[1886]: New session c3 of user root. Jul 7 00:14:05.865261 systemd[2416]: Queued start job for default target default.target. Jul 7 00:14:05.885219 systemd[2416]: Created slice app.slice - User Application Slice. Jul 7 00:14:05.885249 systemd[2416]: Reached target paths.target - Paths. Jul 7 00:14:05.885296 systemd[2416]: Reached target timers.target - Timers. Jul 7 00:14:05.886430 systemd[2416]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 00:14:05.896691 systemd[2416]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:14:05.896749 systemd[2416]: Reached target sockets.target - Sockets. Jul 7 00:14:05.896799 systemd[2416]: Reached target basic.target - Basic System. Jul 7 00:14:05.896843 systemd[2416]: Reached target default.target - Main User Target. Jul 7 00:14:05.896873 systemd[2416]: Startup finished in 199ms. Jul 7 00:14:05.896967 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 7 00:14:05.900115 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 7 00:14:05.939177 kernel: loop6: detected capacity change from 0 to 12644352 Jul 7 00:14:05.945170 kernel: loop6: p9 Jul 7 00:14:06.035409 setup-nvidia[2425]: ░ Spawning container flatcardevelopercontainer-4344.1.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.1.1.bin. Jul 7 00:14:07.339819 systemd[1]: Started sshd@3-10.200.4.18:22-10.200.16.10:44100.service - OpenSSH per-connection server daemon (10.200.16.10:44100). Jul 7 00:14:07.346170 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 7 00:14:07.346225 kernel: EXT4-fs (loop6p9): mounted filesystem 652653fa-37fd-4534-a1c2-1154064d51e9 ro without journal. Quota mode: none. Jul 7 00:14:07.841886 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 7 00:14:07.979415 sshd[2430]: Accepted publickey for core from 10.200.16.10 port 44100 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:07.980793 sshd-session[2430]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:07.985251 systemd-logind[1886]: New session 6 of user core. Jul 7 00:14:08.004345 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 7 00:14:08.399238 sshd[2434]: Connection closed by 10.200.16.10 port 44100 Jul 7 00:14:08.399557 sshd-session[2430]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:08.402735 systemd[1]: sshd@3-10.200.4.18:22-10.200.16.10:44100.service: Deactivated successfully. Jul 7 00:14:08.404304 systemd[1]: session-6.scope: Deactivated successfully. Jul 7 00:14:08.404962 systemd-logind[1886]: Session 6 logged out. Waiting for processes to exit. Jul 7 00:14:08.405901 systemd-logind[1886]: Removed session 6. Jul 7 00:14:08.510360 systemd[1]: Started sshd@4-10.200.4.18:22-10.200.16.10:44102.service - OpenSSH per-connection server daemon (10.200.16.10:44102). Jul 7 00:14:09.127717 sshd[2442]: Accepted publickey for core from 10.200.16.10 port 44102 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:09.128916 sshd-session[2442]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:09.133494 systemd-logind[1886]: New session 7 of user core. Jul 7 00:14:09.146311 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 7 00:14:09.277386 update_engine[1887]: I20250707 00:14:09.277318 1887 update_attempter.cc:509] Updating boot flags... Jul 7 00:14:09.557749 sshd[2478]: Connection closed by 10.200.16.10 port 44102 Jul 7 00:14:09.558092 sshd-session[2442]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:09.561581 systemd[1]: sshd@4-10.200.4.18:22-10.200.16.10:44102.service: Deactivated successfully. Jul 7 00:14:09.563364 systemd[1]: session-7.scope: Deactivated successfully. Jul 7 00:14:09.564086 systemd-logind[1886]: Session 7 logged out. Waiting for processes to exit. Jul 7 00:14:09.565007 systemd-logind[1886]: Removed session 7. Jul 7 00:14:19.668545 systemd[1]: Started sshd@5-10.200.4.18:22-10.200.16.10:57632.service - OpenSSH per-connection server daemon (10.200.16.10:57632). Jul 7 00:14:20.270554 sshd[3282]: Accepted publickey for core from 10.200.16.10 port 57632 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:20.271840 sshd-session[3282]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:20.275938 systemd-logind[1886]: New session 8 of user core. Jul 7 00:14:20.286284 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 7 00:14:20.700455 sshd[3328]: Connection closed by 10.200.16.10 port 57632 Jul 7 00:14:20.700767 sshd-session[3282]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:20.704115 systemd[1]: sshd@5-10.200.4.18:22-10.200.16.10:57632.service: Deactivated successfully. Jul 7 00:14:20.705680 systemd[1]: session-8.scope: Deactivated successfully. Jul 7 00:14:20.706359 systemd-logind[1886]: Session 8 logged out. Waiting for processes to exit. Jul 7 00:14:20.707282 systemd-logind[1886]: Removed session 8. Jul 7 00:14:20.808216 systemd[1]: Started sshd@6-10.200.4.18:22-10.200.16.10:57646.service - OpenSSH per-connection server daemon (10.200.16.10:57646). Jul 7 00:14:21.420060 sshd[3334]: Accepted publickey for core from 10.200.16.10 port 57646 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:21.421373 sshd-session[3334]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:21.425545 systemd-logind[1886]: New session 9 of user core. Jul 7 00:14:21.436307 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 7 00:14:21.839764 sshd[3336]: Connection closed by 10.200.16.10 port 57646 Jul 7 00:14:21.840038 sshd-session[3334]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:21.843529 systemd[1]: sshd@6-10.200.4.18:22-10.200.16.10:57646.service: Deactivated successfully. Jul 7 00:14:21.845135 systemd[1]: session-9.scope: Deactivated successfully. Jul 7 00:14:21.845839 systemd-logind[1886]: Session 9 logged out. Waiting for processes to exit. Jul 7 00:14:21.846782 systemd-logind[1886]: Removed session 9. Jul 7 00:14:31.964534 systemd[1]: Started sshd@7-10.200.4.18:22-10.200.16.10:46900.service - OpenSSH per-connection server daemon (10.200.16.10:46900). Jul 7 00:14:32.565104 sshd[3439]: Accepted publickey for core from 10.200.16.10 port 46900 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:32.566441 sshd-session[3439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:32.570744 systemd-logind[1886]: New session 10 of user core. Jul 7 00:14:32.586271 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 7 00:14:32.996306 sshd[3441]: Connection closed by 10.200.16.10 port 46900 Jul 7 00:14:32.996689 sshd-session[3439]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:33.000131 systemd[1]: sshd@7-10.200.4.18:22-10.200.16.10:46900.service: Deactivated successfully. Jul 7 00:14:33.001736 systemd[1]: session-10.scope: Deactivated successfully. Jul 7 00:14:33.002413 systemd-logind[1886]: Session 10 logged out. Waiting for processes to exit. Jul 7 00:14:33.003338 systemd-logind[1886]: Removed session 10. Jul 7 00:14:33.105350 systemd[1]: Started sshd@8-10.200.4.18:22-10.200.16.10:46906.service - OpenSSH per-connection server daemon (10.200.16.10:46906). Jul 7 00:14:33.707594 sshd[3447]: Accepted publickey for core from 10.200.16.10 port 46906 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:33.708846 sshd-session[3447]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:33.713100 systemd-logind[1886]: New session 11 of user core. Jul 7 00:14:33.725288 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 7 00:14:34.137812 sshd[3449]: Connection closed by 10.200.16.10 port 46906 Jul 7 00:14:34.138139 sshd-session[3447]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:34.141476 systemd[1]: sshd@8-10.200.4.18:22-10.200.16.10:46906.service: Deactivated successfully. Jul 7 00:14:34.143008 systemd[1]: session-11.scope: Deactivated successfully. Jul 7 00:14:34.143714 systemd-logind[1886]: Session 11 logged out. Waiting for processes to exit. Jul 7 00:14:34.144824 systemd-logind[1886]: Removed session 11. Jul 7 00:14:44.248600 systemd[1]: Started sshd@9-10.200.4.18:22-10.200.16.10:51766.service - OpenSSH per-connection server daemon (10.200.16.10:51766). Jul 7 00:14:44.857451 sshd[5495]: Accepted publickey for core from 10.200.16.10 port 51766 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:44.858758 sshd-session[5495]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:44.863012 systemd-logind[1886]: New session 12 of user core. Jul 7 00:14:44.876278 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 7 00:14:46.338945 sshd[5497]: Connection closed by 10.200.16.10 port 51766 Jul 7 00:14:45.392572 systemd[1]: Started sshd@10-10.200.4.18:22-10.200.16.10:51772.service - OpenSSH per-connection server daemon (10.200.16.10:51772). Jul 7 00:14:46.339017 sshd-session[5495]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:46.339684 sshd[5500]: Accepted publickey for core from 10.200.16.10 port 51772 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:46.339271 sshd-session[5500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:46.342923 systemd[1]: sshd@9-10.200.4.18:22-10.200.16.10:51766.service: Deactivated successfully. Jul 7 00:14:46.346061 systemd[1]: session-12.scope: Deactivated successfully. Jul 7 00:14:46.351335 systemd-logind[1886]: New session 13 of user core. Jul 7 00:14:46.363367 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 7 00:14:46.368484 systemd-logind[1886]: Session 12 logged out. Waiting for processes to exit. Jul 7 00:14:46.369379 systemd-logind[1886]: Removed session 12. Jul 7 00:14:46.703183 sshd[5513]: Connection closed by 10.200.16.10 port 51772 Jul 7 00:14:46.703146 sshd-session[5500]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:46.710758 systemd[1]: sshd@10-10.200.4.18:22-10.200.16.10:51772.service: Deactivated successfully. Jul 7 00:14:46.712546 systemd[1]: session-13.scope: Deactivated successfully. Jul 7 00:14:46.713515 systemd-logind[1886]: Session 13 logged out. Waiting for processes to exit. Jul 7 00:14:46.716565 systemd-logind[1886]: Removed session 13. Jul 7 00:14:56.812225 systemd[1]: Started sshd@11-10.200.4.18:22-10.200.16.10:48518.service - OpenSSH per-connection server daemon (10.200.16.10:48518). Jul 7 00:14:57.428039 sshd[7913]: Accepted publickey for core from 10.200.16.10 port 48518 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:57.429540 sshd-session[7913]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:57.437793 systemd-logind[1886]: New session 14 of user core. Jul 7 00:14:57.444649 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 7 00:14:57.857788 sshd[8155]: Connection closed by 10.200.16.10 port 48518 Jul 7 00:14:57.859290 sshd-session[7913]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:57.862916 systemd[1]: sshd@11-10.200.4.18:22-10.200.16.10:48518.service: Deactivated successfully. Jul 7 00:14:57.864643 systemd[1]: session-14.scope: Deactivated successfully. Jul 7 00:14:57.865338 systemd-logind[1886]: Session 14 logged out. Waiting for processes to exit. Jul 7 00:14:57.866351 systemd-logind[1886]: Removed session 14. Jul 7 00:14:57.967649 systemd[1]: Started sshd@12-10.200.4.18:22-10.200.16.10:48526.service - OpenSSH per-connection server daemon (10.200.16.10:48526). Jul 7 00:14:58.574955 sshd[8520]: Accepted publickey for core from 10.200.16.10 port 48526 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:58.575695 sshd-session[8520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:58.581474 systemd-logind[1886]: New session 15 of user core. Jul 7 00:14:58.590336 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 7 00:14:58.995188 sshd[8952]: Connection closed by 10.200.16.10 port 48526 Jul 7 00:14:58.995548 sshd-session[8520]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:59.000936 systemd-logind[1886]: Session 15 logged out. Waiting for processes to exit. Jul 7 00:14:59.002419 systemd[1]: sshd@12-10.200.4.18:22-10.200.16.10:48526.service: Deactivated successfully. Jul 7 00:14:59.004648 systemd[1]: session-15.scope: Deactivated successfully. Jul 7 00:14:59.008464 systemd-logind[1886]: Removed session 15. Jul 7 00:15:09.111888 systemd[1]: Started sshd@13-10.200.4.18:22-10.200.16.10:59336.service - OpenSSH per-connection server daemon (10.200.16.10:59336). Jul 7 00:15:09.725012 sshd[11455]: Accepted publickey for core from 10.200.16.10 port 59336 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:09.725586 sshd-session[11455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:09.731734 systemd-logind[1886]: New session 16 of user core. Jul 7 00:15:09.743246 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 7 00:15:10.155301 sshd[11487]: Connection closed by 10.200.16.10 port 59336 Jul 7 00:15:10.155687 sshd-session[11455]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:10.161973 systemd[1]: sshd@13-10.200.4.18:22-10.200.16.10:59336.service: Deactivated successfully. Jul 7 00:15:10.163553 systemd[1]: session-16.scope: Deactivated successfully. Jul 7 00:15:10.164145 systemd-logind[1886]: Session 16 logged out. Waiting for processes to exit. Jul 7 00:15:10.167695 systemd-logind[1886]: Removed session 16. Jul 7 00:15:10.262924 systemd[1]: Started sshd@14-10.200.4.18:22-10.200.16.10:33834.service - OpenSSH per-connection server daemon (10.200.16.10:33834). Jul 7 00:15:10.882018 sshd[11517]: Accepted publickey for core from 10.200.16.10 port 33834 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:10.883894 sshd-session[11517]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:10.892713 systemd-logind[1886]: New session 17 of user core. Jul 7 00:15:10.901319 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 7 00:15:11.315369 sshd[11537]: Connection closed by 10.200.16.10 port 33834 Jul 7 00:15:11.316121 sshd-session[11517]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:11.319569 systemd-logind[1886]: Session 17 logged out. Waiting for processes to exit. Jul 7 00:15:11.320391 systemd[1]: sshd@14-10.200.4.18:22-10.200.16.10:33834.service: Deactivated successfully. Jul 7 00:15:11.323602 systemd[1]: session-17.scope: Deactivated successfully. Jul 7 00:15:11.326868 systemd-logind[1886]: Removed session 17. Jul 7 00:15:21.433227 systemd[1]: Started sshd@15-10.200.4.18:22-10.200.16.10:42326.service - OpenSSH per-connection server daemon (10.200.16.10:42326). Jul 7 00:15:22.053638 sshd[11939]: Accepted publickey for core from 10.200.16.10 port 42326 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:22.054534 sshd-session[11939]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:22.063880 systemd-logind[1886]: New session 18 of user core. Jul 7 00:15:22.070287 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 7 00:15:22.479466 sshd[11960]: Connection closed by 10.200.16.10 port 42326 Jul 7 00:15:22.481149 sshd-session[11939]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:22.484658 systemd[1]: sshd@15-10.200.4.18:22-10.200.16.10:42326.service: Deactivated successfully. Jul 7 00:15:22.487609 systemd[1]: session-18.scope: Deactivated successfully. Jul 7 00:15:22.490278 systemd-logind[1886]: Session 18 logged out. Waiting for processes to exit. Jul 7 00:15:22.491778 systemd-logind[1886]: Removed session 18. Jul 7 00:15:22.587296 systemd[1]: Started sshd@16-10.200.4.18:22-10.200.16.10:42342.service - OpenSSH per-connection server daemon (10.200.16.10:42342). Jul 7 00:15:23.205756 sshd[11990]: Accepted publickey for core from 10.200.16.10 port 42342 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:23.206950 sshd-session[11990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:23.213283 systemd-logind[1886]: New session 19 of user core. Jul 7 00:15:23.218806 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 7 00:15:23.635926 sshd[12015]: Connection closed by 10.200.16.10 port 42342 Jul 7 00:15:23.636448 sshd-session[11990]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:23.640225 systemd-logind[1886]: Session 19 logged out. Waiting for processes to exit. Jul 7 00:15:23.641296 systemd[1]: sshd@16-10.200.4.18:22-10.200.16.10:42342.service: Deactivated successfully. Jul 7 00:15:23.643004 systemd[1]: session-19.scope: Deactivated successfully. Jul 7 00:15:23.645857 systemd-logind[1886]: Removed session 19. Jul 7 00:15:33.751684 systemd[1]: Started sshd@17-10.200.4.18:22-10.200.16.10:45444.service - OpenSSH per-connection server daemon (10.200.16.10:45444). Jul 7 00:15:34.367651 sshd[12351]: Accepted publickey for core from 10.200.16.10 port 45444 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:34.369529 sshd-session[12351]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:34.376209 systemd-logind[1886]: New session 20 of user core. Jul 7 00:15:34.380799 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 7 00:15:34.798478 sshd[12359]: Connection closed by 10.200.16.10 port 45444 Jul 7 00:15:34.800569 sshd-session[12351]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:34.804506 systemd[1]: sshd@17-10.200.4.18:22-10.200.16.10:45444.service: Deactivated successfully. Jul 7 00:15:34.805200 systemd-logind[1886]: Session 20 logged out. Waiting for processes to exit. Jul 7 00:15:34.807140 systemd[1]: session-20.scope: Deactivated successfully. Jul 7 00:15:34.809533 systemd-logind[1886]: Removed session 20. Jul 7 00:15:34.912995 systemd[1]: Started sshd@18-10.200.4.18:22-10.200.16.10:45450.service - OpenSSH per-connection server daemon (10.200.16.10:45450). Jul 7 00:15:35.529093 sshd[12385]: Accepted publickey for core from 10.200.16.10 port 45450 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:35.530422 sshd-session[12385]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:35.537489 systemd-logind[1886]: New session 21 of user core. Jul 7 00:15:35.544654 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 7 00:15:35.962847 sshd[12409]: Connection closed by 10.200.16.10 port 45450 Jul 7 00:15:35.963641 sshd-session[12385]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:35.967856 systemd-logind[1886]: Session 21 logged out. Waiting for processes to exit. Jul 7 00:15:35.969403 systemd[1]: sshd@18-10.200.4.18:22-10.200.16.10:45450.service: Deactivated successfully. Jul 7 00:15:35.971001 systemd[1]: session-21.scope: Deactivated successfully. Jul 7 00:15:35.973597 systemd-logind[1886]: Removed session 21. Jul 7 00:15:46.072539 systemd[1]: Started sshd@19-10.200.4.18:22-10.200.16.10:43210.service - OpenSSH per-connection server daemon (10.200.16.10:43210). Jul 7 00:15:46.689306 sshd[12613]: Accepted publickey for core from 10.200.16.10 port 43210 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:46.690570 sshd-session[12613]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:46.694869 systemd-logind[1886]: New session 22 of user core. Jul 7 00:15:46.705291 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 7 00:15:46.848172 setup-nvidia[2425]: \u000d Jul 7 00:15:46.848172 setup-nvidia[2425]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 7 00:15:46.848172 setup-nvidia[2425]: frequently when this kernel module was built against the wrong or Jul 7 00:15:46.848172 setup-nvidia[2425]: improperly configured kernel sources, with a version of gcc that differs Jul 7 00:15:46.848172 setup-nvidia[2425]: from the one used to build the target kernel, or if another driver, such Jul 7 00:15:46.848172 setup-nvidia[2425]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 7 00:15:46.848172 setup-nvidia[2425]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 7 00:15:46.848172 setup-nvidia[2425]: installed in this system is supported by this NVIDIA Linux graphics Jul 7 00:15:46.848172 setup-nvidia[2425]: driver release. Jul 7 00:15:46.848172 setup-nvidia[2425]: \u000d Jul 7 00:15:46.848172 setup-nvidia[2425]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 7 00:15:46.848172 setup-nvidia[2425]: messages' at the end of the file Jul 7 00:15:46.848172 setup-nvidia[2425]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 7 00:15:46.848172 setup-nvidia[2425]: information. Jul 7 00:15:46.848172 setup-nvidia[2425]: \u000d Jul 7 00:15:46.862053 setup-nvidia[2425]: dmesg: read kernel buffer failed: Operation not permitted Jul 7 00:15:46.880601 setup-nvidia[2425]: \u000d Jul 7 00:15:46.880601 setup-nvidia[2425]: ERROR: Installation has failed. Please see the file Jul 7 00:15:46.880601 setup-nvidia[2425]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 7 00:15:46.880601 setup-nvidia[2425]: details. You may find suggestions on fixing installation problems in Jul 7 00:15:46.880601 setup-nvidia[2425]: the README available on the Linux driver download page at Jul 7 00:15:46.880601 setup-nvidia[2425]: www.nvidia.com. Jul 7 00:15:46.880601 setup-nvidia[2425]: \u000d Jul 7 00:15:46.880923 setup-nvidia[2425]: Last 50 lines of nvidia-installer.log: Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:46.881729 setup-nvidia[2425]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 7 00:15:46.881729 setup-nvidia[2425]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 7 00:15:46.881729 setup-nvidia[2425]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 7 00:15:46.881729 setup-nvidia[2425]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 7 00:15:46.881729 setup-nvidia[2425]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 7 00:15:46.881729 setup-nvidia[2425]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 7 00:15:46.881729 setup-nvidia[2425]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 00:15:46.881729 setup-nvidia[2425]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 7 00:15:46.881729 setup-nvidia[2425]: make[2]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/build' Jul 7 00:15:46.881729 setup-nvidia[2425]: make[1]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/source' Jul 7 00:15:46.881729 setup-nvidia[2425]: -> done. Jul 7 00:15:46.881729 setup-nvidia[2425]: -> Kernel module compilation complete. Jul 7 00:15:46.881729 setup-nvidia[2425]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 7 00:15:46.881729 setup-nvidia[2425]: 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. Jul 7 00:15:46.881729 setup-nvidia[2425]: \u000d Jul 7 00:15:46.882924 setup-nvidia[2425]: 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. Jul 7 00:15:46.882924 setup-nvidia[2425]: -> Kernel module load error: Operation not permitted Jul 7 00:15:46.882924 setup-nvidia[2425]: -> Kernel messages: Jul 7 00:15:46.882924 setup-nvidia[2425]: 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. Jul 7 00:15:47.110885 sshd[12677]: Connection closed by 10.200.16.10 port 43210 Jul 7 00:15:47.111251 sshd-session[12613]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:47.114794 systemd[1]: sshd@19-10.200.4.18:22-10.200.16.10:43210.service: Deactivated successfully. Jul 7 00:15:47.116742 systemd[1]: session-22.scope: Deactivated successfully. Jul 7 00:15:47.117441 systemd-logind[1886]: Session 22 logged out. Waiting for processes to exit. Jul 7 00:15:47.118433 systemd-logind[1886]: Removed session 22. Jul 7 00:15:47.215248 systemd[1]: Started sshd@20-10.200.4.18:22-10.200.16.10:43222.service - OpenSSH per-connection server daemon (10.200.16.10:43222). Jul 7 00:15:47.810520 sshd[12750]: Accepted publickey for core from 10.200.16.10 port 43222 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:47.811763 sshd-session[12750]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:47.816056 systemd-logind[1886]: New session 23 of user core. Jul 7 00:15:47.827275 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 7 00:15:48.230210 sshd[12752]: Connection closed by 10.200.16.10 port 43222 Jul 7 00:15:48.230519 sshd-session[12750]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:48.234049 systemd[1]: sshd@20-10.200.4.18:22-10.200.16.10:43222.service: Deactivated successfully. Jul 7 00:15:48.235898 systemd[1]: session-23.scope: Deactivated successfully. Jul 7 00:15:48.236709 systemd-logind[1886]: Session 23 logged out. Waiting for processes to exit. Jul 7 00:15:48.237909 systemd-logind[1886]: Removed session 23. Jul 7 00:15:58.341117 systemd[1]: Started sshd@21-10.200.4.18:22-10.200.16.10:54164.service - OpenSSH per-connection server daemon (10.200.16.10:54164). Jul 7 00:15:58.938503 sshd[12759]: Accepted publickey for core from 10.200.16.10 port 54164 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:58.939800 sshd-session[12759]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:58.944317 systemd-logind[1886]: New session 24 of user core. Jul 7 00:15:58.962280 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 7 00:15:59.366752 sshd[12761]: Connection closed by 10.200.16.10 port 54164 Jul 7 00:15:59.367031 sshd-session[12759]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:59.370400 systemd[1]: sshd@21-10.200.4.18:22-10.200.16.10:54164.service: Deactivated successfully. Jul 7 00:15:59.371955 systemd[1]: session-24.scope: Deactivated successfully. Jul 7 00:15:59.372703 systemd-logind[1886]: Session 24 logged out. Waiting for processes to exit. Jul 7 00:15:59.373645 systemd-logind[1886]: Removed session 24. Jul 7 00:15:59.476488 systemd[1]: Started sshd@22-10.200.4.18:22-10.200.16.10:54170.service - OpenSSH per-connection server daemon (10.200.16.10:54170). Jul 7 00:16:00.072827 sshd[12767]: Accepted publickey for core from 10.200.16.10 port 54170 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:00.074124 sshd-session[12767]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:00.078510 systemd-logind[1886]: New session 25 of user core. Jul 7 00:16:00.095291 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 7 00:16:00.371177 kernel: EXT4-fs (loop6p9): unmounting filesystem 652653fa-37fd-4534-a1c2-1154064d51e9. Jul 7 00:16:00.371898 setup-nvidia[2425]: Container flatcardevelopercontainer-4344.1.1.bin exited successfully. Jul 7 00:16:00.374214 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.1.1.bin.mount: Deactivated successfully. Jul 7 00:16:00.427919 sudo[2413]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:00.429764 systemd[1]: session-c2.scope: Deactivated successfully. Jul 7 00:16:00.430031 systemd[1]: session-c2.scope: Consumed 5min 7.003s CPU time, 893.6M memory peak. Jul 7 00:16:00.505928 sshd[12769]: Connection closed by 10.200.16.10 port 54170 Jul 7 00:16:00.506306 sshd-session[12767]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:00.509687 systemd[1]: sshd@22-10.200.4.18:22-10.200.16.10:54170.service: Deactivated successfully. Jul 7 00:16:00.511269 systemd[1]: session-25.scope: Deactivated successfully. Jul 7 00:16:00.511942 systemd-logind[1886]: Session 25 logged out. Waiting for processes to exit. Jul 7 00:16:00.512930 systemd-logind[1886]: Removed session 25. Jul 7 00:16:00.858922 setup-nvidia[1875]: /opt/nvidia / Jul 7 00:16:00.859903 setup-nvidia[1875]: / Jul 7 00:16:00.869174 kernel: IPMI message handler: version 39.2 Jul 7 00:16:00.873177 kernel: ipmi device interface Jul 7 00:16:00.885022 setup-nvidia[1875]: /opt/nvidia/current/usr/lib/modules/6.12.35-flatcar/video / Jul 7 00:16:00.938247 kernel: nvidia: loading out-of-tree module taints kernel. Jul 7 00:16:00.938396 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 7 00:16:00.938424 kernel: Disabling lock debugging due to kernel taint Jul 7 00:16:00.945751 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 7 00:16:00.945785 kernel: nvidia: module license taints kernel. Jul 7 00:16:01.336188 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 7 00:16:01.735734 kernel: Jul 7 00:16:01.735873 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 7 00:16:01.794178 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 7 00:16:01.815174 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jul 7 00:16:01.835188 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 7 00:16:01.878176 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 7 00:16:01.878495 setup-nvidia[1875]: / Jul 7 00:16:01.893353 setup-nvidia[12808]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 00:16:03.464240 setup-nvidia[12825]: Mon Jul 7 00:16:03 2025 Jul 7 00:16:03.464240 setup-nvidia[12825]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:03.464240 setup-nvidia[12825]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 7 00:16:03.464240 setup-nvidia[12825]: |-----------------------------------------+----------------------+----------------------+ Jul 7 00:16:03.464240 setup-nvidia[12825]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 7 00:16:03.464240 setup-nvidia[12825]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 7 00:16:03.464240 setup-nvidia[12825]: | | | MIG M. | Jul 7 00:16:03.464240 setup-nvidia[12825]: |=========================================+======================+======================| Jul 7 00:16:03.468009 setup-nvidia[12825]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 7 00:16:03.468009 setup-nvidia[12825]: | N/A 29C P0 34W / 250W | 0MiB / 16384MiB | 1% Default | Jul 7 00:16:03.468009 setup-nvidia[12825]: | | | N/A | Jul 7 00:16:03.468009 setup-nvidia[12825]: +-----------------------------------------+----------------------+----------------------+ Jul 7 00:16:03.468009 setup-nvidia[12825]: Jul 7 00:16:03.468009 setup-nvidia[12825]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:03.468009 setup-nvidia[12825]: | Processes: | Jul 7 00:16:03.468009 setup-nvidia[12825]: | GPU GI CI PID Type Process name GPU Memory | Jul 7 00:16:03.468009 setup-nvidia[12825]: | ID ID Usage | Jul 7 00:16:03.468009 setup-nvidia[12825]: |=======================================================================================| Jul 7 00:16:03.468292 setup-nvidia[12825]: | No running processes found | Jul 7 00:16:03.468292 setup-nvidia[12825]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:03.835410 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 7 00:16:03.837426 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 7 00:16:03.837740 systemd[1]: Startup finished in 1.080s (firmware) + 29.032s (loader) + 3.803s (kernel) + 12.122s (initrd) + 2min 50.634s (userspace) = 3min 36.673s. Jul 7 00:16:10.617622 systemd[1]: Started sshd@23-10.200.4.18:22-10.200.16.10:53968.service - OpenSSH per-connection server daemon (10.200.16.10:53968). Jul 7 00:16:10.618598 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 7 00:16:10.619362 systemd[2416]: Activating special unit exit.target... Jul 7 00:16:10.619442 systemd[2416]: Stopped target default.target - Main User Target. Jul 7 00:16:10.619481 systemd[2416]: Stopped target basic.target - Basic System. Jul 7 00:16:10.619509 systemd[2416]: Stopped target paths.target - Paths. Jul 7 00:16:10.619587 systemd[2416]: Stopped target sockets.target - Sockets. Jul 7 00:16:10.619620 systemd[2416]: Stopped target timers.target - Timers. Jul 7 00:16:10.619955 systemd[2416]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:16:10.620175 systemd[2416]: Removed slice app.slice - User Application Slice. Jul 7 00:16:10.620209 systemd[2416]: Reached target shutdown.target - Shutdown. Jul 7 00:16:10.620341 systemd[2416]: Finished systemd-exit.service - Exit the Session. Jul 7 00:16:10.620378 systemd[2416]: Reached target exit.target - Exit the Session. Jul 7 00:16:10.633083 (sd-pam)[2417]: pam_unix(systemd-user:session): session closed for user root Jul 7 00:16:10.634407 systemd[1]: user@0.service: Deactivated successfully. Jul 7 00:16:10.634762 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 7 00:16:10.636918 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 00:16:10.660826 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 7 00:16:10.661687 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 7 00:16:10.661873 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 00:16:10.663343 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 7 00:16:10.663437 systemd[1]: user-0.slice: Consumed 5min 7.282s CPU time, 902.6M memory peak. Jul 7 00:16:10.663841 systemd-logind[1886]: Removed session c3. Jul 7 00:16:11.219800 sshd[12832]: Accepted publickey for core from 10.200.16.10 port 53968 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:11.221114 sshd-session[12832]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:11.225706 systemd-logind[1886]: New session 26 of user core. Jul 7 00:16:11.237283 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 7 00:16:11.651266 sshd[12838]: Connection closed by 10.200.16.10 port 53968 Jul 7 00:16:11.651639 sshd-session[12832]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:11.655049 systemd[1]: sshd@23-10.200.4.18:22-10.200.16.10:53968.service: Deactivated successfully. Jul 7 00:16:11.656751 systemd[1]: session-26.scope: Deactivated successfully. Jul 7 00:16:11.657440 systemd-logind[1886]: Session 26 logged out. Waiting for processes to exit. Jul 7 00:16:11.658387 systemd-logind[1886]: Removed session 26. Jul 7 00:16:11.760544 systemd[1]: Started sshd@24-10.200.4.18:22-10.200.16.10:53982.service - OpenSSH per-connection server daemon (10.200.16.10:53982). Jul 7 00:16:12.377357 sshd[12844]: Accepted publickey for core from 10.200.16.10 port 53982 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:12.378691 sshd-session[12844]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:12.383140 systemd-logind[1886]: New session 27 of user core. Jul 7 00:16:12.393277 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 7 00:16:12.802363 sshd[12846]: Connection closed by 10.200.16.10 port 53982 Jul 7 00:16:12.911452 systemd[1]: Started sshd@25-10.200.4.18:22-10.200.16.10:53990.service - OpenSSH per-connection server daemon (10.200.16.10:53990). Jul 7 00:16:19.946087 sshd-session[12844]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:19.946648 sshd[12849]: Accepted publickey for core from 10.200.16.10 port 53990 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:19.946334 sshd-session[12849]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:19.953072 systemd-logind[1886]: New session 28 of user core. Jul 7 00:16:19.953643 systemd[1]: sshd@24-10.200.4.18:22-10.200.16.10:53982.service: Deactivated successfully. Jul 7 00:16:19.955456 systemd[1]: session-27.scope: Deactivated successfully. Jul 7 00:16:19.957916 systemd-logind[1886]: Session 27 logged out. Waiting for processes to exit. Jul 7 00:16:19.971312 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 7 00:16:19.972311 systemd-logind[1886]: Removed session 27. Jul 7 00:16:20.312370 sshd[12854]: Connection closed by 10.200.16.10 port 53990 Jul 7 00:16:20.312659 sshd-session[12849]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:20.316139 systemd[1]: sshd@25-10.200.4.18:22-10.200.16.10:53990.service: Deactivated successfully. Jul 7 00:16:20.317748 systemd[1]: session-28.scope: Deactivated successfully. Jul 7 00:16:20.318447 systemd-logind[1886]: Session 28 logged out. Waiting for processes to exit. Jul 7 00:16:20.319500 systemd-logind[1886]: Removed session 28. Jul 7 00:16:20.422550 systemd[1]: Started sshd@26-10.200.4.18:22-10.200.16.10:49904.service - OpenSSH per-connection server daemon (10.200.16.10:49904). Jul 7 00:16:21.026036 sshd[12860]: Accepted publickey for core from 10.200.16.10 port 49904 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:21.027327 sshd-session[12860]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:21.032234 systemd-logind[1886]: New session 29 of user core. Jul 7 00:16:21.042291 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 7 00:16:21.460227 sshd[12862]: Connection closed by 10.200.16.10 port 49904 Jul 7 00:16:21.460542 sshd-session[12860]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:21.463132 systemd[1]: sshd@26-10.200.4.18:22-10.200.16.10:49904.service: Deactivated successfully. Jul 7 00:16:21.464725 systemd[1]: session-29.scope: Deactivated successfully. Jul 7 00:16:21.465951 systemd-logind[1886]: Session 29 logged out. Waiting for processes to exit. Jul 7 00:16:21.467011 systemd-logind[1886]: Removed session 29. Jul 7 00:16:21.570470 systemd[1]: Started sshd@27-10.200.4.18:22-10.200.16.10:49914.service - OpenSSH per-connection server daemon (10.200.16.10:49914). Jul 7 00:16:22.183069 sshd[12868]: Accepted publickey for core from 10.200.16.10 port 49914 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:22.184422 sshd-session[12868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:22.188734 systemd-logind[1886]: New session 30 of user core. Jul 7 00:16:22.198280 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 7 00:16:24.068009 sshd[12870]: Connection closed by 10.200.16.10 port 49914 Jul 7 00:16:24.068380 sshd-session[12868]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:24.076721 systemd[1]: sshd@27-10.200.4.18:22-10.200.16.10:49914.service: Deactivated successfully. Jul 7 00:16:24.078475 systemd[1]: session-30.scope: Deactivated successfully. Jul 7 00:16:24.078680 systemd[1]: session-30.scope: Consumed 1.402s CPU time, 14.1M memory peak. Jul 7 00:16:24.079199 systemd-logind[1886]: Session 30 logged out. Waiting for processes to exit. Jul 7 00:16:24.080874 systemd-logind[1886]: Removed session 30.