Jun 21 04:42:52.258310 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 23:59:04 -00 2025 Jun 21 04:42:52.258343 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=d3c0be6f64121476b0313f5d7d7bbd73e21bc1a219aacd38b8006b291898eca1 Jun 21 04:42:52.258352 kernel: BIOS-provided physical RAM map: Jun 21 04:42:52.258359 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 21 04:42:52.258365 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jun 21 04:42:52.258372 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jun 21 04:42:52.258380 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jun 21 04:42:52.258389 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jun 21 04:42:52.258396 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jun 21 04:42:52.258403 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jun 21 04:42:52.258410 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jun 21 04:42:52.258416 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jun 21 04:42:52.258423 kernel: printk: legacy bootconsole [earlyser0] enabled Jun 21 04:42:52.258430 kernel: NX (Execute Disable) protection: active Jun 21 04:42:52.258441 kernel: APIC: Static calls initialized Jun 21 04:42:52.258448 kernel: efi: EFI v2.7 by Microsoft Jun 21 04:42:52.258460 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jun 21 04:42:52.258468 kernel: random: crng init done Jun 21 04:42:52.258475 kernel: secureboot: Secure boot disabled Jun 21 04:42:52.258483 kernel: SMBIOS 3.1.0 present. Jun 21 04:42:52.258491 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jun 21 04:42:52.258498 kernel: DMI: Memory slots populated: 3/3 Jun 21 04:42:52.258506 kernel: Hypervisor detected: Microsoft Hyper-V Jun 21 04:42:52.258513 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jun 21 04:42:52.258523 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jun 21 04:42:52.258531 kernel: Hyper-V: Using hypercall for remote TLB flush Jun 21 04:42:52.258538 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 21 04:42:52.258546 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 21 04:42:52.258554 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jun 21 04:42:52.258562 kernel: tsc: Detected 2593.993 MHz processor Jun 21 04:42:52.258570 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 21 04:42:52.258578 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 21 04:42:52.258586 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jun 21 04:42:52.258596 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jun 21 04:42:52.258604 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 21 04:42:52.258611 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jun 21 04:42:52.258619 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jun 21 04:42:52.258626 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jun 21 04:42:52.258634 kernel: Using GB pages for direct mapping Jun 21 04:42:52.258642 kernel: ACPI: Early table checksum verification disabled Jun 21 04:42:52.258653 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jun 21 04:42:52.258668 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258676 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258684 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jun 21 04:42:52.258692 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jun 21 04:42:52.258700 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258709 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258720 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258728 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258736 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258744 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258752 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 21 04:42:52.258760 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jun 21 04:42:52.258768 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jun 21 04:42:52.258776 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jun 21 04:42:52.258786 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jun 21 04:42:52.258795 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jun 21 04:42:52.258802 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jun 21 04:42:52.258811 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jun 21 04:42:52.258819 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jun 21 04:42:52.258827 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jun 21 04:42:52.258835 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jun 21 04:42:52.258853 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jun 21 04:42:52.258862 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jun 21 04:42:52.258873 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jun 21 04:42:52.258881 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jun 21 04:42:52.258889 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jun 21 04:42:52.258898 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jun 21 04:42:52.258906 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jun 21 04:42:52.258914 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jun 21 04:42:52.258922 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jun 21 04:42:52.258930 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jun 21 04:42:52.258938 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jun 21 04:42:52.258949 kernel: Zone ranges: Jun 21 04:42:52.258957 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 21 04:42:52.258965 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 21 04:42:52.258973 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jun 21 04:42:52.258981 kernel: Device empty Jun 21 04:42:52.258989 kernel: Movable zone start for each node Jun 21 04:42:52.258997 kernel: Early memory node ranges Jun 21 04:42:52.259005 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 21 04:42:52.259020 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jun 21 04:42:52.259032 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jun 21 04:42:52.259040 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jun 21 04:42:52.259048 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jun 21 04:42:52.259056 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jun 21 04:42:52.259064 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 21 04:42:52.259072 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 21 04:42:52.259080 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jun 21 04:42:52.259088 kernel: ACPI: PM-Timer IO Port: 0x408 Jun 21 04:42:52.259096 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jun 21 04:42:52.259107 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jun 21 04:42:52.259115 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 21 04:42:52.259123 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 21 04:42:52.259131 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jun 21 04:42:52.259139 kernel: CPU topo: Max. logical packages: 1 Jun 21 04:42:52.259147 kernel: CPU topo: Max. logical dies: 1 Jun 21 04:42:52.259155 kernel: CPU topo: Max. dies per package: 1 Jun 21 04:42:52.259163 kernel: CPU topo: Max. threads per core: 1 Jun 21 04:42:52.259171 kernel: CPU topo: Num. cores per package: 6 Jun 21 04:42:52.259181 kernel: CPU topo: Num. threads per package: 6 Jun 21 04:42:52.259189 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jun 21 04:42:52.259197 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jun 21 04:42:52.259205 kernel: Booting paravirtualized kernel on Hyper-V Jun 21 04:42:52.259213 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 21 04:42:52.259222 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jun 21 04:42:52.259230 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jun 21 04:42:52.259238 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jun 21 04:42:52.259245 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jun 21 04:42:52.259256 kernel: Hyper-V: PV spinlocks enabled Jun 21 04:42:52.259264 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 21 04:42:52.259273 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=d3c0be6f64121476b0313f5d7d7bbd73e21bc1a219aacd38b8006b291898eca1 Jun 21 04:42:52.259282 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 21 04:42:52.259290 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jun 21 04:42:52.259298 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jun 21 04:42:52.259306 kernel: Fallback order for Node 0: 0 Jun 21 04:42:52.259314 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jun 21 04:42:52.259325 kernel: Policy zone: Normal Jun 21 04:42:52.259341 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 21 04:42:52.259350 kernel: software IO TLB: area num 8. Jun 21 04:42:52.259361 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jun 21 04:42:52.259370 kernel: Kernel/User page tables isolation: enabled Jun 21 04:42:52.259378 kernel: ftrace: allocating 40093 entries in 157 pages Jun 21 04:42:52.259386 kernel: ftrace: allocated 157 pages with 5 groups Jun 21 04:42:52.259395 kernel: Dynamic Preempt: voluntary Jun 21 04:42:52.259403 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 21 04:42:52.259412 kernel: rcu: RCU event tracing is enabled. Jun 21 04:42:52.259421 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jun 21 04:42:52.259430 kernel: Trampoline variant of Tasks RCU enabled. Jun 21 04:42:52.259441 kernel: Rude variant of Tasks RCU enabled. Jun 21 04:42:52.259449 kernel: Tracing variant of Tasks RCU enabled. Jun 21 04:42:52.259458 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 21 04:42:52.259466 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jun 21 04:42:52.259474 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 21 04:42:52.259486 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 21 04:42:52.259494 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 21 04:42:52.259503 kernel: Using NULL legacy PIC Jun 21 04:42:52.259511 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jun 21 04:42:52.259520 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 21 04:42:52.259528 kernel: Console: colour dummy device 80x25 Jun 21 04:42:52.259537 kernel: printk: legacy console [tty1] enabled Jun 21 04:42:52.259545 kernel: printk: legacy console [ttyS0] enabled Jun 21 04:42:52.259554 kernel: printk: legacy bootconsole [earlyser0] disabled Jun 21 04:42:52.259565 kernel: ACPI: Core revision 20240827 Jun 21 04:42:52.259573 kernel: Failed to register legacy timer interrupt Jun 21 04:42:52.259581 kernel: APIC: Switch to symmetric I/O mode setup Jun 21 04:42:52.259590 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jun 21 04:42:52.259598 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jun 21 04:42:52.259607 kernel: Hyper-V: Using IPI hypercalls Jun 21 04:42:52.259615 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jun 21 04:42:52.259624 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jun 21 04:42:52.259632 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jun 21 04:42:52.259644 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jun 21 04:42:52.259652 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jun 21 04:42:52.259661 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jun 21 04:42:52.259669 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jun 21 04:42:52.259677 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jun 21 04:42:52.259686 kernel: APIC: read() replaced with hv_apic_read() Jun 21 04:42:52.259694 kernel: APIC: write() replaced with hv_apic_write() Jun 21 04:42:52.259703 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jun 21 04:42:52.259711 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jun 21 04:42:52.259722 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Jun 21 04:42:52.259731 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jun 21 04:42:52.259739 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jun 21 04:42:52.259748 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 21 04:42:52.259756 kernel: Spectre V2 : Mitigation: Retpolines Jun 21 04:42:52.259764 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 21 04:42:52.259773 kernel: Speculative Store Bypass: Vulnerable Jun 21 04:42:52.259781 kernel: MDS: Mitigation: Clear CPU buffers Jun 21 04:42:52.259789 kernel: TAA: Mitigation: Clear CPU buffers Jun 21 04:42:52.259797 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jun 21 04:42:52.259808 kernel: ITS: Mitigation: Aligned branch/return thunks Jun 21 04:42:52.259817 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 21 04:42:52.259825 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 21 04:42:52.259841 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 21 04:42:52.259860 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 21 04:42:52.259869 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 21 04:42:52.259877 kernel: Freeing SMP alternatives memory: 32K Jun 21 04:42:52.259886 kernel: pid_max: default: 32768 minimum: 301 Jun 21 04:42:52.259894 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jun 21 04:42:52.259902 kernel: landlock: Up and running. Jun 21 04:42:52.259910 kernel: SELinux: Initializing. Jun 21 04:42:52.259923 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 21 04:42:52.259931 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 21 04:42:52.259940 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jun 21 04:42:52.259949 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jun 21 04:42:52.259957 kernel: signal: max sigframe size: 1776 Jun 21 04:42:52.259966 kernel: rcu: Hierarchical SRCU implementation. Jun 21 04:42:52.259974 kernel: rcu: Max phase no-delay instances is 400. Jun 21 04:42:52.259983 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 21 04:42:52.259992 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 21 04:42:52.260000 kernel: smp: Bringing up secondary CPUs ... Jun 21 04:42:52.260011 kernel: smpboot: x86: Booting SMP configuration: Jun 21 04:42:52.260020 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jun 21 04:42:52.260028 kernel: smp: Brought up 1 node, 6 CPUs Jun 21 04:42:52.260037 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Jun 21 04:42:52.260046 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54424K init, 2544K bss, 2090000K reserved, 0K cma-reserved) Jun 21 04:42:52.260054 kernel: devtmpfs: initialized Jun 21 04:42:52.260063 kernel: x86/mm: Memory block size: 1024MB Jun 21 04:42:52.260071 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jun 21 04:42:52.260080 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 21 04:42:52.260091 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jun 21 04:42:52.260100 kernel: pinctrl core: initialized pinctrl subsystem Jun 21 04:42:52.260108 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 21 04:42:52.260117 kernel: audit: initializing netlink subsys (disabled) Jun 21 04:42:52.260125 kernel: audit: type=2000 audit(1750480968.029:1): state=initialized audit_enabled=0 res=1 Jun 21 04:42:52.260134 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 21 04:42:52.260142 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 21 04:42:52.260151 kernel: cpuidle: using governor menu Jun 21 04:42:52.260159 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 21 04:42:52.260170 kernel: dca service started, version 1.12.1 Jun 21 04:42:52.260179 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jun 21 04:42:52.260187 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 21 04:42:52.260196 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 21 04:42:52.260204 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 21 04:42:52.260213 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 21 04:42:52.260221 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 21 04:42:52.260230 kernel: ACPI: Added _OSI(Module Device) Jun 21 04:42:52.260238 kernel: ACPI: Added _OSI(Processor Device) Jun 21 04:42:52.260249 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 21 04:42:52.260258 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 21 04:42:52.260266 kernel: ACPI: Interpreter enabled Jun 21 04:42:52.260275 kernel: ACPI: PM: (supports S0 S5) Jun 21 04:42:52.260283 kernel: ACPI: Using IOAPIC for interrupt routing Jun 21 04:42:52.260292 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 21 04:42:52.260300 kernel: PCI: Ignoring E820 reservations for host bridge windows Jun 21 04:42:52.260309 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jun 21 04:42:52.260317 kernel: iommu: Default domain type: Translated Jun 21 04:42:52.260328 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 21 04:42:52.260337 kernel: efivars: Registered efivars operations Jun 21 04:42:52.260345 kernel: PCI: Using ACPI for IRQ routing Jun 21 04:42:52.260353 kernel: PCI: System does not support PCI Jun 21 04:42:52.260362 kernel: vgaarb: loaded Jun 21 04:42:52.260370 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jun 21 04:42:52.260379 kernel: VFS: Disk quotas dquot_6.6.0 Jun 21 04:42:52.260387 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 21 04:42:52.260396 kernel: pnp: PnP ACPI init Jun 21 04:42:52.260407 kernel: pnp: PnP ACPI: found 3 devices Jun 21 04:42:52.260416 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 21 04:42:52.260424 kernel: NET: Registered PF_INET protocol family Jun 21 04:42:52.260433 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 21 04:42:52.260441 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jun 21 04:42:52.260450 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 21 04:42:52.260458 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 21 04:42:52.260467 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 21 04:42:52.260478 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jun 21 04:42:52.260487 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 21 04:42:52.260495 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 21 04:42:52.260504 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 21 04:42:52.260512 kernel: NET: Registered PF_XDP protocol family Jun 21 04:42:52.260520 kernel: PCI: CLS 0 bytes, default 64 Jun 21 04:42:52.260529 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 21 04:42:52.260537 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jun 21 04:42:52.260546 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jun 21 04:42:52.260558 kernel: Initialise system trusted keyrings Jun 21 04:42:52.260566 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jun 21 04:42:52.260574 kernel: Key type asymmetric registered Jun 21 04:42:52.260583 kernel: Asymmetric key parser 'x509' registered Jun 21 04:42:52.260591 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 21 04:42:52.260600 kernel: io scheduler mq-deadline registered Jun 21 04:42:52.260608 kernel: io scheduler kyber registered Jun 21 04:42:52.260616 kernel: io scheduler bfq registered Jun 21 04:42:52.260625 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 21 04:42:52.260636 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 21 04:42:52.260644 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 21 04:42:52.260653 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jun 21 04:42:52.260661 kernel: i8042: PNP: No PS/2 controller found. Jun 21 04:42:52.260826 kernel: rtc_cmos 00:02: registered as rtc0 Jun 21 04:42:52.260946 kernel: rtc_cmos 00:02: setting system clock to 2025-06-21T04:42:51 UTC (1750480971) Jun 21 04:42:52.261300 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jun 21 04:42:52.261318 kernel: intel_pstate: CPU model not supported Jun 21 04:42:52.261334 kernel: efifb: probing for efifb Jun 21 04:42:52.261343 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jun 21 04:42:52.261351 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jun 21 04:42:52.261360 kernel: efifb: scrolling: redraw Jun 21 04:42:52.261369 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 21 04:42:52.261377 kernel: Console: switching to colour frame buffer device 128x48 Jun 21 04:42:52.261386 kernel: fb0: EFI VGA frame buffer device Jun 21 04:42:52.261395 kernel: pstore: Using crash dump compression: deflate Jun 21 04:42:52.261403 kernel: pstore: Registered efi_pstore as persistent store backend Jun 21 04:42:52.261415 kernel: NET: Registered PF_INET6 protocol family Jun 21 04:42:52.261423 kernel: Segment Routing with IPv6 Jun 21 04:42:52.261432 kernel: In-situ OAM (IOAM) with IPv6 Jun 21 04:42:52.261440 kernel: NET: Registered PF_PACKET protocol family Jun 21 04:42:52.261449 kernel: Key type dns_resolver registered Jun 21 04:42:52.261457 kernel: IPI shorthand broadcast: enabled Jun 21 04:42:52.261466 kernel: sched_clock: Marking stable (3947005300, 110183000)->(4389473200, -332284900) Jun 21 04:42:52.261474 kernel: registered taskstats version 1 Jun 21 04:42:52.261483 kernel: Loading compiled-in X.509 certificates Jun 21 04:42:52.261494 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: ec4617d162e00e1890f71f252cdf44036a7b66f7' Jun 21 04:42:52.261503 kernel: Demotion targets for Node 0: null Jun 21 04:42:52.261511 kernel: Key type .fscrypt registered Jun 21 04:42:52.261520 kernel: Key type fscrypt-provisioning registered Jun 21 04:42:52.261528 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 21 04:42:52.261537 kernel: ima: Allocated hash algorithm: sha1 Jun 21 04:42:52.261545 kernel: ima: No architecture policies found Jun 21 04:42:52.261554 kernel: clk: Disabling unused clocks Jun 21 04:42:52.261562 kernel: Warning: unable to open an initial console. Jun 21 04:42:52.261574 kernel: Freeing unused kernel image (initmem) memory: 54424K Jun 21 04:42:52.261583 kernel: Write protecting the kernel read-only data: 24576k Jun 21 04:42:52.261591 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jun 21 04:42:52.261600 kernel: Run /init as init process Jun 21 04:42:52.261608 kernel: with arguments: Jun 21 04:42:52.261617 kernel: /init Jun 21 04:42:52.261625 kernel: with environment: Jun 21 04:42:52.261633 kernel: HOME=/ Jun 21 04:42:52.261642 kernel: TERM=linux Jun 21 04:42:52.261652 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 21 04:42:52.261662 systemd[1]: Successfully made /usr/ read-only. Jun 21 04:42:52.261674 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 21 04:42:52.261683 systemd[1]: Detected virtualization microsoft. Jun 21 04:42:52.261692 systemd[1]: Detected architecture x86-64. Jun 21 04:42:52.261701 systemd[1]: Running in initrd. Jun 21 04:42:52.261709 systemd[1]: No hostname configured, using default hostname. Jun 21 04:42:52.261722 systemd[1]: Hostname set to . Jun 21 04:42:52.261730 systemd[1]: Initializing machine ID from random generator. Jun 21 04:42:52.261739 systemd[1]: Queued start job for default target initrd.target. Jun 21 04:42:52.261748 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 04:42:52.261757 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 04:42:52.261767 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 21 04:42:52.261776 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 21 04:42:52.261785 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 21 04:42:52.261798 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 21 04:42:52.261808 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 21 04:42:52.261818 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 21 04:42:52.261827 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 04:42:52.261836 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 21 04:42:52.261859 systemd[1]: Reached target paths.target - Path Units. Jun 21 04:42:52.261868 systemd[1]: Reached target slices.target - Slice Units. Jun 21 04:42:52.261881 systemd[1]: Reached target swap.target - Swaps. Jun 21 04:42:52.261891 systemd[1]: Reached target timers.target - Timer Units. Jun 21 04:42:52.261900 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 21 04:42:52.261909 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 21 04:42:52.261918 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 21 04:42:52.261927 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 21 04:42:52.261936 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 21 04:42:52.261945 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 21 04:42:52.261954 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 04:42:52.261966 systemd[1]: Reached target sockets.target - Socket Units. Jun 21 04:42:52.261975 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 21 04:42:52.261984 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 21 04:42:52.261993 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 21 04:42:52.262003 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 21 04:42:52.262012 systemd[1]: Starting systemd-fsck-usr.service... Jun 21 04:42:52.262021 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 21 04:42:52.262030 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 21 04:42:52.262042 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 04:42:52.262051 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 21 04:42:52.262061 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 04:42:52.262070 systemd[1]: Finished systemd-fsck-usr.service. Jun 21 04:42:52.262080 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 21 04:42:52.262115 systemd-journald[233]: Collecting audit messages is disabled. Jun 21 04:42:52.262154 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:42:52.262168 systemd-journald[233]: Journal started Jun 21 04:42:52.262189 systemd-journald[233]: Runtime Journal (/run/log/journal/24cc3bd9efbb4800acf02300f82925d7) is 8M, max 2.2G, 2.1G free. Jun 21 04:42:52.258692 systemd-modules-load[234]: Inserted module 'overlay' Jun 21 04:42:52.276866 systemd[1]: Started systemd-journald.service - Journal Service. Jun 21 04:42:52.281394 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 21 04:42:52.285502 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 21 04:42:52.294503 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 21 04:42:52.319431 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 21 04:42:52.327892 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 21 04:42:52.340947 systemd-tmpfiles[253]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jun 21 04:42:52.342741 kernel: Bridge firewalling registered Jun 21 04:42:52.347340 systemd-modules-load[234]: Inserted module 'br_netfilter' Jun 21 04:42:52.349349 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 21 04:42:52.350967 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 21 04:42:52.359061 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 04:42:52.374322 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 04:42:52.389197 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 21 04:42:52.392055 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 21 04:42:52.427873 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 21 04:42:52.430227 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 21 04:42:52.447056 dracut-cmdline[269]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=d3c0be6f64121476b0313f5d7d7bbd73e21bc1a219aacd38b8006b291898eca1 Jun 21 04:42:52.495328 systemd-resolved[283]: Positive Trust Anchors: Jun 21 04:42:52.495344 systemd-resolved[283]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 21 04:42:52.495375 systemd-resolved[283]: 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 21 04:42:52.498074 systemd-resolved[283]: Defaulting to hostname 'linux'. Jun 21 04:42:52.499250 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 21 04:42:52.508798 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 21 04:42:52.610873 kernel: SCSI subsystem initialized Jun 21 04:42:52.622866 kernel: Loading iSCSI transport class v2.0-870. Jun 21 04:42:52.635867 kernel: iscsi: registered transport (tcp) Jun 21 04:42:52.659636 kernel: iscsi: registered transport (qla4xxx) Jun 21 04:42:52.659694 kernel: QLogic iSCSI HBA Driver Jun 21 04:42:52.679731 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 21 04:42:52.707989 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 04:42:52.719753 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 21 04:42:52.768271 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 21 04:42:52.771989 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 21 04:42:52.847877 kernel: raid6: avx2x4 gen() 26969 MB/s Jun 21 04:42:52.866865 kernel: raid6: avx2x2 gen() 26705 MB/s Jun 21 04:42:52.888016 kernel: raid6: avx2x1 gen() 25029 MB/s Jun 21 04:42:52.888056 kernel: raid6: using algorithm avx2x4 gen() 26969 MB/s Jun 21 04:42:52.911038 kernel: raid6: .... xor() 7935 MB/s, rmw enabled Jun 21 04:42:52.911092 kernel: raid6: using avx2x2 recovery algorithm Jun 21 04:42:52.933867 kernel: xor: automatically using best checksumming function avx Jun 21 04:42:53.097877 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 21 04:42:53.105551 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 21 04:42:53.108615 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 04:42:53.144096 systemd-udevd[484]: Using default interface naming scheme 'v255'. Jun 21 04:42:53.150227 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 04:42:53.151987 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 21 04:42:53.190706 dracut-pre-trigger[490]: rd.md=0: removing MD RAID activation Jun 21 04:42:53.219512 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 21 04:42:53.222649 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 21 04:42:53.296685 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 04:42:53.298513 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 21 04:42:53.331870 kernel: cryptd: max_cpu_qlen set to 1000 Jun 21 04:42:53.342892 kernel: AES CTR mode by8 optimization enabled Jun 21 04:42:53.346442 kernel: hv_vmbus: Vmbus version:4.0 Jun 21 04:42:53.371822 kernel: pps_core: LinuxPPS API ver. 1 registered Jun 21 04:42:53.371902 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 21 04:42:53.370867 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 21 04:42:53.371045 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:42:53.377477 kernel: hv_vmbus: registering driver hyperv_keyboard Jun 21 04:42:53.387862 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 21 04:42:53.394742 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 04:42:53.404577 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 04:42:53.439481 kernel: hv_vmbus: registering driver hv_pci Jun 21 04:42:53.439517 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jun 21 04:42:53.439731 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jun 21 04:42:53.439871 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jun 21 04:42:53.440023 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jun 21 04:42:53.440202 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jun 21 04:42:53.440324 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jun 21 04:42:53.440353 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jun 21 04:42:53.414242 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 21 04:42:53.462959 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 21 04:42:53.463018 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 21 04:42:53.463041 kernel: pci 0001:00:00.0: Enabling HDA controller Jun 21 04:42:53.445760 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 21 04:42:53.445879 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:42:53.450238 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 04:42:53.491073 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jun 21 04:42:53.491265 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jun 21 04:42:53.491442 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jun 21 04:42:53.495226 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jun 21 04:42:53.497864 kernel: PTP clock support registered Jun 21 04:42:53.497895 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 21 04:42:53.497908 kernel: hv_vmbus: registering driver hv_storvsc Jun 21 04:42:53.498863 kernel: hv_vmbus: registering driver hv_netvsc Jun 21 04:42:53.499862 kernel: scsi host1: storvsc_host_t Jun 21 04:42:53.504859 kernel: scsi host0: storvsc_host_t Jun 21 04:42:53.518874 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 21 04:42:53.524874 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 21 04:42:53.530873 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jun 21 04:42:53.533264 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:42:53.546863 kernel: hv_vmbus: registering driver hid_hyperv Jun 21 04:42:53.546906 kernel: hv_utils: Registering HyperV Utility Driver Jun 21 04:42:53.556693 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jun 21 04:42:53.556902 kernel: hv_vmbus: registering driver hv_utils Jun 21 04:42:53.556919 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jun 21 04:42:53.557860 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jun 21 04:42:53.558061 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jun 21 04:42:53.558347 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 21 04:42:53.558501 kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 21 04:42:53.558633 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jun 21 04:42:53.558760 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 21 04:42:53.558905 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#389 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:53.559032 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#197 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:53.569735 kernel: hv_utils: Heartbeat IC version 3.0 Jun 21 04:42:53.569770 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jun 21 04:42:53.569955 kernel: hv_utils: Shutdown IC version 3.2 Jun 21 04:42:53.574724 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jun 21 04:42:53.574905 kernel: hv_utils: TimeSync IC version 4.0 Jun 21 04:42:53.574997 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jun 21 04:42:54.266474 systemd-resolved[283]: Clock change detected. Flushing caches. Jun 21 04:42:54.310878 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 21 04:42:54.311096 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#389 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:54.311247 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#396 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:54.315162 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 21 04:42:54.318164 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 21 04:42:54.318543 kernel: sdb: sdb1 Jun 21 04:42:54.321846 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jun 21 04:42:54.331186 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jun 21 04:42:54.331385 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 21 04:42:54.333196 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jun 21 04:42:54.870092 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jun 21 04:42:54.901175 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jun 21 04:42:54.918954 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jun 21 04:42:54.920687 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jun 21 04:42:54.944675 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 21 04:42:54.947922 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 21 04:42:54.985166 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#159 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:55.002172 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 21 04:42:55.011745 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#176 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:55.028173 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 21 04:42:56.035780 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#238 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:42:56.046835 disk-uuid[723]: The operation has completed successfully. Jun 21 04:42:56.050697 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 21 04:42:56.766344 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 21 04:42:56.775677 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 21 04:42:56.779170 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 21 04:42:56.790542 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 21 04:42:56.800827 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 04:42:56.810319 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 21 04:42:56.819515 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 21 04:42:56.828161 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 21 04:42:56.845060 sh[889]: Success Jun 21 04:42:56.852941 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 21 04:42:56.899668 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 21 04:42:56.899722 kernel: device-mapper: uevent: version 1.0.3 Jun 21 04:42:56.904483 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jun 21 04:42:56.916161 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jun 21 04:42:57.171117 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 21 04:42:57.176188 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 21 04:42:57.199002 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 21 04:42:57.221575 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jun 21 04:42:57.221615 kernel: BTRFS: device fsid bfb8168c-5be0-428c-83e7-820ccaf1f8e9 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (914) Jun 21 04:42:57.227165 kernel: BTRFS info (device dm-0): first mount of filesystem bfb8168c-5be0-428c-83e7-820ccaf1f8e9 Jun 21 04:42:57.231449 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 21 04:42:57.234565 kernel: BTRFS info (device dm-0): using free-space-tree Jun 21 04:42:57.764316 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 21 04:42:57.771835 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jun 21 04:42:57.781203 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 21 04:42:57.790089 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 21 04:42:57.809414 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 21 04:42:57.837164 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (941) Jun 21 04:42:57.846772 kernel: BTRFS info (device sda6): first mount of filesystem 57d2b200-37a8-4067-8765-910d3ed0182c Jun 21 04:42:57.846803 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 21 04:42:57.849967 kernel: BTRFS info (device sda6): using free-space-tree Jun 21 04:42:57.884167 kernel: BTRFS info (device sda6): last unmount of filesystem 57d2b200-37a8-4067-8765-910d3ed0182c Jun 21 04:42:57.884917 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 21 04:42:57.890057 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 21 04:42:57.912272 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 21 04:42:57.915696 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 21 04:42:57.955877 systemd-networkd[1083]: lo: Link UP Jun 21 04:42:57.955889 systemd-networkd[1083]: lo: Gained carrier Jun 21 04:42:57.956938 systemd-networkd[1083]: Enumeration completed Jun 21 04:42:57.957029 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 21 04:42:57.957323 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 04:42:57.957328 systemd-networkd[1083]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 21 04:42:57.958256 systemd-networkd[1083]: eth0: Link UP Jun 21 04:42:57.958424 systemd-networkd[1083]: eth0: Gained carrier Jun 21 04:42:57.958434 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 04:42:57.958483 systemd[1]: Reached target network.target - Network. Jun 21 04:42:58.008195 systemd-networkd[1083]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 21 04:42:59.274280 systemd-networkd[1083]: eth0: Gained IPv6LL Jun 21 04:43:00.081102 ignition[1064]: Ignition 2.21.0 Jun 21 04:43:00.081122 ignition[1064]: Stage: fetch-offline Jun 21 04:43:00.083494 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 21 04:43:00.081281 ignition[1064]: no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:00.086341 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jun 21 04:43:00.081294 ignition[1064]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:00.081422 ignition[1064]: parsed url from cmdline: "" Jun 21 04:43:00.081426 ignition[1064]: no config URL provided Jun 21 04:43:00.081432 ignition[1064]: reading system config file "/usr/lib/ignition/user.ign" Jun 21 04:43:00.081441 ignition[1064]: no config at "/usr/lib/ignition/user.ign" Jun 21 04:43:00.081448 ignition[1064]: failed to fetch config: resource requires networking Jun 21 04:43:00.081633 ignition[1064]: Ignition finished successfully Jun 21 04:43:00.139762 ignition[1096]: Ignition 2.21.0 Jun 21 04:43:00.139775 ignition[1096]: Stage: fetch Jun 21 04:43:00.140063 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:00.140077 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:00.140210 ignition[1096]: parsed url from cmdline: "" Jun 21 04:43:00.140215 ignition[1096]: no config URL provided Jun 21 04:43:00.140221 ignition[1096]: reading system config file "/usr/lib/ignition/user.ign" Jun 21 04:43:00.140231 ignition[1096]: no config at "/usr/lib/ignition/user.ign" Jun 21 04:43:00.140270 ignition[1096]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jun 21 04:43:00.238675 ignition[1096]: GET result: OK Jun 21 04:43:00.238741 ignition[1096]: config has been read from IMDS userdata Jun 21 04:43:00.238774 ignition[1096]: parsing config with SHA512: 809a7a205b0fe766093964e74e342207a89dd171289bfd01896701f7ef337a713b12b371f4c120c8a4897d09d665d7ac7a0e580db03e3fae61788cda85d900d3 Jun 21 04:43:00.244867 unknown[1096]: fetched base config from "system" Jun 21 04:43:00.244880 unknown[1096]: fetched base config from "system" Jun 21 04:43:00.245904 ignition[1096]: fetch: fetch complete Jun 21 04:43:00.244946 unknown[1096]: fetched user config from "azure" Jun 21 04:43:00.245910 ignition[1096]: fetch: fetch passed Jun 21 04:43:00.250733 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jun 21 04:43:00.245974 ignition[1096]: Ignition finished successfully Jun 21 04:43:00.254678 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 21 04:43:00.297443 ignition[1106]: Ignition 2.21.0 Jun 21 04:43:00.297458 ignition[1106]: Stage: kargs Jun 21 04:43:00.297718 ignition[1106]: no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:00.297730 ignition[1106]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:00.303933 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 21 04:43:00.299426 ignition[1106]: kargs: kargs passed Jun 21 04:43:00.311785 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 21 04:43:00.300312 ignition[1106]: Ignition finished successfully Jun 21 04:43:00.352266 ignition[1116]: Ignition 2.21.0 Jun 21 04:43:00.352280 ignition[1116]: Stage: disks Jun 21 04:43:00.352542 ignition[1116]: no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:00.352554 ignition[1116]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:00.358402 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 21 04:43:00.355187 ignition[1116]: disks: disks passed Jun 21 04:43:00.361362 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 21 04:43:00.355262 ignition[1116]: Ignition finished successfully Jun 21 04:43:00.373687 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 21 04:43:00.382401 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 21 04:43:00.383893 systemd[1]: Reached target sysinit.target - System Initialization. Jun 21 04:43:00.394536 systemd[1]: Reached target basic.target - Basic System. Jun 21 04:43:00.402009 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 21 04:43:00.514848 systemd-fsck[1128]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jun 21 04:43:00.520344 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 21 04:43:00.531497 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 21 04:43:00.767162 kernel: EXT4-fs (sda9): mounted filesystem 6d18c974-0fd6-4e4a-98cf-62524fcf9e99 r/w with ordered data mode. Quota mode: none. Jun 21 04:43:00.767704 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 21 04:43:00.769796 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 21 04:43:00.787840 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 21 04:43:00.790082 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 21 04:43:00.815773 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jun 21 04:43:00.829980 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1142) Jun 21 04:43:00.817232 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 21 04:43:00.844996 kernel: BTRFS info (device sda6): first mount of filesystem 57d2b200-37a8-4067-8765-910d3ed0182c Jun 21 04:43:00.845016 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 21 04:43:00.845029 kernel: BTRFS info (device sda6): using free-space-tree Jun 21 04:43:00.817263 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 21 04:43:00.842014 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 21 04:43:00.859556 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 21 04:43:00.861552 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 21 04:43:02.519503 coreos-metadata[1144]: Jun 21 04:43:02.519 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 21 04:43:02.528835 initrd-setup-root[1171]: cut: /sysroot/etc/passwd: No such file or directory Jun 21 04:43:02.542199 coreos-metadata[1144]: Jun 21 04:43:02.533 INFO Fetch successful Jun 21 04:43:02.542199 coreos-metadata[1144]: Jun 21 04:43:02.533 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jun 21 04:43:02.555796 coreos-metadata[1144]: Jun 21 04:43:02.549 INFO Fetch successful Jun 21 04:43:02.555796 coreos-metadata[1144]: Jun 21 04:43:02.549 INFO wrote hostname ci-4372.0.0-a-41061a6e96 to /sysroot/etc/hostname Jun 21 04:43:02.552299 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 21 04:43:02.576490 initrd-setup-root[1179]: cut: /sysroot/etc/group: No such file or directory Jun 21 04:43:02.582317 initrd-setup-root[1186]: cut: /sysroot/etc/shadow: No such file or directory Jun 21 04:43:02.587406 initrd-setup-root[1193]: cut: /sysroot/etc/gshadow: No such file or directory Jun 21 04:43:04.086689 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 21 04:43:04.095599 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 21 04:43:04.097926 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 21 04:43:04.136871 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 21 04:43:04.145682 kernel: BTRFS info (device sda6): last unmount of filesystem 57d2b200-37a8-4067-8765-910d3ed0182c Jun 21 04:43:04.161248 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 21 04:43:04.185544 ignition[1261]: INFO : Ignition 2.21.0 Jun 21 04:43:04.185544 ignition[1261]: INFO : Stage: mount Jun 21 04:43:04.191458 ignition[1261]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:04.191458 ignition[1261]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:04.200427 ignition[1261]: INFO : mount: mount passed Jun 21 04:43:04.200427 ignition[1261]: INFO : Ignition finished successfully Jun 21 04:43:04.201993 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 21 04:43:04.211314 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 21 04:43:04.237367 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 21 04:43:04.257160 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1279) Jun 21 04:43:04.257208 kernel: BTRFS info (device sda6): first mount of filesystem 57d2b200-37a8-4067-8765-910d3ed0182c Jun 21 04:43:04.265845 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 21 04:43:04.269006 kernel: BTRFS info (device sda6): using free-space-tree Jun 21 04:43:04.272980 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 21 04:43:04.316313 ignition[1296]: INFO : Ignition 2.21.0 Jun 21 04:43:04.316313 ignition[1296]: INFO : Stage: files Jun 21 04:43:04.324230 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:04.324230 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:04.324230 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Jun 21 04:43:04.337645 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 21 04:43:04.337645 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 21 04:43:04.369460 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 21 04:43:04.375230 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 21 04:43:04.380460 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 21 04:43:04.375769 unknown[1296]: wrote ssh authorized keys file for user: core Jun 21 04:43:04.397798 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 21 04:43:04.405052 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 21 04:43:04.405052 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 21 04:43:04.405052 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 21 04:43:04.405052 ignition[1296]: INFO : files: files passed Jun 21 04:43:04.405052 ignition[1296]: INFO : Ignition finished successfully Jun 21 04:43:04.413936 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 21 04:43:04.422770 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 21 04:43:04.450106 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 21 04:43:04.453320 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 21 04:43:04.453416 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 21 04:43:04.561624 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 21 04:43:04.568061 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 21 04:43:04.574490 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 21 04:43:04.581180 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 21 04:43:04.586743 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 21 04:43:04.597069 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 21 04:43:04.648707 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 21 04:43:04.648821 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 21 04:43:04.657727 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 21 04:43:04.666764 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 21 04:43:04.668237 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 21 04:43:04.669092 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 21 04:43:04.688954 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 21 04:43:04.694717 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 21 04:43:04.707724 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 21 04:43:04.709815 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 04:43:04.719413 systemd[1]: Stopped target timers.target - Timer Units. Jun 21 04:43:04.729602 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 21 04:43:04.729733 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 21 04:43:04.743625 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 21 04:43:04.751368 systemd[1]: Stopped target basic.target - Basic System. Jun 21 04:43:04.759415 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 21 04:43:04.767579 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 21 04:43:04.775346 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 21 04:43:04.783947 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jun 21 04:43:04.785988 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 21 04:43:04.798205 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 21 04:43:04.806402 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 21 04:43:04.815000 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 21 04:43:04.823093 systemd[1]: Stopped target swap.target - Swaps. Jun 21 04:43:04.840079 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 21 04:43:04.840300 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 21 04:43:04.852104 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 21 04:43:04.853709 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 04:43:04.862298 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 21 04:43:04.862441 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 04:43:04.871123 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 21 04:43:04.871271 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 21 04:43:04.890261 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 21 04:43:04.890409 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 21 04:43:04.899073 systemd[1]: ignition-files.service: Deactivated successfully. Jun 21 04:43:04.899219 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 21 04:43:04.907161 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jun 21 04:43:04.907282 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 21 04:43:04.917816 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 21 04:43:04.925314 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 21 04:43:04.925452 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 04:43:04.972834 ignition[1351]: INFO : Ignition 2.21.0 Jun 21 04:43:04.972834 ignition[1351]: INFO : Stage: umount Jun 21 04:43:04.972834 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 04:43:04.972834 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 21 04:43:04.972834 ignition[1351]: INFO : umount: umount passed Jun 21 04:43:04.972834 ignition[1351]: INFO : Ignition finished successfully Jun 21 04:43:04.943884 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 21 04:43:04.949498 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 21 04:43:04.949654 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 04:43:04.959751 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 21 04:43:04.959871 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 21 04:43:04.979443 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 21 04:43:04.980711 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 21 04:43:04.980849 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 21 04:43:04.988171 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 21 04:43:04.988283 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 21 04:43:04.994252 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 21 04:43:04.994316 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 21 04:43:05.002008 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 21 04:43:05.002056 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 21 04:43:05.009339 systemd[1]: ignition-fetch.service: Deactivated successfully. Jun 21 04:43:05.009388 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jun 21 04:43:05.016618 systemd[1]: Stopped target network.target - Network. Jun 21 04:43:05.024209 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 21 04:43:05.024270 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 21 04:43:05.031442 systemd[1]: Stopped target paths.target - Path Units. Jun 21 04:43:05.040397 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 21 04:43:05.046194 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 04:43:05.047010 systemd[1]: Stopped target slices.target - Slice Units. Jun 21 04:43:05.059280 systemd[1]: Stopped target sockets.target - Socket Units. Jun 21 04:43:05.060812 systemd[1]: iscsid.socket: Deactivated successfully. Jun 21 04:43:05.060861 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 21 04:43:05.071111 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 21 04:43:05.071168 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 21 04:43:05.078596 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 21 04:43:05.078657 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 21 04:43:05.086371 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 21 04:43:05.086425 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 21 04:43:05.095218 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 21 04:43:05.103068 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 21 04:43:05.110988 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 21 04:43:05.111306 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 21 04:43:05.124685 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 21 04:43:05.124793 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 21 04:43:05.137773 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 21 04:43:05.137989 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 21 04:43:05.138094 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 21 04:43:05.149557 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 21 04:43:05.151372 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jun 21 04:43:05.154241 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 21 04:43:05.154326 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 21 04:43:05.161009 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 21 04:43:05.161072 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 21 04:43:05.174263 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 21 04:43:05.182544 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 21 04:43:05.182607 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 21 04:43:05.190840 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 21 04:43:05.190892 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 21 04:43:05.199420 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 21 04:43:05.199468 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 21 04:43:05.206244 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 21 04:43:05.206291 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 04:43:05.215966 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 04:43:05.218430 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jun 21 04:43:05.218506 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 21 04:43:05.265852 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 21 04:43:05.266024 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 04:43:05.277169 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 21 04:43:05.277277 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 21 04:43:05.280967 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 21 04:43:05.281025 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 21 04:43:05.289173 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 21 04:43:05.289211 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 04:43:05.301586 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 21 04:43:05.301644 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 21 04:43:05.313608 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 21 04:43:05.313662 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 21 04:43:05.325899 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 21 04:43:05.325972 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 21 04:43:05.339450 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 21 04:43:05.346653 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jun 21 04:43:05.346712 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 04:43:05.360353 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 21 04:43:05.360408 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 04:43:05.370184 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 21 04:43:05.370237 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:43:05.387398 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jun 21 04:43:05.521228 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Jun 21 04:43:05.387459 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jun 21 04:43:05.387505 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 21 04:43:05.387826 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 21 04:43:05.387922 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 21 04:43:05.393166 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 21 04:43:05.403154 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 21 04:43:05.441444 systemd[1]: Switching root. Jun 21 04:43:05.527212 systemd-journald[233]: Journal stopped Jun 21 04:43:11.923634 kernel: SELinux: policy capability network_peer_controls=1 Jun 21 04:43:11.923668 kernel: SELinux: policy capability open_perms=1 Jun 21 04:43:11.923681 kernel: SELinux: policy capability extended_socket_class=1 Jun 21 04:43:11.923690 kernel: SELinux: policy capability always_check_network=0 Jun 21 04:43:11.923699 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 21 04:43:11.923708 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 21 04:43:11.923719 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 21 04:43:11.923731 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 21 04:43:11.923740 kernel: SELinux: policy capability userspace_initial_context=0 Jun 21 04:43:11.923750 kernel: audit: type=1403 audit(1750480986.587:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 21 04:43:11.923761 systemd[1]: Successfully loaded SELinux policy in 271.932ms. Jun 21 04:43:11.923773 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.062ms. Jun 21 04:43:11.923784 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 21 04:43:11.923794 systemd[1]: Detected virtualization microsoft. Jun 21 04:43:11.923808 systemd[1]: Detected architecture x86-64. Jun 21 04:43:11.923819 systemd[1]: Detected first boot. Jun 21 04:43:11.923829 systemd[1]: Hostname set to . Jun 21 04:43:11.923839 systemd[1]: Initializing machine ID from random generator. Jun 21 04:43:11.923850 zram_generator::config[1398]: No configuration found. Jun 21 04:43:11.923864 kernel: Guest personality initialized and is inactive Jun 21 04:43:11.923873 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jun 21 04:43:11.923887 kernel: Initialized host personality Jun 21 04:43:11.923896 kernel: NET: Registered PF_VSOCK protocol family Jun 21 04:43:11.923907 systemd[1]: Populated /etc with preset unit settings. Jun 21 04:43:11.923918 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 21 04:43:11.923929 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 21 04:43:11.923942 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 21 04:43:11.923953 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 21 04:43:11.923963 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 21 04:43:11.923974 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 21 04:43:11.923985 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 21 04:43:11.923996 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 21 04:43:11.924006 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 21 04:43:11.924017 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 21 04:43:11.924030 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 21 04:43:11.924041 systemd[1]: Created slice user.slice - User and Session Slice. Jun 21 04:43:11.924052 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 04:43:11.924062 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 04:43:11.924073 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 21 04:43:11.924084 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 21 04:43:11.924098 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 21 04:43:11.924110 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 21 04:43:11.924126 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 21 04:43:11.924138 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 04:43:11.924158 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 21 04:43:11.924170 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 21 04:43:11.924181 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 21 04:43:11.924192 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 21 04:43:11.924203 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 21 04:43:11.924216 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 04:43:11.924228 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 21 04:43:11.924238 systemd[1]: Reached target slices.target - Slice Units. Jun 21 04:43:11.924249 systemd[1]: Reached target swap.target - Swaps. Jun 21 04:43:11.924260 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 21 04:43:11.924271 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 21 04:43:11.924285 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 21 04:43:11.924296 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 21 04:43:11.924307 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 21 04:43:11.924318 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 04:43:11.924329 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 21 04:43:11.924340 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 21 04:43:11.924351 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 21 04:43:11.924365 systemd[1]: Mounting media.mount - External Media Directory... Jun 21 04:43:11.924379 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 21 04:43:11.924390 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 21 04:43:11.924401 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 21 04:43:11.924412 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 21 04:43:11.924424 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 21 04:43:11.924491 systemd[1]: Reached target machines.target - Containers. Jun 21 04:43:11.924509 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 21 04:43:11.924520 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 04:43:11.924537 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 21 04:43:11.924548 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 21 04:43:11.924560 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 04:43:11.924570 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 21 04:43:11.924581 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 04:43:11.924593 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 21 04:43:11.924604 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 04:43:11.924615 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 21 04:43:11.924629 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 21 04:43:11.924640 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 21 04:43:11.924651 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 21 04:43:11.924662 systemd[1]: Stopped systemd-fsck-usr.service. Jun 21 04:43:11.924674 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 21 04:43:11.924689 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 21 04:43:11.924700 kernel: fuse: init (API version 7.41) Jun 21 04:43:11.924711 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 21 04:43:11.924725 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 21 04:43:11.924737 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 21 04:43:11.924748 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 21 04:43:11.924759 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 21 04:43:11.924770 systemd[1]: verity-setup.service: Deactivated successfully. Jun 21 04:43:11.924781 systemd[1]: Stopped verity-setup.service. Jun 21 04:43:11.924793 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 21 04:43:11.924804 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 21 04:43:11.924818 kernel: loop: module loaded Jun 21 04:43:11.924828 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 21 04:43:11.924839 systemd[1]: Mounted media.mount - External Media Directory. Jun 21 04:43:11.924850 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 21 04:43:11.924861 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 21 04:43:11.924899 systemd-journald[1495]: Collecting audit messages is disabled. Jun 21 04:43:11.924928 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 21 04:43:11.924941 systemd-journald[1495]: Journal started Jun 21 04:43:11.924964 systemd-journald[1495]: Runtime Journal (/run/log/journal/22df5d6b253143c2aabb33cb859fb1d8) is 8M, max 2.2G, 2.1G free. Jun 21 04:43:11.032178 systemd[1]: Queued start job for default target multi-user.target. Jun 21 04:43:11.059814 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jun 21 04:43:11.060427 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 21 04:43:11.933828 systemd[1]: Started systemd-journald.service - Journal Service. Jun 21 04:43:11.938260 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 21 04:43:11.943360 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 04:43:11.948709 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 21 04:43:11.948907 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 21 04:43:11.953948 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 04:43:11.954158 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 04:43:11.959275 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 04:43:11.959473 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 04:43:11.964741 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 21 04:43:11.964935 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 21 04:43:11.969949 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 04:43:11.970152 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 04:43:11.974962 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 21 04:43:11.979764 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 04:43:11.985186 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 21 04:43:11.990559 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 21 04:43:12.005831 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 21 04:43:12.011852 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 21 04:43:12.030418 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 21 04:43:12.034921 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 21 04:43:12.034964 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 21 04:43:12.040339 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 21 04:43:12.046606 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 21 04:43:12.064395 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 04:43:12.066059 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 21 04:43:12.071584 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 21 04:43:12.076277 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 04:43:12.077331 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 21 04:43:12.081788 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 04:43:12.083090 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 21 04:43:12.088350 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 21 04:43:12.094612 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 21 04:43:12.101283 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 04:43:12.106348 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 21 04:43:12.111165 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 21 04:43:12.121172 kernel: ACPI: bus type drm_connector registered Jun 21 04:43:12.122324 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 21 04:43:12.122544 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 21 04:43:12.141636 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 21 04:43:12.146705 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 21 04:43:12.152865 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 21 04:43:12.161179 systemd-journald[1495]: Time spent on flushing to /var/log/journal/22df5d6b253143c2aabb33cb859fb1d8 is 14.137ms for 954 entries. Jun 21 04:43:12.161179 systemd-journald[1495]: System Journal (/var/log/journal/22df5d6b253143c2aabb33cb859fb1d8) is 8M, max 2.6G, 2.6G free. Jun 21 04:43:12.201282 systemd-journald[1495]: Received client request to flush runtime journal. Jun 21 04:43:12.201354 kernel: loop0: detected capacity change from 0 to 146240 Jun 21 04:43:12.203170 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 21 04:43:12.249999 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 21 04:43:12.278328 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 21 04:43:12.279136 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 21 04:43:12.427123 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 21 04:43:12.433140 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 21 04:43:12.600242 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. Jun 21 04:43:12.600264 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. Jun 21 04:43:12.617908 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 04:43:13.023173 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 21 04:43:13.061172 kernel: loop1: detected capacity change from 0 to 113872 Jun 21 04:43:13.421192 kernel: loop2: detected capacity change from 0 to 28496 Jun 21 04:43:13.632329 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 21 04:43:13.638986 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 04:43:13.683089 systemd-udevd[1563]: Using default interface naming scheme 'v255'. Jun 21 04:43:13.760168 kernel: loop3: detected capacity change from 0 to 146240 Jun 21 04:43:13.774167 kernel: loop4: detected capacity change from 0 to 113872 Jun 21 04:43:13.784164 kernel: loop5: detected capacity change from 0 to 28496 Jun 21 04:43:13.789095 (sd-merge)[1566]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jun 21 04:43:13.789582 (sd-merge)[1566]: Merged extensions into '/usr'. Jun 21 04:43:13.793215 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 21 04:43:13.799931 systemd[1]: Starting ensure-sysext.service... Jun 21 04:43:13.803715 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 21 04:43:13.853792 systemd-tmpfiles[1569]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jun 21 04:43:13.853838 systemd-tmpfiles[1569]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jun 21 04:43:13.854132 systemd-tmpfiles[1569]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 21 04:43:13.854435 systemd-tmpfiles[1569]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 21 04:43:13.855331 systemd-tmpfiles[1569]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 21 04:43:13.855683 systemd-tmpfiles[1569]: ACLs are not supported, ignoring. Jun 21 04:43:13.855751 systemd-tmpfiles[1569]: ACLs are not supported, ignoring. Jun 21 04:43:13.856458 systemd[1]: Reload requested from client PID 1568 ('systemctl') (unit ensure-sysext.service)... Jun 21 04:43:13.856477 systemd[1]: Reloading... Jun 21 04:43:13.859725 systemd-tmpfiles[1569]: Detected autofs mount point /boot during canonicalization of boot. Jun 21 04:43:13.859741 systemd-tmpfiles[1569]: Skipping /boot Jun 21 04:43:13.872098 systemd-tmpfiles[1569]: Detected autofs mount point /boot during canonicalization of boot. Jun 21 04:43:13.872113 systemd-tmpfiles[1569]: Skipping /boot Jun 21 04:43:13.895177 zram_generator::config[1596]: No configuration found. Jun 21 04:43:14.011759 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 21 04:43:14.141180 kernel: hv_vmbus: registering driver hyperv_fb Jun 21 04:43:14.141759 systemd[1]: Reloading finished in 284 ms. Jun 21 04:43:14.151529 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jun 21 04:43:14.151603 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jun 21 04:43:14.157153 kernel: Console: switching to colour dummy device 80x25 Jun 21 04:43:14.161642 kernel: Console: switching to colour frame buffer device 128x48 Jun 21 04:43:14.162933 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 04:43:14.163191 kernel: mousedev: PS/2 mouse device common for all mice Jun 21 04:43:14.188106 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 04:43:14.205211 kernel: hv_vmbus: registering driver hv_balloon Jun 21 04:43:14.205275 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jun 21 04:43:14.224318 systemd[1]: Finished ensure-sysext.service. Jun 21 04:43:14.232719 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 21 04:43:14.233607 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Jun 21 04:43:14.238403 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 21 04:43:14.239706 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 21 04:43:14.252497 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 21 04:43:14.257303 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 04:43:14.258324 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 04:43:14.263357 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 21 04:43:14.268236 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 04:43:14.273930 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 04:43:14.277974 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 04:43:14.278029 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 21 04:43:14.279045 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 21 04:43:14.286801 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 21 04:43:14.292810 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 21 04:43:14.297426 systemd[1]: Reached target time-set.target - System Time Set. Jun 21 04:43:14.302842 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 21 04:43:14.308633 kernel: EDAC MC: Ver: 3.0.0 Jun 21 04:43:14.321049 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 04:43:14.325500 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 21 04:43:14.326651 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 04:43:14.326870 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 04:43:14.328663 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 21 04:43:14.328861 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 21 04:43:14.339768 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 04:43:14.352554 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 04:43:14.357988 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 04:43:14.358218 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 04:43:14.368572 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 04:43:14.368783 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 04:43:14.370606 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 21 04:43:14.406770 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 21 04:43:14.450282 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 21 04:43:14.471269 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 21 04:43:14.477154 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 21 04:43:14.487607 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 21 04:43:14.545432 augenrules[1840]: No rules Jun 21 04:43:14.546809 systemd[1]: audit-rules.service: Deactivated successfully. Jun 21 04:43:14.547065 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 21 04:43:14.564254 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 21 04:43:14.672363 systemd-resolved[1736]: Positive Trust Anchors: Jun 21 04:43:14.672378 systemd-resolved[1736]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 21 04:43:14.672410 systemd-resolved[1736]: 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 21 04:43:14.729881 systemd-networkd[1735]: lo: Link UP Jun 21 04:43:14.729890 systemd-networkd[1735]: lo: Gained carrier Jun 21 04:43:14.731339 systemd-networkd[1735]: Enumeration completed Jun 21 04:43:14.731463 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 21 04:43:14.731712 systemd-networkd[1735]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 04:43:14.731722 systemd-networkd[1735]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 21 04:43:14.732299 systemd-networkd[1735]: eth0: Link UP Jun 21 04:43:14.732309 systemd-networkd[1735]: eth0: Gained carrier Jun 21 04:43:14.732324 systemd-networkd[1735]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 04:43:14.734563 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 21 04:43:14.736304 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 21 04:43:14.759039 systemd-resolved[1736]: Using system hostname 'ci-4372.0.0-a-41061a6e96'. Jun 21 04:43:14.761079 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 21 04:43:14.766072 systemd[1]: Reached target network.target - Network. Jun 21 04:43:14.767594 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 21 04:43:14.787189 systemd-networkd[1735]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 21 04:43:14.790619 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 21 04:43:14.853482 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 04:43:15.214811 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 21 04:43:15.220160 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 21 04:43:16.298324 systemd-networkd[1735]: eth0: Gained IPv6LL Jun 21 04:43:16.300996 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 21 04:43:16.306464 systemd[1]: Reached target network-online.target - Network is Online. Jun 21 04:43:20.960459 ldconfig[1533]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 21 04:43:20.971518 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 21 04:43:20.978598 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 21 04:43:21.012165 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 21 04:43:21.017221 systemd[1]: Reached target sysinit.target - System Initialization. Jun 21 04:43:21.021798 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 21 04:43:21.027100 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 21 04:43:21.032556 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jun 21 04:43:21.037637 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 21 04:43:21.042315 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 21 04:43:21.047664 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 21 04:43:21.054086 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 21 04:43:21.054162 systemd[1]: Reached target paths.target - Path Units. Jun 21 04:43:21.058011 systemd[1]: Reached target timers.target - Timer Units. Jun 21 04:43:21.062935 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 21 04:43:21.069207 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 21 04:43:21.076565 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 21 04:43:21.082216 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 21 04:43:21.087691 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 21 04:43:21.094496 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 21 04:43:21.099406 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 21 04:43:21.105357 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 21 04:43:21.110482 systemd[1]: Reached target sockets.target - Socket Units. Jun 21 04:43:21.114366 systemd[1]: Reached target basic.target - Basic System. Jun 21 04:43:21.118444 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 21 04:43:21.118477 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 21 04:43:21.121334 systemd[1]: Starting chronyd.service - NTP client/server... Jun 21 04:43:21.129558 systemd[1]: Starting containerd.service - containerd container runtime... Jun 21 04:43:21.135127 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jun 21 04:43:21.143256 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 21 04:43:21.147922 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 21 04:43:21.153725 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 21 04:43:21.158909 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 21 04:43:21.163041 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 21 04:43:21.163762 jq[1869]: false Jun 21 04:43:21.164880 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jun 21 04:43:21.169103 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 21 04:43:21.170131 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jun 21 04:43:21.170840 (chronyd)[1861]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jun 21 04:43:21.174406 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jun 21 04:43:21.175473 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 21 04:43:21.181212 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 21 04:43:21.187007 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 21 04:43:21.193280 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 21 04:43:21.198591 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Refreshing passwd entry cache Jun 21 04:43:21.198869 oslogin_cache_refresh[1871]: Refreshing passwd entry cache Jun 21 04:43:21.200222 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 21 04:43:21.205295 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 21 04:43:21.205795 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 21 04:43:21.206538 systemd[1]: Starting update-engine.service - Update Engine... Jun 21 04:43:21.208066 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Failure getting users, quitting Jun 21 04:43:21.208066 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 21 04:43:21.208066 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Refreshing group entry cache Jun 21 04:43:21.207849 oslogin_cache_refresh[1871]: Failure getting users, quitting Jun 21 04:43:21.207870 oslogin_cache_refresh[1871]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 21 04:43:21.207916 oslogin_cache_refresh[1871]: Refreshing group entry cache Jun 21 04:43:21.211927 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 21 04:43:21.216900 extend-filesystems[1870]: Found /dev/sda6 Jun 21 04:43:21.226725 extend-filesystems[1870]: Found /dev/sda9 Jun 21 04:43:21.226725 extend-filesystems[1870]: Checking size of /dev/sda9 Jun 21 04:43:21.239036 kernel: hv_utils: KVP IC version 4.0 Jun 21 04:43:21.222909 oslogin_cache_refresh[1871]: Failure getting groups, quitting Jun 21 04:43:21.239187 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Failure getting groups, quitting Jun 21 04:43:21.239187 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 21 04:43:21.218767 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 21 04:43:21.222926 oslogin_cache_refresh[1871]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 21 04:43:21.239398 jq[1893]: true Jun 21 04:43:21.219369 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 21 04:43:21.225789 KVP[1872]: KVP starting; pid is:1872 Jun 21 04:43:21.219581 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 21 04:43:21.230479 KVP[1872]: KVP LIC Version: 3.1 Jun 21 04:43:21.219897 systemd[1]: motdgen.service: Deactivated successfully. Jun 21 04:43:21.230830 chronyd[1901]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jun 21 04:43:21.220116 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 21 04:43:21.232602 chronyd[1901]: Timezone right/UTC failed leap second check, ignoring Jun 21 04:43:21.233781 systemd[1]: Started chronyd.service - NTP client/server. Jun 21 04:43:21.232779 chronyd[1901]: Loaded seccomp filter (level 2) Jun 21 04:43:21.241933 update_engine[1890]: I20250621 04:43:21.241858 1890 main.cc:92] Flatcar Update Engine starting Jun 21 04:43:21.243302 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jun 21 04:43:21.243546 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jun 21 04:43:21.248345 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 21 04:43:21.248562 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 21 04:43:21.254445 extend-filesystems[1870]: Old size kept for /dev/sda9 Jun 21 04:43:21.258244 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 21 04:43:21.258536 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 21 04:43:21.271528 jq[1905]: true Jun 21 04:43:21.273550 (ntainerd)[1906]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 21 04:43:21.325949 systemd-logind[1887]: New seat seat0. Jun 21 04:43:21.327540 systemd-logind[1887]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 21 04:43:21.331510 systemd[1]: Started systemd-logind.service - User Login Management. Jun 21 04:43:21.337334 dbus-daemon[1864]: [system] SELinux support is enabled Jun 21 04:43:21.339357 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 21 04:43:21.340629 update_engine[1890]: I20250621 04:43:21.340564 1890 update_check_scheduler.cc:74] Next update check in 3m7s Jun 21 04:43:21.352213 bash[1940]: Updated "/home/core/.ssh/authorized_keys" Jun 21 04:43:21.353454 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 21 04:43:21.357485 sshd_keygen[1892]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 21 04:43:21.360340 dbus-daemon[1864]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 21 04:43:21.360521 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 21 04:43:21.360617 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 21 04:43:21.360648 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 21 04:43:21.365862 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 21 04:43:21.365890 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 21 04:43:21.371156 systemd[1]: Started update-engine.service - Update Engine. Jun 21 04:43:21.377205 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 21 04:43:21.384469 coreos-metadata[1863]: Jun 21 04:43:21.384 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 21 04:43:21.386812 coreos-metadata[1863]: Jun 21 04:43:21.386 INFO Fetch successful Jun 21 04:43:21.386967 coreos-metadata[1863]: Jun 21 04:43:21.386 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jun 21 04:43:21.390855 coreos-metadata[1863]: Jun 21 04:43:21.390 INFO Fetch successful Jun 21 04:43:21.391137 coreos-metadata[1863]: Jun 21 04:43:21.391 INFO Fetching http://168.63.129.16/machine/9eedba54-e893-4768-b364-e3abfe047c2e/fb0bb3ea%2D33fe%2D42bf%2D8449%2D25d9f0a069be.%5Fci%2D4372.0.0%2Da%2D41061a6e96?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jun 21 04:43:21.392966 setup-nvidia[1874]: Downloading Flatcar Container Linux Developer Container for version: 4372.0.0 Jun 21 04:43:21.393483 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 21 04:43:21.399727 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 21 04:43:21.412570 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jun 21 04:43:21.419159 systemd[1]: issuegen.service: Deactivated successfully. Jun 21 04:43:21.419403 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 21 04:43:21.425192 coreos-metadata[1863]: Jun 21 04:43:21.425 INFO Fetch successful Jun 21 04:43:21.426667 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 21 04:43:21.427101 coreos-metadata[1863]: Jun 21 04:43:21.427 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jun 21 04:43:21.434300 coreos-metadata[1863]: Jun 21 04:43:21.434 INFO Fetch successful Jun 21 04:43:21.453064 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jun 21 04:43:21.458219 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 21 04:43:21.467061 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 21 04:43:21.469959 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 21 04:43:21.475571 systemd[1]: Reached target getty.target - Login Prompts. Jun 21 04:43:21.498498 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jun 21 04:43:21.503608 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 21 04:43:21.544362 locksmithd[2004]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 21 04:43:22.222552 containerd[1906]: time="2025-06-21T04:43:22Z" 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 21 04:43:22.223251 containerd[1906]: time="2025-06-21T04:43:22.223212700Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 21 04:43:22.230861 containerd[1906]: time="2025-06-21T04:43:22.230823100Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.8µs" Jun 21 04:43:22.230992 containerd[1906]: time="2025-06-21T04:43:22.230956800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 21 04:43:22.231051 containerd[1906]: time="2025-06-21T04:43:22.231000300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 21 04:43:22.231296 containerd[1906]: time="2025-06-21T04:43:22.231256200Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 21 04:43:22.231333 containerd[1906]: time="2025-06-21T04:43:22.231318700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 21 04:43:22.231382 containerd[1906]: time="2025-06-21T04:43:22.231355700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 04:43:22.231498 containerd[1906]: time="2025-06-21T04:43:22.231467600Z" 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 21 04:43:22.231498 containerd[1906]: time="2025-06-21T04:43:22.231494100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 21 04:43:22.231806 containerd[1906]: time="2025-06-21T04:43:22.231764400Z" 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 21 04:43:22.231806 containerd[1906]: time="2025-06-21T04:43:22.231792000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 04:43:22.231873 containerd[1906]: time="2025-06-21T04:43:22.231809200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 04:43:22.231873 containerd[1906]: time="2025-06-21T04:43:22.231818700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 21 04:43:22.231924 containerd[1906]: time="2025-06-21T04:43:22.231907700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 21 04:43:22.232186 containerd[1906]: time="2025-06-21T04:43:22.232140500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 21 04:43:22.232226 containerd[1906]: time="2025-06-21T04:43:22.232198800Z" 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 21 04:43:22.232226 containerd[1906]: time="2025-06-21T04:43:22.232214800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 21 04:43:22.232289 containerd[1906]: time="2025-06-21T04:43:22.232251300Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 21 04:43:22.233086 containerd[1906]: time="2025-06-21T04:43:22.232637800Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 21 04:43:22.233086 containerd[1906]: time="2025-06-21T04:43:22.232724000Z" level=info msg="metadata content store policy set" policy=shared Jun 21 04:43:22.247948 containerd[1906]: time="2025-06-21T04:43:22.247905400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.247955200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.247973000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.247987500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.248001200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.248012300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 21 04:43:22.248032 containerd[1906]: time="2025-06-21T04:43:22.248027600Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 21 04:43:22.248194 containerd[1906]: time="2025-06-21T04:43:22.248041000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 21 04:43:22.248194 containerd[1906]: time="2025-06-21T04:43:22.248053900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 21 04:43:22.248194 containerd[1906]: time="2025-06-21T04:43:22.248065200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 21 04:43:22.248194 containerd[1906]: time="2025-06-21T04:43:22.248075300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 21 04:43:22.248194 containerd[1906]: time="2025-06-21T04:43:22.248089600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 21 04:43:22.248299 containerd[1906]: time="2025-06-21T04:43:22.248246400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 21 04:43:22.248299 containerd[1906]: time="2025-06-21T04:43:22.248280700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 21 04:43:22.248342 containerd[1906]: time="2025-06-21T04:43:22.248299400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 21 04:43:22.248342 containerd[1906]: time="2025-06-21T04:43:22.248311300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 21 04:43:22.248342 containerd[1906]: time="2025-06-21T04:43:22.248322400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 21 04:43:22.248342 containerd[1906]: time="2025-06-21T04:43:22.248332500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 21 04:43:22.248424 containerd[1906]: time="2025-06-21T04:43:22.248343700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 21 04:43:22.248424 containerd[1906]: time="2025-06-21T04:43:22.248366900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 21 04:43:22.248424 containerd[1906]: time="2025-06-21T04:43:22.248384700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 21 04:43:22.248424 containerd[1906]: time="2025-06-21T04:43:22.248396200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 21 04:43:22.248424 containerd[1906]: time="2025-06-21T04:43:22.248406500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 21 04:43:22.248528 containerd[1906]: time="2025-06-21T04:43:22.248475800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 21 04:43:22.248528 containerd[1906]: time="2025-06-21T04:43:22.248491900Z" level=info msg="Start snapshots syncer" Jun 21 04:43:22.248528 containerd[1906]: time="2025-06-21T04:43:22.248519800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 21 04:43:22.248791 containerd[1906]: time="2025-06-21T04:43:22.248751000Z" 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 21 04:43:22.248931 containerd[1906]: time="2025-06-21T04:43:22.248802800Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 21 04:43:22.248931 containerd[1906]: time="2025-06-21T04:43:22.248899900Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 21 04:43:22.249028 containerd[1906]: time="2025-06-21T04:43:22.249005200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 21 04:43:22.249061 containerd[1906]: time="2025-06-21T04:43:22.249035300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 21 04:43:22.249061 containerd[1906]: time="2025-06-21T04:43:22.249047700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 21 04:43:22.249109 containerd[1906]: time="2025-06-21T04:43:22.249059800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 21 04:43:22.249109 containerd[1906]: time="2025-06-21T04:43:22.249073000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 21 04:43:22.249109 containerd[1906]: time="2025-06-21T04:43:22.249084900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 21 04:43:22.249109 containerd[1906]: time="2025-06-21T04:43:22.249095200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 21 04:43:22.249226 containerd[1906]: time="2025-06-21T04:43:22.249119000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 21 04:43:22.249226 containerd[1906]: time="2025-06-21T04:43:22.249130900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 21 04:43:22.249226 containerd[1906]: time="2025-06-21T04:43:22.249176100Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 21 04:43:22.249226 containerd[1906]: time="2025-06-21T04:43:22.249213200Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 21 04:43:22.249316 containerd[1906]: time="2025-06-21T04:43:22.249230700Z" 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 21 04:43:22.249316 containerd[1906]: time="2025-06-21T04:43:22.249241100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 04:43:22.249316 containerd[1906]: time="2025-06-21T04:43:22.249251100Z" 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 21 04:43:22.249316 containerd[1906]: time="2025-06-21T04:43:22.249259700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 21 04:43:22.249316 containerd[1906]: time="2025-06-21T04:43:22.249283300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249321900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249344900Z" level=info msg="runtime interface created" Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249351300Z" level=info msg="created NRI interface" Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249360900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249372300Z" level=info msg="Connect containerd service" Jun 21 04:43:22.249421 containerd[1906]: time="2025-06-21T04:43:22.249399600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 21 04:43:22.250176 containerd[1906]: time="2025-06-21T04:43:22.250129500Z" 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 21 04:43:22.530627 setup-nvidia[2049]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 21 04:43:22.531077 setup-nvidia[2049]: Dload Upload Total Spent Left Speed Jun 21 04:43:23.430200 containerd[1906]: time="2025-06-21T04:43:23.430125500Z" level=info msg="Start subscribing containerd event" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430215400Z" level=info msg="Start recovering state" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430334800Z" level=info msg="Start event monitor" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430360700Z" level=info msg="Start cni network conf syncer for default" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430370800Z" level=info msg="Start streaming server" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430382100Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430391800Z" level=info msg="runtime interface starting up..." Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430399500Z" level=info msg="starting plugins..." Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430415300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430381000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430523600Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 21 04:43:23.430647 containerd[1906]: time="2025-06-21T04:43:23.430604000Z" level=info msg="containerd successfully booted in 1.208482s" Jun 21 04:43:23.430727 systemd[1]: Started containerd.service - containerd container runtime. Jun 21 04:43:23.690978 waagent[2027]: 2025-06-21T04:43:23.690788Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jun 21 04:43:23.695364 waagent[2027]: 2025-06-21T04:43:23.695308Z INFO Daemon Daemon OS: flatcar 4372.0.0 Jun 21 04:43:23.698846 waagent[2027]: 2025-06-21T04:43:23.698800Z INFO Daemon Daemon Python: 3.11.12 Jun 21 04:43:23.702152 waagent[2027]: 2025-06-21T04:43:23.702094Z INFO Daemon Daemon Run daemon Jun 21 04:43:23.705192 waagent[2027]: 2025-06-21T04:43:23.705133Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.0.0' Jun 21 04:43:23.711582 waagent[2027]: 2025-06-21T04:43:23.711534Z INFO Daemon Daemon Using waagent for provisioning Jun 21 04:43:23.715625 waagent[2027]: 2025-06-21T04:43:23.715582Z INFO Daemon Daemon Activate resource disk Jun 21 04:43:23.719120 waagent[2027]: 2025-06-21T04:43:23.719076Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jun 21 04:43:23.726639 waagent[2027]: 2025-06-21T04:43:23.726589Z INFO Daemon Daemon Found device: sdb Jun 21 04:43:23.736295 waagent[2027]: 2025-06-21T04:43:23.736245Z INFO Daemon Daemon Examining partition table Jun 21 04:43:23.798849 waagent[2027]: 2025-06-21T04:43:23.798761Z INFO Daemon Daemon GPT not detected, determining filesystem Jun 21 04:43:23.866131 waagent[2027]: 2025-06-21T04:43:23.866047Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jun 21 04:43:23.870775 waagent[2027]: 2025-06-21T04:43:23.870731Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jun 21 04:43:24.271906 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#168 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:43:24.284159 kernel: sdb: sdb1 Jun 21 04:43:24.291254 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#197 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:43:24.304392 waagent[2027]: 2025-06-21T04:43:24.304300Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jun 21 04:43:24.311259 kernel: sdb: sdb1 Jun 21 04:43:24.323171 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#229 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 21 04:43:24.334164 kernel: sdb: sdb1 Jun 21 04:43:24.334288 waagent[2027]: 2025-06-21T04:43:24.334210Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jun 21 04:43:29.232364 login[2032]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jun 21 04:43:29.279972 login[2031]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 21 04:43:29.287363 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 21 04:43:29.288595 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 21 04:43:29.294996 systemd-logind[1887]: New session 2 of user core. Jun 21 04:43:29.355714 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 21 04:43:29.358583 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 21 04:43:29.392132 (systemd)[2132]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 21 04:43:29.394586 systemd-logind[1887]: New session c1 of user core. Jun 21 04:43:30.068415 waagent[2027]: 2025-06-21T04:43:30.068312Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jun 21 04:43:30.138066 systemd[2132]: Queued start job for default target default.target. Jun 21 04:43:30.175978 systemd[2132]: Created slice app.slice - User Application Slice. Jun 21 04:43:30.176027 systemd[2132]: Reached target paths.target - Paths. Jun 21 04:43:30.176092 systemd[2132]: Reached target timers.target - Timers. Jun 21 04:43:30.177713 systemd[2132]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 21 04:43:30.184166 kernel: EXT4-fs (sdb1): mounted filesystem e5e94162-4ff1-4aa5-b7ff-a4c50d474eb8 r/w with ordered data mode. Quota mode: none. Jun 21 04:43:30.184925 waagent[2027]: 2025-06-21T04:43:30.184829Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jun 21 04:43:30.187293 waagent[2027]: 2025-06-21T04:43:30.187244Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 21 04:43:30.188130 waagent[2027]: 2025-06-21T04:43:30.188091Z INFO Daemon Daemon Running default provisioning handler Jun 21 04:43:30.195727 waagent[2027]: 2025-06-21T04:43:30.195681Z 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 21 04:43:30.196414 waagent[2027]: 2025-06-21T04:43:30.196373Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jun 21 04:43:30.196744 waagent[2027]: 2025-06-21T04:43:30.196703Z INFO Daemon Daemon cloud-init is enabled: False Jun 21 04:43:30.197402 waagent[2027]: 2025-06-21T04:43:30.197366Z INFO Daemon Daemon Copying ovf-env.xml Jun 21 04:43:30.225021 systemd[2132]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 21 04:43:30.225185 systemd[2132]: Reached target sockets.target - Sockets. Jun 21 04:43:30.225244 systemd[2132]: Reached target basic.target - Basic System. Jun 21 04:43:30.225291 systemd[2132]: Reached target default.target - Main User Target. Jun 21 04:43:30.225327 systemd[2132]: Startup finished in 824ms. Jun 21 04:43:30.225412 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 21 04:43:30.234173 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 21 04:43:30.241267 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 21 04:43:30.245260 systemd-logind[1887]: New session 1 of user core. Jun 21 04:43:30.246309 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 21 04:43:30.306123 waagent[2027]: 2025-06-21T04:43:30.306029Z INFO Daemon Daemon Successfully mounted dvd Jun 21 04:43:30.369361 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jun 21 04:43:30.369815 waagent[2027]: 2025-06-21T04:43:30.369613Z INFO Daemon Daemon Detect protocol endpoint Jun 21 04:43:30.397161 waagent[2027]: 2025-06-21T04:43:30.371507Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 21 04:43:30.397161 waagent[2027]: 2025-06-21T04:43:30.372993Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jun 21 04:43:30.397161 waagent[2027]: 2025-06-21T04:43:30.378828Z INFO Daemon Daemon Test for route to 168.63.129.16 Jun 21 04:43:30.397161 waagent[2027]: 2025-06-21T04:43:30.380514Z INFO Daemon Daemon Route to 168.63.129.16 exists Jun 21 04:43:30.397161 waagent[2027]: 2025-06-21T04:43:30.381083Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jun 21 04:43:30.399998 waagent[2027]: 2025-06-21T04:43:30.399953Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jun 21 04:43:30.412541 waagent[2027]: 2025-06-21T04:43:30.401827Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jun 21 04:43:30.412541 waagent[2027]: 2025-06-21T04:43:30.403191Z INFO Daemon Daemon Server preferred version:2015-04-05 Jun 21 04:43:30.585042 waagent[2027]: 2025-06-21T04:43:30.584914Z INFO Daemon Daemon Initializing goal state during protocol detection Jun 21 04:43:30.599672 waagent[2027]: 2025-06-21T04:43:30.586723Z INFO Daemon Daemon Forcing an update of the goal state. Jun 21 04:43:30.599672 waagent[2027]: 2025-06-21T04:43:30.591845Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 21 04:43:30.642281 waagent[2027]: 2025-06-21T04:43:30.642126Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.644387Z INFO Daemon Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.644621Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: a616ada6-018e-41b1-8ea5-afb2bf95844c eTag: 7456363483796534711 source: Fabric] Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.646330Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.647049Z INFO Daemon Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.648245Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jun 21 04:43:30.671071 waagent[2027]: 2025-06-21T04:43:30.652723Z INFO Daemon Daemon Downloading artifacts profile blob Jun 21 04:43:30.786202 waagent[2027]: 2025-06-21T04:43:30.786137Z INFO Daemon Downloaded certificate {'thumbprint': 'F253CAC37E7ABFD2972652B6BDAA990CBE0503C1', 'hasPrivateKey': True} Jun 21 04:43:30.788232 waagent[2027]: 2025-06-21T04:43:30.788190Z INFO Daemon Fetch goal state completed Jun 21 04:43:30.825112 waagent[2027]: 2025-06-21T04:43:30.825062Z INFO Daemon Daemon Starting provisioning Jun 21 04:43:30.828192 waagent[2027]: 2025-06-21T04:43:30.828121Z INFO Daemon Daemon Handle ovf-env.xml. Jun 21 04:43:30.830047 waagent[2027]: 2025-06-21T04:43:30.829998Z INFO Daemon Daemon Set hostname [ci-4372.0.0-a-41061a6e96] Jun 21 04:43:30.925152 waagent[2027]: 2025-06-21T04:43:30.925077Z INFO Daemon Daemon Publish hostname [ci-4372.0.0-a-41061a6e96] Jun 21 04:43:30.926943 waagent[2027]: 2025-06-21T04:43:30.926892Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jun 21 04:43:30.928408 waagent[2027]: 2025-06-21T04:43:30.928368Z INFO Daemon Daemon Primary interface is [eth0] Jun 21 04:43:30.946372 systemd-networkd[1735]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 04:43:30.946384 systemd-networkd[1735]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 21 04:43:30.946413 systemd-networkd[1735]: eth0: DHCP lease lost Jun 21 04:43:30.946817 waagent[2027]: 2025-06-21T04:43:30.946773Z INFO Daemon Daemon Create user account if not exists Jun 21 04:43:30.948483 waagent[2027]: 2025-06-21T04:43:30.948444Z INFO Daemon Daemon User core already exists, skip useradd Jun 21 04:43:30.949189 waagent[2027]: 2025-06-21T04:43:30.949131Z INFO Daemon Daemon Configure sudoer Jun 21 04:43:30.953918 waagent[2027]: 2025-06-21T04:43:30.953869Z INFO Daemon Daemon Configure sshd Jun 21 04:43:30.960403 waagent[2027]: 2025-06-21T04:43:30.960361Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jun 21 04:43:30.962133 waagent[2027]: 2025-06-21T04:43:30.962089Z INFO Daemon Daemon Deploy ssh public key. Jun 21 04:43:31.001188 systemd-networkd[1735]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 21 04:43:32.079840 waagent[2027]: 2025-06-21T04:43:32.079769Z INFO Daemon Daemon Provisioning complete Jun 21 04:43:32.089452 waagent[2027]: 2025-06-21T04:43:32.089411Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jun 21 04:43:32.091166 waagent[2027]: 2025-06-21T04:43:32.091111Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jun 21 04:43:32.091891 waagent[2027]: 2025-06-21T04:43:32.091856Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jun 21 04:43:32.249041 waagent[2196]: 2025-06-21T04:43:32.248949Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jun 21 04:43:32.249460 waagent[2196]: 2025-06-21T04:43:32.249113Z INFO ExtHandler ExtHandler OS: flatcar 4372.0.0 Jun 21 04:43:32.249460 waagent[2196]: 2025-06-21T04:43:32.249204Z INFO ExtHandler ExtHandler Python: 3.11.12 Jun 21 04:43:32.249460 waagent[2196]: 2025-06-21T04:43:32.249274Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jun 21 04:43:32.343638 waagent[2196]: 2025-06-21T04:43:32.343471Z INFO ExtHandler ExtHandler Distro: flatcar-4372.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jun 21 04:43:32.343780 waagent[2196]: 2025-06-21T04:43:32.343756Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 21 04:43:32.343869 waagent[2196]: 2025-06-21T04:43:32.343834Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 21 04:43:32.350836 waagent[2196]: 2025-06-21T04:43:32.350788Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 21 04:43:32.355547 waagent[2196]: 2025-06-21T04:43:32.355507Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jun 21 04:43:32.355995 waagent[2196]: 2025-06-21T04:43:32.355956Z INFO ExtHandler Jun 21 04:43:32.356073 waagent[2196]: 2025-06-21T04:43:32.356042Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 50d04ceb-cb3e-4db1-b516-6c4e391c1231 eTag: 7456363483796534711 source: Fabric] Jun 21 04:43:32.356411 waagent[2196]: 2025-06-21T04:43:32.356373Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jun 21 04:43:32.357031 waagent[2196]: 2025-06-21T04:43:32.356993Z INFO ExtHandler Jun 21 04:43:32.357103 waagent[2196]: 2025-06-21T04:43:32.357073Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jun 21 04:43:32.360536 waagent[2196]: 2025-06-21T04:43:32.360500Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jun 21 04:43:32.455303 waagent[2196]: 2025-06-21T04:43:32.455208Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F253CAC37E7ABFD2972652B6BDAA990CBE0503C1', 'hasPrivateKey': True} Jun 21 04:43:32.455871 waagent[2196]: 2025-06-21T04:43:32.455828Z INFO ExtHandler Fetch goal state completed Jun 21 04:43:32.467835 waagent[2196]: 2025-06-21T04:43:32.467788Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jun 21 04:43:32.473183 waagent[2196]: 2025-06-21T04:43:32.473116Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2196 Jun 21 04:43:32.473323 waagent[2196]: 2025-06-21T04:43:32.473287Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jun 21 04:43:32.473655 waagent[2196]: 2025-06-21T04:43:32.473619Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jun 21 04:43:32.475278 waagent[2196]: 2025-06-21T04:43:32.475234Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.0.0', '', 'Flatcar Container Linux by Kinvolk'] Jun 21 04:43:32.475692 waagent[2196]: 2025-06-21T04:43:32.475653Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jun 21 04:43:32.475847 waagent[2196]: 2025-06-21T04:43:32.475812Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jun 21 04:43:32.476466 waagent[2196]: 2025-06-21T04:43:32.476428Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jun 21 04:43:32.518839 waagent[2196]: 2025-06-21T04:43:32.518782Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jun 21 04:43:32.519070 waagent[2196]: 2025-06-21T04:43:32.519032Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jun 21 04:43:32.527354 waagent[2196]: 2025-06-21T04:43:32.527311Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jun 21 04:43:32.535125 systemd[1]: Reload requested from client PID 2211 ('systemctl') (unit waagent.service)... Jun 21 04:43:32.535157 systemd[1]: Reloading... Jun 21 04:43:32.594183 zram_generator::config[2253]: No configuration found. Jun 21 04:43:32.703559 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 21 04:43:32.874311 systemd[1]: Reloading finished in 338 ms. Jun 21 04:43:32.896040 waagent[2196]: 2025-06-21T04:43:32.895954Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jun 21 04:43:32.896187 waagent[2196]: 2025-06-21T04:43:32.896130Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jun 21 04:43:33.519499 waagent[2196]: 2025-06-21T04:43:33.519396Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jun 21 04:43:33.519940 waagent[2196]: 2025-06-21T04:43:33.519799Z 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 21 04:43:33.520710 waagent[2196]: 2025-06-21T04:43:33.520657Z INFO ExtHandler ExtHandler Starting env monitor service. Jun 21 04:43:33.521071 waagent[2196]: 2025-06-21T04:43:33.521017Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 21 04:43:33.521140 waagent[2196]: 2025-06-21T04:43:33.521116Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 21 04:43:33.521425 waagent[2196]: 2025-06-21T04:43:33.521379Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jun 21 04:43:33.521621 waagent[2196]: 2025-06-21T04:43:33.521574Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jun 21 04:43:33.522232 waagent[2196]: 2025-06-21T04:43:33.522179Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jun 21 04:43:33.522419 waagent[2196]: 2025-06-21T04:43:33.522378Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jun 21 04:43:33.522482 waagent[2196]: 2025-06-21T04:43:33.522437Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 21 04:43:33.522542 waagent[2196]: 2025-06-21T04:43:33.522501Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jun 21 04:43:33.522542 waagent[2196]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jun 21 04:43:33.522542 waagent[2196]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jun 21 04:43:33.522542 waagent[2196]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jun 21 04:43:33.522542 waagent[2196]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jun 21 04:43:33.522542 waagent[2196]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 21 04:43:33.522542 waagent[2196]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 21 04:43:33.522979 waagent[2196]: 2025-06-21T04:43:33.522942Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 21 04:43:33.523416 waagent[2196]: 2025-06-21T04:43:33.523355Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jun 21 04:43:33.523481 waagent[2196]: 2025-06-21T04:43:33.523437Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jun 21 04:43:33.523974 waagent[2196]: 2025-06-21T04:43:33.523925Z INFO EnvHandler ExtHandler Configure routes Jun 21 04:43:33.524127 waagent[2196]: 2025-06-21T04:43:33.524090Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jun 21 04:43:33.524372 waagent[2196]: 2025-06-21T04:43:33.524337Z INFO EnvHandler ExtHandler Gateway:None Jun 21 04:43:33.524953 waagent[2196]: 2025-06-21T04:43:33.524920Z INFO EnvHandler ExtHandler Routes:None Jun 21 04:43:33.529841 waagent[2196]: 2025-06-21T04:43:33.529798Z INFO ExtHandler ExtHandler Jun 21 04:43:33.530177 waagent[2196]: 2025-06-21T04:43:33.530123Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 50b0c6ec-9dc6-4e67-98e4-25e6018f3d74 correlation bf3d01af-0ecb-4c56-bde2-736aa398c601 created: 2025-06-21T04:41:56.378563Z] Jun 21 04:43:33.531256 waagent[2196]: 2025-06-21T04:43:33.531206Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jun 21 04:43:33.533221 waagent[2196]: 2025-06-21T04:43:33.533174Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jun 21 04:43:33.602191 waagent[2196]: 2025-06-21T04:43:33.602110Z 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 21 04:43:33.602191 waagent[2196]: Try `iptables -h' or 'iptables --help' for more information.) Jun 21 04:43:33.602625 waagent[2196]: 2025-06-21T04:43:33.602585Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 16A079FF-6C8F-4D12-9C30-379476195B26;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jun 21 04:43:33.673108 waagent[2196]: 2025-06-21T04:43:33.673031Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jun 21 04:43:33.673108 waagent[2196]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.673108 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.673108 waagent[2196]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.673108 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.673108 waagent[2196]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.673108 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.673108 waagent[2196]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 21 04:43:33.673108 waagent[2196]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 21 04:43:33.673108 waagent[2196]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 21 04:43:33.676493 waagent[2196]: 2025-06-21T04:43:33.676453Z INFO EnvHandler ExtHandler Current Firewall rules: Jun 21 04:43:33.676493 waagent[2196]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.676493 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.676493 waagent[2196]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.676493 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.676493 waagent[2196]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 21 04:43:33.676493 waagent[2196]: pkts bytes target prot opt in out source destination Jun 21 04:43:33.676493 waagent[2196]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 21 04:43:33.676493 waagent[2196]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 21 04:43:33.676493 waagent[2196]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 21 04:43:33.676748 waagent[2196]: 2025-06-21T04:43:33.676716Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jun 21 04:43:33.676894 waagent[2196]: 2025-06-21T04:43:33.676864Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jun 21 04:43:33.695067 waagent[2196]: 2025-06-21T04:43:33.695014Z INFO MonitorHandler ExtHandler Network interfaces: Jun 21 04:43:33.695067 waagent[2196]: Executing ['ip', '-a', '-o', 'link']: Jun 21 04:43:33.695067 waagent[2196]: 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 21 04:43:33.695067 waagent[2196]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:e0:8d:59 brd ff:ff:ff:ff:ff:ff Jun 21 04:43:33.695067 waagent[2196]: Executing ['ip', '-4', '-a', '-o', 'address']: Jun 21 04:43:33.695067 waagent[2196]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jun 21 04:43:33.695067 waagent[2196]: 2: eth0 inet 10.200.8.19/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jun 21 04:43:33.695067 waagent[2196]: Executing ['ip', '-6', '-a', '-o', 'address']: Jun 21 04:43:33.695067 waagent[2196]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jun 21 04:43:33.695067 waagent[2196]: 2: eth0 inet6 fe80::6245:bdff:fee0:8d59/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jun 21 04:43:43.431797 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 21 04:43:43.437616 systemd[1]: Started sshd@0-10.200.8.19:22-10.200.16.10:49810.service - OpenSSH per-connection server daemon (10.200.16.10:49810). Jun 21 04:43:44.300546 sshd[2341]: Accepted publickey for core from 10.200.16.10 port 49810 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:43:44.301991 sshd-session[2341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:43:44.306909 systemd-logind[1887]: New session 3 of user core. Jun 21 04:43:44.318282 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 21 04:43:44.752222 setup-nvidia[2049]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 488M 0 751k 0 0 584k 0 0:14:15 0:00:01 0:14:14 584k\u000d 3 488M 3 17.8M 0 0 8016k 0 0:01:02 0:00:02 0:01:00 8017k\u000d 8 488M 8 39.8M 0 0 12.2M 0 0:00:39 0:00:03 0:00:36 12.2M\u000d 12 488M 12 63.0M 0 0 14.8M 0 0:00:32 0:00:04 0:00:28 14.8M\u000d 18 488M 18 88.2M 0 0 16.8M 0 0:00:29 0:00:05 0:00:24 17.4M\u000d 22 488M 22 111M 0 0 17.8M 0 0:00:27 0:00:06 0:00:21 22.3M\u000d 27 488M 27 135M 0 0 18.7M 0 0:00:25 0:00:07 0:00:18 23.8M\u000d 32 488M 32 159M 0 0 19.3M 0 0:00:25 0:00:08 0:00:17 24.1M\u000d 37 488M 37 182M 0 0 19.8M 0 0:00:24 0:00:09 0:00:15 24.1M\u000d 42 488M 42 206M 0 0 20.2M 0 0:00:24 0:00:10 0:00:14 23.9M\u000d 46 488M 46 229M 0 0 20.5M 0 0:00:23 0:00:11 0:00:12 23.8M\u000d 51 488M 51 253M 0 0 20.7M 0 0:00:23 0:00:12 0:00:11 23.7M\u000d 57 488M 57 278M 0 0 21.1M 0 0:00:23 0:00:13 0:00:10 24.0M\u000d 62 488M 62 302M 0 0 21.1M 0 0:00:23 0:00:14 0:00:09 23.5M\u000d 66 488M 66 326M 0 0 21.3M 0 0:00:22 0:00:15 0:00:07 23.4M\u000d 71 488M 71 349M 0 0 21.4M 0 0:00:22 0:00:16 0:00:06 23.4M\u000d 76 488M 76 373M 0 0 21.6M 0 0:00:22 0:00:17 0:00:05 23.5M\u000d 81 488M 81 397M 0 0 21.7M 0 0:00:22 0:00:18 0:00:04 23.2M\u000d 86 488M 86 420M 0 0 21.8M 0 0:00:22 0:00:19 0:00:03 23.6M\u000d 91 488M 91 444M 0 0 21.9M 0 0:00:22 0:00:20 0:00:02 23.8M\u000d 95 488M 95 465M 0 0 21.9M 0 0:00:22 0:00:21 0:00:01 23.5M\u000d 99 488M 99 487M 0 0 21.9M 0 0:00:22 0:00:22 --:--:-- 23.1M\u000d100 488M 100 488M 0 0 21.9M 0 0:00:22 0:00:22 --:--:-- 23.1M Jun 21 04:43:44.867246 systemd[1]: Started sshd@1-10.200.8.19:22-10.200.16.10:49814.service - OpenSSH per-connection server daemon (10.200.16.10:49814). Jun 21 04:43:45.014234 chronyd[1901]: Selected source PHC0 Jun 21 04:43:45.519762 sshd[2356]: Accepted publickey for core from 10.200.16.10 port 49814 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:43:45.521421 sshd-session[2356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:43:45.529956 systemd-logind[1887]: New session 4 of user core. Jun 21 04:43:45.535720 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 21 04:43:45.974818 sshd[2358]: Connection closed by 10.200.16.10 port 49814 Jun 21 04:43:45.975555 sshd-session[2356]: pam_unix(sshd:session): session closed for user core Jun 21 04:43:45.982641 systemd[1]: sshd@1-10.200.8.19:22-10.200.16.10:49814.service: Deactivated successfully. Jun 21 04:43:45.985418 systemd[1]: session-4.scope: Deactivated successfully. Jun 21 04:43:45.987138 systemd-logind[1887]: Session 4 logged out. Waiting for processes to exit. Jun 21 04:43:45.989304 systemd-logind[1887]: Removed session 4. Jun 21 04:43:46.095506 systemd[1]: Started sshd@2-10.200.8.19:22-10.200.16.10:49826.service - OpenSSH per-connection server daemon (10.200.16.10:49826). Jun 21 04:43:46.749171 sshd[2365]: Accepted publickey for core from 10.200.16.10 port 49826 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:43:46.750602 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:43:46.756311 systemd-logind[1887]: New session 5 of user core. Jun 21 04:43:46.770540 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 21 04:43:47.207239 sshd[2367]: Connection closed by 10.200.16.10 port 49826 Jun 21 04:43:47.207669 sshd-session[2365]: pam_unix(sshd:session): session closed for user core Jun 21 04:43:47.211958 systemd[1]: sshd@2-10.200.8.19:22-10.200.16.10:49826.service: Deactivated successfully. Jun 21 04:43:47.213888 systemd[1]: session-5.scope: Deactivated successfully. Jun 21 04:43:47.215301 systemd-logind[1887]: Session 5 logged out. Waiting for processes to exit. Jun 21 04:43:47.216432 systemd-logind[1887]: Removed session 5. Jun 21 04:43:55.885692 setup-nvidia[1874]: Downloading NVIDIA 535.230.02 Driver Jun 21 04:43:55.890471 setup-nvidia[2373]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 21 04:43:55.890728 setup-nvidia[2373]: Dload Upload Total Spent Left Speed Jun 21 04:43:57.320632 systemd[1]: Started sshd@3-10.200.8.19:22-10.200.16.10:56898.service - OpenSSH per-connection server daemon (10.200.16.10:56898). Jun 21 04:43:57.578116 setup-nvidia[2373]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 31 326M 31 103M 0 0 126M 0 0:00:02 --:--:-- 0:00:02 126M\u000d100 326M 100 326M 0 0 193M 0 0:00:01 0:00:01 --:--:-- 193M Jun 21 04:43:57.633493 setup-nvidia[1874]: Extract the NVIDIA Driver Installer 535.230.02 Jun 21 04:43:57.633813 setup-nvidia[1874]: /opt/nvidia/workdir/nvidia-workdir / Jun 21 04:43:57.653960 setup-nvidia[2379]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jun 21 04:43:57.963456 setup-nvidia[2379]: Verifying archive integrity... OK Jun 21 04:43:57.971187 sshd[2375]: Accepted publickey for core from 10.200.16.10 port 56898 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:43:57.972572 sshd-session[2375]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:43:57.977903 systemd-logind[1887]: New session 6 of user core. Jun 21 04:43:57.994285 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 21 04:43:58.428007 sshd[2402]: Connection closed by 10.200.16.10 port 56898 Jun 21 04:43:58.428483 sshd-session[2375]: pam_unix(sshd:session): session closed for user core Jun 21 04:43:58.432375 systemd[1]: sshd@3-10.200.8.19:22-10.200.16.10:56898.service: Deactivated successfully. Jun 21 04:43:58.434124 systemd[1]: session-6.scope: Deactivated successfully. Jun 21 04:43:58.434837 systemd-logind[1887]: Session 6 logged out. Waiting for processes to exit. Jun 21 04:43:58.435872 systemd-logind[1887]: Removed session 6. Jun 21 04:43:58.540208 systemd[1]: Started sshd@4-10.200.8.19:22-10.200.16.10:59418.service - OpenSSH per-connection server daemon (10.200.16.10:59418). Jun 21 04:43:58.706272 setup-nvidia[2379]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jun 21 04:43:59.192505 sshd[2408]: Accepted publickey for core from 10.200.16.10 port 59418 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:43:59.194369 sshd-session[2408]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:43:59.199075 systemd-logind[1887]: New session 7 of user core. Jun 21 04:43:59.213440 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 21 04:43:59.648981 sshd[2417]: Connection closed by 10.200.16.10 port 59418 Jun 21 04:43:59.650938 sshd-session[2408]: pam_unix(sshd:session): session closed for user core Jun 21 04:43:59.654776 systemd[1]: sshd@4-10.200.8.19:22-10.200.16.10:59418.service: Deactivated successfully. Jun 21 04:43:59.656636 systemd[1]: session-7.scope: Deactivated successfully. Jun 21 04:43:59.657344 systemd-logind[1887]: Session 7 logged out. Waiting for processes to exit. Jun 21 04:43:59.658452 systemd-logind[1887]: Removed session 7. Jun 21 04:44:00.712831 setup-nvidia[2413]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jun 21 04:44:00.854749 setup-nvidia[1874]: / Jun 21 04:44:00.854749 setup-nvidia[1874]: Spawn system-nspawn container to install the NVIDIA drivers Jun 21 04:44:01.321181 sudo[2426]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.0.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 21 04:44:01.322954 sudo[2426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 04:44:01.329379 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jun 21 04:44:01.332816 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 21 04:44:01.361650 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 21 04:44:01.366759 systemd[1]: Starting user@0.service - User Manager for UID 0... Jun 21 04:44:01.385301 (systemd)[2429]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jun 21 04:44:01.387660 systemd-logind[1887]: New session c3 of user root. Jun 21 04:44:01.579581 systemd[2429]: Queued start job for default target default.target. Jun 21 04:44:01.597341 systemd[2429]: Created slice app.slice - User Application Slice. Jun 21 04:44:01.597372 systemd[2429]: Reached target paths.target - Paths. Jun 21 04:44:01.597422 systemd[2429]: Reached target timers.target - Timers. Jun 21 04:44:01.598701 systemd[2429]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 21 04:44:01.609858 systemd[2429]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 21 04:44:01.609975 systemd[2429]: Reached target sockets.target - Sockets. Jun 21 04:44:01.610025 systemd[2429]: Reached target basic.target - Basic System. Jun 21 04:44:01.610074 systemd[2429]: Reached target default.target - Main User Target. Jun 21 04:44:01.610108 systemd[2429]: Startup finished in 216ms. Jun 21 04:44:01.610307 systemd[1]: Started user@0.service - User Manager for UID 0. Jun 21 04:44:01.613276 systemd[1]: Started session-c2.scope - Session c2 of User root. Jun 21 04:44:01.728175 kernel: loop6: detected capacity change from 0 to 12644352 Jun 21 04:44:01.736168 kernel: loop6: p9 Jun 21 04:44:01.760010 setup-nvidia[2438]: ░ Spawning container flatcardevelopercontainer-4372.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.0.0.bin. Jun 21 04:44:01.837137 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jun 21 04:44:01.837285 kernel: EXT4-fs (loop6p9): mounted filesystem cbb8b014-b9c9-40d9-aeee-0a301ce97b84 ro without journal. Quota mode: none. Jun 21 04:44:02.292371 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jun 21 04:44:06.325290 update_engine[1890]: I20250621 04:44:06.325203 1890 update_attempter.cc:509] Updating boot flags... Jun 21 04:44:09.770094 systemd[1]: Started sshd@5-10.200.8.19:22-10.200.16.10:50552.service - OpenSSH per-connection server daemon (10.200.16.10:50552). Jun 21 04:44:10.406166 sshd[3322]: Accepted publickey for core from 10.200.16.10 port 50552 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:10.407627 sshd-session[3322]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:10.412339 systemd-logind[1887]: New session 8 of user core. Jun 21 04:44:10.427276 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 21 04:44:10.851641 sshd[3324]: Connection closed by 10.200.16.10 port 50552 Jun 21 04:44:10.852041 sshd-session[3322]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:10.856136 systemd[1]: sshd@5-10.200.8.19:22-10.200.16.10:50552.service: Deactivated successfully. Jun 21 04:44:10.858001 systemd[1]: session-8.scope: Deactivated successfully. Jun 21 04:44:10.858729 systemd-logind[1887]: Session 8 logged out. Waiting for processes to exit. Jun 21 04:44:10.859718 systemd-logind[1887]: Removed session 8. Jun 21 04:44:10.976157 systemd[1]: Started sshd@6-10.200.8.19:22-10.200.16.10:50564.service - OpenSSH per-connection server daemon (10.200.16.10:50564). Jun 21 04:44:11.609329 sshd[3333]: Accepted publickey for core from 10.200.16.10 port 50564 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:11.610781 sshd-session[3333]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:11.615403 systemd-logind[1887]: New session 9 of user core. Jun 21 04:44:11.626308 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 21 04:44:12.053979 sshd[3429]: Connection closed by 10.200.16.10 port 50564 Jun 21 04:44:12.056410 sshd-session[3333]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:12.063572 systemd-logind[1887]: Session 9 logged out. Waiting for processes to exit. Jun 21 04:44:12.064949 systemd[1]: sshd@6-10.200.8.19:22-10.200.16.10:50564.service: Deactivated successfully. Jun 21 04:44:12.067515 systemd[1]: session-9.scope: Deactivated successfully. Jun 21 04:44:12.070720 systemd-logind[1887]: Removed session 9. Jun 21 04:44:22.174168 systemd[1]: Started sshd@7-10.200.8.19:22-10.200.16.10:35586.service - OpenSSH per-connection server daemon (10.200.16.10:35586). Jun 21 04:44:22.817999 sshd[6708]: Accepted publickey for core from 10.200.16.10 port 35586 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:22.819447 sshd-session[6708]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:22.824141 systemd-logind[1887]: New session 10 of user core. Jun 21 04:44:22.833304 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 21 04:44:23.262985 sshd[6917]: Connection closed by 10.200.16.10 port 35586 Jun 21 04:44:23.266366 sshd-session[6708]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:23.271233 systemd-logind[1887]: Session 10 logged out. Waiting for processes to exit. Jun 21 04:44:23.272067 systemd[1]: sshd@7-10.200.8.19:22-10.200.16.10:35586.service: Deactivated successfully. Jun 21 04:44:23.274708 systemd[1]: session-10.scope: Deactivated successfully. Jun 21 04:44:23.277824 systemd-logind[1887]: Removed session 10. Jun 21 04:44:23.382613 systemd[1]: Started sshd@8-10.200.8.19:22-10.200.16.10:35588.service - OpenSSH per-connection server daemon (10.200.16.10:35588). Jun 21 04:44:24.027505 sshd[7026]: Accepted publickey for core from 10.200.16.10 port 35588 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:24.028943 sshd-session[7026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:24.034678 systemd-logind[1887]: New session 11 of user core. Jun 21 04:44:24.043285 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 21 04:44:24.481318 sshd[7096]: Connection closed by 10.200.16.10 port 35588 Jun 21 04:44:24.483374 sshd-session[7026]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:24.487016 systemd-logind[1887]: Session 11 logged out. Waiting for processes to exit. Jun 21 04:44:24.487849 systemd[1]: sshd@8-10.200.8.19:22-10.200.16.10:35588.service: Deactivated successfully. Jun 21 04:44:24.491721 systemd[1]: session-11.scope: Deactivated successfully. Jun 21 04:44:24.497624 systemd-logind[1887]: Removed session 11. Jun 21 04:44:34.598065 systemd[1]: Started sshd@9-10.200.8.19:22-10.200.16.10:41752.service - OpenSSH per-connection server daemon (10.200.16.10:41752). Jun 21 04:44:36.538179 sshd[7869]: Accepted publickey for core from 10.200.16.10 port 41752 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:36.538194 sshd-session[7869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:36.545829 systemd-logind[1887]: New session 12 of user core. Jun 21 04:44:36.564331 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 21 04:44:36.935266 sshd[7873]: Connection closed by 10.200.16.10 port 41752 Jun 21 04:44:36.936000 sshd-session[7869]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:36.943552 systemd[1]: sshd@9-10.200.8.19:22-10.200.16.10:41752.service: Deactivated successfully. Jun 21 04:44:36.947773 systemd[1]: session-12.scope: Deactivated successfully. Jun 21 04:44:36.949517 systemd-logind[1887]: Session 12 logged out. Waiting for processes to exit. Jun 21 04:44:36.951076 systemd-logind[1887]: Removed session 12. Jun 21 04:44:37.062390 systemd[1]: Started sshd@10-10.200.8.19:22-10.200.16.10:41756.service - OpenSSH per-connection server daemon (10.200.16.10:41756). Jun 21 04:44:37.715951 sshd[7994]: Accepted publickey for core from 10.200.16.10 port 41756 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:37.717388 sshd-session[7994]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:37.722083 systemd-logind[1887]: New session 13 of user core. Jun 21 04:44:37.732411 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 21 04:44:38.160781 sshd[8171]: Connection closed by 10.200.16.10 port 41756 Jun 21 04:44:38.161241 sshd-session[7994]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:38.164573 systemd[1]: sshd@10-10.200.8.19:22-10.200.16.10:41756.service: Deactivated successfully. Jun 21 04:44:38.166437 systemd[1]: session-13.scope: Deactivated successfully. Jun 21 04:44:38.167706 systemd-logind[1887]: Session 13 logged out. Waiting for processes to exit. Jun 21 04:44:38.168764 systemd-logind[1887]: Removed session 13. Jun 21 04:44:48.276271 systemd[1]: Started sshd@11-10.200.8.19:22-10.200.16.10:49200.service - OpenSSH per-connection server daemon (10.200.16.10:49200). Jun 21 04:44:48.927965 sshd[9696]: Accepted publickey for core from 10.200.16.10 port 49200 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:48.930122 sshd-session[9696]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:48.942520 systemd-logind[1887]: New session 14 of user core. Jun 21 04:44:48.952600 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 21 04:44:49.381670 sshd[10047]: Connection closed by 10.200.16.10 port 49200 Jun 21 04:44:49.384222 sshd-session[9696]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:49.388687 systemd[1]: sshd@11-10.200.8.19:22-10.200.16.10:49200.service: Deactivated successfully. Jun 21 04:44:49.390428 systemd[1]: session-14.scope: Deactivated successfully. Jun 21 04:44:49.391838 systemd-logind[1887]: Session 14 logged out. Waiting for processes to exit. Jun 21 04:44:49.394042 systemd-logind[1887]: Removed session 14. Jun 21 04:44:49.499820 systemd[1]: Started sshd@12-10.200.8.19:22-10.200.16.10:47340.service - OpenSSH per-connection server daemon (10.200.16.10:47340). Jun 21 04:44:50.147224 sshd[10136]: Accepted publickey for core from 10.200.16.10 port 47340 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:44:50.149241 sshd-session[10136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:44:50.156805 systemd-logind[1887]: New session 15 of user core. Jun 21 04:44:50.163437 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 21 04:44:50.598984 sshd[10241]: Connection closed by 10.200.16.10 port 47340 Jun 21 04:44:50.599431 sshd-session[10136]: pam_unix(sshd:session): session closed for user core Jun 21 04:44:50.605168 systemd-logind[1887]: Session 15 logged out. Waiting for processes to exit. Jun 21 04:44:50.606855 systemd[1]: sshd@12-10.200.8.19:22-10.200.16.10:47340.service: Deactivated successfully. Jun 21 04:44:50.610486 systemd[1]: session-15.scope: Deactivated successfully. Jun 21 04:44:50.613516 systemd-logind[1887]: Removed session 15. Jun 21 04:45:00.723237 systemd[1]: Started sshd@13-10.200.8.19:22-10.200.16.10:35880.service - OpenSSH per-connection server daemon (10.200.16.10:35880). Jun 21 04:45:01.378913 sshd[10753]: Accepted publickey for core from 10.200.16.10 port 35880 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:01.379961 sshd-session[10753]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:01.389583 systemd-logind[1887]: New session 16 of user core. Jun 21 04:45:01.401457 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 21 04:45:01.822899 sshd[10910]: Connection closed by 10.200.16.10 port 35880 Jun 21 04:45:01.824361 sshd-session[10753]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:01.829575 systemd-logind[1887]: Session 16 logged out. Waiting for processes to exit. Jun 21 04:45:01.829867 systemd[1]: sshd@13-10.200.8.19:22-10.200.16.10:35880.service: Deactivated successfully. Jun 21 04:45:01.832098 systemd[1]: session-16.scope: Deactivated successfully. Jun 21 04:45:01.834428 systemd-logind[1887]: Removed session 16. Jun 21 04:45:01.950582 systemd[1]: Started sshd@14-10.200.8.19:22-10.200.16.10:35884.service - OpenSSH per-connection server daemon (10.200.16.10:35884). Jun 21 04:45:02.587454 sshd[11133]: Accepted publickey for core from 10.200.16.10 port 35884 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:02.588938 sshd-session[11133]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:02.593805 systemd-logind[1887]: New session 17 of user core. Jun 21 04:45:02.611349 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 21 04:45:03.034995 sshd[11353]: Connection closed by 10.200.16.10 port 35884 Jun 21 04:45:03.035473 sshd-session[11133]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:03.039550 systemd[1]: sshd@14-10.200.8.19:22-10.200.16.10:35884.service: Deactivated successfully. Jun 21 04:45:03.041302 systemd[1]: session-17.scope: Deactivated successfully. Jun 21 04:45:03.042007 systemd-logind[1887]: Session 17 logged out. Waiting for processes to exit. Jun 21 04:45:03.042991 systemd-logind[1887]: Removed session 17. Jun 21 04:45:13.151318 systemd[1]: Started sshd@15-10.200.8.19:22-10.200.16.10:56554.service - OpenSSH per-connection server daemon (10.200.16.10:56554). Jun 21 04:45:13.789492 sshd[11402]: Accepted publickey for core from 10.200.16.10 port 56554 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:13.790938 sshd-session[11402]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:13.795636 systemd-logind[1887]: New session 18 of user core. Jun 21 04:45:13.803282 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 21 04:45:14.243163 sshd[11412]: Connection closed by 10.200.16.10 port 56554 Jun 21 04:45:14.245355 sshd-session[11402]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:14.248655 systemd[1]: sshd@15-10.200.8.19:22-10.200.16.10:56554.service: Deactivated successfully. Jun 21 04:45:14.252930 systemd[1]: session-18.scope: Deactivated successfully. Jun 21 04:45:14.254305 systemd-logind[1887]: Session 18 logged out. Waiting for processes to exit. Jun 21 04:45:14.256834 systemd-logind[1887]: Removed session 18. Jun 21 04:45:14.362785 systemd[1]: Started sshd@16-10.200.8.19:22-10.200.16.10:56556.service - OpenSSH per-connection server daemon (10.200.16.10:56556). Jun 21 04:45:15.029228 sshd[11440]: Accepted publickey for core from 10.200.16.10 port 56556 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:15.031314 sshd-session[11440]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:15.040340 systemd-logind[1887]: New session 19 of user core. Jun 21 04:45:15.048359 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 21 04:45:15.481121 sshd[11468]: Connection closed by 10.200.16.10 port 56556 Jun 21 04:45:15.482372 sshd-session[11440]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:15.488657 systemd-logind[1887]: Session 19 logged out. Waiting for processes to exit. Jun 21 04:45:15.489587 systemd[1]: sshd@16-10.200.8.19:22-10.200.16.10:56556.service: Deactivated successfully. Jun 21 04:45:15.492225 systemd[1]: session-19.scope: Deactivated successfully. Jun 21 04:45:15.497864 systemd-logind[1887]: Removed session 19. Jun 21 04:45:25.598192 systemd[1]: Started sshd@17-10.200.8.19:22-10.200.16.10:40614.service - OpenSSH per-connection server daemon (10.200.16.10:40614). Jun 21 04:45:26.258942 sshd[11844]: Accepted publickey for core from 10.200.16.10 port 40614 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:26.261447 sshd-session[11844]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:26.268401 systemd-logind[1887]: New session 20 of user core. Jun 21 04:45:26.276637 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 21 04:45:26.713273 sshd[11864]: Connection closed by 10.200.16.10 port 40614 Jun 21 04:45:26.715794 sshd-session[11844]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:26.720077 systemd[1]: sshd@17-10.200.8.19:22-10.200.16.10:40614.service: Deactivated successfully. Jun 21 04:45:26.724118 systemd[1]: session-20.scope: Deactivated successfully. Jun 21 04:45:26.725512 systemd-logind[1887]: Session 20 logged out. Waiting for processes to exit. Jun 21 04:45:26.728772 systemd-logind[1887]: Removed session 20. Jun 21 04:45:26.828590 systemd[1]: Started sshd@18-10.200.8.19:22-10.200.16.10:40628.service - OpenSSH per-connection server daemon (10.200.16.10:40628). Jun 21 04:45:27.497260 sshd[11882]: Accepted publickey for core from 10.200.16.10 port 40628 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:27.500370 sshd-session[11882]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:27.509361 systemd-logind[1887]: New session 21 of user core. Jun 21 04:45:27.518371 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 21 04:45:27.948175 sshd[11896]: Connection closed by 10.200.16.10 port 40628 Jun 21 04:45:27.948603 sshd-session[11882]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:27.955398 systemd[1]: sshd@18-10.200.8.19:22-10.200.16.10:40628.service: Deactivated successfully. Jun 21 04:45:27.957591 systemd-logind[1887]: Session 21 logged out. Waiting for processes to exit. Jun 21 04:45:27.958926 systemd[1]: session-21.scope: Deactivated successfully. Jun 21 04:45:27.964444 systemd-logind[1887]: Removed session 21. Jun 21 04:45:38.067571 systemd[1]: Started sshd@19-10.200.8.19:22-10.200.16.10:60218.service - OpenSSH per-connection server daemon (10.200.16.10:60218). Jun 21 04:45:38.724870 sshd[12216]: Accepted publickey for core from 10.200.16.10 port 60218 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:38.727953 sshd-session[12216]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:38.737625 systemd-logind[1887]: New session 22 of user core. Jun 21 04:45:38.744245 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 21 04:45:39.180168 sshd[12246]: Connection closed by 10.200.16.10 port 60218 Jun 21 04:45:39.181160 sshd-session[12216]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:39.187110 systemd-logind[1887]: Session 22 logged out. Waiting for processes to exit. Jun 21 04:45:39.189106 systemd[1]: sshd@19-10.200.8.19:22-10.200.16.10:60218.service: Deactivated successfully. Jun 21 04:45:39.193006 systemd[1]: session-22.scope: Deactivated successfully. Jun 21 04:45:39.195583 systemd-logind[1887]: Removed session 22. Jun 21 04:45:39.299213 systemd[1]: Started sshd@20-10.200.8.19:22-10.200.16.10:54318.service - OpenSSH per-connection server daemon (10.200.16.10:54318). Jun 21 04:45:39.965072 sshd[12264]: Accepted publickey for core from 10.200.16.10 port 54318 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:39.967301 sshd-session[12264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:39.978700 systemd-logind[1887]: New session 23 of user core. Jun 21 04:45:39.985796 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 21 04:45:40.416236 sshd[12284]: Connection closed by 10.200.16.10 port 54318 Jun 21 04:45:40.416694 sshd-session[12264]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:40.423042 systemd-logind[1887]: Session 23 logged out. Waiting for processes to exit. Jun 21 04:45:40.424736 systemd[1]: sshd@20-10.200.8.19:22-10.200.16.10:54318.service: Deactivated successfully. Jun 21 04:45:40.426515 systemd[1]: session-23.scope: Deactivated successfully. Jun 21 04:45:40.429379 systemd-logind[1887]: Removed session 23. Jun 21 04:45:50.536100 systemd[1]: Started sshd@21-10.200.8.19:22-10.200.16.10:34552.service - OpenSSH per-connection server daemon (10.200.16.10:34552). Jun 21 04:45:51.169591 sshd[12575]: Accepted publickey for core from 10.200.16.10 port 34552 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:51.171116 sshd-session[12575]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:51.175955 systemd-logind[1887]: New session 24 of user core. Jun 21 04:45:51.185321 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 21 04:45:51.616412 sshd[12577]: Connection closed by 10.200.16.10 port 34552 Jun 21 04:45:51.616845 sshd-session[12575]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:51.620706 systemd[1]: sshd@21-10.200.8.19:22-10.200.16.10:34552.service: Deactivated successfully. Jun 21 04:45:51.622460 systemd[1]: session-24.scope: Deactivated successfully. Jun 21 04:45:51.623167 systemd-logind[1887]: Session 24 logged out. Waiting for processes to exit. Jun 21 04:45:51.624241 systemd-logind[1887]: Removed session 24. Jun 21 04:45:51.737997 systemd[1]: Started sshd@22-10.200.8.19:22-10.200.16.10:34564.service - OpenSSH per-connection server daemon (10.200.16.10:34564). Jun 21 04:45:52.372113 sshd[12583]: Accepted publickey for core from 10.200.16.10 port 34564 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:45:52.373609 sshd-session[12583]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:45:52.378317 systemd-logind[1887]: New session 25 of user core. Jun 21 04:45:52.389291 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 21 04:45:52.817592 sshd[12585]: Connection closed by 10.200.16.10 port 34564 Jun 21 04:45:52.818028 sshd-session[12583]: pam_unix(sshd:session): session closed for user core Jun 21 04:45:52.821878 systemd[1]: sshd@22-10.200.8.19:22-10.200.16.10:34564.service: Deactivated successfully. Jun 21 04:45:52.823596 systemd[1]: session-25.scope: Deactivated successfully. Jun 21 04:45:52.824312 systemd-logind[1887]: Session 25 logged out. Waiting for processes to exit. Jun 21 04:45:52.825404 systemd-logind[1887]: Removed session 25. Jun 21 04:45:58.437668 setup-nvidia[2438]: \u000d Jun 21 04:45:58.437668 setup-nvidia[2438]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jun 21 04:45:58.437668 setup-nvidia[2438]: frequently when this kernel module was built against the wrong or Jun 21 04:45:58.437668 setup-nvidia[2438]: improperly configured kernel sources, with a version of gcc that differs Jun 21 04:45:58.437668 setup-nvidia[2438]: from the one used to build the target kernel, or if another driver, such Jun 21 04:45:58.437668 setup-nvidia[2438]: as nouveau, is present and prevents the NVIDIA kernel module from Jun 21 04:45:58.437668 setup-nvidia[2438]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jun 21 04:45:58.437668 setup-nvidia[2438]: installed in this system is supported by this NVIDIA Linux graphics Jun 21 04:45:58.437668 setup-nvidia[2438]: driver release. Jun 21 04:45:58.437668 setup-nvidia[2438]: \u000d Jun 21 04:45:58.437668 setup-nvidia[2438]: Please see the log entries 'Kernel module load error' and 'Kernel Jun 21 04:45:58.437668 setup-nvidia[2438]: messages' at the end of the file Jun 21 04:45:58.437668 setup-nvidia[2438]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jun 21 04:45:58.437668 setup-nvidia[2438]: information. Jun 21 04:45:58.437668 setup-nvidia[2438]: \u000d Jun 21 04:45:58.453036 setup-nvidia[2438]: dmesg: read kernel buffer failed: Operation not permitted Jun 21 04:45:58.471559 setup-nvidia[2438]: \u000d Jun 21 04:45:58.471559 setup-nvidia[2438]: ERROR: Installation has failed. Please see the file Jun 21 04:45:58.471559 setup-nvidia[2438]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jun 21 04:45:58.471559 setup-nvidia[2438]: details. You may find suggestions on fixing installation problems in Jun 21 04:45:58.471559 setup-nvidia[2438]: the README available on the Linux driver download page at Jun 21 04:45:58.471559 setup-nvidia[2438]: www.nvidia.com. Jun 21 04:45:58.471559 setup-nvidia[2438]: \u000d Jun 21 04:45:58.471965 setup-nvidia[2438]: Last 50 lines of nvidia-installer.log: Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jun 21 04:45:58.472812 setup-nvidia[2438]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jun 21 04:45:58.472812 setup-nvidia[2438]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jun 21 04:45:58.472812 setup-nvidia[2438]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jun 21 04:45:58.472812 setup-nvidia[2438]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jun 21 04:45:58.472812 setup-nvidia[2438]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jun 21 04:45:58.472812 setup-nvidia[2438]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jun 21 04:45:58.472812 setup-nvidia[2438]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 21 04:45:58.472812 setup-nvidia[2438]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jun 21 04:45:58.473800 setup-nvidia[2438]: make[2]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/build' Jun 21 04:45:58.473800 setup-nvidia[2438]: make[1]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/source' Jun 21 04:45:58.473800 setup-nvidia[2438]: -> done. Jun 21 04:45:58.473800 setup-nvidia[2438]: -> Kernel module compilation complete. Jun 21 04:45:58.473800 setup-nvidia[2438]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jun 21 04:45:58.473800 setup-nvidia[2438]: 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 21 04:45:58.473800 setup-nvidia[2438]: \u000d Jun 21 04:45:58.473800 setup-nvidia[2438]: 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 21 04:45:58.473800 setup-nvidia[2438]: -> Kernel module load error: Operation not permitted Jun 21 04:45:58.473800 setup-nvidia[2438]: -> Kernel messages: Jun 21 04:45:58.473800 setup-nvidia[2438]: 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 21 04:46:02.929822 systemd[1]: Started sshd@23-10.200.8.19:22-10.200.16.10:53730.service - OpenSSH per-connection server daemon (10.200.16.10:53730). Jun 21 04:46:03.569930 sshd[12771]: Accepted publickey for core from 10.200.16.10 port 53730 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:03.571486 sshd-session[12771]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:03.576291 systemd-logind[1887]: New session 26 of user core. Jun 21 04:46:03.600300 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 21 04:46:04.018873 sshd[12773]: Connection closed by 10.200.16.10 port 53730 Jun 21 04:46:04.019288 sshd-session[12771]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:04.022868 systemd[1]: sshd@23-10.200.8.19:22-10.200.16.10:53730.service: Deactivated successfully. Jun 21 04:46:04.024569 systemd[1]: session-26.scope: Deactivated successfully. Jun 21 04:46:04.025768 systemd-logind[1887]: Session 26 logged out. Waiting for processes to exit. Jun 21 04:46:04.026826 systemd-logind[1887]: Removed session 26. Jun 21 04:46:04.134000 systemd[1]: Started sshd@24-10.200.8.19:22-10.200.16.10:53738.service - OpenSSH per-connection server daemon (10.200.16.10:53738). Jun 21 04:46:04.766484 sshd[12779]: Accepted publickey for core from 10.200.16.10 port 53738 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:04.767911 sshd-session[12779]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:04.772542 systemd-logind[1887]: New session 27 of user core. Jun 21 04:46:04.785275 systemd[1]: Started session-27.scope - Session 27 of User core. Jun 21 04:46:05.215613 sshd[12781]: Connection closed by 10.200.16.10 port 53738 Jun 21 04:46:05.216376 sshd-session[12779]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:05.220593 systemd[1]: sshd@24-10.200.8.19:22-10.200.16.10:53738.service: Deactivated successfully. Jun 21 04:46:05.222282 systemd[1]: session-27.scope: Deactivated successfully. Jun 21 04:46:05.222982 systemd-logind[1887]: Session 27 logged out. Waiting for processes to exit. Jun 21 04:46:05.224162 systemd-logind[1887]: Removed session 27. Jun 21 04:46:14.665179 kernel: EXT4-fs (loop6p9): unmounting filesystem cbb8b014-b9c9-40d9-aeee-0a301ce97b84. Jun 21 04:46:14.665395 setup-nvidia[2438]: Container flatcardevelopercontainer-4372.0.0.bin exited successfully. Jun 21 04:46:14.667775 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.0.0.bin.mount: Deactivated successfully. Jun 21 04:46:14.718174 sudo[2426]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:14.720242 systemd[1]: session-c2.scope: Deactivated successfully. Jun 21 04:46:14.720531 systemd[1]: session-c2.scope: Consumed 5min 33.448s CPU time, 893.3M memory peak. Jun 21 04:46:15.200686 setup-nvidia[1874]: /opt/nvidia / Jun 21 04:46:15.201707 setup-nvidia[1874]: / Jun 21 04:46:15.211175 kernel: IPMI message handler: version 39.2 Jun 21 04:46:15.215168 kernel: ipmi device interface Jun 21 04:46:15.229174 setup-nvidia[1874]: /opt/nvidia/current/usr/lib/modules/6.12.34-flatcar/video / Jun 21 04:46:15.294311 kernel: nvidia: loading out-of-tree module taints kernel. Jun 21 04:46:15.294496 kernel: nvidia: module license 'NVIDIA' taints kernel. Jun 21 04:46:15.294521 kernel: Disabling lock debugging due to kernel taint Jun 21 04:46:15.302310 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jun 21 04:46:15.302359 kernel: nvidia: module license taints kernel. Jun 21 04:46:15.333420 systemd[1]: Started sshd@25-10.200.8.19:22-10.200.16.10:35442.service - OpenSSH per-connection server daemon (10.200.16.10:35442). Jun 21 04:46:15.715177 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jun 21 04:46:15.966883 sshd[12813]: Accepted publickey for core from 10.200.16.10 port 35442 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:15.968335 sshd-session[12813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:15.973020 systemd-logind[1887]: New session 28 of user core. Jun 21 04:46:16.195737 kernel: Jun 21 04:46:16.195907 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jun 21 04:46:16.208443 systemd[1]: Started session-28.scope - Session 28 of User core. Jun 21 04:46:16.405168 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jun 21 04:46:16.426372 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jun 21 04:46:16.450182 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jun 21 04:46:16.499908 setup-nvidia[1874]: / Jun 21 04:46:16.500308 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jun 21 04:46:16.513876 setup-nvidia[12826]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 21 04:46:16.566837 sshd[12817]: Connection closed by 10.200.16.10 port 35442 Jun 21 04:46:16.567249 sshd-session[12813]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:16.570936 systemd[1]: sshd@25-10.200.8.19:22-10.200.16.10:35442.service: Deactivated successfully. Jun 21 04:46:16.572670 systemd[1]: session-28.scope: Deactivated successfully. Jun 21 04:46:16.573411 systemd-logind[1887]: Session 28 logged out. Waiting for processes to exit. Jun 21 04:46:16.574655 systemd-logind[1887]: Removed session 28. Jun 21 04:46:16.686118 systemd[1]: Started sshd@26-10.200.8.19:22-10.200.16.10:35454.service - OpenSSH per-connection server daemon (10.200.16.10:35454). Jun 21 04:46:17.318200 sshd[12851]: Accepted publickey for core from 10.200.16.10 port 35454 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:17.319667 sshd-session[12851]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:17.324318 systemd-logind[1887]: New session 29 of user core. Jun 21 04:46:17.334700 systemd[1]: Started session-29.scope - Session 29 of User core. Jun 21 04:46:17.763278 sshd[12853]: Connection closed by 10.200.16.10 port 35454 Jun 21 04:46:17.763709 sshd-session[12851]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:17.767646 systemd[1]: sshd@26-10.200.8.19:22-10.200.16.10:35454.service: Deactivated successfully. Jun 21 04:46:17.769373 systemd[1]: session-29.scope: Deactivated successfully. Jun 21 04:46:17.770077 systemd-logind[1887]: Session 29 logged out. Waiting for processes to exit. Jun 21 04:46:17.771117 systemd-logind[1887]: Removed session 29. Jun 21 04:46:18.309611 setup-nvidia[12846]: Sat Jun 21 04:46:18 2025 Jun 21 04:46:18.309611 setup-nvidia[12846]: +---------------------------------------------------------------------------------------+ Jun 21 04:46:18.309611 setup-nvidia[12846]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jun 21 04:46:18.309611 setup-nvidia[12846]: |-----------------------------------------+----------------------+----------------------+ Jun 21 04:46:18.309611 setup-nvidia[12846]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jun 21 04:46:18.309611 setup-nvidia[12846]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jun 21 04:46:18.309611 setup-nvidia[12846]: | | | MIG M. | Jun 21 04:46:18.309611 setup-nvidia[12846]: |=========================================+======================+======================| Jun 21 04:46:18.313228 setup-nvidia[12846]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jun 21 04:46:18.313228 setup-nvidia[12846]: | N/A 26C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jun 21 04:46:18.313228 setup-nvidia[12846]: | | | N/A | Jun 21 04:46:18.313228 setup-nvidia[12846]: +-----------------------------------------+----------------------+----------------------+ Jun 21 04:46:18.313337 setup-nvidia[12846]: Jun 21 04:46:18.313337 setup-nvidia[12846]: +---------------------------------------------------------------------------------------+ Jun 21 04:46:18.313337 setup-nvidia[12846]: | Processes: | Jun 21 04:46:18.313337 setup-nvidia[12846]: | GPU GI CI PID Type Process name GPU Memory | Jun 21 04:46:18.313337 setup-nvidia[12846]: | ID ID Usage | Jun 21 04:46:18.313337 setup-nvidia[12846]: |=======================================================================================| Jun 21 04:46:18.313469 setup-nvidia[12846]: | No running processes found | Jun 21 04:46:18.313469 setup-nvidia[12846]: +---------------------------------------------------------------------------------------+ Jun 21 04:46:18.694910 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 21 04:46:18.696940 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 21 04:46:18.697154 systemd[1]: Startup finished in 1.202s (firmware) + 30.102s (loader) + 4.082s (kernel) + 13.913s (initrd) + 3min 12.380s (userspace) = 4min 1.680s. Jun 21 04:46:24.753020 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jun 21 04:46:24.753886 systemd[2429]: Activating special unit exit.target... Jun 21 04:46:24.753986 systemd[2429]: Stopped target default.target - Main User Target. Jun 21 04:46:24.754027 systemd[2429]: Stopped target basic.target - Basic System. Jun 21 04:46:24.754056 systemd[2429]: Stopped target paths.target - Paths. Jun 21 04:46:24.754081 systemd[2429]: Stopped target sockets.target - Sockets. Jun 21 04:46:24.754107 systemd[2429]: Stopped target timers.target - Timers. Jun 21 04:46:24.754492 systemd[2429]: Closed dbus.socket - D-Bus User Message Bus Socket. Jun 21 04:46:24.754712 systemd[2429]: Removed slice app.slice - User Application Slice. Jun 21 04:46:24.754746 systemd[2429]: Reached target shutdown.target - Shutdown. Jun 21 04:46:24.754888 systemd[2429]: Finished systemd-exit.service - Exit the Session. Jun 21 04:46:24.754924 systemd[2429]: Reached target exit.target - Exit the Session. Jun 21 04:46:24.768498 (sd-pam)[2430]: pam_unix(systemd-user:session): session closed for user root Jun 21 04:46:24.770046 systemd[1]: user@0.service: Deactivated successfully. Jun 21 04:46:24.770442 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jun 21 04:46:24.772863 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 21 04:46:24.797685 systemd[1]: run-user-0.mount: Deactivated successfully. Jun 21 04:46:24.798709 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jun 21 04:46:24.798933 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 21 04:46:24.800776 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jun 21 04:46:24.800866 systemd[1]: user-0.slice: Consumed 5min 33.734s CPU time, 903M memory peak. Jun 21 04:46:24.801346 systemd-logind[1887]: Removed session c3. Jun 21 04:46:27.883108 systemd[1]: Started sshd@27-10.200.8.19:22-10.200.16.10:43456.service - OpenSSH per-connection server daemon (10.200.16.10:43456). Jun 21 04:46:28.324900 update_engine[1890]: I20250621 04:46:28.324826 1890 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Jun 21 04:46:28.324900 update_engine[1890]: I20250621 04:46:28.324883 1890 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Jun 21 04:46:28.325362 update_engine[1890]: I20250621 04:46:28.325057 1890 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Jun 21 04:46:28.325576 update_engine[1890]: I20250621 04:46:28.325549 1890 omaha_request_params.cc:62] Current group set to alpha Jun 21 04:46:28.325704 update_engine[1890]: I20250621 04:46:28.325685 1890 update_attempter.cc:499] Already updated boot flags. Skipping. Jun 21 04:46:28.325704 update_engine[1890]: I20250621 04:46:28.325697 1890 update_attempter.cc:643] Scheduling an action processor start. Jun 21 04:46:28.325762 update_engine[1890]: I20250621 04:46:28.325718 1890 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Jun 21 04:46:28.325762 update_engine[1890]: I20250621 04:46:28.325747 1890 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Jun 21 04:46:28.325829 update_engine[1890]: I20250621 04:46:28.325809 1890 omaha_request_action.cc:271] Posting an Omaha request to disabled Jun 21 04:46:28.325829 update_engine[1890]: I20250621 04:46:28.325824 1890 omaha_request_action.cc:272] Request: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.325829 update_engine[1890]: Jun 21 04:46:28.326048 update_engine[1890]: I20250621 04:46:28.325831 1890 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jun 21 04:46:28.326135 locksmithd[2004]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Jun 21 04:46:28.327113 update_engine[1890]: I20250621 04:46:28.327086 1890 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jun 21 04:46:28.327495 update_engine[1890]: I20250621 04:46:28.327463 1890 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jun 21 04:46:28.362485 update_engine[1890]: E20250621 04:46:28.362411 1890 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jun 21 04:46:28.362604 update_engine[1890]: I20250621 04:46:28.362530 1890 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Jun 21 04:46:28.515558 sshd[12865]: Accepted publickey for core from 10.200.16.10 port 43456 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:28.517028 sshd-session[12865]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:28.521673 systemd-logind[1887]: New session 30 of user core. Jun 21 04:46:28.533280 systemd[1]: Started session-30.scope - Session 30 of User core. Jun 21 04:46:28.960029 sshd[12867]: Connection closed by 10.200.16.10 port 43456 Jun 21 04:46:28.960426 sshd-session[12865]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:28.964049 systemd[1]: sshd@27-10.200.8.19:22-10.200.16.10:43456.service: Deactivated successfully. Jun 21 04:46:28.965885 systemd[1]: session-30.scope: Deactivated successfully. Jun 21 04:46:28.966673 systemd-logind[1887]: Session 30 logged out. Waiting for processes to exit. Jun 21 04:46:28.967650 systemd-logind[1887]: Removed session 30. Jun 21 04:46:29.076133 systemd[1]: Started sshd@28-10.200.8.19:22-10.200.16.10:41072.service - OpenSSH per-connection server daemon (10.200.16.10:41072). Jun 21 04:46:29.741966 sshd[12873]: Accepted publickey for core from 10.200.16.10 port 41072 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:29.743514 sshd-session[12873]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:29.748357 systemd-logind[1887]: New session 31 of user core. Jun 21 04:46:29.764344 systemd[1]: Started session-31.scope - Session 31 of User core. Jun 21 04:46:30.226752 sshd[12875]: Connection closed by 10.200.16.10 port 41072 Jun 21 04:46:30.227211 sshd-session[12873]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:30.230977 systemd[1]: sshd@28-10.200.8.19:22-10.200.16.10:41072.service: Deactivated successfully. Jun 21 04:46:30.233073 systemd[1]: session-31.scope: Deactivated successfully. Jun 21 04:46:30.233797 systemd-logind[1887]: Session 31 logged out. Waiting for processes to exit. Jun 21 04:46:30.234771 systemd-logind[1887]: Removed session 31. Jun 21 04:46:30.364992 systemd[1]: Started sshd@29-10.200.8.19:22-10.200.16.10:41074.service - OpenSSH per-connection server daemon (10.200.16.10:41074). Jun 21 04:46:30.998031 sshd[12881]: Accepted publickey for core from 10.200.16.10 port 41074 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:30.999617 sshd-session[12881]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:31.004205 systemd-logind[1887]: New session 32 of user core. Jun 21 04:46:31.016290 systemd[1]: Started session-32.scope - Session 32 of User core. Jun 21 04:46:31.442976 sshd[12883]: Connection closed by 10.200.16.10 port 41074 Jun 21 04:46:31.443403 sshd-session[12881]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:31.447129 systemd[1]: sshd@29-10.200.8.19:22-10.200.16.10:41074.service: Deactivated successfully. Jun 21 04:46:31.448874 systemd[1]: session-32.scope: Deactivated successfully. Jun 21 04:46:31.449655 systemd-logind[1887]: Session 32 logged out. Waiting for processes to exit. Jun 21 04:46:31.450638 systemd-logind[1887]: Removed session 32. Jun 21 04:46:31.563948 systemd[1]: Started sshd@30-10.200.8.19:22-10.200.16.10:41090.service - OpenSSH per-connection server daemon (10.200.16.10:41090). Jun 21 04:46:32.196949 sshd[12889]: Accepted publickey for core from 10.200.16.10 port 41090 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:32.198457 sshd-session[12889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:32.203122 systemd-logind[1887]: New session 33 of user core. Jun 21 04:46:32.212279 systemd[1]: Started session-33.scope - Session 33 of User core. Jun 21 04:46:32.556252 sudo[12892]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 21 04:46:32.556561 sudo[12892]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 04:46:32.604947 sudo[12892]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:32.709844 sshd[12891]: Connection closed by 10.200.16.10 port 41090 Jun 21 04:46:32.710354 sshd-session[12889]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:32.714400 systemd[1]: sshd@30-10.200.8.19:22-10.200.16.10:41090.service: Deactivated successfully. Jun 21 04:46:32.716099 systemd[1]: session-33.scope: Deactivated successfully. Jun 21 04:46:32.716845 systemd-logind[1887]: Session 33 logged out. Waiting for processes to exit. Jun 21 04:46:32.717902 systemd-logind[1887]: Removed session 33. Jun 21 04:46:32.825984 systemd[1]: Started sshd@31-10.200.8.19:22-10.200.16.10:41100.service - OpenSSH per-connection server daemon (10.200.16.10:41100). Jun 21 04:46:33.460018 sshd[12898]: Accepted publickey for core from 10.200.16.10 port 41100 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:33.461617 sshd-session[12898]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:33.466422 systemd-logind[1887]: New session 34 of user core. Jun 21 04:46:33.478283 systemd[1]: Started session-34.scope - Session 34 of User core. Jun 21 04:46:33.807892 sudo[12902]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Jun 21 04:46:33.808237 sudo[12902]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 04:46:33.815440 sudo[12902]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:33.821984 sudo[12901]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jun 21 04:46:33.822289 sudo[12901]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 04:46:33.832417 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 21 04:46:33.884295 augenrules[12924]: No rules Jun 21 04:46:33.885706 systemd[1]: audit-rules.service: Deactivated successfully. Jun 21 04:46:33.885978 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 21 04:46:33.886959 sudo[12901]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:33.989379 sshd[12900]: Connection closed by 10.200.16.10 port 41100 Jun 21 04:46:33.989798 sshd-session[12898]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:33.993960 systemd[1]: sshd@31-10.200.8.19:22-10.200.16.10:41100.service: Deactivated successfully. Jun 21 04:46:33.995735 systemd[1]: session-34.scope: Deactivated successfully. Jun 21 04:46:33.996786 systemd-logind[1887]: Session 34 logged out. Waiting for processes to exit. Jun 21 04:46:33.997984 systemd-logind[1887]: Removed session 34. Jun 21 04:46:34.105850 systemd[1]: Started sshd@32-10.200.8.19:22-10.200.16.10:41110.service - OpenSSH per-connection server daemon (10.200.16.10:41110). Jun 21 04:46:34.765687 sshd[12933]: Accepted publickey for core from 10.200.16.10 port 41110 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:34.767208 sshd-session[12933]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:34.771724 systemd-logind[1887]: New session 35 of user core. Jun 21 04:46:34.791282 systemd[1]: Started session-35.scope - Session 35 of User core. Jun 21 04:46:35.113548 sudo[12936]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jun 21 04:46:35.113850 sudo[12936]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 04:46:35.117442 sudo[12936]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:35.218431 sshd[12935]: Connection closed by 10.200.16.10 port 41110 Jun 21 04:46:35.218910 sshd-session[12933]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:35.222749 systemd[1]: sshd@32-10.200.8.19:22-10.200.16.10:41110.service: Deactivated successfully. Jun 21 04:46:35.224469 systemd[1]: session-35.scope: Deactivated successfully. Jun 21 04:46:35.225180 systemd-logind[1887]: Session 35 logged out. Waiting for processes to exit. Jun 21 04:46:35.226170 systemd-logind[1887]: Removed session 35. Jun 21 04:46:35.333705 systemd[1]: Started sshd@33-10.200.8.19:22-10.200.16.10:41126.service - OpenSSH per-connection server daemon (10.200.16.10:41126). Jun 21 04:46:35.987299 sshd[12942]: Accepted publickey for core from 10.200.16.10 port 41126 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:35.988751 sshd-session[12942]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:35.993356 systemd-logind[1887]: New session 36 of user core. Jun 21 04:46:36.002270 systemd[1]: Started session-36.scope - Session 36 of User core. Jun 21 04:46:36.333377 sudo[12945]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jun 21 04:46:36.333690 sudo[12945]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 04:46:38.323287 update_engine[1890]: I20250621 04:46:38.323182 1890 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jun 21 04:46:38.323754 update_engine[1890]: I20250621 04:46:38.323582 1890 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jun 21 04:46:38.323892 update_engine[1890]: I20250621 04:46:38.323864 1890 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jun 21 04:46:38.331308 update_engine[1890]: E20250621 04:46:38.331261 1890 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jun 21 04:46:38.331380 update_engine[1890]: I20250621 04:46:38.331328 1890 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Jun 21 04:46:39.575966 sudo[12945]: pam_unix(sudo:session): session closed for user root Jun 21 04:46:39.677960 sshd[12944]: Connection closed by 10.200.16.10 port 41126 Jun 21 04:46:39.678379 sshd-session[12942]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:39.682259 systemd[1]: sshd@33-10.200.8.19:22-10.200.16.10:41126.service: Deactivated successfully. Jun 21 04:46:39.684076 systemd[1]: session-36.scope: Deactivated successfully. Jun 21 04:46:39.684875 systemd-logind[1887]: Session 36 logged out. Waiting for processes to exit. Jun 21 04:46:39.685876 systemd-logind[1887]: Removed session 36. Jun 21 04:46:39.793784 systemd[1]: Started sshd@34-10.200.8.19:22-10.200.16.10:54576.service - OpenSSH per-connection server daemon (10.200.16.10:54576). Jun 21 04:46:40.448269 sshd[12951]: Accepted publickey for core from 10.200.16.10 port 54576 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:40.449794 sshd-session[12951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:40.454492 systemd-logind[1887]: New session 37 of user core. Jun 21 04:46:40.470297 systemd[1]: Started session-37.scope - Session 37 of User core. Jun 21 04:46:40.966026 sshd[12953]: Connection closed by 10.200.16.10 port 54576 Jun 21 04:46:40.966472 sshd-session[12951]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:40.970369 systemd[1]: sshd@34-10.200.8.19:22-10.200.16.10:54576.service: Deactivated successfully. Jun 21 04:46:40.972283 systemd[1]: session-37.scope: Deactivated successfully. Jun 21 04:46:40.972981 systemd-logind[1887]: Session 37 logged out. Waiting for processes to exit. Jun 21 04:46:40.974027 systemd-logind[1887]: Removed session 37. Jun 21 04:46:41.081828 systemd[1]: Started sshd@35-10.200.8.19:22-10.200.16.10:54580.service - OpenSSH per-connection server daemon (10.200.16.10:54580). Jun 21 04:46:41.715214 sshd[12969]: Accepted publickey for core from 10.200.16.10 port 54580 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:41.716760 sshd-session[12969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:41.721328 systemd-logind[1887]: New session 38 of user core. Jun 21 04:46:41.735324 systemd[1]: Started session-38.scope - Session 38 of User core. Jun 21 04:46:43.005805 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 21 04:46:43.021516 (dockerd)[12992]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jun 21 04:46:44.239034 dockerd[12992]: time="2025-06-21T04:46:44.238957600Z" level=info msg="Starting up" Jun 21 04:46:44.239734 dockerd[12992]: time="2025-06-21T04:46:44.239703275Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jun 21 04:46:47.629070 dockerd[12992]: time="2025-06-21T04:46:47.628997896Z" level=info msg="Loading containers: start." Jun 21 04:46:47.643172 kernel: Initializing XFRM netlink socket Jun 21 04:46:47.983571 systemd-networkd[1735]: docker0: Link UP Jun 21 04:46:48.001892 dockerd[12992]: time="2025-06-21T04:46:48.001836184Z" level=info msg="Loading containers: done." Jun 21 04:46:48.033267 dockerd[12992]: time="2025-06-21T04:46:48.033140100Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jun 21 04:46:48.033462 dockerd[12992]: time="2025-06-21T04:46:48.033334393Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jun 21 04:46:48.033501 dockerd[12992]: time="2025-06-21T04:46:48.033472788Z" level=info msg="Initializing buildkit" Jun 21 04:46:48.093633 dockerd[12992]: time="2025-06-21T04:46:48.093569407Z" level=info msg="Completed buildkit initialization" Jun 21 04:46:48.103029 dockerd[12992]: time="2025-06-21T04:46:48.102983281Z" level=info msg="Daemon has completed initialization" Jun 21 04:46:48.103167 dockerd[12992]: time="2025-06-21T04:46:48.103071578Z" level=info msg="API listen on /run/docker.sock" Jun 21 04:46:48.103302 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 21 04:46:48.321977 update_engine[1890]: I20250621 04:46:48.321882 1890 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jun 21 04:46:48.322465 update_engine[1890]: I20250621 04:46:48.322203 1890 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jun 21 04:46:48.322502 update_engine[1890]: I20250621 04:46:48.322481 1890 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jun 21 04:46:48.454534 update_engine[1890]: E20250621 04:46:48.454450 1890 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jun 21 04:46:48.455466 update_engine[1890]: I20250621 04:46:48.454555 1890 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Jun 21 04:46:49.205407 systemd[1]: var-lib-docker-overlay2-3eb50a9937602306a4e791f9d22dbb8cbb0b17dc15dc4a173618451831308756\x2dinit-merged.mount: Deactivated successfully. Jun 21 04:46:49.315681 containerd[1906]: time="2025-06-21T04:46:49.315617485Z" level=info msg="connecting to shim 4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9" address="unix:///run/containerd/s/fb26f37816aee5eac0bddc82c1d136217f68618d4bc626d027e51dcb42777f2e" namespace=moby protocol=ttrpc version=3 Jun 21 04:46:49.349319 systemd[1]: Started docker-4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9.scope - libcontainer container 4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9. Jun 21 04:46:49.403389 kernel: docker0: port 1(veth53b0842) entered blocking state Jun 21 04:46:49.403529 kernel: docker0: port 1(veth53b0842) entered disabled state Jun 21 04:46:49.403557 kernel: veth53b0842: entered allmulticast mode Jun 21 04:46:49.408798 kernel: veth53b0842: entered promiscuous mode Jun 21 04:46:49.409336 systemd-networkd[1735]: veth53b0842: Link UP Jun 21 04:46:49.439176 kernel: eth0: renamed from veth7cf64e4 Jun 21 04:46:49.439291 kernel: docker0: port 1(veth53b0842) entered blocking state Jun 21 04:46:49.445364 kernel: docker0: port 1(veth53b0842) entered forwarding state Jun 21 04:46:49.445509 systemd-networkd[1735]: veth53b0842: Gained carrier Jun 21 04:46:49.445890 systemd-networkd[1735]: docker0: Gained carrier Jun 21 04:46:50.506400 systemd-networkd[1735]: veth53b0842: Gained IPv6LL Jun 21 04:46:51.466316 systemd-networkd[1735]: docker0: Gained IPv6LL Jun 21 04:46:52.744378 systemd[1]: docker-4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9.scope: Deactivated successfully. Jun 21 04:46:52.758839 dockerd[12992]: time="2025-06-21T04:46:52.758774183Z" level=info msg="ignoring event" container=4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 21 04:46:52.759298 containerd[1906]: time="2025-06-21T04:46:52.758863880Z" level=info msg="shim disconnected" id=4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9 namespace=moby Jun 21 04:46:52.759298 containerd[1906]: time="2025-06-21T04:46:52.758899979Z" level=warning msg="cleaning up after shim disconnected" id=4d81d8c106e7ab74508ebcc0a544f0747856764b7c10386e18f0460137b87ff9 namespace=moby Jun 21 04:46:52.759298 containerd[1906]: time="2025-06-21T04:46:52.758908378Z" level=info msg="cleaning up dead shim" namespace=moby Jun 21 04:46:52.789878 systemd-networkd[1735]: veth53b0842: Lost carrier Jun 21 04:46:52.790267 kernel: docker0: port 1(veth53b0842) entered disabled state Jun 21 04:46:52.790303 kernel: veth7cf64e4: renamed from eth0 Jun 21 04:46:52.810350 systemd-networkd[1735]: veth53b0842: Link DOWN Jun 21 04:46:52.817134 kernel: docker0: port 1(veth53b0842) entered disabled state Jun 21 04:46:52.817198 kernel: veth53b0842 (unregistering): left allmulticast mode Jun 21 04:46:52.817220 kernel: veth53b0842 (unregistering): left promiscuous mode Jun 21 04:46:52.823725 kernel: docker0: port 1(veth53b0842) entered disabled state Jun 21 04:46:52.860625 systemd[1]: run-docker-netns-15a6d2df6a00.mount: Deactivated successfully. Jun 21 04:46:52.866372 systemd[1]: var-lib-docker-overlay2-3eb50a9937602306a4e791f9d22dbb8cbb0b17dc15dc4a173618451831308756-merged.mount: Deactivated successfully. Jun 21 04:46:53.010619 sshd[12971]: Connection closed by 10.200.16.10 port 54580 Jun 21 04:46:53.011046 sshd-session[12969]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:53.014940 systemd[1]: sshd@35-10.200.8.19:22-10.200.16.10:54580.service: Deactivated successfully. Jun 21 04:46:53.016872 systemd[1]: session-38.scope: Deactivated successfully. Jun 21 04:46:53.017605 systemd-logind[1887]: Session 38 logged out. Waiting for processes to exit. Jun 21 04:46:53.018588 systemd-logind[1887]: Removed session 38. Jun 21 04:46:53.126695 systemd[1]: Started sshd@36-10.200.8.19:22-10.200.16.10:57974.service - OpenSSH per-connection server daemon (10.200.16.10:57974). Jun 21 04:46:53.776775 sshd[13277]: Accepted publickey for core from 10.200.16.10 port 57974 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:53.778298 sshd-session[13277]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:53.782977 systemd-logind[1887]: New session 39 of user core. Jun 21 04:46:53.794268 systemd[1]: Started session-39.scope - Session 39 of User core. Jun 21 04:46:53.834426 systemd-networkd[1735]: docker0: Lost carrier Jun 21 04:46:54.187351 systemd[1]: var-lib-docker-overlay2-021b0bc27712cfce5daf83e3cbbc7e14d809852805f92c9675fa16ecfec1372a\x2dinit-merged.mount: Deactivated successfully. Jun 21 04:46:54.308131 containerd[1906]: time="2025-06-21T04:46:54.308070963Z" level=info msg="connecting to shim 067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde" address="unix:///run/containerd/s/443896220f2ec5b24f56c6bfd24b690f091d11a5e2ff960cec3719d4b9250dfe" namespace=moby protocol=ttrpc version=3 Jun 21 04:46:54.341288 systemd[1]: Started docker-067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde.scope - libcontainer container 067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde. Jun 21 04:46:54.389615 kernel: docker0: port 1(veth8e7b1e4) entered blocking state Jun 21 04:46:54.389731 kernel: docker0: port 1(veth8e7b1e4) entered disabled state Jun 21 04:46:54.393405 kernel: veth8e7b1e4: entered allmulticast mode Jun 21 04:46:54.396092 kernel: veth8e7b1e4: entered promiscuous mode Jun 21 04:46:54.396975 systemd-networkd[1735]: veth8e7b1e4: Link UP Jun 21 04:46:54.420173 kernel: eth0: renamed from vethf26e949 Jun 21 04:46:54.427081 kernel: docker0: port 1(veth8e7b1e4) entered blocking state Jun 21 04:46:54.427136 kernel: docker0: port 1(veth8e7b1e4) entered forwarding state Jun 21 04:46:54.427506 systemd-networkd[1735]: veth8e7b1e4: Gained carrier Jun 21 04:46:54.427771 systemd-networkd[1735]: docker0: Gained carrier Jun 21 04:46:54.463084 systemd[1]: docker-067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde.scope: Deactivated successfully. Jun 21 04:46:54.486848 containerd[1906]: time="2025-06-21T04:46:54.486633740Z" level=info msg="shim disconnected" id=067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde namespace=moby Jun 21 04:46:54.486848 containerd[1906]: time="2025-06-21T04:46:54.486683939Z" level=warning msg="cleaning up after shim disconnected" id=067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde namespace=moby Jun 21 04:46:54.486848 containerd[1906]: time="2025-06-21T04:46:54.486709238Z" level=info msg="cleaning up dead shim" namespace=moby Jun 21 04:46:54.487070 dockerd[12992]: time="2025-06-21T04:46:54.486839933Z" level=info msg="ignoring event" container=067f246b75a1e02196842c543994d2f19bb73fb9b308f24b61c808bdf3392cde module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 21 04:46:54.521885 systemd-networkd[1735]: veth8e7b1e4: Lost carrier Jun 21 04:46:54.522166 kernel: docker0: port 1(veth8e7b1e4) entered disabled state Jun 21 04:46:54.522212 kernel: vethf26e949: renamed from eth0 Jun 21 04:46:54.548450 systemd-networkd[1735]: veth8e7b1e4: Link DOWN Jun 21 04:46:54.553173 kernel: docker0: port 1(veth8e7b1e4) entered disabled state Jun 21 04:46:54.553239 kernel: veth8e7b1e4 (unregistering): left allmulticast mode Jun 21 04:46:54.558987 kernel: veth8e7b1e4 (unregistering): left promiscuous mode Jun 21 04:46:54.563297 kernel: docker0: port 1(veth8e7b1e4) entered disabled state Jun 21 04:46:54.713762 sshd[13280]: Connection closed by 10.200.16.10 port 57974 Jun 21 04:46:54.714107 sshd-session[13277]: pam_unix(sshd:session): session closed for user core Jun 21 04:46:54.717195 systemd[1]: sshd@36-10.200.8.19:22-10.200.16.10:57974.service: Deactivated successfully. Jun 21 04:46:54.719061 systemd[1]: session-39.scope: Deactivated successfully. Jun 21 04:46:54.720414 systemd-logind[1887]: Session 39 logged out. Waiting for processes to exit. Jun 21 04:46:54.721524 systemd-logind[1887]: Removed session 39. Jun 21 04:46:54.828739 systemd[1]: Started sshd@37-10.200.8.19:22-10.200.16.10:57988.service - OpenSSH per-connection server daemon (10.200.16.10:57988). Jun 21 04:46:54.842419 systemd-networkd[1735]: docker0: Lost carrier Jun 21 04:46:55.184763 systemd[1]: var-lib-docker-overlay2-021b0bc27712cfce5daf83e3cbbc7e14d809852805f92c9675fa16ecfec1372a-merged.mount: Deactivated successfully. Jun 21 04:46:55.461993 sshd[13382]: Accepted publickey for core from 10.200.16.10 port 57988 ssh2: RSA SHA256:jYWBM1yPGAIe+p2LiJChTbS35lH+dRwTR5p0HY6W3Bk Jun 21 04:46:55.463419 sshd-session[13382]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 04:46:55.468562 systemd-logind[1887]: New session 40 of user core. Jun 21 04:46:55.489316 systemd[1]: Started session-40.scope - Session 40 of User core. Jun 21 04:46:55.892173 ntpdate[13396]: ntpdate 4.2.8p17@1.4004-o Fri Jun 20 23:20:47 UTC 2025 (1) Jun 21 04:46:58.324189 update_engine[1890]: I20250621 04:46:58.324057 1890 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jun 21 04:46:58.324682 update_engine[1890]: I20250621 04:46:58.324412 1890 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jun 21 04:46:58.324718 update_engine[1890]: I20250621 04:46:58.324699 1890 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jun 21 04:46:58.329589 update_engine[1890]: E20250621 04:46:58.329556 1890 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jun 21 04:46:58.329653 update_engine[1890]: I20250621 04:46:58.329608 1890 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Jun 21 04:46:58.329653 update_engine[1890]: I20250621 04:46:58.329618 1890 omaha_request_action.cc:617] Omaha request response: Jun 21 04:46:58.329728 update_engine[1890]: E20250621 04:46:58.329706 1890 omaha_request_action.cc:636] Omaha request network transfer failed. Jun 21 04:46:58.329761 update_engine[1890]: I20250621 04:46:58.329739 1890 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Jun 21 04:46:58.329761 update_engine[1890]: I20250621 04:46:58.329746 1890 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Jun 21 04:46:58.329761 update_engine[1890]: I20250621 04:46:58.329751 1890 update_attempter.cc:306] Processing Done. Jun 21 04:46:58.329835 update_engine[1890]: E20250621 04:46:58.329768 1890 update_attempter.cc:619] Update failed. Jun 21 04:46:58.329835 update_engine[1890]: I20250621 04:46:58.329774 1890 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Jun 21 04:46:58.329835 update_engine[1890]: I20250621 04:46:58.329780 1890 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Jun 21 04:46:58.329835 update_engine[1890]: I20250621 04:46:58.329785 1890 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Jun 21 04:46:58.329934 update_engine[1890]: I20250621 04:46:58.329865 1890 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Jun 21 04:46:58.329934 update_engine[1890]: I20250621 04:46:58.329892 1890 omaha_request_action.cc:271] Posting an Omaha request to disabled Jun 21 04:46:58.329934 update_engine[1890]: I20250621 04:46:58.329898 1890 omaha_request_action.cc:272] Request: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: Jun 21 04:46:58.329934 update_engine[1890]: I20250621 04:46:58.329905 1890 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jun 21 04:46:58.330160 update_engine[1890]: I20250621 04:46:58.330039 1890 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jun 21 04:46:58.330260 update_engine[1890]: I20250621 04:46:58.330235 1890 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jun 21 04:46:58.330312 locksmithd[2004]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Jun 21 04:46:58.351724 update_engine[1890]: E20250621 04:46:58.351636 1890 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jun 21 04:46:58.351724 update_engine[1890]: I20250621 04:46:58.351734 1890 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351744 1890 omaha_request_action.cc:617] Omaha request response: Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351753 1890 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351760 1890 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351766 1890 update_attempter.cc:306] Processing Done. Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351773 1890 update_attempter.cc:310] Error event sent. Jun 21 04:46:58.351927 update_engine[1890]: I20250621 04:46:58.351785 1890 update_check_scheduler.cc:74] Next update check in 45m0s Jun 21 04:46:58.352322 locksmithd[2004]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Jun 21 04:47:02.671747 ntpdate[13396]: adjust time server 85.91.1.180 offset -0.000393 sec Jun 21 04:47:02.773861 sshd[13385]: Connection closed by 10.200.16.10 port 57988 Jun 21 04:47:02.774331 sshd-session[13382]: pam_unix(sshd:session): session closed for user core Jun 21 04:47:02.778196 systemd[1]: sshd@37-10.200.8.19:22-10.200.16.10:57988.service: Deactivated successfully. Jun 21 04:47:02.779952 systemd[1]: session-40.scope: Deactivated successfully. Jun 21 04:47:02.780670 systemd-logind[1887]: Session 40 logged out. Waiting for processes to exit. Jun 21 04:47:02.781725 systemd-logind[1887]: Removed session 40.