Jun 20 19:11:47.217532 kernel: Linux version 6.12.34-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 Fri Jun 20 17:06:39 -00 2025 Jun 20 19:11:47.217559 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=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.217569 kernel: BIOS-provided physical RAM map: Jun 20 19:11:47.217576 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 19:11:47.217582 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jun 20 19:11:47.217589 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jun 20 19:11:47.217597 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jun 20 19:11:47.217607 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jun 20 19:11:47.217613 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jun 20 19:11:47.217620 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jun 20 19:11:47.217627 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jun 20 19:11:47.217634 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jun 20 19:11:47.217641 kernel: printk: legacy bootconsole [earlyser0] enabled Jun 20 19:11:47.217648 kernel: NX (Execute Disable) protection: active Jun 20 19:11:47.217659 kernel: APIC: Static calls initialized Jun 20 19:11:47.217666 kernel: efi: EFI v2.7 by Microsoft Jun 20 19:11:47.217674 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jun 20 19:11:47.217682 kernel: random: crng init done Jun 20 19:11:47.217689 kernel: secureboot: Secure boot disabled Jun 20 19:11:47.217697 kernel: SMBIOS 3.1.0 present. Jun 20 19:11:47.217705 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jun 20 19:11:47.217712 kernel: DMI: Memory slots populated: 3/3 Jun 20 19:11:47.217720 kernel: Hypervisor detected: Microsoft Hyper-V Jun 20 19:11:47.217727 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jun 20 19:11:47.217737 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jun 20 19:11:47.217745 kernel: Hyper-V: Using hypercall for remote TLB flush Jun 20 19:11:47.217752 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 19:11:47.217760 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 19:11:47.217768 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jun 20 19:11:47.217777 kernel: tsc: Detected 2593.992 MHz processor Jun 20 19:11:47.217784 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 20 19:11:47.217793 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 20 19:11:47.217800 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jun 20 19:11:47.217810 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jun 20 19:11:47.217818 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 20 19:11:47.217826 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jun 20 19:11:47.217834 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jun 20 19:11:47.217841 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jun 20 19:11:47.217849 kernel: Using GB pages for direct mapping Jun 20 19:11:47.217857 kernel: ACPI: Early table checksum verification disabled Jun 20 19:11:47.217868 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jun 20 19:11:47.217878 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217887 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217895 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jun 20 19:11:47.217903 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jun 20 19:11:47.217911 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217919 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217930 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217938 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217946 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217954 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217962 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.217970 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jun 20 19:11:47.217978 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jun 20 19:11:47.217987 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jun 20 19:11:47.217997 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jun 20 19:11:47.218005 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jun 20 19:11:47.218014 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jun 20 19:11:47.218022 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jun 20 19:11:47.218030 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jun 20 19:11:47.218038 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jun 20 19:11:47.218046 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jun 20 19:11:47.218054 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jun 20 19:11:47.218062 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jun 20 19:11:47.218073 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jun 20 19:11:47.218081 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jun 20 19:11:47.218089 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jun 20 19:11:47.218097 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jun 20 19:11:47.218105 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jun 20 19:11:47.218113 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jun 20 19:11:47.218122 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jun 20 19:11:47.218130 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jun 20 19:11:47.218138 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jun 20 19:11:47.218149 kernel: Zone ranges: Jun 20 19:11:47.218157 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 20 19:11:47.218168 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 20 19:11:47.218193 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jun 20 19:11:47.218201 kernel: Device empty Jun 20 19:11:47.218209 kernel: Movable zone start for each node Jun 20 19:11:47.218217 kernel: Early memory node ranges Jun 20 19:11:47.218225 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 20 19:11:47.218234 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jun 20 19:11:47.218245 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jun 20 19:11:47.218253 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jun 20 19:11:47.218261 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jun 20 19:11:47.218269 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jun 20 19:11:47.218277 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 19:11:47.218285 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 20 19:11:47.218294 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jun 20 19:11:47.218302 kernel: ACPI: PM-Timer IO Port: 0x408 Jun 20 19:11:47.218310 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jun 20 19:11:47.218320 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jun 20 19:11:47.218329 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 20 19:11:47.218337 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 20 19:11:47.218345 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jun 20 19:11:47.218353 kernel: CPU topo: Max. logical packages: 1 Jun 20 19:11:47.218361 kernel: CPU topo: Max. logical dies: 1 Jun 20 19:11:47.218369 kernel: CPU topo: Max. dies per package: 1 Jun 20 19:11:47.218377 kernel: CPU topo: Max. threads per core: 1 Jun 20 19:11:47.218385 kernel: CPU topo: Num. cores per package: 6 Jun 20 19:11:47.218396 kernel: CPU topo: Num. threads per package: 6 Jun 20 19:11:47.218404 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jun 20 19:11:47.218412 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jun 20 19:11:47.218420 kernel: Booting paravirtualized kernel on Hyper-V Jun 20 19:11:47.218429 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 20 19:11:47.218437 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jun 20 19:11:47.218445 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jun 20 19:11:47.218453 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jun 20 19:11:47.218461 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jun 20 19:11:47.218472 kernel: Hyper-V: PV spinlocks enabled Jun 20 19:11:47.218480 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 20 19:11:47.218489 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=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.218498 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 20 19:11:47.218506 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jun 20 19:11:47.218514 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jun 20 19:11:47.218522 kernel: Fallback order for Node 0: 0 Jun 20 19:11:47.218530 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jun 20 19:11:47.218541 kernel: Policy zone: Normal Jun 20 19:11:47.218557 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 20 19:11:47.218566 kernel: software IO TLB: area num 8. Jun 20 19:11:47.218576 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jun 20 19:11:47.218585 kernel: Kernel/User page tables isolation: enabled Jun 20 19:11:47.218594 kernel: ftrace: allocating 40093 entries in 157 pages Jun 20 19:11:47.218602 kernel: ftrace: allocated 157 pages with 5 groups Jun 20 19:11:47.218611 kernel: Dynamic Preempt: voluntary Jun 20 19:11:47.218619 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 20 19:11:47.218629 kernel: rcu: RCU event tracing is enabled. Jun 20 19:11:47.218637 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jun 20 19:11:47.218646 kernel: Trampoline variant of Tasks RCU enabled. Jun 20 19:11:47.218657 kernel: Rude variant of Tasks RCU enabled. Jun 20 19:11:47.218666 kernel: Tracing variant of Tasks RCU enabled. Jun 20 19:11:47.218675 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 20 19:11:47.218683 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jun 20 19:11:47.218692 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.218703 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.218712 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.218720 kernel: Using NULL legacy PIC Jun 20 19:11:47.218729 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jun 20 19:11:47.218738 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 20 19:11:47.218746 kernel: Console: colour dummy device 80x25 Jun 20 19:11:47.218755 kernel: printk: legacy console [tty1] enabled Jun 20 19:11:47.218763 kernel: printk: legacy console [ttyS0] enabled Jun 20 19:11:47.218772 kernel: printk: legacy bootconsole [earlyser0] disabled Jun 20 19:11:47.218783 kernel: ACPI: Core revision 20240827 Jun 20 19:11:47.218791 kernel: Failed to register legacy timer interrupt Jun 20 19:11:47.218800 kernel: APIC: Switch to symmetric I/O mode setup Jun 20 19:11:47.218809 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jun 20 19:11:47.218817 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jun 20 19:11:47.218826 kernel: Hyper-V: Using IPI hypercalls Jun 20 19:11:47.218834 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jun 20 19:11:47.218843 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jun 20 19:11:47.218852 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jun 20 19:11:47.218863 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jun 20 19:11:47.218871 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jun 20 19:11:47.218880 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jun 20 19:11:47.218888 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jun 20 19:11:47.218897 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jun 20 19:11:47.218906 kernel: APIC: read() replaced with hv_apic_read() Jun 20 19:11:47.218914 kernel: APIC: write() replaced with hv_apic_write() Jun 20 19:11:47.218923 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jun 20 19:11:47.218931 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jun 20 19:11:47.218943 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jun 20 19:11:47.218956 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jun 20 19:11:47.218965 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jun 20 19:11:47.218973 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 20 19:11:47.218982 kernel: Spectre V2 : Mitigation: Retpolines Jun 20 19:11:47.218990 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 20 19:11:47.218999 kernel: Speculative Store Bypass: Vulnerable Jun 20 19:11:47.219007 kernel: MDS: Mitigation: Clear CPU buffers Jun 20 19:11:47.219016 kernel: TAA: Mitigation: Clear CPU buffers Jun 20 19:11:47.219024 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jun 20 19:11:47.219035 kernel: ITS: Mitigation: Aligned branch/return thunks Jun 20 19:11:47.219044 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 20 19:11:47.219052 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 20 19:11:47.219061 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 20 19:11:47.219069 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 20 19:11:47.219078 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 20 19:11:47.219086 kernel: Freeing SMP alternatives memory: 32K Jun 20 19:11:47.219094 kernel: pid_max: default: 32768 minimum: 301 Jun 20 19:11:47.219103 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jun 20 19:11:47.219111 kernel: landlock: Up and running. Jun 20 19:11:47.219120 kernel: SELinux: Initializing. Jun 20 19:11:47.219130 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.219139 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.219148 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jun 20 19:11:47.219156 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jun 20 19:11:47.219165 kernel: signal: max sigframe size: 1776 Jun 20 19:11:47.219182 kernel: rcu: Hierarchical SRCU implementation. Jun 20 19:11:47.219191 kernel: rcu: Max phase no-delay instances is 400. Jun 20 19:11:47.219200 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 20 19:11:47.219209 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 20 19:11:47.219217 kernel: smp: Bringing up secondary CPUs ... Jun 20 19:11:47.219232 kernel: smpboot: x86: Booting SMP configuration: Jun 20 19:11:47.219241 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jun 20 19:11:47.219249 kernel: smp: Brought up 1 node, 6 CPUs Jun 20 19:11:47.219258 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jun 20 19:11:47.219267 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54424K init, 2544K bss, 2090000K reserved, 0K cma-reserved) Jun 20 19:11:47.219276 kernel: devtmpfs: initialized Jun 20 19:11:47.219284 kernel: x86/mm: Memory block size: 1024MB Jun 20 19:11:47.219293 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jun 20 19:11:47.219302 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 20 19:11:47.219313 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jun 20 19:11:47.219322 kernel: pinctrl core: initialized pinctrl subsystem Jun 20 19:11:47.219330 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 20 19:11:47.219339 kernel: audit: initializing netlink subsys (disabled) Jun 20 19:11:47.219347 kernel: audit: type=2000 audit(1750446703.028:1): state=initialized audit_enabled=0 res=1 Jun 20 19:11:47.219356 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 20 19:11:47.219364 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 20 19:11:47.219373 kernel: cpuidle: using governor menu Jun 20 19:11:47.219382 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 20 19:11:47.219393 kernel: dca service started, version 1.12.1 Jun 20 19:11:47.219401 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jun 20 19:11:47.219410 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 20 19:11:47.219418 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 20 19:11:47.219427 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 20 19:11:47.219436 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 20 19:11:47.219444 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 20 19:11:47.219453 kernel: ACPI: Added _OSI(Module Device) Jun 20 19:11:47.219461 kernel: ACPI: Added _OSI(Processor Device) Jun 20 19:11:47.219472 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 20 19:11:47.219481 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 20 19:11:47.219489 kernel: ACPI: Interpreter enabled Jun 20 19:11:47.219498 kernel: ACPI: PM: (supports S0 S5) Jun 20 19:11:47.219506 kernel: ACPI: Using IOAPIC for interrupt routing Jun 20 19:11:47.219515 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 20 19:11:47.219524 kernel: PCI: Ignoring E820 reservations for host bridge windows Jun 20 19:11:47.219532 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jun 20 19:11:47.219541 kernel: iommu: Default domain type: Translated Jun 20 19:11:47.219552 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 20 19:11:47.219561 kernel: efivars: Registered efivars operations Jun 20 19:11:47.219569 kernel: PCI: Using ACPI for IRQ routing Jun 20 19:11:47.219578 kernel: PCI: System does not support PCI Jun 20 19:11:47.219587 kernel: vgaarb: loaded Jun 20 19:11:47.219595 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jun 20 19:11:47.219604 kernel: VFS: Disk quotas dquot_6.6.0 Jun 20 19:11:47.219612 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 20 19:11:47.219621 kernel: pnp: PnP ACPI init Jun 20 19:11:47.219632 kernel: pnp: PnP ACPI: found 3 devices Jun 20 19:11:47.219641 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 20 19:11:47.219649 kernel: NET: Registered PF_INET protocol family Jun 20 19:11:47.219658 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.219667 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.219675 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 20 19:11:47.219684 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 20 19:11:47.219693 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.219704 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jun 20 19:11:47.219713 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.219721 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.219730 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 20 19:11:47.219738 kernel: NET: Registered PF_XDP protocol family Jun 20 19:11:47.219747 kernel: PCI: CLS 0 bytes, default 64 Jun 20 19:11:47.219756 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 20 19:11:47.219764 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jun 20 19:11:47.219773 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jun 20 19:11:47.219784 kernel: Initialise system trusted keyrings Jun 20 19:11:47.219793 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jun 20 19:11:47.219801 kernel: Key type asymmetric registered Jun 20 19:11:47.219809 kernel: Asymmetric key parser 'x509' registered Jun 20 19:11:47.219818 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 20 19:11:47.219827 kernel: io scheduler mq-deadline registered Jun 20 19:11:47.219835 kernel: io scheduler kyber registered Jun 20 19:11:47.219844 kernel: io scheduler bfq registered Jun 20 19:11:47.219852 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 20 19:11:47.219863 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 20 19:11:47.219872 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 20 19:11:47.219881 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jun 20 19:11:47.219889 kernel: i8042: PNP: No PS/2 controller found. Jun 20 19:11:47.220040 kernel: rtc_cmos 00:02: registered as rtc0 Jun 20 19:11:47.220143 kernel: rtc_cmos 00:02: setting system clock to 2025-06-20T19:11:46 UTC (1750446706) Jun 20 19:11:47.220270 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jun 20 19:11:47.220284 kernel: intel_pstate: CPU model not supported Jun 20 19:11:47.220298 kernel: efifb: probing for efifb Jun 20 19:11:47.220307 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jun 20 19:11:47.220315 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jun 20 19:11:47.220324 kernel: efifb: scrolling: redraw Jun 20 19:11:47.220333 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 20 19:11:47.220341 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 19:11:47.220350 kernel: fb0: EFI VGA frame buffer device Jun 20 19:11:47.220359 kernel: pstore: Using crash dump compression: deflate Jun 20 19:11:47.220367 kernel: pstore: Registered efi_pstore as persistent store backend Jun 20 19:11:47.220379 kernel: NET: Registered PF_INET6 protocol family Jun 20 19:11:47.220387 kernel: Segment Routing with IPv6 Jun 20 19:11:47.220396 kernel: In-situ OAM (IOAM) with IPv6 Jun 20 19:11:47.220404 kernel: NET: Registered PF_PACKET protocol family Jun 20 19:11:47.220413 kernel: Key type dns_resolver registered Jun 20 19:11:47.220422 kernel: IPI shorthand broadcast: enabled Jun 20 19:11:47.220430 kernel: sched_clock: Marking stable (3794005500, 121367500)->(4219458300, -304085300) Jun 20 19:11:47.220439 kernel: registered taskstats version 1 Jun 20 19:11:47.220448 kernel: Loading compiled-in X.509 certificates Jun 20 19:11:47.220459 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: 9a085d119111c823c157514215d0379e3a2f1b94' Jun 20 19:11:47.220467 kernel: Demotion targets for Node 0: null Jun 20 19:11:47.220476 kernel: Key type .fscrypt registered Jun 20 19:11:47.220484 kernel: Key type fscrypt-provisioning registered Jun 20 19:11:47.220493 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 20 19:11:47.220501 kernel: ima: Allocated hash algorithm: sha1 Jun 20 19:11:47.220510 kernel: ima: No architecture policies found Jun 20 19:11:47.220518 kernel: clk: Disabling unused clocks Jun 20 19:11:47.220527 kernel: Warning: unable to open an initial console. Jun 20 19:11:47.220538 kernel: Freeing unused kernel image (initmem) memory: 54424K Jun 20 19:11:47.220547 kernel: Write protecting the kernel read-only data: 24576k Jun 20 19:11:47.220555 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jun 20 19:11:47.220564 kernel: Run /init as init process Jun 20 19:11:47.220573 kernel: with arguments: Jun 20 19:11:47.220581 kernel: /init Jun 20 19:11:47.220590 kernel: with environment: Jun 20 19:11:47.220598 kernel: HOME=/ Jun 20 19:11:47.220606 kernel: TERM=linux Jun 20 19:11:47.220617 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 20 19:11:47.220627 systemd[1]: Successfully made /usr/ read-only. Jun 20 19:11:47.220638 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) Jun 20 19:11:47.220648 systemd[1]: Detected virtualization microsoft. Jun 20 19:11:47.220657 systemd[1]: Detected architecture x86-64. Jun 20 19:11:47.220666 systemd[1]: Running in initrd. Jun 20 19:11:47.220675 systemd[1]: No hostname configured, using default hostname. Jun 20 19:11:47.220687 systemd[1]: Hostname set to . Jun 20 19:11:47.220696 systemd[1]: Initializing machine ID from random generator. Jun 20 19:11:47.220705 systemd[1]: Queued start job for default target initrd.target. Jun 20 19:11:47.220714 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:11:47.220723 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:11:47.220733 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 20 19:11:47.220743 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:11:47.220752 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 20 19:11:47.220764 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 20 19:11:47.220774 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 20 19:11:47.220784 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 20 19:11:47.220793 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:11:47.220802 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:11:47.220811 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:11:47.220820 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:11:47.220832 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:11:47.220841 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:11:47.220850 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:11:47.220859 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:11:47.220869 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 20 19:11:47.220878 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 20 19:11:47.220887 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:11:47.220896 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:11:47.220905 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:11:47.220917 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:11:47.220926 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 20 19:11:47.220935 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:11:47.220945 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 20 19:11:47.220954 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). Jun 20 19:11:47.220963 systemd[1]: Starting systemd-fsck-usr.service... Jun 20 19:11:47.220972 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:11:47.220982 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:11:47.220993 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:47.221021 systemd-journald[232]: Collecting audit messages is disabled. Jun 20 19:11:47.221048 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 20 19:11:47.221061 systemd-journald[232]: Journal started Jun 20 19:11:47.221082 systemd-journald[232]: Runtime Journal (/run/log/journal/6cc6d1bd4d644fc9a63f1ffcc43e4ced) is 8M, max 2.2G, 2.1G free. Jun 20 19:11:47.233202 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:11:47.233379 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:11:47.234364 systemd[1]: Finished systemd-fsck-usr.service. Jun 20 19:11:47.236633 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 19:11:47.237599 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:11:47.275164 systemd-modules-load[234]: Inserted module 'overlay' Jun 20 19:11:47.278697 systemd-tmpfiles[242]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jun 20 19:11:47.285459 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 19:11:47.290934 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:47.293023 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:11:47.295353 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 19:11:47.296356 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:11:47.345190 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 20 19:11:47.348803 systemd-modules-load[234]: Inserted module 'br_netfilter' Jun 20 19:11:47.350357 kernel: Bridge firewalling registered Jun 20 19:11:47.349462 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:11:47.350581 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:11:47.352397 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:11:47.361574 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:11:47.363846 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 20 19:11:47.376761 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:11:47.382122 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:11:47.405936 dracut-cmdline[270]: 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=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.422622 systemd-resolved[274]: Positive Trust Anchors: Jun 20 19:11:47.422630 systemd-resolved[274]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:11:47.422661 systemd-resolved[274]: 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 Jun 20 19:11:47.425194 systemd-resolved[274]: Defaulting to hostname 'linux'. Jun 20 19:11:47.426197 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:11:47.433062 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:11:47.572201 kernel: SCSI subsystem initialized Jun 20 19:11:47.583191 kernel: Loading iSCSI transport class v2.0-870. Jun 20 19:11:47.595197 kernel: iscsi: registered transport (tcp) Jun 20 19:11:47.618073 kernel: iscsi: registered transport (qla4xxx) Jun 20 19:11:47.618100 kernel: QLogic iSCSI HBA Driver Jun 20 19:11:47.635861 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 19:11:47.667576 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:11:47.669606 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 19:11:47.732691 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 20 19:11:47.735499 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 20 19:11:47.800190 kernel: raid6: avx2x4 gen() 30960 MB/s Jun 20 19:11:47.819188 kernel: raid6: avx2x2 gen() 30842 MB/s Jun 20 19:11:47.841588 kernel: raid6: avx2x1 gen() 27793 MB/s Jun 20 19:11:47.841620 kernel: raid6: using algorithm avx2x4 gen() 30960 MB/s Jun 20 19:11:47.863708 kernel: raid6: .... xor() 10157 MB/s, rmw enabled Jun 20 19:11:47.863732 kernel: raid6: using avx2x2 recovery algorithm Jun 20 19:11:47.887194 kernel: xor: automatically using best checksumming function avx Jun 20 19:11:48.067201 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 20 19:11:48.074290 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:11:48.076602 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:11:48.112896 systemd-udevd[483]: Using default interface naming scheme 'v255'. Jun 20 19:11:48.120979 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:11:48.132064 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 20 19:11:48.168276 dracut-pre-trigger[492]: rd.md=0: removing MD RAID activation Jun 20 19:11:48.195277 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:11:48.197143 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:11:48.282546 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:11:48.285656 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 20 19:11:48.317357 kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 19:11:48.329271 kernel: AES CTR mode by8 optimization enabled Jun 20 19:11:48.347196 kernel: hv_vmbus: Vmbus version:4.0 Jun 20 19:11:48.347450 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:11:48.347766 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:48.365417 kernel: hv_vmbus: registering driver hyperv_keyboard Jun 20 19:11:48.372637 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 Jun 20 19:11:48.374297 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:48.383584 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:48.393273 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:48.441779 kernel: hv_vmbus: registering driver hv_netvsc Jun 20 19:11:48.441816 kernel: hv_vmbus: registering driver hv_storvsc Jun 20 19:11:48.441830 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 20 19:11:48.441842 kernel: pps_core: LinuxPPS API ver. 1 registered Jun 20 19:11:48.441853 kernel: scsi host0: storvsc_host_t Jun 20 19:11:48.442042 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 19:11:48.442211 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 20 19:11:48.442226 kernel: hv_vmbus: registering driver hv_pci Jun 20 19:11:48.442238 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 19:11:48.442382 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jun 20 19:11:48.442529 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jun 20 19:11:48.442664 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jun 20 19:11:48.442781 kernel: scsi host1: storvsc_host_t Jun 20 19:11:48.442911 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jun 20 19:11:48.455532 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jun 20 19:11:48.459811 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jun 20 19:11:48.459970 kernel: PTP clock support registered Jun 20 19:11:48.462196 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jun 20 19:11:48.471202 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jun 20 19:11:48.475508 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 19:11:48.479157 kernel: hv_vmbus: registering driver hid_hyperv Jun 20 19:11:48.479203 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 19:11:48.479233 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jun 20 19:11:48.479407 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 20 19:11:48.479553 kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 20 19:11:48.479692 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jun 20 19:11:48.479921 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 19:11:48.480063 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#137 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:48.480218 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jun 20 19:11:48.480357 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jun 20 19:11:48.480484 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jun 20 19:11:48.480609 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jun 20 19:11:48.480736 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 19:11:48.480861 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#499 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:48.480981 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#506 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:48.481096 kernel: hv_utils: Registering HyperV Utility Driver Jun 20 19:11:48.481110 kernel: hv_vmbus: registering driver hv_utils Jun 20 19:11:48.481121 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#448 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:48.545300 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:48.557155 kernel: pci 0001:00:00.0: Enabling HDA controller Jun 20 19:11:48.573318 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jun 20 19:11:48.573482 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jun 20 19:11:48.580222 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jun 20 19:11:48.585195 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jun 20 19:11:48.595721 kernel: sdb: sdb1 Jun 20 19:11:48.595751 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:48.595771 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jun 20 19:11:48.598169 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 20 19:11:48.609365 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jun 20 19:11:48.609740 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 20 19:11:48.609758 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jun 20 19:11:48.609899 kernel: hv_utils: Heartbeat IC version 3.0 Jun 20 19:11:48.614373 kernel: hv_utils: Shutdown IC version 3.2 Jun 20 19:11:48.617053 kernel: hv_utils: TimeSync IC version 4.0 Jun 20 19:11:48.618187 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jun 20 19:11:49.246037 systemd-resolved[274]: Clock change detected. Flushing caches. Jun 20 19:11:49.259453 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jun 20 19:11:49.766987 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jun 20 19:11:49.779578 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 19:11:49.811551 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jun 20 19:11:49.813065 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jun 20 19:11:49.824964 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 20 19:11:49.859868 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jun 20 19:11:49.875460 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#468 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.893903 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:49.903008 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#485 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.929911 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:50.936607 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#98 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:50.952461 disk-uuid[720]: The operation has completed successfully. Jun 20 19:11:50.956398 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:52.269914 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 20 19:11:52.270027 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 20 19:11:52.284056 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 20 19:11:52.293664 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:11:52.304093 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:11:52.313618 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:11:52.322864 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 20 19:11:52.325215 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 20 19:11:52.358628 sh[886]: Success Jun 20 19:11:52.365715 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:11:52.394903 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 20 19:11:52.394943 kernel: device-mapper: uevent: version 1.0.3 Jun 20 19:11:52.399998 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jun 20 19:11:52.411905 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jun 20 19:11:52.589037 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 20 19:11:52.592502 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 20 19:11:52.608383 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 20 19:11:52.629726 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jun 20 19:11:52.629751 kernel: BTRFS: device fsid 048b924a-9f97-43f5-98d6-0fff18874966 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (912) Jun 20 19:11:52.639332 kernel: BTRFS info (device dm-0): first mount of filesystem 048b924a-9f97-43f5-98d6-0fff18874966 Jun 20 19:11:52.639364 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:52.642613 kernel: BTRFS info (device dm-0): using free-space-tree Jun 20 19:11:52.947816 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 20 19:11:52.950138 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jun 20 19:11:52.957350 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 20 19:11:52.958150 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 20 19:11:52.987460 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 20 19:11:53.015903 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (937) Jun 20 19:11:53.015937 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:53.020915 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:53.028139 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:53.064905 kernel: BTRFS info (device sda6): last unmount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:53.065829 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 20 19:11:53.068311 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 20 19:11:53.104413 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:11:53.107576 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:11:53.161026 systemd-networkd[1081]: lo: Link UP Jun 20 19:11:53.161036 systemd-networkd[1081]: lo: Gained carrier Jun 20 19:11:53.162039 systemd-networkd[1081]: Enumeration completed Jun 20 19:11:53.162137 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:11:53.162449 systemd-networkd[1081]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:11:53.162454 systemd-networkd[1081]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:11:53.163250 systemd-networkd[1081]: eth0: Link UP Jun 20 19:11:53.163417 systemd-networkd[1081]: eth0: Gained carrier Jun 20 19:11:53.163427 systemd-networkd[1081]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:11:53.171292 systemd[1]: Reached target network.target - Network. Jun 20 19:11:53.221920 systemd-networkd[1081]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:11:54.160219 ignition[1053]: Ignition 2.21.0 Jun 20 19:11:54.160233 ignition[1053]: Stage: fetch-offline Jun 20 19:11:54.160335 ignition[1053]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.160346 ignition[1053]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.160442 ignition[1053]: parsed url from cmdline: "" Jun 20 19:11:54.160446 ignition[1053]: no config URL provided Jun 20 19:11:54.160451 ignition[1053]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 19:11:54.160460 ignition[1053]: no config at "/usr/lib/ignition/user.ign" Jun 20 19:11:54.160465 ignition[1053]: failed to fetch config: resource requires networking Jun 20 19:11:54.160620 ignition[1053]: Ignition finished successfully Jun 20 19:11:54.194036 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:11:54.196563 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jun 20 19:11:54.240795 ignition[1096]: Ignition 2.21.0 Jun 20 19:11:54.240844 ignition[1096]: Stage: fetch Jun 20 19:11:54.241132 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.241144 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.241458 ignition[1096]: parsed url from cmdline: "" Jun 20 19:11:54.241464 ignition[1096]: no config URL provided Jun 20 19:11:54.241470 ignition[1096]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 19:11:54.241482 ignition[1096]: no config at "/usr/lib/ignition/user.ign" Jun 20 19:11:54.241526 ignition[1096]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jun 20 19:11:54.339056 ignition[1096]: GET result: OK Jun 20 19:11:54.339104 ignition[1096]: config has been read from IMDS userdata Jun 20 19:11:54.339117 ignition[1096]: parsing config with SHA512: 0ca37610597fa326b11a4ef769dcd6a2b534dca94741c5036657581ba962334fd2519337e79f075f9579f1811f0665d01373fd09b24342f16cb2b8c12d124d1f Jun 20 19:11:54.342842 unknown[1096]: fetched base config from "system" Jun 20 19:11:54.343070 ignition[1096]: fetch: fetch complete Jun 20 19:11:54.342849 unknown[1096]: fetched base config from "system" Jun 20 19:11:54.343078 ignition[1096]: fetch: fetch passed Jun 20 19:11:54.342854 unknown[1096]: fetched user config from "azure" Jun 20 19:11:54.343122 ignition[1096]: Ignition finished successfully Jun 20 19:11:54.350771 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jun 20 19:11:54.352936 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 20 19:11:54.395246 ignition[1105]: Ignition 2.21.0 Jun 20 19:11:54.395260 ignition[1105]: Stage: kargs Jun 20 19:11:54.395538 ignition[1105]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.395550 ignition[1105]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.400705 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 20 19:11:54.397326 ignition[1105]: kargs: kargs passed Jun 20 19:11:54.408690 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 20 19:11:54.397378 ignition[1105]: Ignition finished successfully Jun 20 19:11:54.448666 ignition[1115]: Ignition 2.21.0 Jun 20 19:11:54.448680 ignition[1115]: Stage: disks Jun 20 19:11:54.448958 ignition[1115]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.452743 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 20 19:11:54.448971 ignition[1115]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.458334 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 20 19:11:54.451079 ignition[1115]: disks: disks passed Jun 20 19:11:54.465968 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 20 19:11:54.451137 ignition[1115]: Ignition finished successfully Jun 20 19:11:54.467466 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:11:54.479919 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:11:54.491933 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:11:54.494345 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 20 19:11:54.566296 systemd-fsck[1127]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jun 20 19:11:54.571618 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 20 19:11:54.574039 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 20 19:11:54.640993 systemd-networkd[1081]: eth0: Gained IPv6LL Jun 20 19:11:54.795910 kernel: EXT4-fs (sda9): mounted filesystem 6290a154-3512-46a6-a5f5-a7fb62c65caa r/w with ordered data mode. Quota mode: none. Jun 20 19:11:54.797061 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 20 19:11:54.799186 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 20 19:11:54.816943 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:11:54.819178 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 20 19:11:54.837402 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jun 20 19:11:54.862672 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1141) Jun 20 19:11:54.862696 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:54.862709 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:54.862722 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:54.838899 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 20 19:11:54.838930 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:11:54.866049 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 20 19:11:54.880078 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 20 19:11:54.888604 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:11:55.579006 coreos-metadata[1143]: Jun 20 19:11:55.578 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 19:11:55.586636 coreos-metadata[1143]: Jun 20 19:11:55.586 INFO Fetch successful Jun 20 19:11:55.590683 coreos-metadata[1143]: Jun 20 19:11:55.586 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jun 20 19:11:55.599130 coreos-metadata[1143]: Jun 20 19:11:55.593 INFO Fetch successful Jun 20 19:11:55.607747 coreos-metadata[1143]: Jun 20 19:11:55.607 INFO wrote hostname ci-4344.1.0-a-9dc9d3b667 to /sysroot/etc/hostname Jun 20 19:11:55.615111 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 19:11:55.757308 initrd-setup-root[1171]: cut: /sysroot/etc/passwd: No such file or directory Jun 20 19:11:55.790003 initrd-setup-root[1178]: cut: /sysroot/etc/group: No such file or directory Jun 20 19:11:55.804099 initrd-setup-root[1185]: cut: /sysroot/etc/shadow: No such file or directory Jun 20 19:11:55.808933 initrd-setup-root[1192]: cut: /sysroot/etc/gshadow: No such file or directory Jun 20 19:11:56.986341 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 20 19:11:56.992169 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 20 19:11:56.997535 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 20 19:11:57.014801 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 20 19:11:57.024640 kernel: BTRFS info (device sda6): last unmount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:57.038332 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 20 19:11:57.053929 ignition[1264]: INFO : Ignition 2.21.0 Jun 20 19:11:57.053929 ignition[1264]: INFO : Stage: mount Jun 20 19:11:57.059633 ignition[1264]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.059633 ignition[1264]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.059633 ignition[1264]: INFO : mount: mount passed Jun 20 19:11:57.059633 ignition[1264]: INFO : Ignition finished successfully Jun 20 19:11:57.058457 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 20 19:11:57.062059 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 20 19:11:57.092044 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:11:57.121023 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1278) Jun 20 19:11:57.121057 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:57.125319 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:57.128550 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:57.132304 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:11:57.163461 ignition[1295]: INFO : Ignition 2.21.0 Jun 20 19:11:57.163461 ignition[1295]: INFO : Stage: files Jun 20 19:11:57.169377 ignition[1295]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.169377 ignition[1295]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.169377 ignition[1295]: DEBUG : files: compiled without relabeling support, skipping Jun 20 19:11:57.183162 ignition[1295]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 20 19:11:57.183162 ignition[1295]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 20 19:11:57.246043 ignition[1295]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 20 19:11:57.251802 ignition[1295]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 20 19:11:57.251802 ignition[1295]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 20 19:11:57.248436 unknown[1295]: wrote ssh authorized keys file for user: core Jun 20 19:11:57.269895 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:11:57.276980 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:11:57.276980 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:11:57.276980 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:11:57.276980 ignition[1295]: INFO : files: files passed Jun 20 19:11:57.276980 ignition[1295]: INFO : Ignition finished successfully Jun 20 19:11:57.286206 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 20 19:11:57.297747 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 20 19:11:57.328154 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 20 19:11:57.331650 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 20 19:11:57.331745 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 20 19:11:57.375130 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.375130 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.388893 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.389802 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:11:57.397411 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 20 19:11:57.415558 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 20 19:11:57.458831 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 20 19:11:57.458968 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 20 19:11:57.468135 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 20 19:11:57.477001 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 20 19:11:57.478511 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 20 19:11:57.479296 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 20 19:11:57.505922 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:11:57.508634 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 20 19:11:57.525763 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:11:57.527349 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:11:57.536522 systemd[1]: Stopped target timers.target - Timer Units. Jun 20 19:11:57.544946 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 20 19:11:57.545059 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:11:57.559679 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 20 19:11:57.568275 systemd[1]: Stopped target basic.target - Basic System. Jun 20 19:11:57.575814 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 20 19:11:57.583610 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:11:57.592830 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 20 19:11:57.602196 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jun 20 19:11:57.604345 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 20 19:11:57.617114 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:11:57.633513 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 20 19:11:57.641660 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 20 19:11:57.643224 systemd[1]: Stopped target swap.target - Swaps. Jun 20 19:11:57.651044 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 20 19:11:57.651162 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:11:57.664340 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:11:57.673291 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:11:57.682684 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 20 19:11:57.682826 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:11:57.692705 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 20 19:11:57.692817 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 20 19:11:57.706291 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 20 19:11:57.706415 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:11:57.715432 systemd[1]: ignition-files.service: Deactivated successfully. Jun 20 19:11:57.715547 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 20 19:11:57.723629 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jun 20 19:11:57.723740 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 19:11:57.734709 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 20 19:11:57.742561 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 20 19:11:57.742685 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:11:57.782950 ignition[1353]: INFO : Ignition 2.21.0 Jun 20 19:11:57.782950 ignition[1353]: INFO : Stage: umount Jun 20 19:11:57.782950 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.782950 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.782950 ignition[1353]: INFO : umount: umount passed Jun 20 19:11:57.782950 ignition[1353]: INFO : Ignition finished successfully Jun 20 19:11:57.762424 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 20 19:11:57.766016 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 20 19:11:57.766145 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:11:57.781734 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 20 19:11:57.781865 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:11:57.795729 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 20 19:11:57.796667 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 20 19:11:57.796755 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 20 19:11:57.805527 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 20 19:11:57.805622 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 20 19:11:57.811134 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 20 19:11:57.811196 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 20 19:11:57.822640 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 20 19:11:57.822692 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 20 19:11:57.824953 systemd[1]: ignition-fetch.service: Deactivated successfully. Jun 20 19:11:57.824999 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jun 20 19:11:57.842218 systemd[1]: Stopped target network.target - Network. Jun 20 19:11:57.850357 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 20 19:11:57.850411 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:11:57.858962 systemd[1]: Stopped target paths.target - Path Units. Jun 20 19:11:57.860401 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 20 19:11:57.864935 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:11:57.870907 systemd[1]: Stopped target slices.target - Slice Units. Jun 20 19:11:57.882636 systemd[1]: Stopped target sockets.target - Socket Units. Jun 20 19:11:57.887818 systemd[1]: iscsid.socket: Deactivated successfully. Jun 20 19:11:57.887861 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:11:57.895319 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 20 19:11:57.895359 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:11:57.903896 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 20 19:11:57.903951 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 20 19:11:57.911817 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 20 19:11:57.911861 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 20 19:11:57.919921 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 20 19:11:57.927870 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 20 19:11:57.935625 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 20 19:11:57.935734 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 20 19:11:57.950199 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 20 19:11:57.950314 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 20 19:11:57.957215 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 20 19:11:57.957329 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 20 19:11:57.966838 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jun 20 19:11:57.974898 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 20 19:11:57.974955 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:11:57.983034 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 20 19:11:57.983095 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 20 19:11:57.985720 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 20 19:11:57.998575 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 20 19:11:57.998627 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:11:58.005772 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 20 19:11:58.005817 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:11:58.021054 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 20 19:11:58.021102 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 20 19:11:58.029366 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 20 19:11:58.029415 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:11:58.038685 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:11:58.047495 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jun 20 19:11:58.047553 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 20 19:11:58.047592 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 20 19:11:58.047630 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:58.075669 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 20 19:11:58.075866 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:11:58.084546 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 20 19:11:58.084594 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 20 19:11:58.092503 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 20 19:11:58.092553 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:11:58.100474 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 20 19:11:58.100527 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:11:58.104218 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 20 19:11:58.104270 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 20 19:11:58.127331 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 19:11:58.127382 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:11:58.139598 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 20 19:11:58.311979 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Jun 20 19:11:58.143252 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jun 20 19:11:58.143305 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:11:58.160638 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 20 19:11:58.160690 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:11:58.169926 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:11:58.169971 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:58.184334 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jun 20 19:11:58.184391 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jun 20 19:11:58.184433 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:58.184793 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 20 19:11:58.184968 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 20 19:11:58.189088 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 20 19:11:58.189185 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 20 19:11:58.196856 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 20 19:11:58.205442 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 20 19:11:58.239645 systemd[1]: Switching root. Jun 20 19:11:58.316822 systemd-journald[232]: Journal stopped Jun 20 19:12:03.888422 kernel: SELinux: policy capability network_peer_controls=1 Jun 20 19:12:03.888463 kernel: SELinux: policy capability open_perms=1 Jun 20 19:12:03.888475 kernel: SELinux: policy capability extended_socket_class=1 Jun 20 19:12:03.888485 kernel: SELinux: policy capability always_check_network=0 Jun 20 19:12:03.888494 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 20 19:12:03.888504 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 20 19:12:03.888514 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 20 19:12:03.888526 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 20 19:12:03.888536 kernel: SELinux: policy capability userspace_initial_context=0 Jun 20 19:12:03.888545 kernel: audit: type=1403 audit(1750446719.179:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 20 19:12:03.888562 systemd[1]: Successfully loaded SELinux policy in 172.030ms. Jun 20 19:12:03.888574 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.976ms. Jun 20 19:12:03.888586 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) Jun 20 19:12:03.888597 systemd[1]: Detected virtualization microsoft. Jun 20 19:12:03.888610 systemd[1]: Detected architecture x86-64. Jun 20 19:12:03.888621 systemd[1]: Detected first boot. Jun 20 19:12:03.888632 systemd[1]: Hostname set to . Jun 20 19:12:03.888642 systemd[1]: Initializing machine ID from random generator. Jun 20 19:12:03.888677 zram_generator::config[1398]: No configuration found. Jun 20 19:12:03.888699 kernel: Guest personality initialized and is inactive Jun 20 19:12:03.888710 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jun 20 19:12:03.888720 kernel: Initialized host personality Jun 20 19:12:03.888739 kernel: NET: Registered PF_VSOCK protocol family Jun 20 19:12:03.888752 systemd[1]: Populated /etc with preset unit settings. Jun 20 19:12:03.888769 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 20 19:12:03.888782 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 20 19:12:03.888796 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 20 19:12:03.888816 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 20 19:12:03.888834 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 20 19:12:03.888847 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 20 19:12:03.888858 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 20 19:12:03.888873 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 20 19:12:03.888942 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 20 19:12:03.888957 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 20 19:12:03.888973 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 20 19:12:03.888984 systemd[1]: Created slice user.slice - User and Session Slice. Jun 20 19:12:03.888995 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:12:03.889007 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:12:03.889018 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 20 19:12:03.889029 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 20 19:12:03.889044 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 20 19:12:03.889055 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:12:03.889069 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 20 19:12:03.889080 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:12:03.889091 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:12:03.889102 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 20 19:12:03.889113 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 20 19:12:03.889124 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 20 19:12:03.889135 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 20 19:12:03.889149 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:12:03.889160 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:12:03.889171 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:12:03.889186 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:12:03.889197 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 20 19:12:03.889208 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 20 19:12:03.889222 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 20 19:12:03.889234 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:12:03.889245 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:12:03.889256 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:12:03.889268 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 20 19:12:03.889279 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 20 19:12:03.889290 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 20 19:12:03.889303 systemd[1]: Mounting media.mount - External Media Directory... Jun 20 19:12:03.889315 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:03.889326 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 20 19:12:03.889337 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 20 19:12:03.889348 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 20 19:12:03.889360 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). Jun 20 19:12:03.889371 systemd[1]: Reached target machines.target - Containers. Jun 20 19:12:03.889383 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 20 19:12:03.889394 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:12:03.889408 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:12:03.889419 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 20 19:12:03.889434 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:12:03.889445 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:12:03.889457 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:12:03.889468 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 20 19:12:03.889479 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:12:03.889491 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 20 19:12:03.889504 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 20 19:12:03.889516 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 20 19:12:03.889528 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 20 19:12:03.889539 systemd[1]: Stopped systemd-fsck-usr.service. Jun 20 19:12:03.889551 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). Jun 20 19:12:03.889562 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:12:03.889573 kernel: fuse: init (API version 7.41) Jun 20 19:12:03.889584 kernel: loop: module loaded Jun 20 19:12:03.889597 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:12:03.889608 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 19:12:03.889620 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 20 19:12:03.889631 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 20 19:12:03.889642 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:12:03.889653 systemd[1]: verity-setup.service: Deactivated successfully. Jun 20 19:12:03.889665 systemd[1]: Stopped verity-setup.service. Jun 20 19:12:03.889679 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:03.889691 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 20 19:12:03.889704 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 20 19:12:03.889715 systemd[1]: Mounted media.mount - External Media Directory. Jun 20 19:12:03.889727 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 20 19:12:03.889768 systemd-journald[1505]: Collecting audit messages is disabled. Jun 20 19:12:03.889801 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 20 19:12:03.889814 systemd-journald[1505]: Journal started Jun 20 19:12:03.889837 systemd-journald[1505]: Runtime Journal (/run/log/journal/c3f9ffa165424286b8cc86ad2fc4baac) is 8M, max 2.2G, 2.1G free. Jun 20 19:12:02.681255 systemd[1]: Queued start job for default target multi-user.target. Jun 20 19:12:02.705224 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jun 20 19:12:02.705701 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 20 19:12:03.903587 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:12:03.907286 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 20 19:12:03.910899 kernel: ACPI: bus type drm_connector registered Jun 20 19:12:03.914228 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 20 19:12:03.919411 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:12:03.924862 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 20 19:12:03.925098 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 20 19:12:03.930385 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:12:03.930571 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:12:03.935421 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:12:03.935602 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:12:03.940289 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:12:03.940474 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:12:03.945759 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 20 19:12:03.946102 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 20 19:12:03.950693 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:12:03.950870 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:12:03.955603 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:12:03.960751 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:12:03.966171 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 20 19:12:03.971581 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 20 19:12:03.977213 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:12:03.992267 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 19:12:03.998239 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 20 19:12:04.013088 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 20 19:12:04.017713 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 20 19:12:04.017750 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:12:04.023064 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 20 19:12:04.029408 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 20 19:12:04.033684 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:12:04.034955 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 20 19:12:04.040389 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 20 19:12:04.045205 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:12:04.046196 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 20 19:12:04.050667 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:12:04.051625 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:12:04.056720 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 20 19:12:04.062577 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 20 19:12:04.068632 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 20 19:12:04.073694 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 20 19:12:04.082023 systemd-journald[1505]: Time spent on flushing to /var/log/journal/c3f9ffa165424286b8cc86ad2fc4baac is 13.834ms for 948 entries. Jun 20 19:12:04.082023 systemd-journald[1505]: System Journal (/var/log/journal/c3f9ffa165424286b8cc86ad2fc4baac) is 8M, max 2.6G, 2.6G free. Jun 20 19:12:04.134734 systemd-journald[1505]: Received client request to flush runtime journal. Jun 20 19:12:04.134791 kernel: loop0: detected capacity change from 0 to 113872 Jun 20 19:12:04.088139 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 20 19:12:04.093197 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 20 19:12:04.099262 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 20 19:12:04.117901 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:12:04.136136 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 20 19:12:04.160954 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 20 19:12:04.161618 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 20 19:12:04.478777 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 20 19:12:04.484871 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:12:04.676047 systemd-tmpfiles[1555]: ACLs are not supported, ignoring. Jun 20 19:12:04.676069 systemd-tmpfiles[1555]: ACLs are not supported, ignoring. Jun 20 19:12:04.681499 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:12:04.720903 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 20 19:12:04.796915 kernel: loop1: detected capacity change from 0 to 28496 Jun 20 19:12:05.186920 kernel: loop2: detected capacity change from 0 to 146240 Jun 20 19:12:05.253637 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 20 19:12:05.260340 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:12:05.303871 systemd-udevd[1562]: Using default interface naming scheme 'v255'. Jun 20 19:12:05.574219 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:12:05.586368 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:12:05.605115 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 20 19:12:05.671081 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 20 19:12:05.687944 kernel: hv_vmbus: registering driver hyperv_fb Jun 20 19:12:05.692908 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jun 20 19:12:05.697914 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jun 20 19:12:05.697961 kernel: hv_vmbus: registering driver hv_balloon Jun 20 19:12:05.700926 kernel: Console: switching to colour dummy device 80x25 Jun 20 19:12:05.708235 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jun 20 19:12:05.708285 kernel: mousedev: PS/2 mouse device common for all mice Jun 20 19:12:05.711333 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 19:12:05.733406 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:12:05.749967 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:12:05.750173 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:12:05.754707 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 20 19:12:05.760017 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:12:05.760996 kernel: EDAC MC: Ver: 3.0.0 Jun 20 19:12:05.855911 kernel: loop3: detected capacity change from 0 to 113872 Jun 20 19:12:05.874902 kernel: loop4: detected capacity change from 0 to 28496 Jun 20 19:12:05.883905 kernel: loop5: detected capacity change from 0 to 146240 Jun 20 19:12:05.894525 (sd-merge)[1645]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jun 20 19:12:05.895003 (sd-merge)[1645]: Merged extensions into '/usr'. Jun 20 19:12:05.898370 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 20 19:12:05.901385 systemd[1]: Starting ensure-sysext.service... Jun 20 19:12:05.902388 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:12:05.940105 systemd[1]: Reload requested from client PID 1649 ('systemctl') (unit ensure-sysext.service)... Jun 20 19:12:05.940122 systemd[1]: Reloading... Jun 20 19:12:05.940143 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jun 20 19:12:05.940174 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jun 20 19:12:05.940456 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 20 19:12:05.940736 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 20 19:12:05.941973 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 20 19:12:05.942275 systemd-tmpfiles[1650]: ACLs are not supported, ignoring. Jun 20 19:12:05.942337 systemd-tmpfiles[1650]: ACLs are not supported, ignoring. Jun 20 19:12:05.943812 systemd-networkd[1591]: lo: Link UP Jun 20 19:12:05.944101 systemd-networkd[1591]: lo: Gained carrier Jun 20 19:12:05.946283 systemd-networkd[1591]: Enumeration completed Jun 20 19:12:05.946655 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:05.946660 systemd-networkd[1591]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:12:05.947430 systemd-networkd[1591]: eth0: Link UP Jun 20 19:12:05.947525 systemd-networkd[1591]: eth0: Gained carrier Jun 20 19:12:05.947594 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:05.959491 systemd-tmpfiles[1650]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:12:05.959506 systemd-tmpfiles[1650]: Skipping /boot Jun 20 19:12:05.973793 systemd-tmpfiles[1650]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:12:05.973904 systemd-tmpfiles[1650]: Skipping /boot Jun 20 19:12:05.981921 zram_generator::config[1743]: No configuration found. Jun 20 19:12:05.981971 systemd-networkd[1591]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:12:06.097463 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. Jun 20 19:12:06.256426 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 19:12:06.267250 systemd[1]: Reloading finished in 326 ms. Jun 20 19:12:06.295382 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:12:06.300220 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:12:06.321759 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:12:06.366546 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.367846 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:12:06.372784 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 20 19:12:06.377449 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:12:06.395717 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:12:06.401300 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:12:06.406907 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:12:06.411162 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:12:06.412224 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 20 19:12:06.417039 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). Jun 20 19:12:06.418252 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 20 19:12:06.424184 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 20 19:12:06.430204 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 20 19:12:06.436924 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:12:06.444770 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 20 19:12:06.446304 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.448876 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:12:06.449089 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:12:06.449741 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:12:06.449940 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:12:06.451168 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:12:06.451355 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:12:06.459753 systemd[1]: Finished ensure-sysext.service. Jun 20 19:12:06.461611 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.461947 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:12:06.463122 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:12:06.468286 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:12:06.473253 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:12:06.480365 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:12:06.484753 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:12:06.484801 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). Jun 20 19:12:06.485005 systemd[1]: Reached target time-set.target - System Time Set. Jun 20 19:12:06.489311 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.491612 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 20 19:12:06.497216 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 20 19:12:06.502982 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:12:06.503169 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:12:06.508253 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:12:06.508449 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:12:06.513105 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:12:06.513287 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:12:06.518436 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:12:06.518643 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:12:06.528908 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:12:06.529078 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:12:06.530242 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 20 19:12:06.583925 systemd-resolved[1817]: Positive Trust Anchors: Jun 20 19:12:06.583937 systemd-resolved[1817]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:12:06.583968 systemd-resolved[1817]: 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 Jun 20 19:12:06.588427 systemd-resolved[1817]: Using system hostname 'ci-4344.1.0-a-9dc9d3b667'. Jun 20 19:12:06.590424 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:12:06.595668 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 20 19:12:06.601652 systemd[1]: Reached target network.target - Network. Jun 20 19:12:06.605418 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:12:06.625286 augenrules[1855]: No rules Jun 20 19:12:06.626499 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:12:06.626747 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:12:07.277061 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 20 19:12:07.282526 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). Jun 20 19:12:07.761042 systemd-networkd[1591]: eth0: Gained IPv6LL Jun 20 19:12:07.763386 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 20 19:12:07.768827 systemd[1]: Reached target network-online.target - Network is Online. Jun 20 19:12:10.447570 ldconfig[1535]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 19:12:10.463936 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 20 19:12:10.470698 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 20 19:12:10.508704 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 20 19:12:10.513324 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:12:10.517922 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 20 19:12:10.523046 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 20 19:12:10.528253 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jun 20 19:12:10.533218 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 20 19:12:10.538652 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 20 19:12:10.544161 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 20 19:12:10.549686 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 20 19:12:10.549736 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:12:10.553477 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:12:10.597993 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 20 19:12:10.603734 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 20 19:12:10.610298 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 20 19:12:10.615736 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 20 19:12:10.621120 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 20 19:12:10.631566 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 20 19:12:10.636281 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 20 19:12:10.641859 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 20 19:12:10.647419 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:12:10.651514 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:12:10.655315 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:12:10.655363 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:12:10.658007 systemd[1]: Starting chronyd.service - NTP client/server... Jun 20 19:12:10.662467 systemd[1]: Starting containerd.service - containerd container runtime... Jun 20 19:12:10.683772 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jun 20 19:12:10.689064 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 20 19:12:10.694143 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 20 19:12:10.699786 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 20 19:12:10.704908 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 20 19:12:10.708966 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). Jun 20 19:12:10.710003 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jun 20 19:12:10.710816 jq[1876]: false Jun 20 19:12:10.714517 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). Jun 20 19:12:10.715505 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jun 20 19:12:10.720079 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jun 20 19:12:10.721064 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 20 19:12:10.721782 KVP[1882]: KVP starting; pid is:1882 Jun 20 19:12:10.726293 KVP[1882]: KVP LIC Version: 3.1 Jun 20 19:12:10.726905 kernel: hv_utils: KVP IC version 4.0 Jun 20 19:12:10.728416 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 20 19:12:10.729248 (chronyd)[1868]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jun 20 19:12:10.732934 extend-filesystems[1877]: Found /dev/sda6 Jun 20 19:12:10.734629 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 20 19:12:10.734659 chronyd[1890]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jun 20 19:12:10.737093 chronyd[1890]: Timezone right/UTC failed leap second check, ignoring Jun 20 19:12:10.737295 chronyd[1890]: Loaded seccomp filter (level 2) Jun 20 19:12:10.742678 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 20 19:12:10.747775 extend-filesystems[1877]: Found /dev/sda9 Jun 20 19:12:10.750952 extend-filesystems[1877]: Checking size of /dev/sda9 Jun 20 19:12:10.749254 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 20 19:12:10.758488 oslogin_cache_refresh[1878]: Refreshing passwd entry cache Jun 20 19:12:10.759081 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Refreshing passwd entry cache Jun 20 19:12:10.756155 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 20 19:12:10.756648 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 20 19:12:10.757245 systemd[1]: Starting update-engine.service - Update Engine... Jun 20 19:12:10.763699 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 20 19:12:10.769649 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Failure getting users, quitting Jun 20 19:12:10.769649 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 20 19:12:10.769649 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Refreshing group entry cache Jun 20 19:12:10.766522 oslogin_cache_refresh[1878]: Failure getting users, quitting Jun 20 19:12:10.766538 oslogin_cache_refresh[1878]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 20 19:12:10.769795 systemd[1]: Started chronyd.service - NTP client/server. Jun 20 19:12:10.766576 oslogin_cache_refresh[1878]: Refreshing group entry cache Jun 20 19:12:10.771070 extend-filesystems[1877]: Old size kept for /dev/sda9 Jun 20 19:12:10.772315 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 20 19:12:10.779185 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Failure getting groups, quitting Jun 20 19:12:10.779185 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 20 19:12:10.778944 oslogin_cache_refresh[1878]: Failure getting groups, quitting Jun 20 19:12:10.778955 oslogin_cache_refresh[1878]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 20 19:12:10.782798 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 20 19:12:10.787423 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 20 19:12:10.788056 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 20 19:12:10.788349 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 20 19:12:10.790803 jq[1902]: true Jun 20 19:12:10.793454 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jun 20 19:12:10.793661 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jun 20 19:12:10.798509 systemd[1]: motdgen.service: Deactivated successfully. Jun 20 19:12:10.798724 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 20 19:12:10.803389 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 20 19:12:10.803596 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 20 19:12:10.824490 (ntainerd)[1916]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 20 19:12:10.827217 jq[1914]: true Jun 20 19:12:10.865366 update_engine[1898]: I20250620 19:12:10.865293 1898 main.cc:92] Flatcar Update Engine starting Jun 20 19:12:10.890715 systemd-logind[1893]: New seat seat0. Jun 20 19:12:10.892316 systemd-logind[1893]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 20 19:12:10.892980 systemd[1]: Started systemd-logind.service - User Login Management. Jun 20 19:12:10.899916 bash[1996]: Updated "/home/core/.ssh/authorized_keys" Jun 20 19:12:10.900691 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 20 19:12:10.906480 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 20 19:12:10.970584 setup-nvidia[1884]: Downloading Flatcar Container Linux Developer Container for version: 4344.1.0 Jun 20 19:12:10.972846 dbus-daemon[1871]: [system] SELinux support is enabled Jun 20 19:12:10.973069 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 20 19:12:10.976565 update_engine[1898]: I20250620 19:12:10.976100 1898 update_check_scheduler.cc:74] Next update check in 9m5s Jun 20 19:12:10.979598 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). Jun 20 19:12:10.979641 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 20 19:12:10.980479 dbus-daemon[1871]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 20 19:12:10.984867 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). Jun 20 19:12:10.984903 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 20 19:12:10.990057 systemd[1]: Started update-engine.service - Update Engine. Jun 20 19:12:10.995952 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 20 19:12:11.000497 sshd_keygen[1899]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 20 19:12:11.019128 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 20 19:12:11.025385 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 20 19:12:11.041238 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jun 20 19:12:11.042805 coreos-metadata[1870]: Jun 20 19:12:11.042 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 19:12:11.044650 coreos-metadata[1870]: Jun 20 19:12:11.044 INFO Fetch successful Jun 20 19:12:11.044740 coreos-metadata[1870]: Jun 20 19:12:11.044 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jun 20 19:12:11.048295 systemd[1]: issuegen.service: Deactivated successfully. Jun 20 19:12:11.048522 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 20 19:12:11.049153 coreos-metadata[1870]: Jun 20 19:12:11.049 INFO Fetch successful Jun 20 19:12:11.049450 coreos-metadata[1870]: Jun 20 19:12:11.049 INFO Fetching http://168.63.129.16/machine/c6b59a38-299d-4abe-bf13-ab96d7122f9c/8deb7882%2Dcfb9%2D456d%2D9283%2D7e5b77f0b513.%5Fci%2D4344.1.0%2Da%2D9dc9d3b667?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jun 20 19:12:11.051113 coreos-metadata[1870]: Jun 20 19:12:11.051 INFO Fetch successful Jun 20 19:12:11.051250 coreos-metadata[1870]: Jun 20 19:12:11.051 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jun 20 19:12:11.055639 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 20 19:12:11.058513 coreos-metadata[1870]: Jun 20 19:12:11.058 INFO Fetch successful Jun 20 19:12:11.068537 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jun 20 19:12:11.073545 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 20 19:12:11.078459 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 20 19:12:11.081453 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 20 19:12:11.087207 systemd[1]: Reached target getty.target - Login Prompts. Jun 20 19:12:11.092421 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jun 20 19:12:11.097728 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 20 19:12:11.263616 locksmithd[2011]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 20 19:12:11.923469 containerd[1916]: time="2025-06-20T19:12:11Z" 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 Jun 20 19:12:11.924266 containerd[1916]: time="2025-06-20T19:12:11.924230200Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 20 19:12:11.935276 containerd[1916]: time="2025-06-20T19:12:11.935234400Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.5µs" Jun 20 19:12:11.935276 containerd[1916]: time="2025-06-20T19:12:11.935263300Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 20 19:12:11.935348 containerd[1916]: time="2025-06-20T19:12:11.935280900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 20 19:12:11.935442 containerd[1916]: time="2025-06-20T19:12:11.935419000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 20 19:12:11.935487 containerd[1916]: time="2025-06-20T19:12:11.935440800Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 20 19:12:11.935487 containerd[1916]: time="2025-06-20T19:12:11.935463900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 20 19:12:11.935550 containerd[1916]: time="2025-06-20T19:12:11.935526700Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 20 19:12:11.935550 containerd[1916]: time="2025-06-20T19:12:11.935545300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.935862 containerd[1916]: time="2025-06-20T19:12:11.935780800Z" 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 Jun 20 19:12:11.935862 containerd[1916]: time="2025-06-20T19:12:11.935848900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 20 19:12:11.935960 containerd[1916]: time="2025-06-20T19:12:11.935868400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 20 19:12:11.935960 containerd[1916]: time="2025-06-20T19:12:11.935878700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 20 19:12:11.936004 containerd[1916]: time="2025-06-20T19:12:11.935977400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.936416 containerd[1916]: time="2025-06-20T19:12:11.936380600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.936557 containerd[1916]: time="2025-06-20T19:12:11.936465600Z" 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 Jun 20 19:12:11.936705 containerd[1916]: time="2025-06-20T19:12:11.936633200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 20 19:12:11.936759 containerd[1916]: time="2025-06-20T19:12:11.936715400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 20 19:12:11.937249 containerd[1916]: time="2025-06-20T19:12:11.937220900Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 20 19:12:11.937319 containerd[1916]: time="2025-06-20T19:12:11.937297100Z" level=info msg="metadata content store policy set" policy=shared Jun 20 19:12:11.968283 containerd[1916]: time="2025-06-20T19:12:11.968217300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 20 19:12:11.968330 containerd[1916]: time="2025-06-20T19:12:11.968297600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 20 19:12:11.968330 containerd[1916]: time="2025-06-20T19:12:11.968313700Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 20 19:12:11.968330 containerd[1916]: time="2025-06-20T19:12:11.968326700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968337100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968347600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968358700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968369300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968379700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968389000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968397100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 20 19:12:11.968408 containerd[1916]: time="2025-06-20T19:12:11.968408100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 20 19:12:11.968573 containerd[1916]: time="2025-06-20T19:12:11.968520600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 20 19:12:11.968573 containerd[1916]: time="2025-06-20T19:12:11.968540900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 20 19:12:11.968573 containerd[1916]: time="2025-06-20T19:12:11.968553700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 20 19:12:11.968573 containerd[1916]: time="2025-06-20T19:12:11.968566200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968575500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968602000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968618000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968627300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968638500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 20 19:12:11.968659 containerd[1916]: time="2025-06-20T19:12:11.968648300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 20 19:12:11.968790 containerd[1916]: time="2025-06-20T19:12:11.968669100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 20 19:12:11.968790 containerd[1916]: time="2025-06-20T19:12:11.968732000Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 20 19:12:11.968790 containerd[1916]: time="2025-06-20T19:12:11.968745900Z" level=info msg="Start snapshots syncer" Jun 20 19:12:11.968790 containerd[1916]: time="2025-06-20T19:12:11.968770400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 20 19:12:11.969062 containerd[1916]: time="2025-06-20T19:12:11.969004500Z" 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\"}" Jun 20 19:12:11.969186 containerd[1916]: time="2025-06-20T19:12:11.969082300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 20 19:12:11.969186 containerd[1916]: time="2025-06-20T19:12:11.969158500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 20 19:12:11.969283 containerd[1916]: time="2025-06-20T19:12:11.969258300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 20 19:12:11.969315 containerd[1916]: time="2025-06-20T19:12:11.969285500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 20 19:12:11.969338 containerd[1916]: time="2025-06-20T19:12:11.969308900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 20 19:12:11.969367 containerd[1916]: time="2025-06-20T19:12:11.969325000Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 20 19:12:11.969367 containerd[1916]: time="2025-06-20T19:12:11.969357000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 20 19:12:11.969413 containerd[1916]: time="2025-06-20T19:12:11.969371000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 20 19:12:11.969413 containerd[1916]: time="2025-06-20T19:12:11.969382800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 20 19:12:11.969413 containerd[1916]: time="2025-06-20T19:12:11.969405000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 20 19:12:11.969472 containerd[1916]: time="2025-06-20T19:12:11.969416500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 20 19:12:11.969472 containerd[1916]: time="2025-06-20T19:12:11.969427200Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 20 19:12:11.969472 containerd[1916]: time="2025-06-20T19:12:11.969458800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969475200Z" 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 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969483800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969492900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969501100Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969510200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969519700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969535600Z" level=info msg="runtime interface created" Jun 20 19:12:11.969539 containerd[1916]: time="2025-06-20T19:12:11.969541300Z" level=info msg="created NRI interface" Jun 20 19:12:11.969829 containerd[1916]: time="2025-06-20T19:12:11.969549200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 20 19:12:11.969829 containerd[1916]: time="2025-06-20T19:12:11.969559200Z" level=info msg="Connect containerd service" Jun 20 19:12:11.969829 containerd[1916]: time="2025-06-20T19:12:11.969584200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 20 19:12:11.970310 containerd[1916]: time="2025-06-20T19:12:11.970279800Z" 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" Jun 20 19:12:12.115272 setup-nvidia[2055]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 19:12:12.115577 setup-nvidia[2055]: Dload Upload Total Spent Left Speed Jun 20 19:12:12.864751 containerd[1916]: time="2025-06-20T19:12:12.864703300Z" level=info msg="Start subscribing containerd event" Jun 20 19:12:12.864914 containerd[1916]: time="2025-06-20T19:12:12.864759000Z" level=info msg="Start recovering state" Jun 20 19:12:12.864948 containerd[1916]: time="2025-06-20T19:12:12.864912700Z" level=info msg="Start event monitor" Jun 20 19:12:12.864971 containerd[1916]: time="2025-06-20T19:12:12.864944400Z" level=info msg="Start cni network conf syncer for default" Jun 20 19:12:12.864971 containerd[1916]: time="2025-06-20T19:12:12.864959200Z" level=info msg="Start streaming server" Jun 20 19:12:12.865020 containerd[1916]: time="2025-06-20T19:12:12.864975500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 20 19:12:12.865020 containerd[1916]: time="2025-06-20T19:12:12.864985000Z" level=info msg="runtime interface starting up..." Jun 20 19:12:12.865020 containerd[1916]: time="2025-06-20T19:12:12.864990300Z" level=info msg="starting plugins..." Jun 20 19:12:12.865020 containerd[1916]: time="2025-06-20T19:12:12.865004300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 20 19:12:12.865121 containerd[1916]: time="2025-06-20T19:12:12.865096700Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 20 19:12:12.865202 containerd[1916]: time="2025-06-20T19:12:12.865182000Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 20 19:12:12.865293 containerd[1916]: time="2025-06-20T19:12:12.865271000Z" level=info msg="containerd successfully booted in 0.942191s" Jun 20 19:12:12.865377 systemd[1]: Started containerd.service - containerd container runtime. Jun 20 19:12:13.241688 waagent[2033]: 2025-06-20T19:12:13.241551Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jun 20 19:12:13.246017 waagent[2033]: 2025-06-20T19:12:13.245970Z INFO Daemon Daemon OS: flatcar 4344.1.0 Jun 20 19:12:13.249459 waagent[2033]: 2025-06-20T19:12:13.249415Z INFO Daemon Daemon Python: 3.11.12 Jun 20 19:12:13.252810 waagent[2033]: 2025-06-20T19:12:13.252764Z INFO Daemon Daemon Run daemon Jun 20 19:12:13.255849 waagent[2033]: 2025-06-20T19:12:13.255797Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.1.0' Jun 20 19:12:13.262388 waagent[2033]: 2025-06-20T19:12:13.262333Z INFO Daemon Daemon Using waagent for provisioning Jun 20 19:12:13.266349 waagent[2033]: 2025-06-20T19:12:13.266301Z INFO Daemon Daemon Activate resource disk Jun 20 19:12:13.269877 waagent[2033]: 2025-06-20T19:12:13.269834Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jun 20 19:12:13.277355 waagent[2033]: 2025-06-20T19:12:13.277309Z INFO Daemon Daemon Found device: sdb Jun 20 19:12:13.286838 waagent[2033]: 2025-06-20T19:12:13.286789Z INFO Daemon Daemon Examining partition table Jun 20 19:12:13.301130 waagent[2033]: 2025-06-20T19:12:13.301087Z INFO Daemon Daemon GPT not detected, determining filesystem Jun 20 19:12:13.590104 waagent[2033]: 2025-06-20T19:12:13.589943Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jun 20 19:12:13.594549 waagent[2033]: 2025-06-20T19:12:13.594496Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jun 20 19:12:19.084858 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#216 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:12:19.098910 kernel: sdb: sdb1 Jun 20 19:12:19.106906 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#157 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:12:19.114905 kernel: sdb: sdb1 Jun 20 19:12:19.233137 login[2037]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jun 20 19:12:19.534840 login[2038]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 19:12:19.541317 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 20 19:12:19.542421 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 20 19:12:19.548602 systemd-logind[1893]: New session 2 of user core. Jun 20 19:12:19.569265 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 20 19:12:19.571773 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 20 19:12:19.679742 (systemd)[2129]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 20 19:12:19.682118 systemd-logind[1893]: New session c1 of user core. Jun 20 19:12:19.996643 systemd[2129]: Queued start job for default target default.target. Jun 20 19:12:20.018877 systemd[2129]: Created slice app.slice - User Application Slice. Jun 20 19:12:20.018925 systemd[2129]: Reached target paths.target - Paths. Jun 20 19:12:20.018973 systemd[2129]: Reached target timers.target - Timers. Jun 20 19:12:20.020259 systemd[2129]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 19:12:20.030801 systemd[2129]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:12:20.030859 systemd[2129]: Reached target sockets.target - Sockets. Jun 20 19:12:20.030923 systemd[2129]: Reached target basic.target - Basic System. Jun 20 19:12:20.030969 systemd[2129]: Reached target default.target - Main User Target. Jun 20 19:12:20.031000 systemd[2129]: Startup finished in 341ms. Jun 20 19:12:20.031184 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 20 19:12:20.032579 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 20 19:12:20.233559 login[2037]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 19:12:20.239247 systemd-logind[1893]: New session 1 of user core. Jun 20 19:12:20.251020 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 20 19:12:26.878636 waagent[2033]: 2025-06-20T19:12:26.878546Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jun 20 19:12:26.894156 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#154 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:12:26.914905 kernel: sdb: sdb1 Jun 20 19:12:26.915185 waagent[2033]: 2025-06-20T19:12:26.915132Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jun 20 19:12:32.721456 waagent[2033]: 2025-06-20T19:12:32.721391Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jun 20 19:12:32.827858 waagent[2033]: 2025-06-20T19:12:32.827781Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jun 20 19:12:32.827981 kernel: EXT4-fs (sdb1): mounted filesystem 9ddd766b-9657-4ada-9e17-1a9ae5635404 r/w with ordered data mode. Quota mode: none. Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.830104Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.830341Z INFO Daemon Daemon Running default provisioning handler Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.837776Z 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. Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.839602Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.840661Z INFO Daemon Daemon cloud-init is enabled: False Jun 20 19:12:32.849612 waagent[2033]: 2025-06-20T19:12:32.842173Z INFO Daemon Daemon Copying ovf-env.xml Jun 20 19:12:32.958268 waagent[2033]: 2025-06-20T19:12:32.958200Z INFO Daemon Daemon Successfully mounted dvd Jun 20 19:12:32.981592 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jun 20 19:12:32.981934 waagent[2033]: 2025-06-20T19:12:32.981589Z INFO Daemon Daemon Detect protocol endpoint Jun 20 19:12:32.983367 waagent[2033]: 2025-06-20T19:12:32.983283Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 19:12:32.984940 waagent[2033]: 2025-06-20T19:12:32.984852Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jun 20 19:12:32.986380 waagent[2033]: 2025-06-20T19:12:32.986323Z INFO Daemon Daemon Test for route to 168.63.129.16 Jun 20 19:12:32.988153 waagent[2033]: 2025-06-20T19:12:32.988078Z INFO Daemon Daemon Route to 168.63.129.16 exists Jun 20 19:12:32.989446 waagent[2033]: 2025-06-20T19:12:32.989390Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jun 20 19:12:33.004545 waagent[2033]: 2025-06-20T19:12:33.004502Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jun 20 19:12:33.004956 waagent[2033]: 2025-06-20T19:12:33.004932Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jun 20 19:12:33.005881 waagent[2033]: 2025-06-20T19:12:33.005849Z INFO Daemon Daemon Server preferred version:2015-04-05 Jun 20 19:12:33.081445 waagent[2033]: 2025-06-20T19:12:33.081376Z INFO Daemon Daemon Initializing goal state during protocol detection Jun 20 19:12:33.083260 waagent[2033]: 2025-06-20T19:12:33.083192Z INFO Daemon Daemon Forcing an update of the goal state. Jun 20 19:12:33.087690 waagent[2033]: 2025-06-20T19:12:33.087644Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 19:12:33.119856 waagent[2033]: 2025-06-20T19:12:33.119815Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jun 20 19:12:33.121938 waagent[2033]: 2025-06-20T19:12:33.121878Z INFO Daemon Jun 20 19:12:33.122286 waagent[2033]: 2025-06-20T19:12:33.122251Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 01e64fac-089d-4f66-a572-9937193183d5 eTag: 16045290109843509118 source: Fabric] Jun 20 19:12:33.124048 waagent[2033]: 2025-06-20T19:12:33.124006Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jun 20 19:12:33.124708 waagent[2033]: 2025-06-20T19:12:33.124669Z INFO Daemon Jun 20 19:12:33.125973 waagent[2033]: 2025-06-20T19:12:33.125930Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jun 20 19:12:33.147691 waagent[2033]: 2025-06-20T19:12:33.147650Z INFO Daemon Daemon Downloading artifacts profile blob Jun 20 19:12:33.259302 waagent[2033]: 2025-06-20T19:12:33.259200Z INFO Daemon Downloaded certificate {'thumbprint': 'A59C768A54E7DA21D27E794A4E96CC642EDFB142', 'hasPrivateKey': True} Jun 20 19:12:33.261291 waagent[2033]: 2025-06-20T19:12:33.261247Z INFO Daemon Fetch goal state completed Jun 20 19:12:33.291334 waagent[2033]: 2025-06-20T19:12:33.291286Z INFO Daemon Daemon Starting provisioning Jun 20 19:12:33.293079 waagent[2033]: 2025-06-20T19:12:33.293024Z INFO Daemon Daemon Handle ovf-env.xml. Jun 20 19:12:33.294541 waagent[2033]: 2025-06-20T19:12:33.294499Z INFO Daemon Daemon Set hostname [ci-4344.1.0-a-9dc9d3b667] Jun 20 19:12:33.308657 waagent[2033]: 2025-06-20T19:12:33.308616Z INFO Daemon Daemon Publish hostname [ci-4344.1.0-a-9dc9d3b667] Jun 20 19:12:33.310320 waagent[2033]: 2025-06-20T19:12:33.310278Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jun 20 19:12:33.311089 waagent[2033]: 2025-06-20T19:12:33.311050Z INFO Daemon Daemon Primary interface is [eth0] Jun 20 19:12:33.319965 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.320470Z INFO Daemon Daemon Create user account if not exists Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.320780Z INFO Daemon Daemon User core already exists, skip useradd Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.322265Z INFO Daemon Daemon Configure sudoer Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.326071Z INFO Daemon Daemon Configure sshd Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.330140Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jun 20 19:12:33.348292 waagent[2033]: 2025-06-20T19:12:33.330676Z INFO Daemon Daemon Deploy ssh public key. Jun 20 19:12:33.319975 systemd-networkd[1591]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:12:33.320004 systemd-networkd[1591]: eth0: DHCP lease lost Jun 20 19:12:33.408921 systemd-networkd[1591]: eth0: DHCPv4 address 10.200.4.32/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:12:34.516911 chronyd[1890]: Selected source PHC0 Jun 20 19:12:39.835684 setup-nvidia[2055]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 486M 0 13037 0 0 21635 0 6:32:54 --:--:-- 6:32:54 21620\u000d 0 486M 0 3343k 0 0 2052k 0 0:04:02 0:00:01 0:04:01 2051k\u000d 4 486M 4 23.6M 0 0 9450k 0 0:00:52 0:00:02 0:00:50 9447k\u000d 9 486M 9 45.0M 0 0 12.4M 0 0:00:38 0:00:03 0:00:35 12.4M\u000d 13 486M 13 65.5M 0 0 14.5M 0 0:00:33 0:00:04 0:00:29 14.5M\u000d 15 486M 15 75.6M 0 0 13.1M 0 0:00:36 0:00:05 0:00:31 14.6M\u000d 18 486M 18 89.6M 0 0 13.5M 0 0:00:35 0:00:06 0:00:29 17.3M\u000d 22 486M 22 111M 0 0 14.8M 0 0:00:32 0:00:07 0:00:25 17.7M\u000d 27 486M 27 134M 0 0 15.6M 0 0:00:31 0:00:08 0:00:23 17.9M\u000d 32 486M 32 159M 0 0 16.5M 0 0:00:29 0:00:09 0:00:20 18.3M\u000d 36 486M 36 179M 0 0 17.0M 0 0:00:28 0:00:10 0:00:18 21.7M\u000d 41 486M 41 200M 0 0 14.5M 0 0:00:33 0:00:13 0:00:20 15.3M\u000d 42 486M 42 206M 0 0 14.2M 0 0:00:34 0:00:14 0:00:20 13.5M\u000d 46 486M 46 227M 0 0 14.5M 0 0:00:33 0:00:15 0:00:18 13.3M\u000d 51 486M 51 249M 0 0 14.9M 0 0:00:32 0:00:16 0:00:16 12.7M\u000d 55 486M 55 268M 0 0 15.3M 0 0:00:31 0:00:17 0:00:14 12.7M\u000d 60 486M 60 292M 0 0 15.7M 0 0:00:30 0:00:18 0:00:12 19.1M\u000d 64 486M 64 313M 0 0 16.0M 0 0:00:30 0:00:19 0:00:11 21.3M\u000d 68 486M 68 335M 0 0 16.2M 0 0:00:29 0:00:20 0:00:09 21.4M\u000d 73 486M 73 356M 0 0 16.5M 0 0:00:29 0:00:21 0:00:08 21.9M\u000d 77 486M 77 374M 0 0 16.6M 0 0:00:29 0:00:22 0:00:07 21.0M\u000d 82 486M 82 399M 0 0 16.9M 0 0:00:28 0:00:23 0:00:05 21.2M\u000d 86 486M 86 419M 0 0 17.0M 0 0:00:28 0:00:24 0:00:04 21.1M\u000d 91 486M 91 443M 0 0 17.3M 0 0:00:28 0:00:25 0:00:03 21.6M\u000d 95 486M 95 462M 0 0 17.4M 0 0:00:27 0:00:26 0:00:01 21.2M\u000d 99 486M 99 482M 0 0 17.5M 0 0:00:27 0:00:27 --:--:-- 21.6M\u000d100 486M 100 486M 0 0 17.5M 0 0:00:27 0:00:27 --:--:-- 21.2M Jun 20 19:12:49.954367 setup-nvidia[1884]: Downloading NVIDIA 535.230.02 Driver Jun 20 19:12:49.958725 setup-nvidia[2223]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 19:12:49.958982 setup-nvidia[2223]: Dload Upload Total Spent Left Speed Jun 20 19:12:51.316253 setup-nvidia[2223]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 44 326M 44 143M 0 0 217M 0 0:00:01 --:--:-- 0:00:01 217M\u000d100 326M 100 326M 0 0 240M 0 0:00:01 0:00:01 --:--:-- 240M Jun 20 19:12:51.336445 setup-nvidia[1884]: Extract the NVIDIA Driver Installer 535.230.02 Jun 20 19:12:51.336612 setup-nvidia[1884]: /opt/nvidia/workdir/nvidia-workdir / Jun 20 19:12:51.353968 setup-nvidia[2226]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jun 20 19:12:51.668961 setup-nvidia[2226]: Verifying archive integrity... OK Jun 20 19:12:52.400791 setup-nvidia[2226]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jun 20 19:12:53.826146 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jun 20 19:12:54.119900 setup-nvidia[2252]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jun 20 19:12:54.172148 setup-nvidia[1884]: / Jun 20 19:12:54.172347 setup-nvidia[1884]: Spawn system-nspawn container to install the NVIDIA drivers Jun 20 19:12:54.474424 sudo[2259]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.1.0.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 Jun 20 19:12:54.476170 sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 19:12:54.481628 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jun 20 19:12:54.482598 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 19:12:54.504072 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 19:12:54.506688 systemd[1]: Starting user@0.service - User Manager for UID 0... Jun 20 19:12:54.522814 (systemd)[2262]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jun 20 19:12:54.525124 systemd-logind[1893]: New session c3 of user root. Jun 20 19:12:54.714751 systemd[2262]: Queued start job for default target default.target. Jun 20 19:12:54.737073 systemd[2262]: Created slice app.slice - User Application Slice. Jun 20 19:12:54.737103 systemd[2262]: Reached target paths.target - Paths. Jun 20 19:12:54.737151 systemd[2262]: Reached target timers.target - Timers. Jun 20 19:12:54.738318 systemd[2262]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 19:12:54.748798 systemd[2262]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:12:54.748855 systemd[2262]: Reached target sockets.target - Sockets. Jun 20 19:12:54.748912 systemd[2262]: Reached target basic.target - Basic System. Jun 20 19:12:54.748956 systemd[2262]: Reached target default.target - Main User Target. Jun 20 19:12:54.748994 systemd[2262]: Startup finished in 218ms. Jun 20 19:12:54.749088 systemd[1]: Started user@0.service - User Manager for UID 0. Jun 20 19:12:54.750469 systemd[1]: Started session-c2.scope - Session c2 of User root. Jun 20 19:12:54.837911 kernel: loop6: detected capacity change from 0 to 12644352 Jun 20 19:12:54.878906 kernel: loop6: p9 Jun 20 19:12:54.972948 setup-nvidia[2271]: ░ Spawning container flatcardevelopercontainer-4344.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.1.0.bin. Jun 20 19:12:56.244661 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jun 20 19:12:56.244810 kernel: EXT4-fs (loop6p9): mounted filesystem c16469d9-dda3-462c-ac5d-9d8b0405d9ca ro without journal. Quota mode: none. Jun 20 19:12:56.418018 update_engine[1898]: I20250620 19:12:56.417958 1898 update_attempter.cc:509] Updating boot flags... Jun 20 19:13:03.429999 waagent[2033]: 2025-06-20T19:13:03.429936Z INFO Daemon Daemon Provisioning complete Jun 20 19:13:03.444123 waagent[2033]: 2025-06-20T19:13:03.444071Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jun 20 19:13:03.445894 waagent[2033]: 2025-06-20T19:13:03.445840Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jun 20 19:13:03.446618 waagent[2033]: 2025-06-20T19:13:03.446582Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jun 20 19:13:03.589175 waagent[2517]: 2025-06-20T19:13:03.589088Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jun 20 19:13:03.589525 waagent[2517]: 2025-06-20T19:13:03.589235Z INFO ExtHandler ExtHandler OS: flatcar 4344.1.0 Jun 20 19:13:03.589525 waagent[2517]: 2025-06-20T19:13:03.589306Z INFO ExtHandler ExtHandler Python: 3.11.12 Jun 20 19:13:03.589525 waagent[2517]: 2025-06-20T19:13:03.589376Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jun 20 19:13:03.694402 waagent[2517]: 2025-06-20T19:13:03.694320Z INFO ExtHandler ExtHandler Distro: flatcar-4344.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jun 20 19:13:03.694918 waagent[2517]: 2025-06-20T19:13:03.694864Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:13:03.695006 waagent[2517]: 2025-06-20T19:13:03.694974Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:13:03.702735 waagent[2517]: 2025-06-20T19:13:03.702690Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 19:13:03.713226 waagent[2517]: 2025-06-20T19:13:03.713179Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jun 20 19:13:03.713809 waagent[2517]: 2025-06-20T19:13:03.713765Z INFO ExtHandler Jun 20 19:13:03.714286 waagent[2517]: 2025-06-20T19:13:03.713861Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: d684d848-e4ba-4eb1-a13c-6d2ac33069cc eTag: 16045290109843509118 source: Fabric] Jun 20 19:13:03.714286 waagent[2517]: 2025-06-20T19:13:03.714205Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jun 20 19:13:03.714830 waagent[2517]: 2025-06-20T19:13:03.714791Z INFO ExtHandler Jun 20 19:13:03.714916 waagent[2517]: 2025-06-20T19:13:03.714871Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jun 20 19:13:03.718053 waagent[2517]: 2025-06-20T19:13:03.718022Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jun 20 19:13:03.781399 waagent[2517]: 2025-06-20T19:13:03.781323Z INFO ExtHandler Downloaded certificate {'thumbprint': 'A59C768A54E7DA21D27E794A4E96CC642EDFB142', 'hasPrivateKey': True} Jun 20 19:13:03.781925 waagent[2517]: 2025-06-20T19:13:03.781864Z INFO ExtHandler Fetch goal state completed Jun 20 19:13:03.793470 waagent[2517]: 2025-06-20T19:13:03.793423Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jun 20 19:13:03.798678 waagent[2517]: 2025-06-20T19:13:03.798628Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2517 Jun 20 19:13:03.798809 waagent[2517]: 2025-06-20T19:13:03.798774Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jun 20 19:13:03.799282 waagent[2517]: 2025-06-20T19:13:03.799234Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jun 20 19:13:03.800827 waagent[2517]: 2025-06-20T19:13:03.800787Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.1.0', '', 'Flatcar Container Linux by Kinvolk'] Jun 20 19:13:03.801273 waagent[2517]: 2025-06-20T19:13:03.801236Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jun 20 19:13:03.801430 waagent[2517]: 2025-06-20T19:13:03.801396Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jun 20 19:13:03.802054 waagent[2517]: 2025-06-20T19:13:03.802014Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jun 20 19:13:03.828943 waagent[2517]: 2025-06-20T19:13:03.828898Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jun 20 19:13:03.829113 waagent[2517]: 2025-06-20T19:13:03.829077Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jun 20 19:13:03.835595 waagent[2517]: 2025-06-20T19:13:03.835555Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jun 20 19:13:03.843780 systemd[1]: Reload requested from client PID 2652 ('systemctl') (unit waagent.service)... Jun 20 19:13:03.843798 systemd[1]: Reloading... Jun 20 19:13:03.911053 zram_generator::config[2717]: No configuration found. Jun 20 19:13:04.027294 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. Jun 20 19:13:04.197923 systemd[1]: Reloading finished in 353 ms. Jun 20 19:13:04.218536 waagent[2517]: 2025-06-20T19:13:04.218462Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jun 20 19:13:04.218635 waagent[2517]: 2025-06-20T19:13:04.218607Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jun 20 19:13:04.916094 waagent[2517]: 2025-06-20T19:13:04.916018Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jun 20 19:13:04.916501 waagent[2517]: 2025-06-20T19:13:04.916344Z 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] Jun 20 19:13:04.917110 waagent[2517]: 2025-06-20T19:13:04.917065Z INFO ExtHandler ExtHandler Starting env monitor service. Jun 20 19:13:04.917465 waagent[2517]: 2025-06-20T19:13:04.917413Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:13:04.917664 waagent[2517]: 2025-06-20T19:13:04.917617Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jun 20 19:13:04.917862 waagent[2517]: 2025-06-20T19:13:04.917823Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:13:04.919071 waagent[2517]: 2025-06-20T19:13:04.919024Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:13:04.919169 waagent[2517]: 2025-06-20T19:13:04.919115Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jun 20 19:13:04.919237 waagent[2517]: 2025-06-20T19:13:04.919191Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jun 20 19:13:04.919458 waagent[2517]: 2025-06-20T19:13:04.919414Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jun 20 19:13:04.919782 waagent[2517]: 2025-06-20T19:13:04.919734Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:13:04.920440 waagent[2517]: 2025-06-20T19:13:04.920385Z INFO EnvHandler ExtHandler Configure routes Jun 20 19:13:04.920540 waagent[2517]: 2025-06-20T19:13:04.920493Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jun 20 19:13:04.920853 waagent[2517]: 2025-06-20T19:13:04.920806Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jun 20 19:13:04.920853 waagent[2517]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jun 20 19:13:04.920853 waagent[2517]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jun 20 19:13:04.920853 waagent[2517]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jun 20 19:13:04.920853 waagent[2517]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:13:04.920853 waagent[2517]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:13:04.920853 waagent[2517]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:13:04.921179 waagent[2517]: 2025-06-20T19:13:04.921123Z INFO EnvHandler ExtHandler Gateway:None Jun 20 19:13:04.921254 waagent[2517]: 2025-06-20T19:13:04.921217Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jun 20 19:13:04.921413 waagent[2517]: 2025-06-20T19:13:04.921368Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jun 20 19:13:04.921691 waagent[2517]: 2025-06-20T19:13:04.921654Z INFO EnvHandler ExtHandler Routes:None Jun 20 19:13:04.929164 waagent[2517]: 2025-06-20T19:13:04.929116Z INFO ExtHandler ExtHandler Jun 20 19:13:04.929238 waagent[2517]: 2025-06-20T19:13:04.929212Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 4c080aa0-6225-4c63-8546-03ecadbf7ee7 correlation 88e8a555-399b-4575-bc43-3cb045e95a6e created: 2025-06-20T19:10:57.855949Z] Jun 20 19:13:04.930185 waagent[2517]: 2025-06-20T19:13:04.930138Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jun 20 19:13:04.931654 waagent[2517]: 2025-06-20T19:13:04.931608Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Jun 20 19:13:04.953861 waagent[2517]: 2025-06-20T19:13:04.953763Z 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 Jun 20 19:13:04.953861 waagent[2517]: Try `iptables -h' or 'iptables --help' for more information.) Jun 20 19:13:04.954298 waagent[2517]: 2025-06-20T19:13:04.954254Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 37651092-9145-4F5C-BFC5-36B41DF448A5;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jun 20 19:13:05.000375 waagent[2517]: 2025-06-20T19:13:05.000321Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jun 20 19:13:05.000375 waagent[2517]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.000375 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.000375 waagent[2517]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.000375 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.000375 waagent[2517]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.000375 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.000375 waagent[2517]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 19:13:05.000375 waagent[2517]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 19:13:05.000375 waagent[2517]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 19:13:05.003831 waagent[2517]: 2025-06-20T19:13:05.003786Z INFO EnvHandler ExtHandler Current Firewall rules: Jun 20 19:13:05.003831 waagent[2517]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.003831 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.003831 waagent[2517]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.003831 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.003831 waagent[2517]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:13:05.003831 waagent[2517]: pkts bytes target prot opt in out source destination Jun 20 19:13:05.003831 waagent[2517]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 19:13:05.003831 waagent[2517]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 19:13:05.003831 waagent[2517]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 19:13:05.004088 waagent[2517]: 2025-06-20T19:13:05.004054Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jun 20 19:13:05.004239 waagent[2517]: 2025-06-20T19:13:05.004209Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jun 20 19:13:05.023642 waagent[2517]: 2025-06-20T19:13:05.023592Z INFO MonitorHandler ExtHandler Network interfaces: Jun 20 19:13:05.023642 waagent[2517]: Executing ['ip', '-a', '-o', 'link']: Jun 20 19:13:05.023642 waagent[2517]: 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 Jun 20 19:13:05.023642 waagent[2517]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:03:f6:1d brd ff:ff:ff:ff:ff:ff Jun 20 19:13:05.023642 waagent[2517]: Executing ['ip', '-4', '-a', '-o', 'address']: Jun 20 19:13:05.023642 waagent[2517]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jun 20 19:13:05.023642 waagent[2517]: 2: eth0 inet 10.200.4.32/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jun 20 19:13:05.023642 waagent[2517]: Executing ['ip', '-6', '-a', '-o', 'address']: Jun 20 19:13:05.023642 waagent[2517]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jun 20 19:13:05.023642 waagent[2517]: 2: eth0 inet6 fe80::7e1e:52ff:fe03:f61d/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jun 20 19:13:15.178647 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 20 19:13:15.186962 systemd[1]: Started sshd@0-10.200.4.32:22-10.200.16.10:38516.service - OpenSSH per-connection server daemon (10.200.16.10:38516). Jun 20 19:13:17.247698 sshd[5713]: Accepted publickey for core from 10.200.16.10 port 38516 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:17.249509 sshd-session[5713]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:17.255237 systemd-logind[1893]: New session 3 of user core. Jun 20 19:13:17.271223 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 20 19:13:17.766120 systemd[1]: Started sshd@1-10.200.4.32:22-10.200.16.10:38518.service - OpenSSH per-connection server daemon (10.200.16.10:38518). Jun 20 19:13:18.374184 sshd[6240]: Accepted publickey for core from 10.200.16.10 port 38518 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:18.375464 sshd-session[6240]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:18.380786 systemd-logind[1893]: New session 4 of user core. Jun 20 19:13:18.392029 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 20 19:13:18.791720 sshd[6410]: Connection closed by 10.200.16.10 port 38518 Jun 20 19:13:18.793810 sshd-session[6240]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:18.798024 systemd-logind[1893]: Session 4 logged out. Waiting for processes to exit. Jun 20 19:13:18.799265 systemd[1]: sshd@1-10.200.4.32:22-10.200.16.10:38518.service: Deactivated successfully. Jun 20 19:13:18.801641 systemd[1]: session-4.scope: Deactivated successfully. Jun 20 19:13:18.804079 systemd-logind[1893]: Removed session 4. Jun 20 19:13:18.901371 systemd[1]: Started sshd@2-10.200.4.32:22-10.200.16.10:58348.service - OpenSSH per-connection server daemon (10.200.16.10:58348). Jun 20 19:13:19.515389 sshd[6552]: Accepted publickey for core from 10.200.16.10 port 58348 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:19.516226 sshd-session[6552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:19.525794 systemd-logind[1893]: New session 5 of user core. Jun 20 19:13:19.533056 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 20 19:13:19.936738 sshd[6681]: Connection closed by 10.200.16.10 port 58348 Jun 20 19:13:19.937112 sshd-session[6552]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:19.940051 systemd[1]: sshd@2-10.200.4.32:22-10.200.16.10:58348.service: Deactivated successfully. Jun 20 19:13:19.941841 systemd[1]: session-5.scope: Deactivated successfully. Jun 20 19:13:19.943844 systemd-logind[1893]: Session 5 logged out. Waiting for processes to exit. Jun 20 19:13:19.944819 systemd-logind[1893]: Removed session 5. Jun 20 19:13:30.046335 systemd[1]: Started sshd@3-10.200.4.32:22-10.200.16.10:34584.service - OpenSSH per-connection server daemon (10.200.16.10:34584). Jun 20 19:13:33.759242 sshd[8149]: Accepted publickey for core from 10.200.16.10 port 34584 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:30.638549 sshd-session[8149]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:30.642851 systemd-logind[1893]: New session 6 of user core. Jun 20 19:13:33.759856 sshd[8151]: Connection closed by 10.200.16.10 port 34584 Jun 20 19:13:33.759970 sshd[8157]: Accepted publickey for core from 10.200.16.10 port 34592 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:31.063794 sshd-session[8149]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:30.652009 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 20 19:13:33.760430 sshd[8159]: Connection closed by 10.200.16.10 port 34592 Jun 20 19:13:31.764013 sshd-session[8157]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:31.067295 systemd[1]: sshd@3-10.200.4.32:22-10.200.16.10:34584.service: Deactivated successfully. Jun 20 19:13:32.187829 sshd-session[8157]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:31.068933 systemd[1]: session-6.scope: Deactivated successfully. Jun 20 19:13:31.069598 systemd-logind[1893]: Session 6 logged out. Waiting for processes to exit. Jun 20 19:13:31.070547 systemd-logind[1893]: Removed session 6. Jun 20 19:13:31.172252 systemd[1]: Started sshd@4-10.200.4.32:22-10.200.16.10:34592.service - OpenSSH per-connection server daemon (10.200.16.10:34592). Jun 20 19:13:31.768311 systemd-logind[1893]: New session 7 of user core. Jun 20 19:13:31.789014 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 20 19:13:32.190634 systemd[1]: sshd@4-10.200.4.32:22-10.200.16.10:34592.service: Deactivated successfully. Jun 20 19:13:32.192476 systemd[1]: session-7.scope: Deactivated successfully. Jun 20 19:13:32.193268 systemd-logind[1893]: Session 7 logged out. Waiting for processes to exit. Jun 20 19:13:32.194240 systemd-logind[1893]: Removed session 7. Jun 20 19:13:42.294535 systemd[1]: Started sshd@5-10.200.4.32:22-10.200.16.10:60798.service - OpenSSH per-connection server daemon (10.200.16.10:60798). Jun 20 19:13:42.907341 sshd[9596]: Accepted publickey for core from 10.200.16.10 port 60798 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:42.908751 sshd-session[9596]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:42.913942 systemd-logind[1893]: New session 8 of user core. Jun 20 19:13:42.923019 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 20 19:13:43.332968 sshd[9840]: Connection closed by 10.200.16.10 port 60798 Jun 20 19:13:43.333596 sshd-session[9596]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:43.339756 systemd[1]: sshd@5-10.200.4.32:22-10.200.16.10:60798.service: Deactivated successfully. Jun 20 19:13:43.340311 systemd-logind[1893]: Session 8 logged out. Waiting for processes to exit. Jun 20 19:13:43.343908 systemd[1]: session-8.scope: Deactivated successfully. Jun 20 19:13:43.346447 systemd-logind[1893]: Removed session 8. Jun 20 19:13:43.445123 systemd[1]: Started sshd@6-10.200.4.32:22-10.200.16.10:60810.service - OpenSSH per-connection server daemon (10.200.16.10:60810). Jun 20 19:13:44.048830 sshd[10016]: Accepted publickey for core from 10.200.16.10 port 60810 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:46.917176 sshd-session[10016]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:46.922690 systemd-logind[1893]: New session 9 of user core. Jun 20 19:13:46.938133 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 20 19:13:47.280032 sshd[10161]: Connection closed by 10.200.16.10 port 60810 Jun 20 19:13:47.280402 sshd-session[10016]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:47.286420 systemd-logind[1893]: Session 9 logged out. Waiting for processes to exit. Jun 20 19:13:47.287897 systemd[1]: sshd@6-10.200.4.32:22-10.200.16.10:60810.service: Deactivated successfully. Jun 20 19:13:47.291749 systemd[1]: session-9.scope: Deactivated successfully. Jun 20 19:13:47.294286 systemd-logind[1893]: Removed session 9. Jun 20 19:13:57.385237 systemd[1]: Started sshd@7-10.200.4.32:22-10.200.16.10:35122.service - OpenSSH per-connection server daemon (10.200.16.10:35122). Jun 20 19:14:01.716932 sshd[10910]: Accepted publickey for core from 10.200.16.10 port 35122 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:57.977054 sshd-session[10910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:57.981499 systemd-logind[1893]: New session 10 of user core. Jun 20 19:14:01.717528 sshd[10912]: Connection closed by 10.200.16.10 port 35122 Jun 20 19:14:01.717609 sshd[10918]: Accepted publickey for core from 10.200.16.10 port 35126 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:58.392803 sshd-session[10910]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:57.992015 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 20 19:14:01.718409 sshd[10920]: Connection closed by 10.200.16.10 port 35126 Jun 20 19:13:59.114402 sshd-session[10918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:58.396296 systemd[1]: sshd@7-10.200.4.32:22-10.200.16.10:35122.service: Deactivated successfully. Jun 20 19:13:59.533330 sshd-session[10918]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:58.397869 systemd[1]: session-10.scope: Deactivated successfully. Jun 20 19:13:58.398560 systemd-logind[1893]: Session 10 logged out. Waiting for processes to exit. Jun 20 19:13:58.399502 systemd-logind[1893]: Removed session 10. Jun 20 19:13:58.500904 systemd[1]: Started sshd@8-10.200.4.32:22-10.200.16.10:35126.service - OpenSSH per-connection server daemon (10.200.16.10:35126). Jun 20 19:13:59.118690 systemd-logind[1893]: New session 11 of user core. Jun 20 19:13:59.129015 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 20 19:13:59.536122 systemd[1]: sshd@8-10.200.4.32:22-10.200.16.10:35126.service: Deactivated successfully. Jun 20 19:13:59.537777 systemd[1]: session-11.scope: Deactivated successfully. Jun 20 19:13:59.538496 systemd-logind[1893]: Session 11 logged out. Waiting for processes to exit. Jun 20 19:13:59.539511 systemd-logind[1893]: Removed session 11. Jun 20 19:14:09.643047 systemd[1]: Started sshd@9-10.200.4.32:22-10.200.16.10:59636.service - OpenSSH per-connection server daemon (10.200.16.10:59636). Jun 20 19:14:10.257065 sshd[11605]: Accepted publickey for core from 10.200.16.10 port 59636 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:10.258101 sshd-session[11605]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:10.266366 systemd-logind[1893]: New session 12 of user core. Jun 20 19:14:10.274975 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 20 19:14:10.684132 sshd[11653]: Connection closed by 10.200.16.10 port 59636 Jun 20 19:14:10.684527 sshd-session[11605]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:10.688196 systemd[1]: sshd@9-10.200.4.32:22-10.200.16.10:59636.service: Deactivated successfully. Jun 20 19:14:10.690329 systemd-logind[1893]: Session 12 logged out. Waiting for processes to exit. Jun 20 19:14:10.691132 systemd[1]: session-12.scope: Deactivated successfully. Jun 20 19:14:10.694804 systemd-logind[1893]: Removed session 12. Jun 20 19:14:10.793255 systemd[1]: Started sshd@10-10.200.4.32:22-10.200.16.10:59652.service - OpenSSH per-connection server daemon (10.200.16.10:59652). Jun 20 19:14:11.415003 sshd[11671]: Accepted publickey for core from 10.200.16.10 port 59652 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:11.416418 sshd-session[11671]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:11.424451 systemd-logind[1893]: New session 13 of user core. Jun 20 19:14:11.431103 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 20 19:14:11.847177 sshd[11685]: Connection closed by 10.200.16.10 port 59652 Jun 20 19:14:11.853259 sshd-session[11671]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:11.856309 systemd[1]: sshd@10-10.200.4.32:22-10.200.16.10:59652.service: Deactivated successfully. Jun 20 19:14:11.859609 systemd[1]: session-13.scope: Deactivated successfully. Jun 20 19:14:11.862168 systemd-logind[1893]: Session 13 logged out. Waiting for processes to exit. Jun 20 19:14:11.865253 systemd-logind[1893]: Removed session 13. Jun 20 19:14:21.960253 systemd[1]: Started sshd@11-10.200.4.32:22-10.200.16.10:45446.service - OpenSSH per-connection server daemon (10.200.16.10:45446). Jun 20 19:14:22.571860 sshd[12021]: Accepted publickey for core from 10.200.16.10 port 45446 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:22.573158 sshd-session[12021]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:22.583347 systemd-logind[1893]: New session 14 of user core. Jun 20 19:14:22.589025 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 20 19:14:23.003092 sshd[12053]: Connection closed by 10.200.16.10 port 45446 Jun 20 19:14:23.003714 sshd-session[12021]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:23.007271 systemd[1]: sshd@11-10.200.4.32:22-10.200.16.10:45446.service: Deactivated successfully. Jun 20 19:14:23.008968 systemd[1]: session-14.scope: Deactivated successfully. Jun 20 19:14:23.010484 systemd-logind[1893]: Session 14 logged out. Waiting for processes to exit. Jun 20 19:14:23.014424 systemd-logind[1893]: Removed session 14. Jun 20 19:14:23.108482 systemd[1]: Started sshd@12-10.200.4.32:22-10.200.16.10:45454.service - OpenSSH per-connection server daemon (10.200.16.10:45454). Jun 20 19:14:23.722959 sshd[12065]: Accepted publickey for core from 10.200.16.10 port 45454 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:23.723791 sshd-session[12065]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:23.729963 systemd-logind[1893]: New session 15 of user core. Jun 20 19:14:23.739978 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 20 19:14:24.147945 sshd[12092]: Connection closed by 10.200.16.10 port 45454 Jun 20 19:14:24.148062 sshd-session[12065]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:24.151498 systemd-logind[1893]: Session 15 logged out. Waiting for processes to exit. Jun 20 19:14:24.153051 systemd[1]: sshd@12-10.200.4.32:22-10.200.16.10:45454.service: Deactivated successfully. Jun 20 19:14:24.154630 systemd[1]: session-15.scope: Deactivated successfully. Jun 20 19:14:24.157294 systemd-logind[1893]: Removed session 15. Jun 20 19:14:34.260564 systemd[1]: Started sshd@13-10.200.4.32:22-10.200.16.10:45250.service - OpenSSH per-connection server daemon (10.200.16.10:45250). Jun 20 19:14:34.957528 sshd[12441]: Accepted publickey for core from 10.200.16.10 port 45250 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:34.958926 sshd-session[12441]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:34.963178 systemd-logind[1893]: New session 16 of user core. Jun 20 19:14:34.975005 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 20 19:14:35.381116 sshd[12480]: Connection closed by 10.200.16.10 port 45250 Jun 20 19:14:35.381457 sshd-session[12441]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:35.385224 systemd[1]: sshd@13-10.200.4.32:22-10.200.16.10:45250.service: Deactivated successfully. Jun 20 19:14:35.386819 systemd[1]: session-16.scope: Deactivated successfully. Jun 20 19:14:35.387525 systemd-logind[1893]: Session 16 logged out. Waiting for processes to exit. Jun 20 19:14:35.388470 systemd-logind[1893]: Removed session 16. Jun 20 19:14:35.509477 systemd[1]: Started sshd@14-10.200.4.32:22-10.200.16.10:45262.service - OpenSSH per-connection server daemon (10.200.16.10:45262). Jun 20 19:14:36.106640 sshd[12486]: Accepted publickey for core from 10.200.16.10 port 45262 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:36.107991 sshd-session[12486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:36.112398 systemd-logind[1893]: New session 17 of user core. Jun 20 19:14:36.126001 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 20 19:14:36.527129 sshd[12488]: Connection closed by 10.200.16.10 port 45262 Jun 20 19:14:36.527494 sshd-session[12486]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:36.531045 systemd[1]: sshd@14-10.200.4.32:22-10.200.16.10:45262.service: Deactivated successfully. Jun 20 19:14:36.532927 systemd[1]: session-17.scope: Deactivated successfully. Jun 20 19:14:36.533593 systemd-logind[1893]: Session 17 logged out. Waiting for processes to exit. Jun 20 19:14:36.534581 systemd-logind[1893]: Removed session 17. Jun 20 19:14:43.606501 setup-nvidia[2271]: \u000d Jun 20 19:14:43.606501 setup-nvidia[2271]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jun 20 19:14:43.606501 setup-nvidia[2271]: frequently when this kernel module was built against the wrong or Jun 20 19:14:43.606501 setup-nvidia[2271]: improperly configured kernel sources, with a version of gcc that differs Jun 20 19:14:43.606501 setup-nvidia[2271]: from the one used to build the target kernel, or if another driver, such Jun 20 19:14:43.606501 setup-nvidia[2271]: as nouveau, is present and prevents the NVIDIA kernel module from Jun 20 19:14:43.606501 setup-nvidia[2271]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jun 20 19:14:43.606501 setup-nvidia[2271]: installed in this system is supported by this NVIDIA Linux graphics Jun 20 19:14:43.606501 setup-nvidia[2271]: driver release. Jun 20 19:14:43.606501 setup-nvidia[2271]: \u000d Jun 20 19:14:43.606501 setup-nvidia[2271]: Please see the log entries 'Kernel module load error' and 'Kernel Jun 20 19:14:43.606501 setup-nvidia[2271]: messages' at the end of the file Jun 20 19:14:43.606501 setup-nvidia[2271]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jun 20 19:14:43.606501 setup-nvidia[2271]: information. Jun 20 19:14:43.606501 setup-nvidia[2271]: \u000d Jun 20 19:14:43.621570 setup-nvidia[2271]: dmesg: read kernel buffer failed: Operation not permitted Jun 20 19:14:43.639922 setup-nvidia[2271]: \u000d Jun 20 19:14:43.639922 setup-nvidia[2271]: ERROR: Installation has failed. Please see the file Jun 20 19:14:43.639922 setup-nvidia[2271]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jun 20 19:14:43.639922 setup-nvidia[2271]: details. You may find suggestions on fixing installation problems in Jun 20 19:14:43.639922 setup-nvidia[2271]: the README available on the Linux driver download page at Jun 20 19:14:43.639922 setup-nvidia[2271]: www.nvidia.com. Jun 20 19:14:43.639922 setup-nvidia[2271]: \u000d Jun 20 19:14:43.640260 setup-nvidia[2271]: Last 50 lines of nvidia-installer.log: Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:43.641066 setup-nvidia[2271]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jun 20 19:14:43.641066 setup-nvidia[2271]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jun 20 19:14:43.641066 setup-nvidia[2271]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jun 20 19:14:43.641066 setup-nvidia[2271]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jun 20 19:14:43.641066 setup-nvidia[2271]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jun 20 19:14:43.641066 setup-nvidia[2271]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jun 20 19:14:43.641066 setup-nvidia[2271]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 19:14:43.641066 setup-nvidia[2271]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jun 20 19:14:43.641066 setup-nvidia[2271]: make[2]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/build' Jun 20 19:14:43.641066 setup-nvidia[2271]: make[1]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/source' Jun 20 19:14:43.641066 setup-nvidia[2271]: -> done. Jun 20 19:14:43.641066 setup-nvidia[2271]: -> Kernel module compilation complete. Jun 20 19:14:43.641066 setup-nvidia[2271]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jun 20 19:14:43.642187 setup-nvidia[2271]: 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. Jun 20 19:14:43.642187 setup-nvidia[2271]: \u000d Jun 20 19:14:43.642187 setup-nvidia[2271]: 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. Jun 20 19:14:43.642187 setup-nvidia[2271]: -> Kernel module load error: Operation not permitted Jun 20 19:14:43.642187 setup-nvidia[2271]: -> Kernel messages: Jun 20 19:14:43.642187 setup-nvidia[2271]: 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. Jun 20 19:14:46.639947 systemd[1]: Started sshd@15-10.200.4.32:22-10.200.16.10:47344.service - OpenSSH per-connection server daemon (10.200.16.10:47344). Jun 20 19:14:47.247984 sshd[12674]: Accepted publickey for core from 10.200.16.10 port 47344 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:47.249303 sshd-session[12674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:47.253557 systemd-logind[1893]: New session 18 of user core. Jun 20 19:14:47.263035 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 20 19:14:47.665099 sshd[12676]: Connection closed by 10.200.16.10 port 47344 Jun 20 19:14:47.665369 sshd-session[12674]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:47.669515 systemd[1]: sshd@15-10.200.4.32:22-10.200.16.10:47344.service: Deactivated successfully. Jun 20 19:14:47.671385 systemd[1]: session-18.scope: Deactivated successfully. Jun 20 19:14:47.672200 systemd-logind[1893]: Session 18 logged out. Waiting for processes to exit. Jun 20 19:14:47.673194 systemd-logind[1893]: Removed session 18. Jun 20 19:14:47.775231 systemd[1]: Started sshd@16-10.200.4.32:22-10.200.16.10:47348.service - OpenSSH per-connection server daemon (10.200.16.10:47348). Jun 20 19:14:48.373104 sshd[12682]: Accepted publickey for core from 10.200.16.10 port 47348 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:48.374379 sshd-session[12682]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:48.378750 systemd-logind[1893]: New session 19 of user core. Jun 20 19:14:48.400013 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 20 19:14:48.800588 sshd[12684]: Connection closed by 10.200.16.10 port 47348 Jun 20 19:14:48.800955 sshd-session[12682]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:48.804352 systemd[1]: sshd@16-10.200.4.32:22-10.200.16.10:47348.service: Deactivated successfully. Jun 20 19:14:48.806133 systemd[1]: session-19.scope: Deactivated successfully. Jun 20 19:14:48.806805 systemd-logind[1893]: Session 19 logged out. Waiting for processes to exit. Jun 20 19:14:48.807833 systemd-logind[1893]: Removed session 19. Jun 20 19:14:57.887913 kernel: EXT4-fs (loop6p9): unmounting filesystem c16469d9-dda3-462c-ac5d-9d8b0405d9ca. Jun 20 19:14:57.888116 setup-nvidia[2271]: Container flatcardevelopercontainer-4344.1.0.bin exited successfully. Jun 20 19:14:57.890509 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.1.0.bin.mount: Deactivated successfully. Jun 20 19:14:57.935692 sudo[2259]: pam_unix(sudo:session): session closed for user root Jun 20 19:14:57.937642 systemd[1]: session-c2.scope: Deactivated successfully. Jun 20 19:14:57.937908 systemd[1]: session-c2.scope: Consumed 5min 6.642s CPU time, 892.7M memory peak. Jun 20 19:14:58.357904 setup-nvidia[1884]: /opt/nvidia / Jun 20 19:14:58.358928 setup-nvidia[1884]: / Jun 20 19:14:58.368911 kernel: IPMI message handler: version 39.2 Jun 20 19:14:58.371905 kernel: ipmi device interface Jun 20 19:14:58.384200 setup-nvidia[1884]: /opt/nvidia/current/usr/lib/modules/6.12.34-flatcar/video / Jun 20 19:14:58.440273 kernel: nvidia: loading out-of-tree module taints kernel. Jun 20 19:14:58.440483 kernel: nvidia: module license 'NVIDIA' taints kernel. Jun 20 19:14:58.440562 kernel: Disabling lock debugging due to kernel taint Jun 20 19:14:58.447745 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jun 20 19:14:58.447786 kernel: nvidia: module license taints kernel. Jun 20 19:14:58.834920 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jun 20 19:14:59.236695 kernel: Jun 20 19:14:59.236832 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jun 20 19:14:59.251959 systemd[1]: Started sshd@17-10.200.4.32:22-10.200.16.10:60690.service - OpenSSH per-connection server daemon (10.200.16.10:60690). Jun 20 19:14:59.295909 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jun 20 19:14:59.316926 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jun 20 19:14:59.338927 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jun 20 19:14:59.386658 setup-nvidia[1884]: / Jun 20 19:14:59.387133 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jun 20 19:14:59.401577 setup-nvidia[12727]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 19:14:59.849857 sshd[12718]: Accepted publickey for core from 10.200.16.10 port 60690 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:59.851202 sshd-session[12718]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:59.855556 systemd-logind[1893]: New session 20 of user core. Jun 20 19:14:59.864004 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 20 19:15:00.271597 sshd[12748]: Connection closed by 10.200.16.10 port 60690 Jun 20 19:15:00.271962 sshd-session[12718]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:00.275319 systemd[1]: sshd@17-10.200.4.32:22-10.200.16.10:60690.service: Deactivated successfully. Jun 20 19:15:00.276910 systemd[1]: session-20.scope: Deactivated successfully. Jun 20 19:15:00.277590 systemd-logind[1893]: Session 20 logged out. Waiting for processes to exit. Jun 20 19:15:00.278563 systemd-logind[1893]: Removed session 20. Jun 20 19:15:00.387995 systemd[1]: Started sshd@18-10.200.4.32:22-10.200.16.10:60706.service - OpenSSH per-connection server daemon (10.200.16.10:60706). Jun 20 19:15:01.002050 sshd[12754]: Accepted publickey for core from 10.200.16.10 port 60706 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:01.003433 sshd-session[12754]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:01.007923 systemd-logind[1893]: New session 21 of user core. Jun 20 19:15:01.015003 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 20 19:15:01.015892 setup-nvidia[12744]: Fri Jun 20 19:15:01 2025 Jun 20 19:15:01.015892 setup-nvidia[12744]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:01.015892 setup-nvidia[12744]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jun 20 19:15:01.015892 setup-nvidia[12744]: |-----------------------------------------+----------------------+----------------------+ Jun 20 19:15:01.015892 setup-nvidia[12744]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jun 20 19:15:01.015892 setup-nvidia[12744]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jun 20 19:15:01.015892 setup-nvidia[12744]: | | | MIG M. | Jun 20 19:15:01.015892 setup-nvidia[12744]: |=========================================+======================+======================| Jun 20 19:15:01.019436 setup-nvidia[12744]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jun 20 19:15:01.019436 setup-nvidia[12744]: | N/A 30C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Jun 20 19:15:01.019436 setup-nvidia[12744]: | | | N/A | Jun 20 19:15:01.019436 setup-nvidia[12744]: +-----------------------------------------+----------------------+----------------------+ Jun 20 19:15:01.019436 setup-nvidia[12744]: Jun 20 19:15:01.019436 setup-nvidia[12744]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:01.019436 setup-nvidia[12744]: | Processes: | Jun 20 19:15:01.019436 setup-nvidia[12744]: | GPU GI CI PID Type Process name GPU Memory | Jun 20 19:15:01.019436 setup-nvidia[12744]: | ID ID Usage | Jun 20 19:15:01.019436 setup-nvidia[12744]: |=======================================================================================| Jun 20 19:15:01.019667 setup-nvidia[12744]: | No running processes found | Jun 20 19:15:01.019667 setup-nvidia[12744]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:01.389536 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 20 19:15:01.391623 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 20 19:15:01.392020 systemd[1]: Startup finished in 1.045s (firmware) + 26.050s (loader) + 3.929s (kernel) + 11.661s (initrd) + 3min 2.382s (userspace) = 3min 45.070s. Jun 20 19:15:01.421974 sshd[12756]: Connection closed by 10.200.16.10 port 60706 Jun 20 19:15:01.422319 sshd-session[12754]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:01.425297 systemd[1]: sshd@18-10.200.4.32:22-10.200.16.10:60706.service: Deactivated successfully. Jun 20 19:15:01.426802 systemd[1]: session-21.scope: Deactivated successfully. Jun 20 19:15:01.427487 systemd-logind[1893]: Session 21 logged out. Waiting for processes to exit. Jun 20 19:15:01.428496 systemd-logind[1893]: Removed session 21. Jun 20 19:15:08.182704 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jun 20 19:15:08.183502 systemd[2262]: Activating special unit exit.target... Jun 20 19:15:08.183587 systemd[2262]: Stopped target default.target - Main User Target. Jun 20 19:15:08.183626 systemd[2262]: Stopped target basic.target - Basic System. Jun 20 19:15:08.183654 systemd[2262]: Stopped target paths.target - Paths. Jun 20 19:15:08.183679 systemd[2262]: Stopped target sockets.target - Sockets. Jun 20 19:15:08.183704 systemd[2262]: Stopped target timers.target - Timers. Jun 20 19:15:08.184064 systemd[2262]: Closed dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:15:08.184282 systemd[2262]: Removed slice app.slice - User Application Slice. Jun 20 19:15:08.184317 systemd[2262]: Reached target shutdown.target - Shutdown. Jun 20 19:15:08.184460 systemd[2262]: Finished systemd-exit.service - Exit the Session. Jun 20 19:15:08.184496 systemd[2262]: Reached target exit.target - Exit the Session. Jun 20 19:15:08.196924 (sd-pam)[2263]: pam_unix(systemd-user:session): session closed for user root Jun 20 19:15:08.198267 systemd[1]: user@0.service: Deactivated successfully. Jun 20 19:15:08.198616 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jun 20 19:15:08.200905 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 19:15:08.223795 systemd[1]: run-user-0.mount: Deactivated successfully. Jun 20 19:15:08.224700 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jun 20 19:15:08.224895 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 19:15:08.226401 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jun 20 19:15:08.226499 systemd[1]: user-0.slice: Consumed 5min 6.897s CPU time, 901.7M memory peak. Jun 20 19:15:08.226914 systemd-logind[1893]: Removed session c3. Jun 20 19:15:11.528444 systemd[1]: Started sshd@19-10.200.4.32:22-10.200.16.10:58350.service - OpenSSH per-connection server daemon (10.200.16.10:58350). Jun 20 19:15:12.123302 sshd[12768]: Accepted publickey for core from 10.200.16.10 port 58350 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:12.124598 sshd-session[12768]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:12.128829 systemd-logind[1893]: New session 22 of user core. Jun 20 19:15:12.144010 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 20 19:15:12.541710 sshd[12770]: Connection closed by 10.200.16.10 port 58350 Jun 20 19:15:12.542105 sshd-session[12768]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:12.545536 systemd[1]: sshd@19-10.200.4.32:22-10.200.16.10:58350.service: Deactivated successfully. Jun 20 19:15:12.547152 systemd[1]: session-22.scope: Deactivated successfully. Jun 20 19:15:12.547824 systemd-logind[1893]: Session 22 logged out. Waiting for processes to exit. Jun 20 19:15:12.548781 systemd-logind[1893]: Removed session 22. Jun 20 19:15:12.650330 systemd[1]: Started sshd@20-10.200.4.32:22-10.200.16.10:58366.service - OpenSSH per-connection server daemon (10.200.16.10:58366). Jun 20 19:15:13.239982 sshd[12776]: Accepted publickey for core from 10.200.16.10 port 58366 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:13.241260 sshd-session[12776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:13.245465 systemd-logind[1893]: New session 23 of user core. Jun 20 19:15:13.256017 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 20 19:15:13.652501 sshd[12778]: Connection closed by 10.200.16.10 port 58366 Jun 20 19:15:13.652727 sshd-session[12776]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:13.656006 systemd[1]: sshd@20-10.200.4.32:22-10.200.16.10:58366.service: Deactivated successfully. Jun 20 19:15:13.657575 systemd[1]: session-23.scope: Deactivated successfully. Jun 20 19:15:13.658254 systemd-logind[1893]: Session 23 logged out. Waiting for processes to exit. Jun 20 19:15:13.659200 systemd-logind[1893]: Removed session 23. Jun 20 19:15:13.761955 systemd[1]: Started sshd@21-10.200.4.32:22-10.200.16.10:58382.service - OpenSSH per-connection server daemon (10.200.16.10:58382). Jun 20 19:15:14.378255 sshd[12784]: Accepted publickey for core from 10.200.16.10 port 58382 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:14.379604 sshd-session[12784]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:14.383851 systemd-logind[1893]: New session 24 of user core. Jun 20 19:15:14.392006 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 20 19:15:14.797471 sshd[12786]: Connection closed by 10.200.16.10 port 58382 Jun 20 19:15:14.797810 sshd-session[12784]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:14.801081 systemd[1]: sshd@21-10.200.4.32:22-10.200.16.10:58382.service: Deactivated successfully. Jun 20 19:15:14.802744 systemd[1]: session-24.scope: Deactivated successfully. Jun 20 19:15:14.803429 systemd-logind[1893]: Session 24 logged out. Waiting for processes to exit. Jun 20 19:15:14.804378 systemd-logind[1893]: Removed session 24. Jun 20 19:15:14.906146 systemd[1]: Started sshd@22-10.200.4.32:22-10.200.16.10:58392.service - OpenSSH per-connection server daemon (10.200.16.10:58392). Jun 20 19:15:15.505091 sshd[12792]: Accepted publickey for core from 10.200.16.10 port 58392 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:15.506421 sshd-session[12792]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:15.510737 systemd-logind[1893]: New session 25 of user core. Jun 20 19:15:15.522006 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 20 19:15:15.927978 sshd[12794]: Connection closed by 10.200.16.10 port 58392 Jun 20 19:15:15.928272 sshd-session[12792]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:15.931800 systemd[1]: sshd@22-10.200.4.32:22-10.200.16.10:58392.service: Deactivated successfully. Jun 20 19:15:15.933423 systemd[1]: session-25.scope: Deactivated successfully. Jun 20 19:15:15.934106 systemd-logind[1893]: Session 25 logged out. Waiting for processes to exit. Jun 20 19:15:15.935112 systemd-logind[1893]: Removed session 25. Jun 20 19:15:16.037164 systemd[1]: Started sshd@23-10.200.4.32:22-10.200.16.10:58404.service - OpenSSH per-connection server daemon (10.200.16.10:58404). Jun 20 19:15:16.657672 sshd[12800]: Accepted publickey for core from 10.200.16.10 port 58404 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:16.659520 sshd-session[12800]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:16.663720 systemd-logind[1893]: New session 26 of user core. Jun 20 19:15:16.677021 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 20 19:15:18.530576 sshd[12802]: Connection closed by 10.200.16.10 port 58404 Jun 20 19:15:18.530977 sshd-session[12800]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:18.534510 systemd[1]: sshd@23-10.200.4.32:22-10.200.16.10:58404.service: Deactivated successfully. Jun 20 19:15:18.536172 systemd[1]: session-26.scope: Deactivated successfully. Jun 20 19:15:18.536373 systemd[1]: session-26.scope: Consumed 1.395s CPU time, 14M memory peak. Jun 20 19:15:18.536847 systemd-logind[1893]: Session 26 logged out. Waiting for processes to exit. Jun 20 19:15:18.537796 systemd-logind[1893]: Removed session 26.