Jul 7 06:12:32.195893 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:56:00 -00 2025 Jul 7 06:12:32.195921 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:12:32.195932 kernel: BIOS-provided physical RAM map: Jul 7 06:12:32.195938 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 7 06:12:32.195945 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 7 06:12:32.195952 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 7 06:12:32.195960 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 7 06:12:32.195969 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 7 06:12:32.195976 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 7 06:12:32.195983 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 7 06:12:32.195990 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 7 06:12:32.195997 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 7 06:12:32.196004 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 7 06:12:32.196012 kernel: NX (Execute Disable) protection: active Jul 7 06:12:32.196022 kernel: APIC: Static calls initialized Jul 7 06:12:32.196030 kernel: efi: EFI v2.7 by Microsoft Jul 7 06:12:32.196038 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 7 06:12:32.196046 kernel: random: crng init done Jul 7 06:12:32.196054 kernel: secureboot: Secure boot disabled Jul 7 06:12:32.196062 kernel: SMBIOS 3.1.0 present. Jul 7 06:12:32.196070 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 7 06:12:32.196078 kernel: DMI: Memory slots populated: 3/3 Jul 7 06:12:32.196085 kernel: Hypervisor detected: Microsoft Hyper-V Jul 7 06:12:32.196093 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 7 06:12:32.196103 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 7 06:12:32.196111 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 7 06:12:32.196119 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 06:12:32.196127 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 06:12:32.196135 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 7 06:12:32.196144 kernel: tsc: Detected 2593.990 MHz processor Jul 7 06:12:32.196152 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 7 06:12:32.196160 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 7 06:12:32.196168 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 7 06:12:32.196178 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 7 06:12:32.196186 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 7 06:12:32.196194 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 7 06:12:32.196202 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 7 06:12:32.196209 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 7 06:12:32.196217 kernel: Using GB pages for direct mapping Jul 7 06:12:32.196225 kernel: ACPI: Early table checksum verification disabled Jul 7 06:12:32.196237 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 7 06:12:32.196248 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196256 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196264 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 7 06:12:32.196272 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 7 06:12:32.196281 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196289 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196300 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196308 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196316 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196325 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196333 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:12:32.196341 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 7 06:12:32.196349 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 7 06:12:32.196358 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 7 06:12:32.196368 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 7 06:12:32.196376 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 7 06:12:32.196385 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 7 06:12:32.196393 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 7 06:12:32.196401 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 7 06:12:32.196409 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 7 06:12:32.196418 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 7 06:12:32.196426 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 7 06:12:32.196434 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 7 06:12:32.196445 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 7 06:12:32.196453 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 7 06:12:32.196461 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 7 06:12:32.196469 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 7 06:12:32.196477 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 7 06:12:32.196486 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 7 06:12:32.196494 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 7 06:12:32.196502 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 7 06:12:32.196511 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 7 06:12:32.196540 kernel: Zone ranges: Jul 7 06:12:32.196550 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 7 06:12:32.196558 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 7 06:12:32.196566 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 7 06:12:32.196576 kernel: Device empty Jul 7 06:12:32.196585 kernel: Movable zone start for each node Jul 7 06:12:32.196593 kernel: Early memory node ranges Jul 7 06:12:32.196602 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 7 06:12:32.196610 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 7 06:12:32.196621 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 7 06:12:32.196630 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 7 06:12:32.196638 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 7 06:12:32.196646 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 7 06:12:32.196654 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 7 06:12:32.196663 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 7 06:12:32.196671 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 7 06:12:32.196679 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 7 06:12:32.196687 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 7 06:12:32.196698 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 7 06:12:32.196707 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 7 06:12:32.196715 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 7 06:12:32.196723 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 7 06:12:32.196732 kernel: CPU topo: Max. logical packages: 1 Jul 7 06:12:32.196740 kernel: CPU topo: Max. logical dies: 1 Jul 7 06:12:32.196748 kernel: CPU topo: Max. dies per package: 1 Jul 7 06:12:32.196756 kernel: CPU topo: Max. threads per core: 1 Jul 7 06:12:32.196764 kernel: CPU topo: Num. cores per package: 6 Jul 7 06:12:32.196775 kernel: CPU topo: Num. threads per package: 6 Jul 7 06:12:32.196783 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 7 06:12:32.196791 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 7 06:12:32.196800 kernel: Booting paravirtualized kernel on Hyper-V Jul 7 06:12:32.196809 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 7 06:12:32.196817 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 7 06:12:32.196825 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 7 06:12:32.196833 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 7 06:12:32.196842 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 7 06:12:32.196852 kernel: Hyper-V: PV spinlocks enabled Jul 7 06:12:32.196861 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 7 06:12:32.196870 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:12:32.196879 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 7 06:12:32.196887 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 7 06:12:32.196895 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 7 06:12:32.196904 kernel: Fallback order for Node 0: 0 Jul 7 06:12:32.196912 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 7 06:12:32.196923 kernel: Policy zone: Normal Jul 7 06:12:32.196939 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 7 06:12:32.196947 kernel: software IO TLB: area num 8. Jul 7 06:12:32.196958 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 7 06:12:32.196967 kernel: Kernel/User page tables isolation: enabled Jul 7 06:12:32.196976 kernel: ftrace: allocating 40095 entries in 157 pages Jul 7 06:12:32.196985 kernel: ftrace: allocated 157 pages with 5 groups Jul 7 06:12:32.196993 kernel: Dynamic Preempt: voluntary Jul 7 06:12:32.197002 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 7 06:12:32.197012 kernel: rcu: RCU event tracing is enabled. Jul 7 06:12:32.197020 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 7 06:12:32.197029 kernel: Trampoline variant of Tasks RCU enabled. Jul 7 06:12:32.197041 kernel: Rude variant of Tasks RCU enabled. Jul 7 06:12:32.197049 kernel: Tracing variant of Tasks RCU enabled. Jul 7 06:12:32.197058 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 7 06:12:32.197067 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 7 06:12:32.197076 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:12:32.197087 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:12:32.197096 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:12:32.197105 kernel: Using NULL legacy PIC Jul 7 06:12:32.197113 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 7 06:12:32.197122 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 7 06:12:32.197131 kernel: Console: colour dummy device 80x25 Jul 7 06:12:32.197140 kernel: printk: legacy console [tty1] enabled Jul 7 06:12:32.197148 kernel: printk: legacy console [ttyS0] enabled Jul 7 06:12:32.197157 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 7 06:12:32.197168 kernel: ACPI: Core revision 20240827 Jul 7 06:12:32.197177 kernel: Failed to register legacy timer interrupt Jul 7 06:12:32.197185 kernel: APIC: Switch to symmetric I/O mode setup Jul 7 06:12:32.197194 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 7 06:12:32.197203 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 7 06:12:32.197211 kernel: Hyper-V: Using IPI hypercalls Jul 7 06:12:32.197220 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 7 06:12:32.197229 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 7 06:12:32.197238 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 7 06:12:32.197249 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 7 06:12:32.197257 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 7 06:12:32.197266 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 7 06:12:32.197275 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 7 06:12:32.197284 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 7 06:12:32.197292 kernel: APIC: read() replaced with hv_apic_read() Jul 7 06:12:32.197301 kernel: APIC: write() replaced with hv_apic_write() Jul 7 06:12:32.197310 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 7 06:12:32.197318 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 7 06:12:32.197331 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Jul 7 06:12:32.197341 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 7 06:12:32.197349 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 7 06:12:32.197358 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 7 06:12:32.197367 kernel: Spectre V2 : Mitigation: Retpolines Jul 7 06:12:32.197375 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 7 06:12:32.197384 kernel: Speculative Store Bypass: Vulnerable Jul 7 06:12:32.197392 kernel: MDS: Mitigation: Clear CPU buffers Jul 7 06:12:32.197401 kernel: TAA: Mitigation: Clear CPU buffers Jul 7 06:12:32.197410 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 7 06:12:32.197420 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 7 06:12:32.197429 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 7 06:12:32.197438 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 7 06:12:32.197446 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 7 06:12:32.197454 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 7 06:12:32.197463 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 7 06:12:32.197472 kernel: Freeing SMP alternatives memory: 32K Jul 7 06:12:32.197480 kernel: pid_max: default: 32768 minimum: 301 Jul 7 06:12:32.197489 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 7 06:12:32.197497 kernel: landlock: Up and running. Jul 7 06:12:32.197506 kernel: SELinux: Initializing. Jul 7 06:12:32.197517 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 06:12:32.197535 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 06:12:32.197544 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 7 06:12:32.197553 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 7 06:12:32.197561 kernel: signal: max sigframe size: 1776 Jul 7 06:12:32.197570 kernel: rcu: Hierarchical SRCU implementation. Jul 7 06:12:32.197579 kernel: rcu: Max phase no-delay instances is 400. Jul 7 06:12:32.197588 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 7 06:12:32.197597 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 7 06:12:32.197605 kernel: smp: Bringing up secondary CPUs ... Jul 7 06:12:32.197617 kernel: smpboot: x86: Booting SMP configuration: Jul 7 06:12:32.197625 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 7 06:12:32.197634 kernel: smp: Brought up 1 node, 6 CPUs Jul 7 06:12:32.197643 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Jul 7 06:12:32.197652 kernel: Memory: 115339084K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54432K init, 2536K bss, 2090004K reserved, 0K cma-reserved) Jul 7 06:12:32.197661 kernel: devtmpfs: initialized Jul 7 06:12:32.197669 kernel: x86/mm: Memory block size: 1024MB Jul 7 06:12:32.197678 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 7 06:12:32.197687 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 7 06:12:32.197698 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 7 06:12:32.197707 kernel: pinctrl core: initialized pinctrl subsystem Jul 7 06:12:32.197716 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 7 06:12:32.197724 kernel: audit: initializing netlink subsys (disabled) Jul 7 06:12:32.197733 kernel: audit: type=2000 audit(1751868748.028:1): state=initialized audit_enabled=0 res=1 Jul 7 06:12:32.197742 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 7 06:12:32.197751 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 7 06:12:32.197759 kernel: cpuidle: using governor menu Jul 7 06:12:32.197768 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 7 06:12:32.197779 kernel: dca service started, version 1.12.1 Jul 7 06:12:32.197788 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 7 06:12:32.197797 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 7 06:12:32.197806 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 7 06:12:32.197814 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 7 06:12:32.197823 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 7 06:12:32.197832 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 7 06:12:32.197840 kernel: ACPI: Added _OSI(Module Device) Jul 7 06:12:32.197849 kernel: ACPI: Added _OSI(Processor Device) Jul 7 06:12:32.197860 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 7 06:12:32.197869 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 7 06:12:32.197878 kernel: ACPI: Interpreter enabled Jul 7 06:12:32.197886 kernel: ACPI: PM: (supports S0 S5) Jul 7 06:12:32.197895 kernel: ACPI: Using IOAPIC for interrupt routing Jul 7 06:12:32.197904 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 7 06:12:32.197913 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 7 06:12:32.197921 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 7 06:12:32.197930 kernel: iommu: Default domain type: Translated Jul 7 06:12:32.197941 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 7 06:12:32.197950 kernel: efivars: Registered efivars operations Jul 7 06:12:32.197958 kernel: PCI: Using ACPI for IRQ routing Jul 7 06:12:32.197967 kernel: PCI: System does not support PCI Jul 7 06:12:32.197976 kernel: vgaarb: loaded Jul 7 06:12:32.197985 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 7 06:12:32.197993 kernel: VFS: Disk quotas dquot_6.6.0 Jul 7 06:12:32.198002 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 7 06:12:32.198011 kernel: pnp: PnP ACPI init Jul 7 06:12:32.198022 kernel: pnp: PnP ACPI: found 3 devices Jul 7 06:12:32.198031 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 7 06:12:32.198040 kernel: NET: Registered PF_INET protocol family Jul 7 06:12:32.198048 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 7 06:12:32.198057 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 7 06:12:32.198066 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 7 06:12:32.198075 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 7 06:12:32.198084 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:12:32.198095 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 7 06:12:32.198104 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:12:32.198113 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:12:32.198121 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 7 06:12:32.198130 kernel: NET: Registered PF_XDP protocol family Jul 7 06:12:32.198139 kernel: PCI: CLS 0 bytes, default 64 Jul 7 06:12:32.198147 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 7 06:12:32.198156 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Jul 7 06:12:32.198165 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 7 06:12:32.198176 kernel: Initialise system trusted keyrings Jul 7 06:12:32.198185 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 7 06:12:32.198194 kernel: Key type asymmetric registered Jul 7 06:12:32.198202 kernel: Asymmetric key parser 'x509' registered Jul 7 06:12:32.198211 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 7 06:12:32.198219 kernel: io scheduler mq-deadline registered Jul 7 06:12:32.198228 kernel: io scheduler kyber registered Jul 7 06:12:32.198237 kernel: io scheduler bfq registered Jul 7 06:12:32.198245 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 7 06:12:32.198256 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 7 06:12:32.198265 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 7 06:12:32.198274 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 7 06:12:32.198283 kernel: i8042: PNP: No PS/2 controller found. Jul 7 06:12:32.198447 kernel: rtc_cmos 00:02: registered as rtc0 Jul 7 06:12:32.198634 kernel: rtc_cmos 00:02: setting system clock to 2025-07-07T06:12:31 UTC (1751868751) Jul 7 06:12:32.198737 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 7 06:12:32.198749 kernel: intel_pstate: CPU model not supported Jul 7 06:12:32.198764 kernel: efifb: probing for efifb Jul 7 06:12:32.198773 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 7 06:12:32.198781 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 7 06:12:32.198790 kernel: efifb: scrolling: redraw Jul 7 06:12:32.198799 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 7 06:12:32.198808 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 06:12:32.198817 kernel: fb0: EFI VGA frame buffer device Jul 7 06:12:32.198826 kernel: pstore: Using crash dump compression: deflate Jul 7 06:12:32.198835 kernel: pstore: Registered efi_pstore as persistent store backend Jul 7 06:12:32.198846 kernel: NET: Registered PF_INET6 protocol family Jul 7 06:12:32.198855 kernel: Segment Routing with IPv6 Jul 7 06:12:32.198864 kernel: In-situ OAM (IOAM) with IPv6 Jul 7 06:12:32.198873 kernel: NET: Registered PF_PACKET protocol family Jul 7 06:12:32.198881 kernel: Key type dns_resolver registered Jul 7 06:12:32.198890 kernel: IPI shorthand broadcast: enabled Jul 7 06:12:32.198899 kernel: sched_clock: Marking stable (3703005300, 110612000)->(4156576200, -342958900) Jul 7 06:12:32.198908 kernel: registered taskstats version 1 Jul 7 06:12:32.198917 kernel: Loading compiled-in X.509 certificates Jul 7 06:12:32.198928 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.35-flatcar: b8e96f4c6a9e663230fc9c12b186cf91fcc7a64e' Jul 7 06:12:32.198937 kernel: Demotion targets for Node 0: null Jul 7 06:12:32.198945 kernel: Key type .fscrypt registered Jul 7 06:12:32.198954 kernel: Key type fscrypt-provisioning registered Jul 7 06:12:32.198963 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 7 06:12:32.198971 kernel: ima: Allocated hash algorithm: sha1 Jul 7 06:12:32.198980 kernel: ima: No architecture policies found Jul 7 06:12:32.198989 kernel: clk: Disabling unused clocks Jul 7 06:12:32.198998 kernel: Warning: unable to open an initial console. Jul 7 06:12:32.199009 kernel: Freeing unused kernel image (initmem) memory: 54432K Jul 7 06:12:32.199018 kernel: Write protecting the kernel read-only data: 24576k Jul 7 06:12:32.199027 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 7 06:12:32.199035 kernel: Run /init as init process Jul 7 06:12:32.199044 kernel: with arguments: Jul 7 06:12:32.199053 kernel: /init Jul 7 06:12:32.199061 kernel: with environment: Jul 7 06:12:32.199070 kernel: HOME=/ Jul 7 06:12:32.199078 kernel: TERM=linux Jul 7 06:12:32.199089 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 7 06:12:32.199099 systemd[1]: Successfully made /usr/ read-only. Jul 7 06:12:32.199111 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 06:12:32.199121 systemd[1]: Detected virtualization microsoft. Jul 7 06:12:32.199130 systemd[1]: Detected architecture x86-64. Jul 7 06:12:32.199139 systemd[1]: Running in initrd. Jul 7 06:12:32.199148 systemd[1]: No hostname configured, using default hostname. Jul 7 06:12:32.199160 systemd[1]: Hostname set to . Jul 7 06:12:32.199169 systemd[1]: Initializing machine ID from random generator. Jul 7 06:12:32.199178 systemd[1]: Queued start job for default target initrd.target. Jul 7 06:12:32.199187 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:12:32.199197 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:12:32.199207 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 7 06:12:32.199216 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 06:12:32.199225 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 7 06:12:32.199238 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 7 06:12:32.199248 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 06:12:32.199258 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 7 06:12:32.199267 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:12:32.199277 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:12:32.199286 systemd[1]: Reached target paths.target - Path Units. Jul 7 06:12:32.199295 systemd[1]: Reached target slices.target - Slice Units. Jul 7 06:12:32.199307 systemd[1]: Reached target swap.target - Swaps. Jul 7 06:12:32.199316 systemd[1]: Reached target timers.target - Timer Units. Jul 7 06:12:32.199326 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 06:12:32.199335 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 06:12:32.199345 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 7 06:12:32.199354 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 7 06:12:32.199363 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:12:32.199373 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 06:12:32.199382 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:12:32.199394 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 06:12:32.199403 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 7 06:12:32.199412 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 06:12:32.199422 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 7 06:12:32.199431 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 06:12:32.199441 systemd[1]: Starting systemd-fsck-usr.service... Jul 7 06:12:32.199450 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 06:12:32.199459 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 06:12:32.199489 systemd-journald[233]: Collecting audit messages is disabled. Jul 7 06:12:32.199512 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:12:32.199535 systemd-journald[233]: Journal started Jul 7 06:12:32.199559 systemd-journald[233]: Runtime Journal (/run/log/journal/11f26cf91f534f788ee38f45d0d05ac9) is 8M, max 2.2G, 2.1G free. Jul 7 06:12:32.202927 systemd-modules-load[235]: Inserted module 'overlay' Jul 7 06:12:32.208567 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 06:12:32.208920 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 7 06:12:32.209425 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:12:32.210161 systemd[1]: Finished systemd-fsck-usr.service. Jul 7 06:12:32.212401 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 06:12:32.213573 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 06:12:32.262894 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 7 06:12:32.263493 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:12:32.274220 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:12:32.276280 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:12:32.292143 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 7 06:12:32.301260 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 06:12:32.328540 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 06:12:32.332405 systemd-modules-load[235]: Inserted module 'br_netfilter' Jul 7 06:12:32.336024 kernel: Bridge firewalling registered Jul 7 06:12:32.337961 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 06:12:32.340330 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 06:12:32.349255 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 06:12:32.351749 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 7 06:12:32.365190 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:12:32.378369 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:12:32.388676 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 06:12:32.404942 dracut-cmdline[271]: 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:12:32.424386 systemd-resolved[278]: Positive Trust Anchors: Jul 7 06:12:32.424394 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 06:12:32.424424 systemd-resolved[278]: 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 06:12:32.426965 systemd-resolved[278]: Defaulting to hostname 'linux'. Jul 7 06:12:32.427951 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 06:12:32.437172 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:12:32.582552 kernel: SCSI subsystem initialized Jul 7 06:12:32.593543 kernel: Loading iSCSI transport class v2.0-870. Jul 7 06:12:32.605540 kernel: iscsi: registered transport (tcp) Jul 7 06:12:32.631197 kernel: iscsi: registered transport (qla4xxx) Jul 7 06:12:32.631225 kernel: QLogic iSCSI HBA Driver Jul 7 06:12:32.649322 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 06:12:32.686853 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:12:32.698706 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 06:12:32.741397 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 7 06:12:32.750667 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 7 06:12:32.814543 kernel: raid6: avx2x4 gen() 30483 MB/s Jul 7 06:12:32.834539 kernel: raid6: avx2x2 gen() 31097 MB/s Jul 7 06:12:32.855982 kernel: raid6: avx2x1 gen() 26645 MB/s Jul 7 06:12:32.856013 kernel: raid6: using algorithm avx2x2 gen() 31097 MB/s Jul 7 06:12:32.877853 kernel: raid6: .... xor() 18452 MB/s, rmw enabled Jul 7 06:12:32.877877 kernel: raid6: using avx2x2 recovery algorithm Jul 7 06:12:32.901542 kernel: xor: automatically using best checksumming function avx Jul 7 06:12:33.075551 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 7 06:12:33.082683 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 7 06:12:33.085588 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:12:33.122260 systemd-udevd[486]: Using default interface naming scheme 'v255'. Jul 7 06:12:33.128352 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:12:33.131035 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 7 06:12:33.166736 dracut-pre-trigger[494]: rd.md=0: removing MD RAID activation Jul 7 06:12:33.189713 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 06:12:33.192293 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 06:12:33.269461 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:12:33.278372 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 7 06:12:33.299724 kernel: cryptd: max_cpu_qlen set to 1000 Jul 7 06:12:33.311556 kernel: AES CTR mode by8 optimization enabled Jul 7 06:12:33.323537 kernel: hv_vmbus: Vmbus version:4.0 Jul 7 06:12:33.329923 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 06:12:33.330145 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:12:33.354316 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 7 06:12:33.354348 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 7 06:12:33.343046 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:12:33.360075 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:12:33.369465 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 7 06:12:33.369492 kernel: PTP clock support registered Jul 7 06:12:33.376547 kernel: hv_vmbus: registering driver hv_pci Jul 7 06:12:33.379691 kernel: hv_vmbus: registering driver hv_storvsc Jul 7 06:12:33.379721 kernel: hv_vmbus: registering driver hid_hyperv Jul 7 06:12:33.379734 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 7 06:12:33.385878 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 7 06:12:33.386148 kernel: scsi host1: storvsc_host_t Jul 7 06:12:33.386607 kernel: scsi host0: storvsc_host_t Jul 7 06:12:33.386810 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 06:12:33.387031 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 7 06:12:33.387183 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 7 06:12:33.387197 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 06:12:33.391916 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 7 06:12:33.391955 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 7 06:12:33.397735 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 7 06:12:33.401601 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 7 06:12:33.404594 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 06:12:33.411564 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 7 06:12:33.415582 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 7 06:12:33.419559 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 7 06:12:33.433730 kernel: hv_utils: Registering HyperV Utility Driver Jul 7 06:12:33.433764 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 7 06:12:33.433793 kernel: hv_vmbus: registering driver hv_utils Jul 7 06:12:33.441582 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 7 06:12:33.444555 kernel: hv_utils: Heartbeat IC version 3.0 Jul 7 06:12:33.450608 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 7 06:12:33.450648 kernel: hv_utils: Shutdown IC version 3.2 Jul 7 06:12:33.471230 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 7 06:12:33.471399 kernel: hv_utils: TimeSync IC version 4.0 Jul 7 06:12:33.471414 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 7 06:12:34.559487 systemd-resolved[278]: Clock change detected. Flushing caches. Jul 7 06:12:34.570953 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 7 06:12:34.571123 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 7 06:12:34.581534 kernel: hv_vmbus: registering driver hv_netvsc Jul 7 06:12:34.581565 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 7 06:12:34.582593 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 7 06:12:34.582773 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jul 7 06:12:34.582920 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jul 7 06:12:34.585595 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jul 7 06:12:34.585771 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 7 06:12:34.591908 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jul 7 06:12:34.592111 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jul 7 06:12:34.592253 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 06:12:34.600528 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 06:12:34.600798 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#556 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:34.600943 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#577 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:34.605587 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#563 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:34.642607 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#332 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:34.644281 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:12:34.662288 kernel: sdb: sdb1 Jul 7 06:12:34.662312 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jul 7 06:12:34.662492 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 06:12:34.662506 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 7 06:12:34.666098 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 7 06:12:34.669042 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 7 06:12:34.671528 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 7 06:12:35.205214 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 06:12:35.273756 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 7 06:12:35.361075 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 7 06:12:35.371384 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 7 06:12:35.384182 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 7 06:12:35.543161 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 7 06:12:36.433817 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#515 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:36.445494 disk-uuid[714]: The operation has completed successfully. Jul 7 06:12:36.449616 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 7 06:12:36.514105 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 7 06:12:36.518873 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 7 06:12:36.518964 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 7 06:12:36.561058 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 06:12:36.566274 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:12:36.575941 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 06:12:36.585541 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 7 06:12:36.593262 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 7 06:12:36.629205 sh[885]: Success Jul 7 06:12:36.636073 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 7 06:12:36.692451 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 7 06:12:36.692491 kernel: device-mapper: uevent: version 1.0.3 Jul 7 06:12:36.697447 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 7 06:12:36.709530 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 7 06:12:36.960740 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 7 06:12:36.964058 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 7 06:12:36.988107 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 7 06:12:37.008197 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 7 06:12:37.008220 kernel: BTRFS: device fsid 9d124217-7448-4fc6-a329-8a233bb5a0ac devid 1 transid 38 /dev/mapper/usr (254:0) scanned by mount (910) Jul 7 06:12:37.009524 kernel: BTRFS info (device dm-0): first mount of filesystem 9d124217-7448-4fc6-a329-8a233bb5a0ac Jul 7 06:12:37.017796 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:12:37.020993 kernel: BTRFS info (device dm-0): using free-space-tree Jul 7 06:12:37.571137 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 7 06:12:37.572999 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 7 06:12:37.579325 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 7 06:12:37.580142 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 7 06:12:37.611256 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 7 06:12:37.642545 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (955) Jul 7 06:12:37.642582 kernel: BTRFS info (device sda6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:12:37.651341 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:12:37.654606 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 06:12:37.700327 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 06:12:37.702739 kernel: BTRFS info (device sda6): last unmount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:12:37.713119 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 06:12:37.714856 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 7 06:12:37.724088 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 7 06:12:37.764219 systemd-networkd[1077]: lo: Link UP Jul 7 06:12:37.764230 systemd-networkd[1077]: lo: Gained carrier Jul 7 06:12:37.765382 systemd-networkd[1077]: Enumeration completed Jul 7 06:12:37.765480 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 06:12:37.765751 systemd-networkd[1077]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:12:37.765755 systemd-networkd[1077]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:12:37.766550 systemd-networkd[1077]: eth0: Link UP Jul 7 06:12:37.766760 systemd-networkd[1077]: eth0: Gained carrier Jul 7 06:12:37.766770 systemd-networkd[1077]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:12:37.771234 systemd[1]: Reached target network.target - Network. Jul 7 06:12:37.827552 systemd-networkd[1077]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:12:38.867623 systemd-networkd[1077]: eth0: Gained IPv6LL Jul 7 06:12:39.373300 ignition[1079]: Ignition 2.21.0 Jul 7 06:12:39.373314 ignition[1079]: Stage: fetch-offline Jul 7 06:12:39.373400 ignition[1079]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:39.377600 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 06:12:39.373409 ignition[1079]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:39.387311 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 7 06:12:39.373525 ignition[1079]: parsed url from cmdline: "" Jul 7 06:12:39.373530 ignition[1079]: no config URL provided Jul 7 06:12:39.373536 ignition[1079]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 06:12:39.373545 ignition[1079]: no config at "/usr/lib/ignition/user.ign" Jul 7 06:12:39.373550 ignition[1079]: failed to fetch config: resource requires networking Jul 7 06:12:39.376046 ignition[1079]: Ignition finished successfully Jul 7 06:12:39.463358 ignition[1092]: Ignition 2.21.0 Jul 7 06:12:39.463372 ignition[1092]: Stage: fetch Jul 7 06:12:39.463631 ignition[1092]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:39.463643 ignition[1092]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:39.463741 ignition[1092]: parsed url from cmdline: "" Jul 7 06:12:39.463744 ignition[1092]: no config URL provided Jul 7 06:12:39.463750 ignition[1092]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 06:12:39.463759 ignition[1092]: no config at "/usr/lib/ignition/user.ign" Jul 7 06:12:39.463790 ignition[1092]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 7 06:12:39.559029 ignition[1092]: GET result: OK Jul 7 06:12:39.559084 ignition[1092]: config has been read from IMDS userdata Jul 7 06:12:39.559110 ignition[1092]: parsing config with SHA512: ebdf40abfbd2fae4a664c502105f74d4e1f7e46f9208945345be19841cdaa20a91951b38441967bacfc2bbe4b410156df83bcfae72f0135fd840712da47e4eb3 Jul 7 06:12:39.562572 unknown[1092]: fetched base config from "system" Jul 7 06:12:39.562745 ignition[1092]: fetch: fetch complete Jul 7 06:12:39.562579 unknown[1092]: fetched base config from "system" Jul 7 06:12:39.562750 ignition[1092]: fetch: fetch passed Jul 7 06:12:39.562585 unknown[1092]: fetched user config from "azure" Jul 7 06:12:39.562795 ignition[1092]: Ignition finished successfully Jul 7 06:12:39.571009 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 7 06:12:39.573356 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 7 06:12:39.617969 ignition[1102]: Ignition 2.21.0 Jul 7 06:12:39.617982 ignition[1102]: Stage: kargs Jul 7 06:12:39.618254 ignition[1102]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:39.618266 ignition[1102]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:39.621503 ignition[1102]: kargs: kargs passed Jul 7 06:12:39.631244 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 7 06:12:39.621587 ignition[1102]: Ignition finished successfully Jul 7 06:12:39.638121 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 7 06:12:39.677608 ignition[1112]: Ignition 2.21.0 Jul 7 06:12:39.677620 ignition[1112]: Stage: disks Jul 7 06:12:39.677858 ignition[1112]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:39.677870 ignition[1112]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:39.683542 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 7 06:12:39.678848 ignition[1112]: disks: disks passed Jul 7 06:12:39.689973 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 7 06:12:39.678924 ignition[1112]: Ignition finished successfully Jul 7 06:12:39.698789 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 7 06:12:39.707561 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 06:12:39.709022 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 06:12:39.719701 systemd[1]: Reached target basic.target - Basic System. Jul 7 06:12:39.727206 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 7 06:12:39.855481 systemd-fsck[1124]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 7 06:12:39.865912 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 7 06:12:39.868356 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 7 06:12:40.122540 kernel: EXT4-fs (sda9): mounted filesystem df0fa228-af1b-4496-9a54-2d4ccccd27d9 r/w with ordered data mode. Quota mode: none. Jul 7 06:12:40.124465 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 7 06:12:40.131207 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 7 06:12:40.148775 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 06:12:40.151204 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 7 06:12:40.177020 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 7 06:12:40.202364 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1138) Jul 7 06:12:40.202390 kernel: BTRFS info (device sda6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:12:40.202403 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:12:40.202416 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 06:12:40.178640 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 7 06:12:40.178690 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 06:12:40.205065 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 7 06:12:40.218569 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 7 06:12:40.238724 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 06:12:41.340053 coreos-metadata[1140]: Jul 07 06:12:41.340 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 06:12:41.348184 coreos-metadata[1140]: Jul 07 06:12:41.348 INFO Fetch successful Jul 7 06:12:41.352190 coreos-metadata[1140]: Jul 07 06:12:41.348 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 7 06:12:41.360544 coreos-metadata[1140]: Jul 07 06:12:41.355 INFO Fetch successful Jul 7 06:12:41.360544 coreos-metadata[1140]: Jul 07 06:12:41.355 INFO wrote hostname ci-4372.0.1-a-1825ea7151 to /sysroot/etc/hostname Jul 7 06:12:41.371641 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 06:12:41.537917 initrd-setup-root[1168]: cut: /sysroot/etc/passwd: No such file or directory Jul 7 06:12:41.647780 initrd-setup-root[1175]: cut: /sysroot/etc/group: No such file or directory Jul 7 06:12:41.656898 initrd-setup-root[1182]: cut: /sysroot/etc/shadow: No such file or directory Jul 7 06:12:41.664280 initrd-setup-root[1189]: cut: /sysroot/etc/gshadow: No such file or directory Jul 7 06:12:42.569780 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 7 06:12:42.572774 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 7 06:12:42.593077 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 7 06:12:42.598937 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 7 06:12:42.609789 kernel: BTRFS info (device sda6): last unmount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:12:42.627371 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 7 06:12:42.639133 ignition[1257]: INFO : Ignition 2.21.0 Jul 7 06:12:42.639133 ignition[1257]: INFO : Stage: mount Jul 7 06:12:42.645923 ignition[1257]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:42.645923 ignition[1257]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:42.645923 ignition[1257]: INFO : mount: mount passed Jul 7 06:12:42.645923 ignition[1257]: INFO : Ignition finished successfully Jul 7 06:12:42.651420 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 7 06:12:42.653894 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 7 06:12:42.685052 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 06:12:42.703527 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1272) Jul 7 06:12:42.712822 kernel: BTRFS info (device sda6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:12:42.712851 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:12:42.716092 kernel: BTRFS info (device sda6): using free-space-tree Jul 7 06:12:42.719706 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 06:12:42.756607 ignition[1289]: INFO : Ignition 2.21.0 Jul 7 06:12:42.756607 ignition[1289]: INFO : Stage: files Jul 7 06:12:42.762399 ignition[1289]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:42.762399 ignition[1289]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:42.762399 ignition[1289]: DEBUG : files: compiled without relabeling support, skipping Jul 7 06:12:42.776086 ignition[1289]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 7 06:12:42.776086 ignition[1289]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 7 06:12:42.804359 ignition[1289]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 7 06:12:42.809950 ignition[1289]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 7 06:12:42.815621 unknown[1289]: wrote ssh authorized keys file for user: core Jul 7 06:12:42.819434 ignition[1289]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 7 06:12:42.848506 ignition[1289]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 06:12:42.855758 ignition[1289]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 06:12:42.855758 ignition[1289]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 7 06:12:42.855758 ignition[1289]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 7 06:12:42.855758 ignition[1289]: INFO : files: files passed Jul 7 06:12:42.855758 ignition[1289]: INFO : Ignition finished successfully Jul 7 06:12:42.871426 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 7 06:12:42.882211 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 7 06:12:42.898763 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 7 06:12:42.903692 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 7 06:12:42.903789 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 7 06:12:42.924993 initrd-setup-root-after-ignition[1322]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:12:42.924993 initrd-setup-root-after-ignition[1322]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:12:42.939350 initrd-setup-root-after-ignition[1326]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:12:42.926481 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 06:12:42.938075 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 7 06:12:42.950773 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 7 06:12:42.999227 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 7 06:12:42.999330 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 7 06:12:43.008312 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 7 06:12:43.017179 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 7 06:12:43.025597 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 7 06:12:43.026652 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 7 06:12:43.070128 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 06:12:43.081282 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 7 06:12:43.115576 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:12:43.116481 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:12:43.129907 systemd[1]: Stopped target timers.target - Timer Units. Jul 7 06:12:43.138260 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 7 06:12:43.138379 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 06:12:43.151092 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 7 06:12:43.160573 systemd[1]: Stopped target basic.target - Basic System. Jul 7 06:12:43.169378 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 7 06:12:43.177227 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 06:12:43.186312 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 7 06:12:43.195843 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 7 06:12:43.204700 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 7 06:12:43.213373 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 06:12:43.222259 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 7 06:12:43.231355 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 7 06:12:43.239258 systemd[1]: Stopped target swap.target - Swaps. Jul 7 06:12:43.246210 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 7 06:12:43.246337 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 7 06:12:43.257333 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:12:43.265944 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:12:43.268019 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 7 06:12:43.279637 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:12:43.281919 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 7 06:12:43.282042 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 7 06:12:43.299110 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 7 06:12:43.299235 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 06:12:43.307754 systemd[1]: ignition-files.service: Deactivated successfully. Jul 7 06:12:43.307876 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 7 06:12:43.315859 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 7 06:12:43.315976 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 06:12:43.326076 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 7 06:12:43.358815 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 7 06:12:43.360314 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 7 06:12:43.360451 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:12:43.383300 ignition[1346]: INFO : Ignition 2.21.0 Jul 7 06:12:43.383300 ignition[1346]: INFO : Stage: umount Jul 7 06:12:43.383300 ignition[1346]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:12:43.383300 ignition[1346]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:12:43.383300 ignition[1346]: INFO : umount: umount passed Jul 7 06:12:43.383300 ignition[1346]: INFO : Ignition finished successfully Jul 7 06:12:43.367370 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 7 06:12:43.367488 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 06:12:43.385552 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 7 06:12:43.386422 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 7 06:12:43.386545 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 7 06:12:43.396040 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 7 06:12:43.396152 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 7 06:12:43.407340 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 7 06:12:43.407407 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 7 06:12:43.414094 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 7 06:12:43.414142 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 7 06:12:43.421572 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 7 06:12:43.421618 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 7 06:12:43.429538 systemd[1]: Stopped target network.target - Network. Jul 7 06:12:43.437353 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 7 06:12:43.437408 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 06:12:43.445623 systemd[1]: Stopped target paths.target - Path Units. Jul 7 06:12:43.447089 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 7 06:12:43.450558 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:12:43.457325 systemd[1]: Stopped target slices.target - Slice Units. Jul 7 06:12:43.469336 systemd[1]: Stopped target sockets.target - Socket Units. Jul 7 06:12:43.481800 systemd[1]: iscsid.socket: Deactivated successfully. Jul 7 06:12:43.485553 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 06:12:43.487080 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 7 06:12:43.487129 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 06:12:43.499422 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 7 06:12:43.499478 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 7 06:12:43.507664 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 7 06:12:43.507711 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 7 06:12:43.515965 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 7 06:12:43.526243 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 7 06:12:43.536084 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 7 06:12:43.536194 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 7 06:12:43.548506 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 7 06:12:43.548674 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 7 06:12:43.562972 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 7 06:12:43.563062 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 7 06:12:43.571151 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 7 06:12:43.571208 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 7 06:12:43.572455 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 7 06:12:43.574962 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 7 06:12:43.575005 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:12:43.582787 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 7 06:12:43.582844 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 7 06:12:43.591200 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 7 06:12:43.598823 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 7 06:12:43.598873 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 06:12:43.605573 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 7 06:12:43.605618 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:12:43.618729 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 7 06:12:43.618775 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 7 06:12:43.625993 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 7 06:12:43.626043 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:12:43.635536 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:12:43.637972 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 7 06:12:43.638037 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 7 06:12:43.667146 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 7 06:12:43.667310 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 7 06:12:43.690293 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 7 06:12:43.690443 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:12:43.700012 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 7 06:12:43.700054 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 7 06:12:43.708899 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 7 06:12:43.708935 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:12:43.716832 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 7 06:12:43.716880 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 7 06:12:43.729863 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 7 06:12:43.729913 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 7 06:12:43.742067 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 7 06:12:43.742120 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 06:12:43.757189 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 7 06:12:43.764487 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 7 06:12:43.764550 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:12:43.774579 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 7 06:12:43.774630 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:12:43.783489 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 7 06:12:43.783549 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:12:43.792775 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 7 06:12:43.792819 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:12:43.946921 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Jul 7 06:12:43.801118 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 06:12:43.801162 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:12:43.812593 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 7 06:12:43.812654 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 7 06:12:43.812694 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 7 06:12:43.812735 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 06:12:43.813034 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 7 06:12:43.813122 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 7 06:12:43.817968 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 7 06:12:43.827263 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 7 06:12:43.860333 systemd[1]: Switching root. Jul 7 06:12:43.951616 systemd-journald[233]: Journal stopped Jul 7 06:12:49.309549 kernel: SELinux: policy capability network_peer_controls=1 Jul 7 06:12:49.309585 kernel: SELinux: policy capability open_perms=1 Jul 7 06:12:49.309598 kernel: SELinux: policy capability extended_socket_class=1 Jul 7 06:12:49.309607 kernel: SELinux: policy capability always_check_network=0 Jul 7 06:12:49.309616 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 7 06:12:49.309625 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 7 06:12:49.309636 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 7 06:12:49.309647 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 7 06:12:49.309657 kernel: SELinux: policy capability userspace_initial_context=0 Jul 7 06:12:49.309666 kernel: audit: type=1403 audit(1751868764.844:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 7 06:12:49.309678 systemd[1]: Successfully loaded SELinux policy in 199.990ms. Jul 7 06:12:49.309690 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.167ms. Jul 7 06:12:49.309701 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 06:12:49.309711 systemd[1]: Detected virtualization microsoft. Jul 7 06:12:49.309725 systemd[1]: Detected architecture x86-64. Jul 7 06:12:49.309735 systemd[1]: Detected first boot. Jul 7 06:12:49.309746 systemd[1]: Hostname set to . Jul 7 06:12:49.309795 systemd[1]: Initializing machine ID from random generator. Jul 7 06:12:49.309820 zram_generator::config[1393]: No configuration found. Jul 7 06:12:49.309842 kernel: Guest personality initialized and is inactive Jul 7 06:12:49.309852 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 7 06:12:49.309861 kernel: Initialized host personality Jul 7 06:12:49.309871 kernel: NET: Registered PF_VSOCK protocol family Jul 7 06:12:49.309882 systemd[1]: Populated /etc with preset unit settings. Jul 7 06:12:49.309894 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 7 06:12:49.309904 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 7 06:12:49.309917 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 7 06:12:49.309928 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 7 06:12:49.309939 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 7 06:12:49.309950 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 7 06:12:49.309960 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 7 06:12:49.309971 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 7 06:12:49.309982 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 7 06:12:49.309993 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 7 06:12:49.310006 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 7 06:12:49.310017 systemd[1]: Created slice user.slice - User and Session Slice. Jul 7 06:12:49.310027 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:12:49.310038 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:12:49.310049 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 7 06:12:49.310060 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 7 06:12:49.310077 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 7 06:12:49.310089 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 06:12:49.310102 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 7 06:12:49.310113 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:12:49.310124 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:12:49.310135 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 7 06:12:49.310147 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 7 06:12:49.310158 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 7 06:12:49.310169 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 7 06:12:49.310182 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:12:49.310193 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 06:12:49.310204 systemd[1]: Reached target slices.target - Slice Units. Jul 7 06:12:49.310215 systemd[1]: Reached target swap.target - Swaps. Jul 7 06:12:49.310226 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 7 06:12:49.310237 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 7 06:12:49.310250 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 7 06:12:49.310262 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:12:49.310273 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 06:12:49.310284 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:12:49.310295 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 7 06:12:49.310306 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 7 06:12:49.310320 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 7 06:12:49.310334 systemd[1]: Mounting media.mount - External Media Directory... Jul 7 06:12:49.310345 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:12:49.310356 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 7 06:12:49.310367 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 7 06:12:49.310379 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 7 06:12:49.310390 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 06:12:49.310402 systemd[1]: Reached target machines.target - Containers. Jul 7 06:12:49.310413 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 7 06:12:49.310424 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 06:12:49.310437 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 06:12:49.310449 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 7 06:12:49.310460 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 06:12:49.310471 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 06:12:49.310482 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 06:12:49.310492 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 7 06:12:49.310504 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 06:12:49.310527 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 7 06:12:49.310543 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 7 06:12:49.310554 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 7 06:12:49.310565 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 7 06:12:49.310580 systemd[1]: Stopped systemd-fsck-usr.service. Jul 7 06:12:49.310592 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 06:12:49.310603 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 06:12:49.310614 kernel: fuse: init (API version 7.41) Jul 7 06:12:49.310624 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 06:12:49.310639 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 06:12:49.310650 kernel: loop: module loaded Jul 7 06:12:49.310660 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 7 06:12:49.310671 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 7 06:12:49.310682 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 06:12:49.310694 systemd[1]: verity-setup.service: Deactivated successfully. Jul 7 06:12:49.310730 systemd-journald[1500]: Collecting audit messages is disabled. Jul 7 06:12:49.310761 systemd[1]: Stopped verity-setup.service. Jul 7 06:12:49.310773 systemd-journald[1500]: Journal started Jul 7 06:12:49.310795 systemd-journald[1500]: Runtime Journal (/run/log/journal/b63c17a5a3ff4f1fa9b342dd19993a15) is 8M, max 2.2G, 2.1G free. Jul 7 06:12:48.573786 systemd[1]: Queued start job for default target multi-user.target. Jul 7 06:12:48.599066 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 7 06:12:48.599552 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 7 06:12:49.320584 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 06:12:49.327598 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 06:12:49.331947 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 7 06:12:49.336549 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 7 06:12:49.341322 systemd[1]: Mounted media.mount - External Media Directory. Jul 7 06:12:49.345658 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 7 06:12:49.350621 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 7 06:12:49.355494 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 7 06:12:49.360254 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 7 06:12:49.365822 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:12:49.371477 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 7 06:12:49.371694 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 7 06:12:49.377286 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 06:12:49.377544 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 06:12:49.382460 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 06:12:49.382662 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 06:12:49.388187 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 7 06:12:49.388388 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 7 06:12:49.393385 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 06:12:49.393949 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 06:12:49.399472 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 06:12:49.404624 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:12:49.410371 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 7 06:12:49.415972 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 7 06:12:49.421538 kernel: ACPI: bus type drm_connector registered Jul 7 06:12:49.424213 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 06:12:49.424398 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 06:12:49.438450 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 06:12:49.444300 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 7 06:12:49.461766 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 7 06:12:49.466551 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 7 06:12:49.466595 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 06:12:49.471823 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 7 06:12:49.478927 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 7 06:12:49.493713 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 06:12:49.506992 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 7 06:12:49.523934 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 7 06:12:49.528675 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 06:12:49.529598 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 7 06:12:49.534239 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 06:12:49.535176 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 06:12:49.542323 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 7 06:12:49.548239 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 06:12:49.555739 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:12:49.561052 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 7 06:12:49.567170 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 7 06:12:49.569173 systemd-journald[1500]: Time spent on flushing to /var/log/journal/b63c17a5a3ff4f1fa9b342dd19993a15 is 13.912ms for 946 entries. Jul 7 06:12:49.569173 systemd-journald[1500]: System Journal (/var/log/journal/b63c17a5a3ff4f1fa9b342dd19993a15) is 8M, max 2.6G, 2.6G free. Jul 7 06:12:49.633398 systemd-journald[1500]: Received client request to flush runtime journal. Jul 7 06:12:49.633462 kernel: loop0: detected capacity change from 0 to 28496 Jul 7 06:12:49.581899 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 7 06:12:49.586953 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 7 06:12:49.592983 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 7 06:12:49.634365 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 7 06:12:49.654476 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Jul 7 06:12:49.654497 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Jul 7 06:12:49.659597 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:12:49.666255 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 7 06:12:49.681907 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 7 06:12:49.682780 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 7 06:12:49.691491 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:12:50.057341 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 7 06:12:50.063929 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 06:12:50.093053 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Jul 7 06:12:50.093073 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Jul 7 06:12:50.097721 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:12:50.265547 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 7 06:12:50.425536 kernel: loop1: detected capacity change from 0 to 146240 Jul 7 06:12:50.960680 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 7 06:12:50.967295 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:12:51.004741 systemd-udevd[1559]: Using default interface naming scheme 'v255'. Jul 7 06:12:51.041534 kernel: loop2: detected capacity change from 0 to 113872 Jul 7 06:12:51.344940 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:12:51.353544 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 06:12:51.363537 kernel: loop3: detected capacity change from 0 to 28496 Jul 7 06:12:51.376573 kernel: loop4: detected capacity change from 0 to 146240 Jul 7 06:12:51.401853 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 7 06:12:51.408538 kernel: loop5: detected capacity change from 0 to 113872 Jul 7 06:12:51.426046 (sd-merge)[1589]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 7 06:12:51.427269 (sd-merge)[1589]: Merged extensions into '/usr'. Jul 7 06:12:51.431135 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 7 06:12:51.439148 systemd[1]: Starting ensure-sysext.service... Jul 7 06:12:51.450633 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 06:12:51.487677 systemd-tmpfiles[1611]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 7 06:12:51.488033 systemd-tmpfiles[1611]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 7 06:12:51.488409 systemd-tmpfiles[1611]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 7 06:12:51.488804 systemd-tmpfiles[1611]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 7 06:12:51.489798 systemd-tmpfiles[1611]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 7 06:12:51.490181 systemd-tmpfiles[1611]: ACLs are not supported, ignoring. Jul 7 06:12:51.490369 systemd-tmpfiles[1611]: ACLs are not supported, ignoring. Jul 7 06:12:51.504226 kernel: mousedev: PS/2 mouse device common for all mice Jul 7 06:12:51.504293 kernel: hv_vmbus: registering driver hv_balloon Jul 7 06:12:51.502042 systemd-tmpfiles[1611]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 06:12:51.502051 systemd-tmpfiles[1611]: Skipping /boot Jul 7 06:12:51.504348 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 7 06:12:51.511722 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 7 06:12:51.523235 systemd-tmpfiles[1611]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 06:12:51.523356 systemd-tmpfiles[1611]: Skipping /boot Jul 7 06:12:51.526532 kernel: EDAC MC: Ver: 3.0.0 Jul 7 06:12:51.530532 kernel: hv_vmbus: registering driver hyperv_fb Jul 7 06:12:51.538603 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 7 06:12:51.538651 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 7 06:12:51.542456 kernel: Console: switching to colour dummy device 80x25 Jul 7 06:12:51.543532 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 06:12:51.547188 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:12:51.559181 systemd[1]: Reload requested from client PID 1610 ('systemctl') (unit ensure-sysext.service)... Jul 7 06:12:51.559197 systemd[1]: Reloading... Jul 7 06:12:51.598544 zram_generator::config[1670]: No configuration found. Jul 7 06:12:51.714312 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 06:12:51.799013 systemd-networkd[1585]: lo: Link UP Jul 7 06:12:51.799024 systemd-networkd[1585]: lo: Gained carrier Jul 7 06:12:51.801179 systemd-networkd[1585]: Enumeration completed Jul 7 06:12:51.801722 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:12:51.801816 systemd-networkd[1585]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:12:51.802583 systemd-networkd[1585]: eth0: Link UP Jul 7 06:12:51.802595 systemd-networkd[1585]: eth0: Gained carrier Jul 7 06:12:51.802612 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:12:51.839563 systemd-networkd[1585]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:12:51.841787 systemd[1]: Reloading finished in 282 ms. Jul 7 06:12:51.869787 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 7 06:12:51.874645 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 06:12:51.954461 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 06:12:51.962745 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:12:51.964170 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 06:12:51.989527 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 7 06:12:51.993811 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 06:12:51.994990 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 06:12:52.001918 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 06:12:52.007009 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 06:12:52.012750 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 06:12:52.016799 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 06:12:52.017885 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 7 06:12:52.022620 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 06:12:52.023978 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 7 06:12:52.029916 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 7 06:12:52.035908 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 7 06:12:52.042490 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 06:12:52.048883 systemd[1]: Reached target time-set.target - System Time Set. Jul 7 06:12:52.054164 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 7 06:12:52.073875 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:12:52.078449 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 06:12:52.083991 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 06:12:52.084198 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 06:12:52.090995 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 06:12:52.091184 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 06:12:52.098031 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 06:12:52.098233 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 06:12:52.100190 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 06:12:52.100397 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 06:12:52.103394 systemd[1]: Finished ensure-sysext.service. Jul 7 06:12:52.104615 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 7 06:12:52.110524 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 06:12:52.110677 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 06:12:52.127767 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 7 06:12:52.129836 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 7 06:12:52.185772 augenrules[1840]: No rules Jul 7 06:12:52.187130 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 06:12:52.187566 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 06:12:52.196482 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 7 06:12:52.223927 systemd-resolved[1806]: Positive Trust Anchors: Jul 7 06:12:52.223943 systemd-resolved[1806]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 06:12:52.223974 systemd-resolved[1806]: 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 06:12:52.239176 systemd-resolved[1806]: Using system hostname 'ci-4372.0.1-a-1825ea7151'. Jul 7 06:12:52.241132 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 06:12:52.242770 systemd[1]: Reached target network.target - Network. Jul 7 06:12:52.243297 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:12:52.483859 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:12:52.527466 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 7 06:12:52.533051 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 06:12:53.459665 systemd-networkd[1585]: eth0: Gained IPv6LL Jul 7 06:12:53.461917 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 7 06:12:53.467092 systemd[1]: Reached target network-online.target - Network is Online. Jul 7 06:12:56.247177 ldconfig[1529]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 06:12:56.260834 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 7 06:12:56.267612 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 7 06:12:56.295108 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 7 06:12:56.299842 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 06:12:56.304310 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 7 06:12:56.309423 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 06:12:56.314784 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 7 06:12:56.319612 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 7 06:12:56.324113 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 7 06:12:56.329336 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 7 06:12:56.334399 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 7 06:12:56.334445 systemd[1]: Reached target paths.target - Path Units. Jul 7 06:12:56.338213 systemd[1]: Reached target timers.target - Timer Units. Jul 7 06:12:56.343052 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 7 06:12:56.348850 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 7 06:12:56.355294 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 7 06:12:56.360659 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 7 06:12:56.365728 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 7 06:12:56.372011 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 7 06:12:56.376693 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 7 06:12:56.382254 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 7 06:12:56.387707 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 06:12:56.391681 systemd[1]: Reached target basic.target - Basic System. Jul 7 06:12:56.395409 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 7 06:12:56.395442 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 7 06:12:56.397836 systemd[1]: Starting chronyd.service - NTP client/server... Jul 7 06:12:56.402224 systemd[1]: Starting containerd.service - containerd container runtime... Jul 7 06:12:56.427310 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 7 06:12:56.432358 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 7 06:12:56.436847 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 7 06:12:56.442745 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 7 06:12:56.448087 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 7 06:12:56.450629 jq[1865]: false Jul 7 06:12:56.452237 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 06:12:56.453338 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 7 06:12:56.457563 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 06:12:56.458774 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 7 06:12:56.462734 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Refreshing passwd entry cache Jul 7 06:12:56.462745 oslogin_cache_refresh[1867]: Refreshing passwd entry cache Jul 7 06:12:56.463029 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 06:12:56.464171 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 7 06:12:56.469473 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 7 06:12:56.473450 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Failure getting users, quitting Jul 7 06:12:56.473450 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 06:12:56.473433 oslogin_cache_refresh[1867]: Failure getting users, quitting Jul 7 06:12:56.473621 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Refreshing group entry cache Jul 7 06:12:56.473449 oslogin_cache_refresh[1867]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 06:12:56.473493 oslogin_cache_refresh[1867]: Refreshing group entry cache Jul 7 06:12:56.474487 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 7 06:12:56.476009 extend-filesystems[1866]: Found /dev/sda6 Jul 7 06:12:56.484694 kernel: hv_utils: KVP IC version 4.0 Jul 7 06:12:56.477948 oslogin_cache_refresh[1867]: Failure getting groups, quitting Jul 7 06:12:56.480855 (chronyd)[1857]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 7 06:12:56.484974 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Failure getting groups, quitting Jul 7 06:12:56.484974 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 06:12:56.477959 oslogin_cache_refresh[1867]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 06:12:56.484826 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 7 06:12:56.478500 KVP[1871]: KVP starting; pid is:1871 Jul 7 06:12:56.480119 KVP[1871]: KVP LIC Version: 3.1 Jul 7 06:12:56.487883 chronyd[1881]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 7 06:12:56.491440 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 7 06:12:56.493880 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 7 06:12:56.494314 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 7 06:12:56.494878 systemd[1]: Starting update-engine.service - Update Engine... Jul 7 06:12:56.500635 extend-filesystems[1866]: Found /dev/sda9 Jul 7 06:12:56.504835 extend-filesystems[1866]: Checking size of /dev/sda9 Jul 7 06:12:56.511337 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 7 06:12:56.519880 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 7 06:12:56.521123 jq[1890]: true Jul 7 06:12:56.525414 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 7 06:12:56.525649 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 7 06:12:56.526021 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 7 06:12:56.526229 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 7 06:12:56.531738 systemd[1]: motdgen.service: Deactivated successfully. Jul 7 06:12:56.531953 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 7 06:12:56.536323 extend-filesystems[1866]: Old size kept for /dev/sda9 Jul 7 06:12:56.537071 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 7 06:12:56.537299 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 7 06:12:56.544756 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 7 06:12:56.544968 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 7 06:12:56.550426 chronyd[1881]: Timezone right/UTC failed leap second check, ignoring Jul 7 06:12:56.550621 chronyd[1881]: Loaded seccomp filter (level 2) Jul 7 06:12:56.551976 systemd[1]: Started chronyd.service - NTP client/server. Jul 7 06:12:56.556698 (ntainerd)[1900]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 7 06:12:56.559967 jq[1899]: true Jul 7 06:12:56.575547 update_engine[1886]: I20250707 06:12:56.574863 1886 main.cc:92] Flatcar Update Engine starting Jul 7 06:12:56.593112 systemd-logind[1885]: New seat seat0. Jul 7 06:12:56.607302 systemd-logind[1885]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 7 06:12:56.607828 systemd[1]: Started systemd-logind.service - User Login Management. Jul 7 06:12:56.659823 setup-nvidia[1873]: Downloading Flatcar Container Linux Developer Container for version: 4372.0.1 Jul 7 06:12:56.668246 bash[1949]: Updated "/home/core/.ssh/authorized_keys" Jul 7 06:12:56.672262 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 7 06:12:56.678813 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 7 06:12:56.737991 dbus-daemon[1860]: [system] SELinux support is enabled Jul 7 06:12:56.738157 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 7 06:12:56.740725 update_engine[1886]: I20250707 06:12:56.740672 1886 update_check_scheduler.cc:74] Next update check in 7m13s Jul 7 06:12:56.744814 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 06:12:56.744848 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 7 06:12:56.745716 dbus-daemon[1860]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 7 06:12:56.750147 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 06:12:56.750174 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 7 06:12:56.754827 sshd_keygen[1895]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 7 06:12:56.755366 systemd[1]: Started update-engine.service - Update Engine. Jul 7 06:12:56.761440 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 7 06:12:56.780507 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 7 06:12:56.786770 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 7 06:12:56.797928 coreos-metadata[1859]: Jul 07 06:12:56.797 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 06:12:56.799807 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 7 06:12:56.803977 coreos-metadata[1859]: Jul 07 06:12:56.800 INFO Fetch successful Jul 7 06:12:56.803977 coreos-metadata[1859]: Jul 07 06:12:56.800 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 7 06:12:56.804529 coreos-metadata[1859]: Jul 07 06:12:56.804 INFO Fetch successful Jul 7 06:12:56.804826 coreos-metadata[1859]: Jul 07 06:12:56.804 INFO Fetching http://168.63.129.16/machine/37742a5d-c535-4a15-b870-47a5dcd4121b/49c4347d%2D1a4a%2D496e%2D8d3d%2Df9e527eb4d44.%5Fci%2D4372.0.1%2Da%2D1825ea7151?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 7 06:12:56.805300 systemd[1]: issuegen.service: Deactivated successfully. Jul 7 06:12:56.805522 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 7 06:12:56.812201 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 7 06:12:56.826546 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 7 06:12:56.833904 coreos-metadata[1859]: Jul 07 06:12:56.833 INFO Fetch successful Jul 7 06:12:56.834057 coreos-metadata[1859]: Jul 07 06:12:56.834 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 7 06:12:56.840846 coreos-metadata[1859]: Jul 07 06:12:56.840 INFO Fetch successful Jul 7 06:12:56.845640 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 7 06:12:56.848740 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 7 06:12:56.853138 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 7 06:12:56.859159 systemd[1]: Reached target getty.target - Login Prompts. Jul 7 06:12:56.884248 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 7 06:12:56.889272 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 7 06:12:57.047451 locksmithd[2008]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 7 06:12:57.769960 setup-nvidia[2042]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 06:12:57.770259 setup-nvidia[2042]: Dload Upload Total Spent Left Speed Jul 7 06:12:57.821609 containerd[1900]: time="2025-07-07T06:12:57Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jul 7 06:12:57.822304 containerd[1900]: time="2025-07-07T06:12:57.822272200Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 7 06:12:57.831086 containerd[1900]: time="2025-07-07T06:12:57.831048800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Jul 7 06:12:57.831086 containerd[1900]: time="2025-07-07T06:12:57.831076800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 7 06:12:57.831162 containerd[1900]: time="2025-07-07T06:12:57.831094700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 7 06:12:57.831276 containerd[1900]: time="2025-07-07T06:12:57.831253200Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 7 06:12:57.831312 containerd[1900]: time="2025-07-07T06:12:57.831276400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 7 06:12:57.831312 containerd[1900]: time="2025-07-07T06:12:57.831304400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831383 containerd[1900]: time="2025-07-07T06:12:57.831362100Z" 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 06:12:57.831383 containerd[1900]: time="2025-07-07T06:12:57.831379700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831604 containerd[1900]: time="2025-07-07T06:12:57.831580600Z" 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 06:12:57.831604 containerd[1900]: time="2025-07-07T06:12:57.831601100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831663 containerd[1900]: time="2025-07-07T06:12:57.831611900Z" 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 06:12:57.831663 containerd[1900]: time="2025-07-07T06:12:57.831620700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831716 containerd[1900]: time="2025-07-07T06:12:57.831701800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831935 containerd[1900]: time="2025-07-07T06:12:57.831913400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 7 06:12:57.831966 containerd[1900]: time="2025-07-07T06:12:57.831948700Z" 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 06:12:57.831993 containerd[1900]: time="2025-07-07T06:12:57.831970600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 7 06:12:57.832016 containerd[1900]: time="2025-07-07T06:12:57.832001400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 7 06:12:57.833472 containerd[1900]: time="2025-07-07T06:12:57.833321000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 7 06:12:57.833571 containerd[1900]: time="2025-07-07T06:12:57.833547700Z" level=info msg="metadata content store policy set" policy=shared Jul 7 06:12:57.851687 containerd[1900]: time="2025-07-07T06:12:57.851650100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 7 06:12:57.851746 containerd[1900]: time="2025-07-07T06:12:57.851691300Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 7 06:12:57.851746 containerd[1900]: time="2025-07-07T06:12:57.851706200Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 7 06:12:57.851746 containerd[1900]: time="2025-07-07T06:12:57.851717200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 7 06:12:57.851746 containerd[1900]: time="2025-07-07T06:12:57.851729100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 7 06:12:57.851746 containerd[1900]: time="2025-07-07T06:12:57.851740800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851751100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851761500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851771200Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851780500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851790000Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 7 06:12:57.851864 containerd[1900]: time="2025-07-07T06:12:57.851801500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 7 06:12:57.851980 containerd[1900]: time="2025-07-07T06:12:57.851907100Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 7 06:12:57.851980 containerd[1900]: time="2025-07-07T06:12:57.851926600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 7 06:12:57.851980 containerd[1900]: time="2025-07-07T06:12:57.851940900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 7 06:12:57.851980 containerd[1900]: time="2025-07-07T06:12:57.851950900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 7 06:12:57.851980 containerd[1900]: time="2025-07-07T06:12:57.851960900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.851985000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852000100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852012500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852023300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852033800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852043100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852096700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 7 06:12:57.852110 containerd[1900]: time="2025-07-07T06:12:57.852110000Z" level=info msg="Start snapshots syncer" Jul 7 06:12:57.852302 containerd[1900]: time="2025-07-07T06:12:57.852138900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 7 06:12:57.852386 containerd[1900]: time="2025-07-07T06:12:57.852328900Z" 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 06:12:57.852502 containerd[1900]: time="2025-07-07T06:12:57.852400200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 7 06:12:57.852502 containerd[1900]: time="2025-07-07T06:12:57.852486300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 7 06:12:57.852633 containerd[1900]: time="2025-07-07T06:12:57.852608900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 7 06:12:57.852668 containerd[1900]: time="2025-07-07T06:12:57.852638300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 7 06:12:57.852668 containerd[1900]: time="2025-07-07T06:12:57.852649900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 7 06:12:57.852668 containerd[1900]: time="2025-07-07T06:12:57.852659600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 7 06:12:57.852737 containerd[1900]: time="2025-07-07T06:12:57.852670400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 7 06:12:57.852737 containerd[1900]: time="2025-07-07T06:12:57.852681100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 7 06:12:57.852737 containerd[1900]: time="2025-07-07T06:12:57.852690700Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 7 06:12:57.852737 containerd[1900]: time="2025-07-07T06:12:57.852722300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 7 06:12:57.852737 containerd[1900]: time="2025-07-07T06:12:57.852736900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852747100Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852774500Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852787700Z" 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 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852797000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852805300Z" 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 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852812600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852820500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 7 06:12:57.852846 containerd[1900]: time="2025-07-07T06:12:57.852829600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 7 06:12:57.853007 containerd[1900]: time="2025-07-07T06:12:57.852845700Z" level=info msg="runtime interface created" Jul 7 06:12:57.853007 containerd[1900]: time="2025-07-07T06:12:57.852865500Z" level=info msg="created NRI interface" Jul 7 06:12:57.853007 containerd[1900]: time="2025-07-07T06:12:57.852881100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 7 06:12:57.853007 containerd[1900]: time="2025-07-07T06:12:57.852894000Z" level=info msg="Connect containerd service" Jul 7 06:12:57.853007 containerd[1900]: time="2025-07-07T06:12:57.852917600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 7 06:12:57.853636 containerd[1900]: time="2025-07-07T06:12:57.853610500Z" 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 06:12:58.732337 containerd[1900]: time="2025-07-07T06:12:58.732290700Z" level=info msg="Start subscribing containerd event" Jul 7 06:12:58.732463 containerd[1900]: time="2025-07-07T06:12:58.732348500Z" level=info msg="Start recovering state" Jul 7 06:12:58.732532 containerd[1900]: time="2025-07-07T06:12:58.732465900Z" level=info msg="Start event monitor" Jul 7 06:12:58.732532 containerd[1900]: time="2025-07-07T06:12:58.732473200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 7 06:12:58.732583 containerd[1900]: time="2025-07-07T06:12:58.732491100Z" level=info msg="Start cni network conf syncer for default" Jul 7 06:12:58.732583 containerd[1900]: time="2025-07-07T06:12:58.732570300Z" level=info msg="Start streaming server" Jul 7 06:12:58.732636 containerd[1900]: time="2025-07-07T06:12:58.732580500Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 7 06:12:58.732636 containerd[1900]: time="2025-07-07T06:12:58.732583500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 7 06:12:58.732636 containerd[1900]: time="2025-07-07T06:12:58.732634600Z" level=info msg="runtime interface starting up..." Jul 7 06:12:58.732703 containerd[1900]: time="2025-07-07T06:12:58.732640900Z" level=info msg="starting plugins..." Jul 7 06:12:58.732703 containerd[1900]: time="2025-07-07T06:12:58.732656600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 7 06:12:58.732833 containerd[1900]: time="2025-07-07T06:12:58.732808800Z" level=info msg="containerd successfully booted in 0.911645s" Jul 7 06:12:58.732913 systemd[1]: Started containerd.service - containerd container runtime. Jul 7 06:12:59.048113 waagent[2024]: 2025-07-07T06:12:59.048039Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 7 06:12:59.053659 waagent[2024]: 2025-07-07T06:12:59.053610Z INFO Daemon Daemon OS: flatcar 4372.0.1 Jul 7 06:12:59.057197 waagent[2024]: 2025-07-07T06:12:59.057150Z INFO Daemon Daemon Python: 3.11.12 Jul 7 06:12:59.060645 waagent[2024]: 2025-07-07T06:12:59.060600Z INFO Daemon Daemon Run daemon Jul 7 06:12:59.063718 waagent[2024]: 2025-07-07T06:12:59.063676Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.0.1' Jul 7 06:12:59.070440 waagent[2024]: 2025-07-07T06:12:59.070382Z INFO Daemon Daemon Using waagent for provisioning Jul 7 06:12:59.074486 waagent[2024]: 2025-07-07T06:12:59.074436Z INFO Daemon Daemon Activate resource disk Jul 7 06:12:59.078142 waagent[2024]: 2025-07-07T06:12:59.078097Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 7 06:12:59.086320 waagent[2024]: 2025-07-07T06:12:59.086269Z INFO Daemon Daemon Found device: sdb Jul 7 06:12:59.095353 waagent[2024]: 2025-07-07T06:12:59.095305Z INFO Daemon Daemon Examining partition table Jul 7 06:12:59.135199 waagent[2024]: 2025-07-07T06:12:59.135146Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 7 06:12:59.164141 waagent[2024]: 2025-07-07T06:12:59.164068Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jul 7 06:12:59.168810 waagent[2024]: 2025-07-07T06:12:59.168759Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 7 06:12:59.482089 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#603 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:12:59.489539 kernel: sdb: sdb1 Jul 7 06:12:59.508943 waagent[2024]: 2025-07-07T06:12:59.508867Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jul 7 06:12:59.521448 waagent[2024]: 2025-07-07T06:12:59.521393Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jul 7 06:13:04.801161 login[2029]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 7 06:13:04.801446 login[2028]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 06:13:04.807963 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 7 06:13:04.809074 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 7 06:13:04.815225 systemd-logind[1885]: New session 2 of user core. Jul 7 06:13:04.924302 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 7 06:13:04.926923 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 7 06:13:04.962687 (systemd)[2111]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 7 06:13:04.965444 systemd-logind[1885]: New session c1 of user core. Jul 7 06:13:05.010625 waagent[2024]: 2025-07-07T06:13:05.010547Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jul 7 06:13:05.115483 waagent[2024]: 2025-07-07T06:13:05.115354Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jul 7 06:13:05.115602 kernel: EXT4-fs (sdb1): mounted filesystem 728359ee-53c4-4af3-8090-8d67ed3c448c r/w with ordered data mode. Quota mode: none. Jul 7 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.117677Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.117940Z INFO Daemon Daemon Running default provisioning handler Jul 7 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.125968Z 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 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.126812Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 7 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.127244Z INFO Daemon Daemon cloud-init is enabled: False Jul 7 06:13:05.152699 waagent[2024]: 2025-07-07T06:13:05.128743Z INFO Daemon Daemon Copying ovf-env.xml Jul 7 06:13:05.241451 waagent[2024]: 2025-07-07T06:13:05.241387Z INFO Daemon Daemon Successfully mounted dvd Jul 7 06:13:05.275052 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 7 06:13:05.275184 waagent[2024]: 2025-07-07T06:13:05.275047Z INFO Daemon Daemon Detect protocol endpoint Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.276707Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.277479Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.278191Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.279817Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.280445Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.290559Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.290968Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 7 06:13:05.311054 waagent[2024]: 2025-07-07T06:13:05.291474Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 7 06:13:05.281751 systemd[2111]: Queued start job for default target default.target. Jul 7 06:13:05.331481 systemd[2111]: Created slice app.slice - User Application Slice. Jul 7 06:13:05.331530 systemd[2111]: Reached target paths.target - Paths. Jul 7 06:13:05.331583 systemd[2111]: Reached target timers.target - Timers. Jul 7 06:13:05.332835 systemd[2111]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 06:13:05.343457 systemd[2111]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:13:05.343532 systemd[2111]: Reached target sockets.target - Sockets. Jul 7 06:13:05.343579 systemd[2111]: Reached target basic.target - Basic System. Jul 7 06:13:05.343623 systemd[2111]: Reached target default.target - Main User Target. Jul 7 06:13:05.343659 systemd[2111]: Startup finished in 370ms. Jul 7 06:13:05.343770 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 7 06:13:05.345175 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 7 06:13:05.356845 waagent[2024]: 2025-07-07T06:13:05.356768Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 7 06:13:05.358533 waagent[2024]: 2025-07-07T06:13:05.358476Z INFO Daemon Daemon Forcing an update of the goal state. Jul 7 06:13:05.363147 waagent[2024]: 2025-07-07T06:13:05.363103Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 06:13:05.401976 waagent[2024]: 2025-07-07T06:13:05.401900Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 7 06:13:05.403984 waagent[2024]: 2025-07-07T06:13:05.403946Z INFO Daemon Jul 7 06:13:05.404341 waagent[2024]: 2025-07-07T06:13:05.404308Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 5e854f95-1c8f-4d21-a948-f9af1f42888d eTag: 799637555470215310 source: Fabric] Jul 7 06:13:05.406075 waagent[2024]: 2025-07-07T06:13:05.406033Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 7 06:13:05.411355 waagent[2024]: 2025-07-07T06:13:05.411310Z INFO Daemon Jul 7 06:13:05.412485 waagent[2024]: 2025-07-07T06:13:05.412453Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 7 06:13:05.416846 waagent[2024]: 2025-07-07T06:13:05.416817Z INFO Daemon Daemon Downloading artifacts profile blob Jul 7 06:13:05.533899 waagent[2024]: 2025-07-07T06:13:05.533838Z INFO Daemon Downloaded certificate {'thumbprint': 'D3672A12608E156AA2D670D16F5BBBC200CEEBF4', 'hasPrivateKey': True} Jul 7 06:13:05.541710 waagent[2024]: 2025-07-07T06:13:05.541667Z INFO Daemon Fetch goal state completed Jul 7 06:13:05.575637 waagent[2024]: 2025-07-07T06:13:05.575598Z INFO Daemon Daemon Starting provisioning Jul 7 06:13:05.579285 waagent[2024]: 2025-07-07T06:13:05.579242Z INFO Daemon Daemon Handle ovf-env.xml. Jul 7 06:13:05.583037 waagent[2024]: 2025-07-07T06:13:05.582996Z INFO Daemon Daemon Set hostname [ci-4372.0.1-a-1825ea7151] Jul 7 06:13:05.605093 waagent[2024]: 2025-07-07T06:13:05.605052Z INFO Daemon Daemon Publish hostname [ci-4372.0.1-a-1825ea7151] Jul 7 06:13:05.610154 waagent[2024]: 2025-07-07T06:13:05.610109Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 7 06:13:05.611940 waagent[2024]: 2025-07-07T06:13:05.611901Z INFO Daemon Daemon Primary interface is [eth0] Jul 7 06:13:05.620986 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:13:05.620996 systemd-networkd[1585]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:13:05.621023 systemd-networkd[1585]: eth0: DHCP lease lost Jul 7 06:13:05.621652 waagent[2024]: 2025-07-07T06:13:05.621382Z INFO Daemon Daemon Create user account if not exists Jul 7 06:13:05.623120 waagent[2024]: 2025-07-07T06:13:05.623078Z INFO Daemon Daemon User core already exists, skip useradd Jul 7 06:13:05.624498 waagent[2024]: 2025-07-07T06:13:05.624464Z INFO Daemon Daemon Configure sudoer Jul 7 06:13:05.628987 waagent[2024]: 2025-07-07T06:13:05.628944Z INFO Daemon Daemon Configure sshd Jul 7 06:13:05.632842 waagent[2024]: 2025-07-07T06:13:05.632800Z 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 06:13:05.634429 waagent[2024]: 2025-07-07T06:13:05.634395Z INFO Daemon Daemon Deploy ssh public key. Jul 7 06:13:05.715557 systemd-networkd[1585]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:13:05.802957 login[2029]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 06:13:05.807294 systemd-logind[1885]: New session 1 of user core. Jul 7 06:13:05.828617 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 7 06:13:20.330601 chronyd[1881]: Selected source PHC0 Jul 7 06:13:26.597208 setup-nvidia[2042]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 485M 0 751k 0 0 551k 0 0:15:01 0:00:01 0:15:00 551k\u000d 3 485M 3 19.2M 0 0 8214k 0 0:01:00 0:00:02 0:00:58 8212k\u000d 8 485M 8 42.1M 0 0 12.2M 0 0:00:39 0:00:03 0:00:36 12.2M\u000d 13 485M 13 64.5M 0 0 14.3M 0 0:00:33 0:00:04 0:00:29 14.3M\u000d 17 485M 17 82.8M 0 0 15.4M 0 0:00:31 0:00:05 0:00:26 16.9M\u000d 21 485M 21 105M 0 0 16.3M 0 0:00:29 0:00:06 0:00:23 20.6M\u000d 26 485M 26 129M 0 0 17.3M 0 0:00:28 0:00:07 0:00:21 21.7M\u000d 28 485M 28 140M 0 0 16.7M 0 0:00:29 0:00:08 0:00:21 19.8M\u000d 28 485M 28 140M 0 0 14.9M 0 0:00:32 0:00:09 0:00:23 15.4M\u000d 28 485M 28 140M 0 0 13.5M 0 0:00:35 0:00:10 0:00:25 11.4M\u000d 29 485M 29 142M 0 0 12.4M 0 0:00:39 0:00:11 0:00:28 7560k\u000d 29 485M 29 143M 0 0 11.5M 0 0:00:42 0:00:12 0:00:30 2976k\u000d 33 485M 33 162M 0 0 12.0M 0 0:00:40 0:00:13 0:00:27 4500k\u000d 36 485M 36 179M 0 0 12.5M 0 0:00:38 0:00:14 0:00:24 8111k\u000d 41 485M 41 199M 0 0 12.9M 0 0:00:37 0:00:15 0:00:22 11.7M\u000d 45 485M 45 221M 0 0 13.5M 0 0:00:35 0:00:16 0:00:19 15.9M\u000d 50 485M 50 245M 0 0 14.0M 0 0:00:34 0:00:17 0:00:17 20.1M\u000d 53 485M 53 258M 0 0 14.0M 0 0:00:34 0:00:18 0:00:16 19.6M\u000d 57 485M 57 277M 0 0 14.3M 0 0:00:33 0:00:19 0:00:14 19.5M\u000d 61 485M 61 300M 0 0 14.7M 0 0:00:32 0:00:20 0:00:12 20.1M\u000d 66 485M 66 323M 0 0 15.0M 0 0:00:32 0:00:21 0:00:11 20.1M\u000d 70 485M 70 343M 0 0 15.3M 0 0:00:31 0:00:22 0:00:09 19.9M\u000d 75 485M 75 367M 0 0 15.6M 0 0:00:30 0:00:23 0:00:07 21.4M\u000d 80 485M 80 389M 0 0 15.8M 0 0:00:30 0:00:24 0:00:06 21.9M\u000d 84 485M 84 409M 0 0 16.1M 0 0:00:30 0:00:25 0:00:05 22.0M\u000d 89 485M 89 434M 0 0 16.4M 0 0:00:29 0:00:26 0:00:03 22.4M\u000d 94 485M 94 457M 0 0 16.6M 0 0:00:29 0:00:27 0:00:02 22.4M\u000d 98 485M 98 476M 0 0 16.7M 0 0:00:28 0:00:28 --:--:-- 22.0M\u000d100 485M 100 485M 0 0 16.8M 0 0:00:28 0:00:28 --:--:-- 22.1M Jul 7 06:13:35.743819 waagent[2024]: 2025-07-07T06:13:35.743762Z INFO Daemon Daemon Provisioning complete Jul 7 06:13:35.754904 waagent[2024]: 2025-07-07T06:13:35.754857Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 7 06:13:35.756614 waagent[2024]: 2025-07-07T06:13:35.756569Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 7 06:13:35.757320 waagent[2024]: 2025-07-07T06:13:35.757280Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 7 06:13:35.947949 waagent[2179]: 2025-07-07T06:13:35.947819Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 7 06:13:35.948990 waagent[2179]: 2025-07-07T06:13:35.948463Z INFO ExtHandler ExtHandler OS: flatcar 4372.0.1 Jul 7 06:13:35.948990 waagent[2179]: 2025-07-07T06:13:35.948574Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 7 06:13:35.948990 waagent[2179]: 2025-07-07T06:13:35.948653Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 7 06:13:36.031105 waagent[2179]: 2025-07-07T06:13:36.028683Z INFO ExtHandler ExtHandler Distro: flatcar-4372.0.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 7 06:13:36.031105 waagent[2179]: 2025-07-07T06:13:36.028930Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:13:36.031105 waagent[2179]: 2025-07-07T06:13:36.029008Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:13:36.037507 waagent[2179]: 2025-07-07T06:13:36.037461Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 06:13:36.048760 waagent[2179]: 2025-07-07T06:13:36.048710Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 7 06:13:36.052533 waagent[2179]: 2025-07-07T06:13:36.051086Z INFO ExtHandler Jul 7 06:13:36.052533 waagent[2179]: 2025-07-07T06:13:36.051176Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 9b1aece6-67fb-4474-80d0-783c49c93d55 eTag: 799637555470215310 source: Fabric] Jul 7 06:13:36.052533 waagent[2179]: 2025-07-07T06:13:36.051478Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 7 06:13:36.052533 waagent[2179]: 2025-07-07T06:13:36.052109Z INFO ExtHandler Jul 7 06:13:36.052533 waagent[2179]: 2025-07-07T06:13:36.052180Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 7 06:13:36.056393 waagent[2179]: 2025-07-07T06:13:36.056359Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 7 06:13:36.140592 waagent[2179]: 2025-07-07T06:13:36.140496Z INFO ExtHandler Downloaded certificate {'thumbprint': 'D3672A12608E156AA2D670D16F5BBBC200CEEBF4', 'hasPrivateKey': True} Jul 7 06:13:36.141303 waagent[2179]: 2025-07-07T06:13:36.141266Z INFO ExtHandler Fetch goal state completed Jul 7 06:13:36.154819 waagent[2179]: 2025-07-07T06:13:36.154767Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 7 06:13:36.165494 waagent[2179]: 2025-07-07T06:13:36.165439Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2179 Jul 7 06:13:36.166369 waagent[2179]: 2025-07-07T06:13:36.166330Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 7 06:13:36.168894 waagent[2179]: 2025-07-07T06:13:36.168856Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 7 06:13:36.170592 waagent[2179]: 2025-07-07T06:13:36.170554Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.0.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 7 06:13:36.171114 waagent[2179]: 2025-07-07T06:13:36.171077Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.0.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 7 06:13:36.171361 waagent[2179]: 2025-07-07T06:13:36.171327Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 7 06:13:36.172113 waagent[2179]: 2025-07-07T06:13:36.172076Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 7 06:13:36.253343 waagent[2179]: 2025-07-07T06:13:36.253300Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 7 06:13:36.253713 waagent[2179]: 2025-07-07T06:13:36.253677Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 7 06:13:36.264122 waagent[2179]: 2025-07-07T06:13:36.264031Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 7 06:13:36.273853 systemd[1]: Reload requested from client PID 2194 ('systemctl') (unit waagent.service)... Jul 7 06:13:36.273873 systemd[1]: Reloading... Jul 7 06:13:36.386547 zram_generator::config[2233]: No configuration found. Jul 7 06:13:36.582105 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 06:13:36.763345 systemd[1]: Reloading finished in 489 ms. Jul 7 06:13:36.786660 waagent[2179]: 2025-07-07T06:13:36.785665Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 7 06:13:36.786660 waagent[2179]: 2025-07-07T06:13:36.785810Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 7 06:13:37.317841 setup-nvidia[1873]: Downloading NVIDIA 535.230.02 Driver Jul 7 06:13:37.322067 setup-nvidia[2298]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 06:13:37.322271 setup-nvidia[2298]: Dload Upload Total Spent Left Speed Jul 7 06:13:37.530186 waagent[2179]: 2025-07-07T06:13:37.530102Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 7 06:13:37.530544 waagent[2179]: 2025-07-07T06:13:37.530449Z 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 06:13:37.531254 waagent[2179]: 2025-07-07T06:13:37.531207Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 7 06:13:37.531695 waagent[2179]: 2025-07-07T06:13:37.531654Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 7 06:13:37.532053 waagent[2179]: 2025-07-07T06:13:37.532005Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:13:37.532248 waagent[2179]: 2025-07-07T06:13:37.532206Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:13:37.532574 waagent[2179]: 2025-07-07T06:13:37.532538Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:13:37.532621 waagent[2179]: 2025-07-07T06:13:37.532583Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:13:37.532860 waagent[2179]: 2025-07-07T06:13:37.532801Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 7 06:13:37.532928 waagent[2179]: 2025-07-07T06:13:37.532879Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 7 06:13:37.533531 waagent[2179]: 2025-07-07T06:13:37.533475Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 7 06:13:37.533713 waagent[2179]: 2025-07-07T06:13:37.533668Z INFO EnvHandler ExtHandler Configure routes Jul 7 06:13:37.534060 waagent[2179]: 2025-07-07T06:13:37.533943Z INFO EnvHandler ExtHandler Gateway:None Jul 7 06:13:37.534304 waagent[2179]: 2025-07-07T06:13:37.534249Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 7 06:13:37.534460 waagent[2179]: 2025-07-07T06:13:37.534418Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 7 06:13:37.534841 waagent[2179]: 2025-07-07T06:13:37.534808Z INFO EnvHandler ExtHandler Routes:None Jul 7 06:13:37.535307 waagent[2179]: 2025-07-07T06:13:37.535263Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 7 06:13:37.536461 waagent[2179]: 2025-07-07T06:13:37.536342Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 7 06:13:37.536461 waagent[2179]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 7 06:13:37.536461 waagent[2179]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jul 7 06:13:37.536461 waagent[2179]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 7 06:13:37.536461 waagent[2179]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:13:37.536461 waagent[2179]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:13:37.536461 waagent[2179]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:13:37.541336 waagent[2179]: 2025-07-07T06:13:37.541291Z INFO ExtHandler ExtHandler Jul 7 06:13:37.541666 waagent[2179]: 2025-07-07T06:13:37.541629Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: da5ec4fd-70f5-42d1-bccf-c136172e805b correlation ca7f841b-2108-44f1-bf38-d39ba57fcc5b created: 2025-07-07T06:11:37.560806Z] Jul 7 06:13:37.542686 waagent[2179]: 2025-07-07T06:13:37.542641Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 7 06:13:37.544800 waagent[2179]: 2025-07-07T06:13:37.544760Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 7 06:13:37.569367 waagent[2179]: 2025-07-07T06:13:37.569270Z 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 06:13:37.569367 waagent[2179]: Try `iptables -h' or 'iptables --help' for more information.) Jul 7 06:13:37.569783 waagent[2179]: 2025-07-07T06:13:37.569671Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C60BFF61-63E8-4E1D-8D10-EE0A824DF423;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 7 06:13:37.625765 waagent[2179]: 2025-07-07T06:13:37.625711Z INFO MonitorHandler ExtHandler Network interfaces: Jul 7 06:13:37.625765 waagent[2179]: Executing ['ip', '-a', '-o', 'link']: Jul 7 06:13:37.625765 waagent[2179]: 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 06:13:37.625765 waagent[2179]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:40:d1:4f brd ff:ff:ff:ff:ff:ff Jul 7 06:13:37.625765 waagent[2179]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 7 06:13:37.625765 waagent[2179]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 7 06:13:37.625765 waagent[2179]: 2: eth0 inet 10.200.4.28/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 7 06:13:37.625765 waagent[2179]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 7 06:13:37.625765 waagent[2179]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 7 06:13:37.625765 waagent[2179]: 2: eth0 inet6 fe80::222:48ff:fe40:d14f/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 7 06:13:37.742823 waagent[2179]: 2025-07-07T06:13:37.742760Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 7 06:13:37.742823 waagent[2179]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:13:37.742823 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.742823 waagent[2179]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:13:37.742823 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.742823 waagent[2179]: Chain OUTPUT (policy ACCEPT 51 packets, 2687 bytes) Jul 7 06:13:37.742823 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.742823 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 06:13:37.742823 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 06:13:37.742823 waagent[2179]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 06:13:37.748030 waagent[2179]: 2025-07-07T06:13:37.747857Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 7 06:13:37.748030 waagent[2179]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:13:37.748030 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.748030 waagent[2179]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:13:37.748030 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.748030 waagent[2179]: Chain OUTPUT (policy ACCEPT 77 packets, 4039 bytes) Jul 7 06:13:37.748030 waagent[2179]: pkts bytes target prot opt in out source destination Jul 7 06:13:37.748030 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 06:13:37.748030 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 06:13:37.748030 waagent[2179]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 06:13:37.748286 waagent[2179]: 2025-07-07T06:13:37.748114Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 7 06:13:37.748286 waagent[2179]: 2025-07-07T06:13:37.748252Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 7 06:13:39.008061 setup-nvidia[2298]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 30 326M 30 98.0M 0 0 121M 0 0:00:02 --:--:-- 0:00:02 120M\u000d100 326M 100 326M 0 0 193M 0 0:00:01 0:00:01 --:--:-- 193M Jul 7 06:13:39.060354 setup-nvidia[1873]: Extract the NVIDIA Driver Installer 535.230.02 Jul 7 06:13:39.060354 setup-nvidia[1873]: /opt/nvidia/workdir/nvidia-workdir / Jul 7 06:13:39.108488 setup-nvidia[2330]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 7 06:13:39.384311 setup-nvidia[2330]: Verifying archive integrity... OK Jul 7 06:13:39.657697 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 7 06:13:40.135773 setup-nvidia[2330]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 7 06:13:41.914041 setup-nvidia[2356]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 7 06:13:41.998727 update_engine[1886]: I20250707 06:13:41.998667 1886 update_attempter.cc:509] Updating boot flags... Jul 7 06:13:42.054341 setup-nvidia[1873]: / Jul 7 06:13:42.054609 setup-nvidia[1873]: Spawn system-nspawn container to install the NVIDIA drivers Jul 7 06:13:42.372507 sudo[2427]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.0.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 06:13:42.374293 sudo[2427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 7 06:13:42.379977 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 7 06:13:42.382560 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 06:13:42.408801 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 06:13:42.416538 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 7 06:13:42.435466 (systemd)[2482]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 7 06:13:42.437716 systemd-logind[1885]: New session c3 of user root. Jul 7 06:13:42.605544 systemd[2482]: Queued start job for default target default.target. Jul 7 06:13:42.627589 systemd[2482]: Created slice app.slice - User Application Slice. Jul 7 06:13:42.627619 systemd[2482]: Reached target paths.target - Paths. Jul 7 06:13:42.627662 systemd[2482]: Reached target timers.target - Timers. Jul 7 06:13:42.628803 systemd[2482]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 06:13:42.639001 systemd[2482]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:13:42.639059 systemd[2482]: Reached target sockets.target - Sockets. Jul 7 06:13:42.639108 systemd[2482]: Reached target basic.target - Basic System. Jul 7 06:13:42.639152 systemd[2482]: Reached target default.target - Main User Target. Jul 7 06:13:42.639183 systemd[2482]: Startup finished in 195ms. Jul 7 06:13:42.639294 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 7 06:13:42.642200 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 7 06:13:42.728538 kernel: loop6: detected capacity change from 0 to 12644352 Jul 7 06:13:42.767533 kernel: loop6: p9 Jul 7 06:13:42.960522 setup-nvidia[2491]: ░ Spawning container flatcardevelopercontainer-4372.0.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.0.1.bin. Jul 7 06:13:45.266097 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 7 06:13:45.266212 kernel: EXT4-fs (loop6p9): mounted filesystem bbc9574b-3fa0-492d-bcc0-ab0a1bc7f9ec ro without journal. Quota mode: none. Jul 7 06:13:54.646917 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 7 06:13:54.651605 systemd[1]: Started sshd@0-10.200.4.28:22-10.200.16.10:33112.service - OpenSSH per-connection server daemon (10.200.16.10:33112). Jul 7 06:13:55.404051 sshd[3106]: Accepted publickey for core from 10.200.16.10 port 33112 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:13:55.405354 sshd-session[3106]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:13:55.409546 systemd-logind[1885]: New session 3 of user core. Jul 7 06:13:55.426639 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 7 06:13:55.924649 systemd[1]: Started sshd@1-10.200.4.28:22-10.200.16.10:33120.service - OpenSSH per-connection server daemon (10.200.16.10:33120). Jul 7 06:13:56.520779 sshd[3219]: Accepted publickey for core from 10.200.16.10 port 33120 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:13:56.522089 sshd-session[3219]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:13:56.526469 systemd-logind[1885]: New session 4 of user core. Jul 7 06:13:56.546623 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 7 06:13:56.947986 sshd[3221]: Connection closed by 10.200.16.10 port 33120 Jul 7 06:13:56.948244 sshd-session[3219]: pam_unix(sshd:session): session closed for user core Jul 7 06:13:56.951381 systemd[1]: sshd@1-10.200.4.28:22-10.200.16.10:33120.service: Deactivated successfully. Jul 7 06:13:56.952951 systemd[1]: session-4.scope: Deactivated successfully. Jul 7 06:13:56.953650 systemd-logind[1885]: Session 4 logged out. Waiting for processes to exit. Jul 7 06:13:56.954906 systemd-logind[1885]: Removed session 4. Jul 7 06:13:57.054875 systemd[1]: Started sshd@2-10.200.4.28:22-10.200.16.10:33134.service - OpenSSH per-connection server daemon (10.200.16.10:33134). Jul 7 06:13:57.649584 sshd[3227]: Accepted publickey for core from 10.200.16.10 port 33134 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:13:57.652954 sshd-session[3227]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:13:57.657164 systemd-logind[1885]: New session 5 of user core. Jul 7 06:13:57.665628 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 7 06:13:58.074446 sshd[3229]: Connection closed by 10.200.16.10 port 33134 Jul 7 06:13:58.074796 sshd-session[3227]: pam_unix(sshd:session): session closed for user core Jul 7 06:13:58.078111 systemd[1]: sshd@2-10.200.4.28:22-10.200.16.10:33134.service: Deactivated successfully. Jul 7 06:13:58.079972 systemd[1]: session-5.scope: Deactivated successfully. Jul 7 06:13:58.080717 systemd-logind[1885]: Session 5 logged out. Waiting for processes to exit. Jul 7 06:13:58.081806 systemd-logind[1885]: Removed session 5. Jul 7 06:14:08.189969 systemd[1]: Started sshd@3-10.200.4.28:22-10.200.16.10:50352.service - OpenSSH per-connection server daemon (10.200.16.10:50352). Jul 7 06:14:08.790270 sshd[4233]: Accepted publickey for core from 10.200.16.10 port 50352 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:08.791534 sshd-session[4233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:08.795824 systemd-logind[1885]: New session 6 of user core. Jul 7 06:14:08.801657 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 7 06:14:09.211382 sshd[4645]: Connection closed by 10.200.16.10 port 50352 Jul 7 06:14:09.211677 sshd-session[4233]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:09.214617 systemd[1]: sshd@3-10.200.4.28:22-10.200.16.10:50352.service: Deactivated successfully. Jul 7 06:14:09.216373 systemd[1]: session-6.scope: Deactivated successfully. Jul 7 06:14:09.217706 systemd-logind[1885]: Session 6 logged out. Waiting for processes to exit. Jul 7 06:14:09.218693 systemd-logind[1885]: Removed session 6. Jul 7 06:14:09.325683 systemd[1]: Started sshd@4-10.200.4.28:22-10.200.16.10:50362.service - OpenSSH per-connection server daemon (10.200.16.10:50362). Jul 7 06:14:09.918644 sshd[4787]: Accepted publickey for core from 10.200.16.10 port 50362 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:09.920018 sshd-session[4787]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:09.924535 systemd-logind[1885]: New session 7 of user core. Jul 7 06:14:09.929655 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 7 06:14:10.345688 sshd[4866]: Connection closed by 10.200.16.10 port 50362 Jul 7 06:14:10.345983 sshd-session[4787]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:10.349390 systemd[1]: sshd@4-10.200.4.28:22-10.200.16.10:50362.service: Deactivated successfully. Jul 7 06:14:10.350924 systemd[1]: session-7.scope: Deactivated successfully. Jul 7 06:14:10.351605 systemd-logind[1885]: Session 7 logged out. Waiting for processes to exit. Jul 7 06:14:10.352708 systemd-logind[1885]: Removed session 7. Jul 7 06:14:20.453923 systemd[1]: Started sshd@5-10.200.4.28:22-10.200.16.10:47572.service - OpenSSH per-connection server daemon (10.200.16.10:47572). Jul 7 06:14:21.058272 sshd[7263]: Accepted publickey for core from 10.200.16.10 port 47572 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:21.058882 sshd-session[7263]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:21.064025 systemd-logind[1885]: New session 8 of user core. Jul 7 06:14:21.073947 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 7 06:14:21.484886 sshd[7444]: Connection closed by 10.200.16.10 port 47572 Jul 7 06:14:21.485164 sshd-session[7263]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:21.488504 systemd[1]: sshd@5-10.200.4.28:22-10.200.16.10:47572.service: Deactivated successfully. Jul 7 06:14:21.490073 systemd[1]: session-8.scope: Deactivated successfully. Jul 7 06:14:21.490744 systemd-logind[1885]: Session 8 logged out. Waiting for processes to exit. Jul 7 06:14:21.491898 systemd-logind[1885]: Removed session 8. Jul 7 06:14:21.590857 systemd[1]: Started sshd@6-10.200.4.28:22-10.200.16.10:47574.service - OpenSSH per-connection server daemon (10.200.16.10:47574). Jul 7 06:14:22.207361 sshd[7604]: Accepted publickey for core from 10.200.16.10 port 47574 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:22.207158 sshd-session[7604]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:22.215977 systemd-logind[1885]: New session 9 of user core. Jul 7 06:14:22.218885 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 7 06:14:22.629762 sshd[7716]: Connection closed by 10.200.16.10 port 47574 Jul 7 06:14:22.630141 sshd-session[7604]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:22.634247 systemd-logind[1885]: Session 9 logged out. Waiting for processes to exit. Jul 7 06:14:22.636866 systemd[1]: sshd@6-10.200.4.28:22-10.200.16.10:47574.service: Deactivated successfully. Jul 7 06:14:22.639254 systemd[1]: session-9.scope: Deactivated successfully. Jul 7 06:14:22.642850 systemd-logind[1885]: Removed session 9. Jul 7 06:14:32.746833 systemd[1]: Started sshd@7-10.200.4.28:22-10.200.16.10:55188.service - OpenSSH per-connection server daemon (10.200.16.10:55188). Jul 7 06:14:33.354025 sshd[11286]: Accepted publickey for core from 10.200.16.10 port 55188 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:33.354600 sshd-session[11286]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:33.360617 systemd-logind[1885]: New session 10 of user core. Jul 7 06:14:33.369637 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 7 06:14:33.789358 sshd[11313]: Connection closed by 10.200.16.10 port 55188 Jul 7 06:14:33.789759 sshd-session[11286]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:33.793069 systemd-logind[1885]: Session 10 logged out. Waiting for processes to exit. Jul 7 06:14:33.795870 systemd[1]: sshd@7-10.200.4.28:22-10.200.16.10:55188.service: Deactivated successfully. Jul 7 06:14:33.797858 systemd[1]: session-10.scope: Deactivated successfully. Jul 7 06:14:33.801045 systemd-logind[1885]: Removed session 10. Jul 7 06:14:33.895366 systemd[1]: Started sshd@8-10.200.4.28:22-10.200.16.10:55198.service - OpenSSH per-connection server daemon (10.200.16.10:55198). Jul 7 06:14:34.511253 sshd[11327]: Accepted publickey for core from 10.200.16.10 port 55198 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:34.512106 sshd-session[11327]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:34.518576 systemd-logind[1885]: New session 11 of user core. Jul 7 06:14:34.525054 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 7 06:14:34.939620 sshd[11351]: Connection closed by 10.200.16.10 port 55198 Jul 7 06:14:34.939914 sshd-session[11327]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:34.943961 systemd-logind[1885]: Session 11 logged out. Waiting for processes to exit. Jul 7 06:14:34.944945 systemd[1]: sshd@8-10.200.4.28:22-10.200.16.10:55198.service: Deactivated successfully. Jul 7 06:14:34.948431 systemd[1]: session-11.scope: Deactivated successfully. Jul 7 06:14:34.950390 systemd-logind[1885]: Removed session 11. Jul 7 06:14:45.048774 systemd[1]: Started sshd@9-10.200.4.28:22-10.200.16.10:53002.service - OpenSSH per-connection server daemon (10.200.16.10:53002). Jul 7 06:14:45.675291 sshd[11784]: Accepted publickey for core from 10.200.16.10 port 53002 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:45.676737 sshd-session[11784]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:45.685429 systemd-logind[1885]: New session 12 of user core. Jul 7 06:14:45.690184 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 7 06:14:46.108619 sshd[11803]: Connection closed by 10.200.16.10 port 53002 Jul 7 06:14:46.108989 sshd-session[11784]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:46.114209 systemd-logind[1885]: Session 12 logged out. Waiting for processes to exit. Jul 7 06:14:46.115689 systemd[1]: sshd@9-10.200.4.28:22-10.200.16.10:53002.service: Deactivated successfully. Jul 7 06:14:46.117215 systemd[1]: session-12.scope: Deactivated successfully. Jul 7 06:14:46.119601 systemd-logind[1885]: Removed session 12. Jul 7 06:14:46.217762 systemd[1]: Started sshd@10-10.200.4.28:22-10.200.16.10:53006.service - OpenSSH per-connection server daemon (10.200.16.10:53006). Jul 7 06:14:46.838076 sshd[11827]: Accepted publickey for core from 10.200.16.10 port 53006 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:46.839541 sshd-session[11827]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:46.848305 systemd-logind[1885]: New session 13 of user core. Jul 7 06:14:46.854684 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 7 06:14:47.281292 sshd[11841]: Connection closed by 10.200.16.10 port 53006 Jul 7 06:14:47.282796 sshd-session[11827]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:47.286223 systemd[1]: sshd@10-10.200.4.28:22-10.200.16.10:53006.service: Deactivated successfully. Jul 7 06:14:47.288316 systemd[1]: session-13.scope: Deactivated successfully. Jul 7 06:14:47.292357 systemd-logind[1885]: Session 13 logged out. Waiting for processes to exit. Jul 7 06:14:47.293752 systemd-logind[1885]: Removed session 13. Jul 7 06:14:57.389761 systemd[1]: Started sshd@11-10.200.4.28:22-10.200.16.10:43624.service - OpenSSH per-connection server daemon (10.200.16.10:43624). Jul 7 06:14:58.006644 sshd[12195]: Accepted publickey for core from 10.200.16.10 port 43624 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:58.008109 sshd-session[12195]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:58.016928 systemd-logind[1885]: New session 14 of user core. Jul 7 06:14:58.023922 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 7 06:14:58.441547 sshd[12210]: Connection closed by 10.200.16.10 port 43624 Jul 7 06:14:58.442166 sshd-session[12195]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:58.445803 systemd-logind[1885]: Session 14 logged out. Waiting for processes to exit. Jul 7 06:14:58.447901 systemd[1]: sshd@11-10.200.4.28:22-10.200.16.10:43624.service: Deactivated successfully. Jul 7 06:14:58.451938 systemd[1]: session-14.scope: Deactivated successfully. Jul 7 06:14:58.453985 systemd-logind[1885]: Removed session 14. Jul 7 06:14:58.552786 systemd[1]: Started sshd@12-10.200.4.28:22-10.200.16.10:43638.service - OpenSSH per-connection server daemon (10.200.16.10:43638). Jul 7 06:14:59.169833 sshd[12233]: Accepted publickey for core from 10.200.16.10 port 43638 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:14:59.170695 sshd-session[12233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:14:59.180493 systemd-logind[1885]: New session 15 of user core. Jul 7 06:14:59.186655 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 7 06:14:59.602103 sshd[12243]: Connection closed by 10.200.16.10 port 43638 Jul 7 06:14:59.602721 sshd-session[12233]: pam_unix(sshd:session): session closed for user core Jul 7 06:14:59.608166 systemd-logind[1885]: Session 15 logged out. Waiting for processes to exit. Jul 7 06:14:59.609940 systemd[1]: sshd@12-10.200.4.28:22-10.200.16.10:43638.service: Deactivated successfully. Jul 7 06:14:59.611651 systemd[1]: session-15.scope: Deactivated successfully. Jul 7 06:14:59.614683 systemd-logind[1885]: Removed session 15. Jul 7 06:15:09.719825 systemd[1]: Started sshd@13-10.200.4.28:22-10.200.16.10:48662.service - OpenSSH per-connection server daemon (10.200.16.10:48662). Jul 7 06:15:10.313425 sshd[12484]: Accepted publickey for core from 10.200.16.10 port 48662 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:10.314902 sshd-session[12484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:10.319222 systemd-logind[1885]: New session 16 of user core. Jul 7 06:15:10.335637 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 7 06:15:10.743184 sshd[12486]: Connection closed by 10.200.16.10 port 48662 Jul 7 06:15:10.743461 sshd-session[12484]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:10.746970 systemd[1]: sshd@13-10.200.4.28:22-10.200.16.10:48662.service: Deactivated successfully. Jul 7 06:15:10.748558 systemd[1]: session-16.scope: Deactivated successfully. Jul 7 06:15:10.749234 systemd-logind[1885]: Session 16 logged out. Waiting for processes to exit. Jul 7 06:15:10.750327 systemd-logind[1885]: Removed session 16. Jul 7 06:15:10.851161 systemd[1]: Started sshd@14-10.200.4.28:22-10.200.16.10:48674.service - OpenSSH per-connection server daemon (10.200.16.10:48674). Jul 7 06:15:11.466848 sshd[12492]: Accepted publickey for core from 10.200.16.10 port 48674 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:11.468144 sshd-session[12492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:11.472721 systemd-logind[1885]: New session 17 of user core. Jul 7 06:15:11.484628 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 7 06:15:11.894708 sshd[12502]: Connection closed by 10.200.16.10 port 48674 Jul 7 06:15:11.895037 sshd-session[12492]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:11.898386 systemd[1]: sshd@14-10.200.4.28:22-10.200.16.10:48674.service: Deactivated successfully. Jul 7 06:15:11.899895 systemd[1]: session-17.scope: Deactivated successfully. Jul 7 06:15:11.900569 systemd-logind[1885]: Session 17 logged out. Waiting for processes to exit. Jul 7 06:15:11.901609 systemd-logind[1885]: Removed session 17. Jul 7 06:15:13.918175 setup-nvidia[2491]: \u000d Jul 7 06:15:13.918175 setup-nvidia[2491]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 7 06:15:13.918175 setup-nvidia[2491]: frequently when this kernel module was built against the wrong or Jul 7 06:15:13.918175 setup-nvidia[2491]: improperly configured kernel sources, with a version of gcc that differs Jul 7 06:15:13.918175 setup-nvidia[2491]: from the one used to build the target kernel, or if another driver, such Jul 7 06:15:13.918175 setup-nvidia[2491]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 7 06:15:13.918175 setup-nvidia[2491]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 7 06:15:13.918175 setup-nvidia[2491]: installed in this system is supported by this NVIDIA Linux graphics Jul 7 06:15:13.918175 setup-nvidia[2491]: driver release. Jul 7 06:15:13.918175 setup-nvidia[2491]: \u000d Jul 7 06:15:13.918175 setup-nvidia[2491]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 7 06:15:13.918175 setup-nvidia[2491]: messages' at the end of the file Jul 7 06:15:13.918175 setup-nvidia[2491]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 7 06:15:13.918175 setup-nvidia[2491]: information. Jul 7 06:15:13.918175 setup-nvidia[2491]: \u000d Jul 7 06:15:13.933188 setup-nvidia[2491]: dmesg: read kernel buffer failed: Operation not permitted Jul 7 06:15:13.952115 setup-nvidia[2491]: \u000d Jul 7 06:15:13.952115 setup-nvidia[2491]: ERROR: Installation has failed. Please see the file Jul 7 06:15:13.952115 setup-nvidia[2491]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 7 06:15:13.952115 setup-nvidia[2491]: details. You may find suggestions on fixing installation problems in Jul 7 06:15:13.952115 setup-nvidia[2491]: the README available on the Linux driver download page at Jul 7 06:15:13.952115 setup-nvidia[2491]: www.nvidia.com. Jul 7 06:15:13.952115 setup-nvidia[2491]: \u000d Jul 7 06:15:13.952504 setup-nvidia[2491]: Last 50 lines of nvidia-installer.log: Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:15:13.953316 setup-nvidia[2491]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 7 06:15:13.953316 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 7 06:15:13.953316 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jul 7 06:15:13.953316 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 7 06:15:13.953316 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 7 06:15:13.954045 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 7 06:15:13.954045 setup-nvidia[2491]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 7 06:15:13.954045 setup-nvidia[2491]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 7 06:15:13.954045 setup-nvidia[2491]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 7 06:15:13.954045 setup-nvidia[2491]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 7 06:15:13.954045 setup-nvidia[2491]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 7 06:15:13.954045 setup-nvidia[2491]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 06:15:13.954045 setup-nvidia[2491]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 7 06:15:13.954045 setup-nvidia[2491]: make[2]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/build' Jul 7 06:15:13.954045 setup-nvidia[2491]: make[1]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/source' Jul 7 06:15:13.954045 setup-nvidia[2491]: -> done. Jul 7 06:15:13.954045 setup-nvidia[2491]: -> Kernel module compilation complete. Jul 7 06:15:13.954045 setup-nvidia[2491]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 7 06:15:13.954045 setup-nvidia[2491]: 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 06:15:13.954045 setup-nvidia[2491]: \u000d Jul 7 06:15:13.954045 setup-nvidia[2491]: 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 06:15:13.954045 setup-nvidia[2491]: -> Kernel module load error: Operation not permitted Jul 7 06:15:13.954045 setup-nvidia[2491]: -> Kernel messages: Jul 7 06:15:13.954045 setup-nvidia[2491]: 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 06:15:22.005861 systemd[1]: Started sshd@15-10.200.4.28:22-10.200.16.10:39604.service - OpenSSH per-connection server daemon (10.200.16.10:39604). Jul 7 06:15:22.602489 sshd[12680]: Accepted publickey for core from 10.200.16.10 port 39604 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:22.603765 sshd-session[12680]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:22.607925 systemd-logind[1885]: New session 18 of user core. Jul 7 06:15:22.618626 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 7 06:15:23.034490 sshd[12682]: Connection closed by 10.200.16.10 port 39604 Jul 7 06:15:23.034874 sshd-session[12680]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:23.038312 systemd[1]: sshd@15-10.200.4.28:22-10.200.16.10:39604.service: Deactivated successfully. Jul 7 06:15:23.039852 systemd[1]: session-18.scope: Deactivated successfully. Jul 7 06:15:23.040587 systemd-logind[1885]: Session 18 logged out. Waiting for processes to exit. Jul 7 06:15:23.041694 systemd-logind[1885]: Removed session 18. Jul 7 06:15:23.141667 systemd[1]: Started sshd@16-10.200.4.28:22-10.200.16.10:39616.service - OpenSSH per-connection server daemon (10.200.16.10:39616). Jul 7 06:15:23.759775 sshd[12688]: Accepted publickey for core from 10.200.16.10 port 39616 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:23.761038 sshd-session[12688]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:23.765285 systemd-logind[1885]: New session 19 of user core. Jul 7 06:15:23.772620 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 7 06:15:24.182257 sshd[12690]: Connection closed by 10.200.16.10 port 39616 Jul 7 06:15:24.182546 sshd-session[12688]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:24.185918 systemd[1]: sshd@16-10.200.4.28:22-10.200.16.10:39616.service: Deactivated successfully. Jul 7 06:15:24.187472 systemd[1]: session-19.scope: Deactivated successfully. Jul 7 06:15:24.188181 systemd-logind[1885]: Session 19 logged out. Waiting for processes to exit. Jul 7 06:15:24.189217 systemd-logind[1885]: Removed session 19. Jul 7 06:15:29.350546 kernel: EXT4-fs (loop6p9): unmounting filesystem bbc9574b-3fa0-492d-bcc0-ab0a1bc7f9ec. Jul 7 06:15:29.350917 setup-nvidia[2491]: Container flatcardevelopercontainer-4372.0.1.bin exited successfully. Jul 7 06:15:29.353025 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.0.1.bin.mount: Deactivated successfully. Jul 7 06:15:29.410334 sudo[2427]: pam_unix(sudo:session): session closed for user root Jul 7 06:15:29.412273 systemd[1]: session-c2.scope: Deactivated successfully. Jul 7 06:15:29.412539 systemd[1]: session-c2.scope: Consumed 5min 11.301s CPU time, 893.2M memory peak. Jul 7 06:15:29.828133 setup-nvidia[1873]: /opt/nvidia / Jul 7 06:15:29.829121 setup-nvidia[1873]: / Jul 7 06:15:29.838548 kernel: IPMI message handler: version 39.2 Jul 7 06:15:29.841548 kernel: ipmi device interface Jul 7 06:15:29.854135 setup-nvidia[1873]: /opt/nvidia/current/usr/lib/modules/6.12.35-flatcar/video / Jul 7 06:15:29.907462 kernel: nvidia: loading out-of-tree module taints kernel. Jul 7 06:15:29.907620 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 7 06:15:29.907644 kernel: Disabling lock debugging due to kernel taint Jul 7 06:15:29.914871 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 7 06:15:29.914923 kernel: nvidia: module license taints kernel. Jul 7 06:15:30.321548 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 7 06:15:30.717430 kernel: Jul 7 06:15:30.717591 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 7 06:15:30.779529 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 7 06:15:30.800537 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 06:15:30.819549 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 7 06:15:30.866137 setup-nvidia[1873]: / Jul 7 06:15:30.866532 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 7 06:15:30.881259 setup-nvidia[12730]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 06:15:32.480544 setup-nvidia[12747]: Mon Jul 7 06:15:32 2025 Jul 7 06:15:32.480544 setup-nvidia[12747]: +---------------------------------------------------------------------------------------+ Jul 7 06:15:32.480544 setup-nvidia[12747]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 7 06:15:32.480544 setup-nvidia[12747]: |-----------------------------------------+----------------------+----------------------+ Jul 7 06:15:32.480544 setup-nvidia[12747]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 7 06:15:32.480544 setup-nvidia[12747]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 7 06:15:32.480544 setup-nvidia[12747]: | | | MIG M. | Jul 7 06:15:32.480544 setup-nvidia[12747]: |=========================================+======================+======================| Jul 7 06:15:32.488807 setup-nvidia[12747]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Jul 7 06:15:32.488807 setup-nvidia[12747]: | N/A 29C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Jul 7 06:15:32.488807 setup-nvidia[12747]: | | | N/A | Jul 7 06:15:32.488807 setup-nvidia[12747]: +-----------------------------------------+----------------------+----------------------+ Jul 7 06:15:32.488807 setup-nvidia[12747]: Jul 7 06:15:32.488807 setup-nvidia[12747]: +---------------------------------------------------------------------------------------+ Jul 7 06:15:32.488807 setup-nvidia[12747]: | Processes: | Jul 7 06:15:32.488807 setup-nvidia[12747]: | GPU GI CI PID Type Process name GPU Memory | Jul 7 06:15:32.488807 setup-nvidia[12747]: | ID ID Usage | Jul 7 06:15:32.488807 setup-nvidia[12747]: |=======================================================================================| Jul 7 06:15:32.488807 setup-nvidia[12747]: | No running processes found | Jul 7 06:15:32.488807 setup-nvidia[12747]: +---------------------------------------------------------------------------------------+ Jul 7 06:15:32.862572 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 7 06:15:32.864785 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 7 06:15:32.866625 systemd[1]: Startup finished in 1.073s (firmware) + 33.435s (loader) + 3.823s (kernel) + 11.859s (initrd) + 2min 48.221s (userspace) = 3min 38.412s. Jul 7 06:15:34.291330 systemd[1]: Started sshd@17-10.200.4.28:22-10.200.16.10:40678.service - OpenSSH per-connection server daemon (10.200.16.10:40678). Jul 7 06:15:34.904679 sshd[12754]: Accepted publickey for core from 10.200.16.10 port 40678 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:34.905919 sshd-session[12754]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:34.910308 systemd-logind[1885]: New session 20 of user core. Jul 7 06:15:34.922624 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 7 06:15:35.331916 sshd[12756]: Connection closed by 10.200.16.10 port 40678 Jul 7 06:15:35.332235 sshd-session[12754]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:35.335422 systemd[1]: sshd@17-10.200.4.28:22-10.200.16.10:40678.service: Deactivated successfully. Jul 7 06:15:35.337039 systemd[1]: session-20.scope: Deactivated successfully. Jul 7 06:15:35.337721 systemd-logind[1885]: Session 20 logged out. Waiting for processes to exit. Jul 7 06:15:35.338726 systemd-logind[1885]: Removed session 20. Jul 7 06:15:35.445555 systemd[1]: Started sshd@18-10.200.4.28:22-10.200.16.10:40686.service - OpenSSH per-connection server daemon (10.200.16.10:40686). Jul 7 06:15:36.067531 sshd[12762]: Accepted publickey for core from 10.200.16.10 port 40686 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:36.073760 sshd-session[12762]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:36.078048 systemd-logind[1885]: New session 21 of user core. Jul 7 06:15:36.088622 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 7 06:15:36.486410 sshd[12764]: Connection closed by 10.200.16.10 port 40686 Jul 7 06:15:36.486682 sshd-session[12762]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:36.489429 systemd[1]: sshd@18-10.200.4.28:22-10.200.16.10:40686.service: Deactivated successfully. Jul 7 06:15:36.491112 systemd[1]: session-21.scope: Deactivated successfully. Jul 7 06:15:36.492300 systemd-logind[1885]: Session 21 logged out. Waiting for processes to exit. Jul 7 06:15:36.493316 systemd-logind[1885]: Removed session 21. Jul 7 06:15:36.598592 systemd[1]: Started sshd@19-10.200.4.28:22-10.200.16.10:40702.service - OpenSSH per-connection server daemon (10.200.16.10:40702). Jul 7 06:15:37.211190 sshd[12770]: Accepted publickey for core from 10.200.16.10 port 40702 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:37.212440 sshd-session[12770]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:37.216719 systemd-logind[1885]: New session 22 of user core. Jul 7 06:15:37.228643 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 7 06:15:37.633651 sshd[12772]: Connection closed by 10.200.16.10 port 40702 Jul 7 06:15:37.633999 sshd-session[12770]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:37.637334 systemd[1]: sshd@19-10.200.4.28:22-10.200.16.10:40702.service: Deactivated successfully. Jul 7 06:15:37.638883 systemd[1]: session-22.scope: Deactivated successfully. Jul 7 06:15:37.641953 systemd-logind[1885]: Session 22 logged out. Waiting for processes to exit. Jul 7 06:15:37.643205 systemd-logind[1885]: Removed session 22. Jul 7 06:15:37.743794 systemd[1]: Started sshd@20-10.200.4.28:22-10.200.16.10:40710.service - OpenSSH per-connection server daemon (10.200.16.10:40710). Jul 7 06:15:38.361006 sshd[12778]: Accepted publickey for core from 10.200.16.10 port 40710 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:38.362333 sshd-session[12778]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:38.366914 systemd-logind[1885]: New session 23 of user core. Jul 7 06:15:38.377640 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 7 06:15:38.790329 sshd[12780]: Connection closed by 10.200.16.10 port 40710 Jul 7 06:15:38.790691 sshd-session[12778]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:38.794006 systemd[1]: sshd@20-10.200.4.28:22-10.200.16.10:40710.service: Deactivated successfully. Jul 7 06:15:38.795610 systemd[1]: session-23.scope: Deactivated successfully. Jul 7 06:15:38.796278 systemd-logind[1885]: Session 23 logged out. Waiting for processes to exit. Jul 7 06:15:38.797227 systemd-logind[1885]: Removed session 23. Jul 7 06:15:38.900221 systemd[1]: Started sshd@21-10.200.4.28:22-10.200.16.10:40722.service - OpenSSH per-connection server daemon (10.200.16.10:40722). Jul 7 06:15:39.509019 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 7 06:15:39.509835 systemd[2482]: Activating special unit exit.target... Jul 7 06:15:39.509921 systemd[2482]: Stopped target default.target - Main User Target. Jul 7 06:15:39.509961 systemd[2482]: Stopped target basic.target - Basic System. Jul 7 06:15:39.509989 systemd[2482]: Stopped target paths.target - Paths. Jul 7 06:15:39.510014 systemd[2482]: Stopped target sockets.target - Sockets. Jul 7 06:15:39.510039 systemd[2482]: Stopped target timers.target - Timers. Jul 7 06:15:39.510398 systemd[2482]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:15:39.510627 systemd[2482]: Removed slice app.slice - User Application Slice. Jul 7 06:15:39.510659 systemd[2482]: Reached target shutdown.target - Shutdown. Jul 7 06:15:39.510802 systemd[2482]: Finished systemd-exit.service - Exit the Session. Jul 7 06:15:39.510838 systemd[2482]: Reached target exit.target - Exit the Session. Jul 7 06:15:39.519498 sshd[12786]: Accepted publickey for core from 10.200.16.10 port 40722 ssh2: RSA SHA256:PeaY1ARzHsbOeco3lgwGg1fw6Gwj3izNJG3N0cypLdg Jul 7 06:15:39.520802 sshd-session[12786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:15:39.524602 (sd-pam)[2483]: pam_unix(systemd-user:session): session closed for user root Jul 7 06:15:39.524868 systemd-logind[1885]: New session 24 of user core. Jul 7 06:15:39.526539 systemd[1]: user@0.service: Deactivated successfully. Jul 7 06:15:39.526896 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 7 06:15:39.542821 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 7 06:15:39.544505 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 06:15:39.557381 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 7 06:15:39.558347 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 7 06:15:39.558561 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 06:15:39.560169 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 7 06:15:39.560265 systemd[1]: user-0.slice: Consumed 5min 11.528s CPU time, 902.4M memory peak. Jul 7 06:15:39.560689 systemd-logind[1885]: Removed session c3. Jul 7 06:15:41.447830 sshd[12791]: Connection closed by 10.200.16.10 port 40722 Jul 7 06:15:41.448200 sshd-session[12786]: pam_unix(sshd:session): session closed for user core Jul 7 06:15:41.451614 systemd[1]: sshd@21-10.200.4.28:22-10.200.16.10:40722.service: Deactivated successfully. Jul 7 06:15:41.453237 systemd[1]: session-24.scope: Deactivated successfully. Jul 7 06:15:41.453433 systemd[1]: session-24.scope: Consumed 1.435s CPU time, 14M memory peak. Jul 7 06:15:41.453933 systemd-logind[1885]: Session 24 logged out. Waiting for processes to exit. Jul 7 06:15:41.454925 systemd-logind[1885]: Removed session 24.