Apr 30 12:48:35.232282 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Tue Apr 29 22:26:36 -00 2025 Apr 30 12:48:35.232311 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=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.232322 kernel: BIOS-provided physical RAM map: Apr 30 12:48:35.232329 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Apr 30 12:48:35.232336 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Apr 30 12:48:35.232342 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Apr 30 12:48:35.232351 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Apr 30 12:48:35.232361 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Apr 30 12:48:35.232368 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Apr 30 12:48:35.232375 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Apr 30 12:48:35.232382 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Apr 30 12:48:35.232389 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Apr 30 12:48:35.232396 kernel: printk: bootconsole [earlyser0] enabled Apr 30 12:48:35.232403 kernel: NX (Execute Disable) protection: active Apr 30 12:48:35.232415 kernel: APIC: Static calls initialized Apr 30 12:48:35.232423 kernel: efi: EFI v2.7 by Microsoft Apr 30 12:48:35.232431 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Apr 30 12:48:35.232439 kernel: random: crng init done Apr 30 12:48:35.232446 kernel: secureboot: Secure boot disabled Apr 30 12:48:35.232493 kernel: SMBIOS 3.1.0 present. Apr 30 12:48:35.232502 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Apr 30 12:48:35.232510 kernel: Hypervisor detected: Microsoft Hyper-V Apr 30 12:48:35.232518 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Apr 30 12:48:35.232526 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Apr 30 12:48:35.232539 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Apr 30 12:48:35.232546 kernel: Hyper-V: Using hypercall for remote TLB flush Apr 30 12:48:35.232555 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Apr 30 12:48:35.232563 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Apr 30 12:48:35.232571 kernel: tsc: Marking TSC unstable due to running on Hyper-V Apr 30 12:48:35.232580 kernel: tsc: Detected 2593.992 MHz processor Apr 30 12:48:35.232588 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 30 12:48:35.232596 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 30 12:48:35.232604 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Apr 30 12:48:35.232615 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Apr 30 12:48:35.232623 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 30 12:48:35.232631 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Apr 30 12:48:35.232639 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Apr 30 12:48:35.232647 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Apr 30 12:48:35.232654 kernel: Using GB pages for direct mapping Apr 30 12:48:35.232662 kernel: ACPI: Early table checksum verification disabled Apr 30 12:48:35.232674 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Apr 30 12:48:35.232685 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232694 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232702 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Apr 30 12:48:35.232710 kernel: ACPI: FACS 0x000000003FFFE000 000040 Apr 30 12:48:35.232719 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232727 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232738 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232746 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232755 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232763 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232772 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 30 12:48:35.232780 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Apr 30 12:48:35.232789 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Apr 30 12:48:35.232797 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Apr 30 12:48:35.232808 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Apr 30 12:48:35.232816 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Apr 30 12:48:35.232824 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Apr 30 12:48:35.232832 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Apr 30 12:48:35.232841 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Apr 30 12:48:35.232849 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Apr 30 12:48:35.232857 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Apr 30 12:48:35.232866 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Apr 30 12:48:35.232874 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Apr 30 12:48:35.232884 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Apr 30 12:48:35.232893 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Apr 30 12:48:35.232901 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Apr 30 12:48:35.232909 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Apr 30 12:48:35.232917 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Apr 30 12:48:35.232926 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Apr 30 12:48:35.232934 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Apr 30 12:48:35.232943 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Apr 30 12:48:35.232951 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Apr 30 12:48:35.232962 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Apr 30 12:48:35.232970 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Apr 30 12:48:35.232979 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Apr 30 12:48:35.232987 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Apr 30 12:48:35.232995 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Apr 30 12:48:35.233004 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Apr 30 12:48:35.233012 kernel: Zone ranges: Apr 30 12:48:35.233021 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 30 12:48:35.233029 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Apr 30 12:48:35.233040 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Apr 30 12:48:35.233048 kernel: Movable zone start for each node Apr 30 12:48:35.233056 kernel: Early memory node ranges Apr 30 12:48:35.233065 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Apr 30 12:48:35.233073 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Apr 30 12:48:35.233081 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Apr 30 12:48:35.233090 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Apr 30 12:48:35.233098 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Apr 30 12:48:35.233106 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Apr 30 12:48:35.233117 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 30 12:48:35.233126 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Apr 30 12:48:35.233134 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Apr 30 12:48:35.233142 kernel: ACPI: PM-Timer IO Port: 0x408 Apr 30 12:48:35.233150 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Apr 30 12:48:35.233159 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Apr 30 12:48:35.233180 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 30 12:48:35.233189 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 30 12:48:35.233197 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Apr 30 12:48:35.233209 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Apr 30 12:48:35.233217 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Apr 30 12:48:35.233226 kernel: Booting paravirtualized kernel on Hyper-V Apr 30 12:48:35.233234 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 30 12:48:35.233243 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Apr 30 12:48:35.233251 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Apr 30 12:48:35.233265 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Apr 30 12:48:35.233275 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Apr 30 12:48:35.233283 kernel: Hyper-V: PV spinlocks enabled Apr 30 12:48:35.233295 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Apr 30 12:48:35.233305 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=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.233314 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 12:48:35.233322 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Apr 30 12:48:35.233331 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Apr 30 12:48:35.233339 kernel: Fallback order for Node 0: 0 Apr 30 12:48:35.233347 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Apr 30 12:48:35.233356 kernel: Policy zone: Normal Apr 30 12:48:35.233367 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 12:48:35.233383 kernel: software IO TLB: area num 8. Apr 30 12:48:35.233392 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 2103120K reserved, 0K cma-reserved) Apr 30 12:48:35.233404 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Apr 30 12:48:35.233413 kernel: Kernel/User page tables isolation: enabled Apr 30 12:48:35.233422 kernel: ftrace: allocating 37918 entries in 149 pages Apr 30 12:48:35.233431 kernel: ftrace: allocated 149 pages with 4 groups Apr 30 12:48:35.233439 kernel: Dynamic Preempt: voluntary Apr 30 12:48:35.233448 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 12:48:35.233457 kernel: rcu: RCU event tracing is enabled. Apr 30 12:48:35.233466 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Apr 30 12:48:35.233476 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 12:48:35.233487 kernel: Rude variant of Tasks RCU enabled. Apr 30 12:48:35.233496 kernel: Tracing variant of Tasks RCU enabled. Apr 30 12:48:35.233505 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 12:48:35.233514 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Apr 30 12:48:35.233534 kernel: Using NULL legacy PIC Apr 30 12:48:35.233548 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Apr 30 12:48:35.233565 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 12:48:35.233582 kernel: Console: colour dummy device 80x25 Apr 30 12:48:35.233596 kernel: printk: console [tty1] enabled Apr 30 12:48:35.233605 kernel: printk: console [ttyS0] enabled Apr 30 12:48:35.233614 kernel: printk: bootconsole [earlyser0] disabled Apr 30 12:48:35.233623 kernel: ACPI: Core revision 20230628 Apr 30 12:48:35.233635 kernel: Failed to register legacy timer interrupt Apr 30 12:48:35.233653 kernel: APIC: Switch to symmetric I/O mode setup Apr 30 12:48:35.233674 kernel: Hyper-V: enabling crash_kexec_post_notifiers Apr 30 12:48:35.233688 kernel: Hyper-V: Using IPI hypercalls Apr 30 12:48:35.233697 kernel: APIC: send_IPI() replaced with hv_send_ipi() Apr 30 12:48:35.233705 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Apr 30 12:48:35.233714 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Apr 30 12:48:35.233723 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Apr 30 12:48:35.233732 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Apr 30 12:48:35.233741 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Apr 30 12:48:35.233750 kernel: Hyper-V: Using enlightened APIC (xapic mode) Apr 30 12:48:35.233762 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Apr 30 12:48:35.233771 kernel: APIC: read() replaced with hv_apic_read() Apr 30 12:48:35.233779 kernel: APIC: write() replaced with hv_apic_write() Apr 30 12:48:35.233788 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Apr 30 12:48:35.233797 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Apr 30 12:48:35.233806 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Apr 30 12:48:35.233815 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Apr 30 12:48:35.233824 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Apr 30 12:48:35.233833 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 30 12:48:35.233844 kernel: Spectre V2 : Mitigation: Retpolines Apr 30 12:48:35.233853 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Apr 30 12:48:35.233861 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Apr 30 12:48:35.233870 kernel: Speculative Store Bypass: Vulnerable Apr 30 12:48:35.233879 kernel: MDS: Mitigation: Clear CPU buffers Apr 30 12:48:35.233887 kernel: TAA: Mitigation: Clear CPU buffers Apr 30 12:48:35.233896 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Apr 30 12:48:35.233905 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Apr 30 12:48:35.233913 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Apr 30 12:48:35.233922 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Apr 30 12:48:35.233931 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Apr 30 12:48:35.233942 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Apr 30 12:48:35.233953 kernel: Freeing SMP alternatives memory: 32K Apr 30 12:48:35.233962 kernel: pid_max: default: 32768 minimum: 301 Apr 30 12:48:35.233971 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 12:48:35.233979 kernel: landlock: Up and running. Apr 30 12:48:35.233988 kernel: SELinux: Initializing. Apr 30 12:48:35.233997 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.234005 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.234014 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Apr 30 12:48:35.234023 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.234032 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.234044 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Apr 30 12:48:35.234053 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Apr 30 12:48:35.234062 kernel: signal: max sigframe size: 1776 Apr 30 12:48:35.234071 kernel: rcu: Hierarchical SRCU implementation. Apr 30 12:48:35.234080 kernel: rcu: Max phase no-delay instances is 400. Apr 30 12:48:35.234089 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Apr 30 12:48:35.234097 kernel: smp: Bringing up secondary CPUs ... Apr 30 12:48:35.234106 kernel: smpboot: x86: Booting SMP configuration: Apr 30 12:48:35.234115 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Apr 30 12:48:35.234126 kernel: smp: Brought up 1 node, 6 CPUs Apr 30 12:48:35.234135 kernel: smpboot: Max logical packages: 1 Apr 30 12:48:35.234144 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Apr 30 12:48:35.234152 kernel: devtmpfs: initialized Apr 30 12:48:35.234182 kernel: x86/mm: Memory block size: 1024MB Apr 30 12:48:35.234193 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Apr 30 12:48:35.234202 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 12:48:35.234211 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Apr 30 12:48:35.234220 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 12:48:35.234232 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 12:48:35.234241 kernel: audit: initializing netlink subsys (disabled) Apr 30 12:48:35.234250 kernel: audit: type=2000 audit(1746017313.028:1): state=initialized audit_enabled=0 res=1 Apr 30 12:48:35.234259 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 12:48:35.234268 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 30 12:48:35.234276 kernel: cpuidle: using governor menu Apr 30 12:48:35.234285 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 12:48:35.234294 kernel: dca service started, version 1.12.1 Apr 30 12:48:35.234303 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Apr 30 12:48:35.234314 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 30 12:48:35.234323 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 30 12:48:35.234332 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Apr 30 12:48:35.234341 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 12:48:35.234350 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 12:48:35.234359 kernel: ACPI: Added _OSI(Module Device) Apr 30 12:48:35.234367 kernel: ACPI: Added _OSI(Processor Device) Apr 30 12:48:35.234376 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 12:48:35.234385 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 12:48:35.234396 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 12:48:35.234405 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 30 12:48:35.234414 kernel: ACPI: Interpreter enabled Apr 30 12:48:35.234423 kernel: ACPI: PM: (supports S0 S5) Apr 30 12:48:35.234432 kernel: ACPI: Using IOAPIC for interrupt routing Apr 30 12:48:35.234441 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 30 12:48:35.234450 kernel: PCI: Ignoring E820 reservations for host bridge windows Apr 30 12:48:35.234459 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Apr 30 12:48:35.234467 kernel: iommu: Default domain type: Translated Apr 30 12:48:35.234479 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 30 12:48:35.234487 kernel: efivars: Registered efivars operations Apr 30 12:48:35.234496 kernel: PCI: Using ACPI for IRQ routing Apr 30 12:48:35.234505 kernel: PCI: System does not support PCI Apr 30 12:48:35.234514 kernel: vgaarb: loaded Apr 30 12:48:35.234523 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Apr 30 12:48:35.234532 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 12:48:35.234540 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 12:48:35.234549 kernel: pnp: PnP ACPI init Apr 30 12:48:35.234560 kernel: pnp: PnP ACPI: found 3 devices Apr 30 12:48:35.234570 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 30 12:48:35.234578 kernel: NET: Registered PF_INET protocol family Apr 30 12:48:35.234587 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.234596 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Apr 30 12:48:35.234605 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 12:48:35.234614 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 30 12:48:35.234623 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.234635 kernel: TCP: Hash tables configured (established 524288 bind 65536) Apr 30 12:48:35.234644 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.234653 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Apr 30 12:48:35.234661 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 12:48:35.234670 kernel: NET: Registered PF_XDP protocol family Apr 30 12:48:35.234679 kernel: PCI: CLS 0 bytes, default 64 Apr 30 12:48:35.234688 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Apr 30 12:48:35.234697 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Apr 30 12:48:35.234706 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Apr 30 12:48:35.234717 kernel: Initialise system trusted keyrings Apr 30 12:48:35.234726 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Apr 30 12:48:35.234734 kernel: Key type asymmetric registered Apr 30 12:48:35.234743 kernel: Asymmetric key parser 'x509' registered Apr 30 12:48:35.234752 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 30 12:48:35.234761 kernel: io scheduler mq-deadline registered Apr 30 12:48:35.234769 kernel: io scheduler kyber registered Apr 30 12:48:35.234778 kernel: io scheduler bfq registered Apr 30 12:48:35.234787 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 30 12:48:35.234796 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 12:48:35.234807 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 30 12:48:35.234816 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Apr 30 12:48:35.234825 kernel: i8042: PNP: No PS/2 controller found. Apr 30 12:48:35.234984 kernel: rtc_cmos 00:02: registered as rtc0 Apr 30 12:48:35.235092 kernel: rtc_cmos 00:02: setting system clock to 2025-04-30T12:48:34 UTC (1746017314) Apr 30 12:48:35.235213 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Apr 30 12:48:35.235227 kernel: intel_pstate: CPU model not supported Apr 30 12:48:35.235242 kernel: efifb: probing for efifb Apr 30 12:48:35.235250 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Apr 30 12:48:35.235259 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Apr 30 12:48:35.235268 kernel: efifb: scrolling: redraw Apr 30 12:48:35.235277 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Apr 30 12:48:35.235286 kernel: Console: switching to colour frame buffer device 128x48 Apr 30 12:48:35.235295 kernel: fb0: EFI VGA frame buffer device Apr 30 12:48:35.235303 kernel: pstore: Using crash dump compression: deflate Apr 30 12:48:35.235312 kernel: pstore: Registered efi_pstore as persistent store backend Apr 30 12:48:35.235327 kernel: NET: Registered PF_INET6 protocol family Apr 30 12:48:35.235336 kernel: Segment Routing with IPv6 Apr 30 12:48:35.235345 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 12:48:35.235354 kernel: NET: Registered PF_PACKET protocol family Apr 30 12:48:35.235363 kernel: Key type dns_resolver registered Apr 30 12:48:35.235371 kernel: IPI shorthand broadcast: enabled Apr 30 12:48:35.235380 kernel: sched_clock: Marking stable (1736053200, 107088400)->(2127774300, -284632700) Apr 30 12:48:35.235389 kernel: registered taskstats version 1 Apr 30 12:48:35.235398 kernel: Loading compiled-in X.509 certificates Apr 30 12:48:35.235409 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: 10d2d341d26c1df942e743344427c053ef3a2a5f' Apr 30 12:48:35.235418 kernel: Key type .fscrypt registered Apr 30 12:48:35.235427 kernel: Key type fscrypt-provisioning registered Apr 30 12:48:35.235436 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 12:48:35.235444 kernel: ima: Allocated hash algorithm: sha1 Apr 30 12:48:35.235453 kernel: ima: No architecture policies found Apr 30 12:48:35.235462 kernel: clk: Disabling unused clocks Apr 30 12:48:35.235471 kernel: Freeing unused kernel image (initmem) memory: 43484K Apr 30 12:48:35.235479 kernel: Write protecting the kernel read-only data: 38912k Apr 30 12:48:35.235491 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K Apr 30 12:48:35.235500 kernel: Run /init as init process Apr 30 12:48:35.235509 kernel: with arguments: Apr 30 12:48:35.235517 kernel: /init Apr 30 12:48:35.235526 kernel: with environment: Apr 30 12:48:35.235534 kernel: HOME=/ Apr 30 12:48:35.235543 kernel: TERM=linux Apr 30 12:48:35.235552 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 12:48:35.235562 systemd[1]: Successfully made /usr/ read-only. Apr 30 12:48:35.235576 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) Apr 30 12:48:35.235586 systemd[1]: Detected virtualization microsoft. Apr 30 12:48:35.235595 systemd[1]: Detected architecture x86-64. Apr 30 12:48:35.235604 systemd[1]: Running in initrd. Apr 30 12:48:35.235613 systemd[1]: No hostname configured, using default hostname. Apr 30 12:48:35.235622 systemd[1]: Hostname set to . Apr 30 12:48:35.235631 systemd[1]: Initializing machine ID from random generator. Apr 30 12:48:35.235643 systemd[1]: Queued start job for default target initrd.target. Apr 30 12:48:35.235652 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:48:35.235661 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:48:35.235671 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 12:48:35.235681 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:48:35.235690 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 12:48:35.235700 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 12:48:35.235713 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 12:48:35.235723 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 12:48:35.235732 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:48:35.235742 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:48:35.235751 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:48:35.235760 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:48:35.235769 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:48:35.235779 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:48:35.235791 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:48:35.235800 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:48:35.235809 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 12:48:35.235819 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Apr 30 12:48:35.235828 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:48:35.235837 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:48:35.235847 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:48:35.235856 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:48:35.235865 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 12:48:35.235877 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:48:35.235886 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 12:48:35.235896 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 12:48:35.235905 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:48:35.235915 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:48:35.235944 systemd-journald[205]: Collecting audit messages is disabled. Apr 30 12:48:35.235969 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:35.235979 systemd-journald[205]: Journal started Apr 30 12:48:35.235999 systemd-journald[205]: Runtime Journal (/run/log/journal/5708d7ecab514382abb2a906b3230182) is 8M, max 2.2G, 2.1G free. Apr 30 12:48:35.238772 systemd-modules-load[207]: Inserted module 'overlay' Apr 30 12:48:35.245179 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:48:35.250361 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 12:48:35.252100 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:48:35.260781 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 12:48:35.283324 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 12:48:35.284771 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:48:35.299094 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:35.301336 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 12:48:35.316567 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:35.327311 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:48:35.332069 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:48:35.345074 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:35.347587 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 12:48:35.361383 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:48:35.381611 dracut-cmdline[234]: dracut-dracut-053 Apr 30 12:48:35.385558 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 12:48:35.386999 dracut-cmdline[234]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:48:35.389681 kernel: Bridge firewalling registered Apr 30 12:48:35.388187 systemd-modules-load[207]: Inserted module 'br_netfilter' Apr 30 12:48:35.389013 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:48:35.391183 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:48:35.443449 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:48:35.459309 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:48:35.493795 systemd-resolved[268]: Positive Trust Anchors: Apr 30 12:48:35.493810 systemd-resolved[268]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:48:35.493841 systemd-resolved[268]: 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 Apr 30 12:48:35.496363 systemd-resolved[268]: Defaulting to hostname 'linux'. Apr 30 12:48:35.497398 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:48:35.507520 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:48:35.561180 kernel: SCSI subsystem initialized Apr 30 12:48:35.573186 kernel: Loading iSCSI transport class v2.0-870. Apr 30 12:48:35.585185 kernel: iscsi: registered transport (tcp) Apr 30 12:48:35.607979 kernel: iscsi: registered transport (qla4xxx) Apr 30 12:48:35.608006 kernel: QLogic iSCSI HBA Driver Apr 30 12:48:35.646693 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 12:48:35.667284 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 12:48:35.697254 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 12:48:35.697295 kernel: device-mapper: uevent: version 1.0.3 Apr 30 12:48:35.702084 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 12:48:35.743182 kernel: raid6: avx2x4 gen() 31252 MB/s Apr 30 12:48:35.763183 kernel: raid6: avx2x2 gen() 30969 MB/s Apr 30 12:48:35.784542 kernel: raid6: avx2x1 gen() 26802 MB/s Apr 30 12:48:35.784571 kernel: raid6: using algorithm avx2x4 gen() 31252 MB/s Apr 30 12:48:35.806559 kernel: raid6: .... xor() 10230 MB/s, rmw enabled Apr 30 12:48:35.806582 kernel: raid6: using avx2x2 recovery algorithm Apr 30 12:48:35.830200 kernel: xor: automatically using best checksumming function avx Apr 30 12:48:35.981194 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 12:48:35.991499 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:48:36.005308 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:48:36.029498 systemd-udevd[426]: Using default interface naming scheme 'v255'. Apr 30 12:48:36.035035 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:48:36.037451 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 12:48:36.067765 dracut-pre-trigger[436]: rd.md=0: removing MD RAID activation Apr 30 12:48:36.098478 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:48:36.118288 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:48:36.174565 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:48:36.198181 kernel: cryptd: max_cpu_qlen set to 1000 Apr 30 12:48:36.201380 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 12:48:36.217185 kernel: AVX2 version of gcm_enc/dec engaged. Apr 30 12:48:36.218437 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 12:48:36.231751 kernel: AES CTR mode by8 optimization enabled Apr 30 12:48:36.231773 kernel: hv_vmbus: Vmbus version:4.0 Apr 30 12:48:36.230390 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:48:36.233445 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:48:36.267223 kernel: hv_vmbus: registering driver hyperv_keyboard Apr 30 12:48:36.267244 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 Apr 30 12:48:36.255991 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:48:36.314807 kernel: pps_core: LinuxPPS API ver. 1 registered Apr 30 12:48:36.314838 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 30 12:48:36.314863 kernel: hv_vmbus: registering driver hv_netvsc Apr 30 12:48:36.314876 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 30 12:48:36.314889 kernel: PTP clock support registered Apr 30 12:48:36.314912 kernel: hv_vmbus: registering driver hv_storvsc Apr 30 12:48:36.314925 kernel: scsi host0: storvsc_host_t Apr 30 12:48:36.315147 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Apr 30 12:48:36.315190 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Apr 30 12:48:36.315211 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Apr 30 12:48:36.315231 kernel: hv_vmbus: registering driver hv_pci Apr 30 12:48:36.285612 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 12:48:36.350395 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Apr 30 12:48:36.417153 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Apr 30 12:48:36.417327 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Apr 30 12:48:36.417484 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Apr 30 12:48:36.417616 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Apr 30 12:48:36.417749 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Apr 30 12:48:36.417908 kernel: scsi host1: storvsc_host_t Apr 30 12:48:36.418042 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Apr 30 12:48:36.418202 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Apr 30 12:48:36.418345 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Apr 30 12:48:36.418478 kernel: hv_utils: Registering HyperV Utility Driver Apr 30 12:48:36.418498 kernel: pci 0001:00:00.0: Enabling HDA controller Apr 30 12:48:36.418641 kernel: hv_vmbus: registering driver hv_utils Apr 30 12:48:36.418656 kernel: hv_vmbus: registering driver hid_hyperv Apr 30 12:48:36.418671 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Apr 30 12:48:36.418800 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Apr 30 12:48:36.418935 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Apr 30 12:48:36.419067 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Apr 30 12:48:36.292064 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:48:36.292245 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:36.350741 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:36.369212 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:48:36.369444 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:36.385366 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:36.418468 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:48:36.420454 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:48:36.421006 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:48:36.475108 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:36.489319 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:48:36.511242 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:37.149923 kernel: hv_utils: Heartbeat IC version 3.0 Apr 30 12:48:37.149954 kernel: hv_utils: Shutdown IC version 3.2 Apr 30 12:48:37.149968 kernel: hv_utils: TimeSync IC version 4.0 Apr 30 12:48:37.149980 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Apr 30 12:48:37.149993 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Apr 30 12:48:37.131301 systemd-resolved[268]: Clock change detected. Flushing caches. Apr 30 12:48:37.161788 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Apr 30 12:48:37.163176 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Apr 30 12:48:37.163193 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Apr 30 12:48:37.176923 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Apr 30 12:48:37.209486 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Apr 30 12:48:37.209661 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Apr 30 12:48:37.209829 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Apr 30 12:48:37.210684 kernel: sd 0:0:0:1: [sda] Write Protect is off Apr 30 12:48:37.210920 kernel: sd 0:0:0:0: [sdb] Write Protect is off Apr 30 12:48:37.211082 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Apr 30 12:48:37.211228 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Apr 30 12:48:37.211372 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Apr 30 12:48:37.211509 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Apr 30 12:48:37.211651 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:37.211666 kernel: sda: sda1 Apr 30 12:48:37.211678 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Apr 30 12:48:37.211816 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Apr 30 12:48:37.910175 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Apr 30 12:48:37.986810 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Apr 30 12:48:38.015859 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sdb6 scanned by (udev-worker) (491) Apr 30 12:48:38.039343 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Apr 30 12:48:38.107864 kernel: BTRFS: device fsid 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 devid 1 transid 40 /dev/sdb3 scanned by (udev-worker) (502) Apr 30 12:48:38.128054 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Apr 30 12:48:38.128875 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Apr 30 12:48:38.160959 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 12:48:38.179855 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:38.187854 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:39.194263 disk-uuid[640]: The operation has completed successfully. Apr 30 12:48:39.200932 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Apr 30 12:48:39.569789 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 12:48:39.569911 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 12:48:39.589999 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 12:48:39.599796 sh[727]: Success Apr 30 12:48:39.628863 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Apr 30 12:48:39.907015 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 12:48:39.929211 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 12:48:39.931266 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 12:48:39.956685 kernel: BTRFS info (device dm-0): first mount of filesystem 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 Apr 30 12:48:39.956726 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:39.962088 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 12:48:39.965924 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 12:48:39.969178 kernel: BTRFS info (device dm-0): using free space tree Apr 30 12:48:40.749117 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 12:48:40.756509 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 12:48:40.768988 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 12:48:40.770492 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 12:48:40.809403 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:40.809440 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:40.812659 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:40.831862 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:40.839859 kernel: BTRFS info (device sdb6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:40.843346 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 12:48:40.854012 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 12:48:40.876213 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:48:40.878935 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:48:40.916352 systemd-networkd[908]: lo: Link UP Apr 30 12:48:40.916362 systemd-networkd[908]: lo: Gained carrier Apr 30 12:48:40.917565 systemd-networkd[908]: Enumeration completed Apr 30 12:48:40.917648 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:48:40.917939 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:48:40.917944 systemd-networkd[908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:48:40.918674 systemd-networkd[908]: eth0: Link UP Apr 30 12:48:40.918849 systemd-networkd[908]: eth0: Gained carrier Apr 30 12:48:40.918859 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:48:40.923456 systemd[1]: Reached target network.target - Network. Apr 30 12:48:40.974874 systemd-networkd[908]: eth0: DHCPv4 address 10.200.4.30/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:48:42.211957 systemd-networkd[908]: eth0: Gained IPv6LL Apr 30 12:48:42.536291 ignition[881]: Ignition 2.20.0 Apr 30 12:48:42.536304 ignition[881]: Stage: fetch-offline Apr 30 12:48:42.536358 ignition[881]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.536371 ignition[881]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.543282 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:48:42.536478 ignition[881]: parsed url from cmdline: "" Apr 30 12:48:42.559008 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 30 12:48:42.536482 ignition[881]: no config URL provided Apr 30 12:48:42.536488 ignition[881]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:48:42.536498 ignition[881]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:48:42.536503 ignition[881]: failed to fetch config: resource requires networking Apr 30 12:48:42.539003 ignition[881]: Ignition finished successfully Apr 30 12:48:42.570228 ignition[920]: Ignition 2.20.0 Apr 30 12:48:42.570235 ignition[920]: Stage: fetch Apr 30 12:48:42.570379 ignition[920]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.570391 ignition[920]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.570499 ignition[920]: parsed url from cmdline: "" Apr 30 12:48:42.570503 ignition[920]: no config URL provided Apr 30 12:48:42.570509 ignition[920]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:48:42.570519 ignition[920]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:48:42.570544 ignition[920]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Apr 30 12:48:42.644214 ignition[920]: GET result: OK Apr 30 12:48:42.644256 ignition[920]: config has been read from IMDS userdata Apr 30 12:48:42.644267 ignition[920]: parsing config with SHA512: 8a1eb2ab1521ed166f182cf7c78cab5c9bd7c8ed24f37444998c736667ea32333c30dc06195e7e6b6d25cfb2d2f5e08d06d87a9ce911ad8fc7d0cdef7089e8c3 Apr 30 12:48:42.648577 unknown[920]: fetched base config from "system" Apr 30 12:48:42.648808 ignition[920]: fetch: fetch complete Apr 30 12:48:42.648605 unknown[920]: fetched base config from "system" Apr 30 12:48:42.648813 ignition[920]: fetch: fetch passed Apr 30 12:48:42.648611 unknown[920]: fetched user config from "azure" Apr 30 12:48:42.648872 ignition[920]: Ignition finished successfully Apr 30 12:48:42.655089 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 30 12:48:42.673996 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 12:48:42.698301 ignition[930]: Ignition 2.20.0 Apr 30 12:48:42.698312 ignition[930]: Stage: kargs Apr 30 12:48:42.698484 ignition[930]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.698498 ignition[930]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.699072 ignition[930]: kargs: kargs passed Apr 30 12:48:42.699117 ignition[930]: Ignition finished successfully Apr 30 12:48:42.711540 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 12:48:42.729997 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 12:48:42.740938 ignition[939]: Ignition 2.20.0 Apr 30 12:48:42.740950 ignition[939]: Stage: disks Apr 30 12:48:42.741133 ignition[939]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:42.741146 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:42.741687 ignition[939]: disks: disks passed Apr 30 12:48:42.750206 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 12:48:42.741733 ignition[939]: Ignition finished successfully Apr 30 12:48:42.757787 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 12:48:42.764804 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 12:48:42.773761 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:48:42.780962 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:48:42.782477 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:48:42.815010 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 12:48:42.890849 systemd-fsck[952]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Apr 30 12:48:42.896399 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 12:48:42.918927 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 12:48:43.013331 kernel: EXT4-fs (sdb9): mounted filesystem 59d16236-967d-47d1-a9bd-4b055a17ab77 r/w with ordered data mode. Quota mode: none. Apr 30 12:48:43.013538 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 12:48:43.015860 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 12:48:43.065913 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:48:43.093830 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sdb6 scanned by mount (963) Apr 30 12:48:43.093870 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:43.093884 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:43.093896 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:43.093908 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:43.083006 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 12:48:43.100810 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Apr 30 12:48:43.101664 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 12:48:43.101696 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:48:43.117285 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:48:43.125807 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 12:48:43.138287 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 12:48:44.443215 coreos-metadata[980]: Apr 30 12:48:44.443 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Apr 30 12:48:44.449727 coreos-metadata[980]: Apr 30 12:48:44.449 INFO Fetch successful Apr 30 12:48:44.449727 coreos-metadata[980]: Apr 30 12:48:44.449 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Apr 30 12:48:44.462191 coreos-metadata[980]: Apr 30 12:48:44.453 INFO Fetch successful Apr 30 12:48:44.467066 coreos-metadata[980]: Apr 30 12:48:44.467 INFO wrote hostname ci-4230.1.1-a-2d897ebf50 to /sysroot/etc/hostname Apr 30 12:48:44.474463 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:48:44.625162 initrd-setup-root[993]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 12:48:44.698213 initrd-setup-root[1000]: cut: /sysroot/etc/group: No such file or directory Apr 30 12:48:44.759119 initrd-setup-root[1007]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 12:48:44.763958 initrd-setup-root[1014]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 12:48:45.938946 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 12:48:45.964928 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 12:48:45.981304 kernel: BTRFS info (device sdb6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:45.969419 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 12:48:45.982059 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 12:48:46.013754 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 12:48:46.023497 ignition[1084]: INFO : Ignition 2.20.0 Apr 30 12:48:46.023497 ignition[1084]: INFO : Stage: mount Apr 30 12:48:46.029460 ignition[1084]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:46.029460 ignition[1084]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:46.029460 ignition[1084]: INFO : mount: mount passed Apr 30 12:48:46.029460 ignition[1084]: INFO : Ignition finished successfully Apr 30 12:48:46.033908 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 12:48:46.044374 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 12:48:46.066582 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:48:46.112198 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sdb6 scanned by mount (1097) Apr 30 12:48:46.112237 kernel: BTRFS info (device sdb6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:48:46.116610 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:48:46.119920 kernel: BTRFS info (device sdb6): using free space tree Apr 30 12:48:46.125855 kernel: BTRFS info (device sdb6): auto enabling async discard Apr 30 12:48:46.127221 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:48:46.157725 ignition[1114]: INFO : Ignition 2.20.0 Apr 30 12:48:46.157725 ignition[1114]: INFO : Stage: files Apr 30 12:48:46.163740 ignition[1114]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:46.163740 ignition[1114]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:46.163740 ignition[1114]: DEBUG : files: compiled without relabeling support, skipping Apr 30 12:48:46.191709 ignition[1114]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 12:48:46.191709 ignition[1114]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 12:48:46.402554 ignition[1114]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 12:48:46.408398 ignition[1114]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 12:48:46.408398 ignition[1114]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 12:48:46.403284 unknown[1114]: wrote ssh authorized keys file for user: core Apr 30 12:48:46.469704 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:48:46.476819 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:48:46.476819 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:48:46.476819 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:48:46.476819 ignition[1114]: INFO : files: files passed Apr 30 12:48:46.476819 ignition[1114]: INFO : Ignition finished successfully Apr 30 12:48:46.478362 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 12:48:46.519962 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 12:48:46.529285 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 12:48:46.531182 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 12:48:46.531271 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 12:48:46.577266 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:46.577266 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:46.590276 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:48:46.597749 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:48:46.599485 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 12:48:46.626966 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 12:48:46.650402 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 12:48:46.650509 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 12:48:46.659533 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 12:48:46.668655 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 12:48:46.676800 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 12:48:46.690988 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 12:48:46.708641 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:48:46.710627 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 12:48:46.727605 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:48:46.729989 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:48:46.739394 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 12:48:46.748983 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 12:48:46.749116 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:48:46.763617 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 12:48:46.772433 systemd[1]: Stopped target basic.target - Basic System. Apr 30 12:48:46.780243 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 12:48:46.788303 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:48:46.797197 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 12:48:46.806480 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 12:48:46.815113 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:48:46.824201 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 12:48:46.833508 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 12:48:46.841617 systemd[1]: Stopped target swap.target - Swaps. Apr 30 12:48:46.848942 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 12:48:46.849079 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:48:46.856596 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:48:46.864821 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:48:46.873768 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 12:48:46.873904 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:48:46.883234 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 12:48:46.883355 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 12:48:46.896203 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 12:48:46.896333 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:48:46.906951 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 12:48:46.907067 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 12:48:46.915114 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Apr 30 12:48:46.915233 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:48:46.943992 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 12:48:46.966436 ignition[1170]: INFO : Ignition 2.20.0 Apr 30 12:48:46.966436 ignition[1170]: INFO : Stage: umount Apr 30 12:48:46.966436 ignition[1170]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:48:46.966436 ignition[1170]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 30 12:48:46.966436 ignition[1170]: INFO : umount: umount passed Apr 30 12:48:46.966436 ignition[1170]: INFO : Ignition finished successfully Apr 30 12:48:46.945566 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 12:48:46.945693 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:48:46.959466 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 12:48:46.967958 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 12:48:46.968089 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:48:46.974331 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 12:48:46.974450 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:48:46.990553 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 12:48:46.990648 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 12:48:46.994912 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 12:48:46.995019 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 12:48:47.005543 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 12:48:47.005648 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 12:48:47.007811 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 30 12:48:47.007935 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 30 12:48:47.018618 systemd[1]: Stopped target network.target - Network. Apr 30 12:48:47.027412 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 12:48:47.027539 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:48:47.034857 systemd[1]: Stopped target paths.target - Path Units. Apr 30 12:48:47.043764 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 12:48:47.048888 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:48:47.052236 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 12:48:47.061485 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 12:48:47.069659 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 12:48:47.069761 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:48:47.077383 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 12:48:47.077481 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:48:47.085244 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 12:48:47.085374 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 12:48:47.095055 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 12:48:47.095168 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 12:48:47.103211 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 12:48:47.112866 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 12:48:47.125889 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 12:48:47.127685 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 12:48:47.127790 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 12:48:47.140908 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Apr 30 12:48:47.141193 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 12:48:47.141296 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 12:48:47.150971 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Apr 30 12:48:47.153064 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 12:48:47.153158 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 12:48:47.157247 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 12:48:47.157284 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:48:47.186953 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 12:48:47.188958 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 12:48:47.189017 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:48:47.195789 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 12:48:47.195837 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:48:47.208942 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 12:48:47.208989 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 12:48:47.216588 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 12:48:47.216636 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:48:47.226481 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:48:47.230333 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 30 12:48:47.230403 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:48:47.256493 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 12:48:47.256600 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 12:48:47.262688 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 12:48:47.262789 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 12:48:47.272700 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 12:48:47.272852 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:48:47.282935 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 12:48:47.283023 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 12:48:47.291774 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 12:48:47.291818 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:48:47.293988 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 12:48:47.294036 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:48:47.313395 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 12:48:47.313446 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 12:48:47.326146 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:48:47.326197 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:48:47.341680 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 12:48:47.341734 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 12:48:47.383020 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 12:48:47.387725 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 12:48:47.387787 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:48:47.399248 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:48:47.399301 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:48:47.414229 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Apr 30 12:48:47.414291 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:48:47.414729 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 12:48:47.414821 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 12:48:47.424219 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 12:48:47.451954 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 12:48:47.618532 systemd[1]: Switching root. Apr 30 12:48:47.964226 systemd-journald[205]: Journal stopped Apr 30 12:49:04.463115 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Apr 30 12:49:04.463149 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 12:49:04.463162 kernel: SELinux: policy capability open_perms=1 Apr 30 12:49:04.463171 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 12:49:04.463180 kernel: SELinux: policy capability always_check_network=0 Apr 30 12:49:04.463189 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 12:49:04.463199 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 12:49:04.463211 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 12:49:04.463221 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 12:49:04.463230 kernel: audit: type=1403 audit(1746017331.556:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 12:49:04.463240 systemd[1]: Successfully loaded SELinux policy in 774.042ms. Apr 30 12:49:04.463251 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.462ms. Apr 30 12:49:04.463263 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) Apr 30 12:49:04.463273 systemd[1]: Detected virtualization microsoft. Apr 30 12:49:04.463287 systemd[1]: Detected architecture x86-64. Apr 30 12:49:04.463302 systemd[1]: Detected first boot. Apr 30 12:49:04.463313 systemd[1]: Hostname set to . Apr 30 12:49:04.463323 systemd[1]: Initializing machine ID from random generator. Apr 30 12:49:04.463333 zram_generator::config[1219]: No configuration found. Apr 30 12:49:04.463347 kernel: Guest personality initialized and is inactive Apr 30 12:49:04.463357 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Apr 30 12:49:04.463367 kernel: Initialized host personality Apr 30 12:49:04.463377 kernel: NET: Registered PF_VSOCK protocol family Apr 30 12:49:04.463387 systemd[1]: Populated /etc with preset unit settings. Apr 30 12:49:04.463398 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Apr 30 12:49:04.463409 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 30 12:49:04.463419 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 30 12:49:04.463432 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 30 12:49:04.463442 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 12:49:04.463453 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 12:49:04.463464 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 12:49:04.463475 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 12:49:04.463495 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 12:49:04.463507 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 12:49:04.463521 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 12:49:04.463532 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 12:49:04.463546 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:49:04.463557 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:49:04.463567 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 12:49:04.463578 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 12:49:04.463594 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 12:49:04.463605 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:49:04.463619 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 30 12:49:04.463630 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:49:04.463641 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 30 12:49:04.463652 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 30 12:49:04.463663 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 30 12:49:04.463674 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 12:49:04.463685 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:49:04.463695 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:49:04.463709 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:49:04.463720 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:49:04.463731 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 12:49:04.463742 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 12:49:04.463752 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Apr 30 12:49:04.463767 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:49:04.463778 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:49:04.463792 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:49:04.463803 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 12:49:04.463814 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 12:49:04.463825 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 12:49:04.463836 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 12:49:04.463857 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:04.463872 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 12:49:04.463883 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 12:49:04.463894 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 12:49:04.463906 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). Apr 30 12:49:04.463917 systemd[1]: Reached target machines.target - Containers. Apr 30 12:49:04.463928 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 12:49:04.463939 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:49:04.463959 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:49:04.463974 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 12:49:04.463985 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:49:04.463996 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:49:04.464007 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:49:04.464018 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 12:49:04.464029 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:49:04.464040 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 12:49:04.464055 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 30 12:49:04.464069 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 30 12:49:04.464080 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 30 12:49:04.464091 systemd[1]: Stopped systemd-fsck-usr.service. Apr 30 12:49:04.464103 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). Apr 30 12:49:04.464114 kernel: loop: module loaded Apr 30 12:49:04.464125 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:49:04.464135 kernel: fuse: init (API version 7.39) Apr 30 12:49:04.464146 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:49:04.464180 systemd-journald[1326]: Collecting audit messages is disabled. Apr 30 12:49:04.464205 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 12:49:04.464218 systemd-journald[1326]: Journal started Apr 30 12:49:04.464244 systemd-journald[1326]: Runtime Journal (/run/log/journal/57b2718a75624374b9fd5acb719163eb) is 8M, max 2.2G, 2.1G free. Apr 30 12:49:03.781624 systemd[1]: Queued start job for default target multi-user.target. Apr 30 12:49:03.794118 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Apr 30 12:49:03.794570 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 30 12:49:04.479863 kernel: ACPI: bus type drm_connector registered Apr 30 12:49:04.479902 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 12:49:04.500476 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Apr 30 12:49:04.507057 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:49:04.514855 systemd[1]: verity-setup.service: Deactivated successfully. Apr 30 12:49:04.514897 systemd[1]: Stopped verity-setup.service. Apr 30 12:49:04.531858 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:04.537851 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:49:04.543064 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 12:49:04.547566 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 12:49:04.552356 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 12:49:04.556643 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 12:49:04.561397 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 12:49:04.566284 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 12:49:04.570528 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 12:49:04.575883 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:49:04.581507 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 12:49:04.581736 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 12:49:04.587068 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:49:04.587248 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:49:04.592451 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:49:04.592639 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:49:04.597396 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:49:04.597578 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:49:04.603059 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 12:49:04.603238 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 12:49:04.608033 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:49:04.608216 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:49:04.613023 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:49:04.618054 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 12:49:04.623698 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 12:49:04.629733 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Apr 30 12:49:04.644365 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 12:49:04.661923 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 12:49:04.667578 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 12:49:04.672216 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 12:49:04.672252 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:49:04.677648 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Apr 30 12:49:04.683869 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 12:49:04.689653 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 12:49:04.693923 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:49:04.709619 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 12:49:04.715063 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 12:49:04.719995 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:49:04.720936 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 12:49:04.725514 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:49:04.726447 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:49:04.731601 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 12:49:04.736014 systemd-journald[1326]: Time spent on flushing to /var/log/journal/57b2718a75624374b9fd5acb719163eb is 13.925ms for 934 entries. Apr 30 12:49:04.736014 systemd-journald[1326]: System Journal (/var/log/journal/57b2718a75624374b9fd5acb719163eb) is 8M, max 2.6G, 2.6G free. Apr 30 12:49:04.778256 systemd-journald[1326]: Received client request to flush runtime journal. Apr 30 12:49:04.748286 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 12:49:04.754674 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:49:04.760897 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 12:49:04.766063 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 12:49:04.771513 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 12:49:04.779285 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 12:49:04.784926 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 12:49:04.790962 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 12:49:04.806035 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Apr 30 12:49:04.812039 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 12:49:04.821858 kernel: loop0: detected capacity change from 0 to 138176 Apr 30 12:49:04.834285 udevadm[1374]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 30 12:49:04.849754 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 12:49:04.850412 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Apr 30 12:49:04.867276 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:49:04.909818 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 12:49:04.923029 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:49:05.141690 systemd-tmpfiles[1378]: ACLs are not supported, ignoring. Apr 30 12:49:05.141712 systemd-tmpfiles[1378]: ACLs are not supported, ignoring. Apr 30 12:49:05.147416 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:49:05.838903 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 12:49:05.893866 kernel: loop1: detected capacity change from 0 to 147912 Apr 30 12:49:06.185400 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 12:49:06.204048 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:49:06.227034 systemd-udevd[1385]: Using default interface naming scheme 'v255'. Apr 30 12:49:06.392865 kernel: loop2: detected capacity change from 0 to 28272 Apr 30 12:49:06.798091 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:49:06.815026 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:49:06.826394 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 30 12:49:06.921861 kernel: hv_vmbus: registering driver hyperv_fb Apr 30 12:49:06.921946 kernel: hv_vmbus: registering driver hv_balloon Apr 30 12:49:06.924890 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Apr 30 12:49:06.924956 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Apr 30 12:49:06.924981 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Apr 30 12:49:06.936858 kernel: Console: switching to colour dummy device 80x25 Apr 30 12:49:06.936909 kernel: mousedev: PS/2 mouse device common for all mice Apr 30 12:49:06.942244 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:49:06.942862 kernel: Console: switching to colour frame buffer device 128x48 Apr 30 12:49:06.957452 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 12:49:06.963113 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:49:06.963338 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:49:06.969270 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:49:06.970563 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:49:07.016667 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 12:49:07.029930 kernel: EDAC MC: Ver: 3.0.0 Apr 30 12:49:07.134066 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (1409) Apr 30 12:49:07.181019 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Apr 30 12:49:07.196972 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 12:49:07.198874 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 12:49:07.200970 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 12:49:07.242879 kernel: loop3: detected capacity change from 0 to 138176 Apr 30 12:49:07.277880 kernel: loop4: detected capacity change from 0 to 147912 Apr 30 12:49:07.293865 kernel: loop5: detected capacity change from 0 to 28272 Apr 30 12:49:07.299528 (sd-merge)[1517]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Apr 30 12:49:07.300049 (sd-merge)[1517]: Merged extensions into '/usr'. Apr 30 12:49:07.303499 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 12:49:07.320044 systemd[1]: Starting ensure-sysext.service... Apr 30 12:49:07.322542 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:49:07.333108 systemd[1]: Reload requested from client PID 1519 ('systemctl') (unit ensure-sysext.service)... Apr 30 12:49:07.333123 systemd[1]: Reloading... Apr 30 12:49:07.378870 zram_generator::config[1557]: No configuration found. Apr 30 12:49:07.469601 systemd-networkd[1401]: lo: Link UP Apr 30 12:49:07.469613 systemd-networkd[1401]: lo: Gained carrier Apr 30 12:49:07.471059 systemd-networkd[1401]: Enumeration completed Apr 30 12:49:07.471444 systemd-networkd[1401]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:07.471455 systemd-networkd[1401]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:49:07.472038 systemd-networkd[1401]: eth0: Link UP Apr 30 12:49:07.472047 systemd-networkd[1401]: eth0: Gained carrier Apr 30 12:49:07.472061 systemd-networkd[1401]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:07.525881 systemd-networkd[1401]: eth0: DHCPv4 address 10.200.4.30/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:49:07.569836 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. Apr 30 12:49:07.706707 systemd[1]: Reloading finished in 373 ms. Apr 30 12:49:07.725888 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:49:07.730930 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 12:49:07.758655 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:49:07.771096 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:49:07.775673 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:49:07.778220 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:49:07.778834 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:49:07.778964 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). Apr 30 12:49:07.780187 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Apr 30 12:49:07.781095 lvm[1514]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:49:07.782096 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 12:49:07.783790 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:49:07.783973 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:49:07.785068 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:49:07.785239 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:49:07.792113 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:49:07.793304 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:49:07.794788 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:49:07.796224 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:49:07.796775 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:49:07.796963 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). Apr 30 12:49:07.797178 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 12:49:07.799559 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:49:07.799741 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:49:07.801183 systemd[1]: Finished ensure-sysext.service. Apr 30 12:49:07.801501 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:49:07.801680 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:49:07.802265 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:49:07.802438 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:49:07.802583 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 12:49:07.802899 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 12:49:07.803800 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 12:49:07.804092 systemd-tmpfiles[1520]: ACLs are not supported, ignoring. Apr 30 12:49:07.804163 systemd-tmpfiles[1520]: ACLs are not supported, ignoring. Apr 30 12:49:07.806110 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:49:07.840727 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:49:07.840964 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:49:07.844753 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Apr 30 12:49:07.846549 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:49:07.879353 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 12:49:07.881145 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:49:07.898484 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 12:49:07.907377 lvm[1632]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:49:07.948462 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 12:49:07.956011 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:07.956040 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:49:08.182875 systemd-tmpfiles[1520]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:49:08.182889 systemd-tmpfiles[1520]: Skipping /boot Apr 30 12:49:08.194719 systemd-tmpfiles[1520]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:49:08.194733 systemd-tmpfiles[1520]: Skipping /boot Apr 30 12:49:08.215830 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:49:08.233063 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 12:49:08.238935 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 12:49:08.245058 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 12:49:08.252277 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:49:08.257580 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 12:49:08.263020 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:49:08.277161 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 12:49:08.515986 systemd-networkd[1401]: eth0: Gained IPv6LL Apr 30 12:49:08.521314 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 12:49:08.952940 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 12:49:09.017027 systemd-resolved[1639]: Positive Trust Anchors: Apr 30 12:49:09.017045 systemd-resolved[1639]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:49:09.017076 systemd-resolved[1639]: 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 Apr 30 12:49:09.021053 systemd-resolved[1639]: Using system hostname 'ci-4230.1.1-a-2d897ebf50'. Apr 30 12:49:09.022900 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:49:09.027710 systemd[1]: Reached target network.target - Network. Apr 30 12:49:09.031435 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 12:49:09.036109 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:49:09.725674 augenrules[1665]: No rules Apr 30 12:49:09.728098 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 12:49:09.728344 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 12:49:12.921699 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 12:49:12.927807 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). Apr 30 12:49:24.625081 ldconfig[1356]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 12:49:24.634475 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 12:49:24.650044 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 12:49:24.658284 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 12:49:24.663543 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:49:24.668444 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 12:49:24.674102 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 12:49:24.679994 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 12:49:24.684944 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 12:49:24.690687 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 12:49:24.696377 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 12:49:24.696421 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:49:24.700511 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:49:24.705479 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 12:49:24.711798 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 12:49:24.719206 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Apr 30 12:49:24.724530 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Apr 30 12:49:24.729907 systemd[1]: Reached target ssh-access.target - SSH Access Available. Apr 30 12:49:24.736262 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 12:49:24.741013 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Apr 30 12:49:24.746666 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 12:49:24.751344 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:49:24.755414 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:49:24.759309 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:49:24.759347 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:49:24.776924 systemd[1]: Starting chronyd.service - NTP client/server... Apr 30 12:49:24.781459 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 12:49:24.787069 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 30 12:49:24.792330 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 12:49:24.797040 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 12:49:24.802289 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 12:49:24.806455 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). Apr 30 12:49:24.806498 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Apr 30 12:49:24.807547 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Apr 30 12:49:24.811987 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Apr 30 12:49:24.813118 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 12:49:24.814541 KVP[1686]: KVP starting; pid is:1686 Apr 30 12:49:24.817372 jq[1681]: false Apr 30 12:49:24.817627 KVP[1686]: KVP LIC Version: 3.1 Apr 30 12:49:24.819864 kernel: hv_utils: KVP IC version 4.0 Apr 30 12:49:24.820362 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 12:49:24.825392 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 12:49:24.825873 (chronyd)[1677]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Apr 30 12:49:24.831272 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 12:49:24.837973 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 12:49:24.843030 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 12:49:24.843153 chronyd[1696]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Apr 30 12:49:24.843474 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 12:49:24.844105 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 12:49:24.848696 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 12:49:24.855519 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 12:49:24.855745 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 12:49:24.856167 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 12:49:24.856375 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 12:49:24.862919 jq[1698]: true Apr 30 12:49:24.870851 jq[1702]: true Apr 30 12:49:24.882882 (ntainerd)[1711]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 12:49:24.906266 chronyd[1696]: Timezone right/UTC failed leap second check, ignoring Apr 30 12:49:24.906424 chronyd[1696]: Loaded seccomp filter (level 2) Apr 30 12:49:24.907658 systemd[1]: Started chronyd.service - NTP client/server. Apr 30 12:49:24.924353 systemd-logind[1692]: New seat seat0. Apr 30 12:49:24.925709 systemd-logind[1692]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 30 12:49:24.925913 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 12:49:24.939014 update_engine[1697]: I20250430 12:49:24.938697 1697 main.cc:92] Flatcar Update Engine starting Apr 30 12:49:24.939609 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 12:49:24.940109 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 12:49:24.951892 extend-filesystems[1685]: Found loop3 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found loop4 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found loop5 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sda Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sda1 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb1 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb2 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb3 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found usr Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb4 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb6 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb7 Apr 30 12:49:24.951892 extend-filesystems[1685]: Found sdb9 Apr 30 12:49:24.951892 extend-filesystems[1685]: Checking size of /dev/sdb9 Apr 30 12:49:24.963822 dbus-daemon[1680]: [system] SELinux support is enabled Apr 30 12:49:25.025007 coreos-metadata[1679]: Apr 30 12:49:25.009 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Apr 30 12:49:25.025007 coreos-metadata[1679]: Apr 30 12:49:25.017 INFO Fetch successful Apr 30 12:49:25.025007 coreos-metadata[1679]: Apr 30 12:49:25.017 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Apr 30 12:49:25.025007 coreos-metadata[1679]: Apr 30 12:49:25.022 INFO Fetch successful Apr 30 12:49:25.025007 coreos-metadata[1679]: Apr 30 12:49:25.023 INFO Fetching http://168.63.129.16/machine/1684c73e-6fe1-419e-b8d1-f5cb9078990c/ef4bce4e%2D76c3%2D433e%2Da01c%2D563009a1da18.%5Fci%2D4230.1.1%2Da%2D2d897ebf50?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Apr 30 12:49:25.025304 update_engine[1697]: I20250430 12:49:24.966602 1697 update_check_scheduler.cc:74] Next update check in 5m44s Apr 30 12:49:25.025340 bash[1726]: Updated "/home/core/.ssh/authorized_keys" Apr 30 12:49:24.968660 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 12:49:24.973544 dbus-daemon[1680]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 30 12:49:24.972885 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). Apr 30 12:49:24.972915 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 12:49:24.983573 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). Apr 30 12:49:24.983594 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 12:49:24.991394 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 12:49:25.002663 systemd[1]: Started update-engine.service - Update Engine. Apr 30 12:49:25.008973 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Apr 30 12:49:25.024107 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 12:49:25.027678 extend-filesystems[1685]: Old size kept for /dev/sdb9 Apr 30 12:49:25.030054 extend-filesystems[1685]: Found sr0 Apr 30 12:49:25.029077 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 12:49:25.029673 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 12:49:25.038952 coreos-metadata[1679]: Apr 30 12:49:25.038 INFO Fetch successful Apr 30 12:49:25.040214 coreos-metadata[1679]: Apr 30 12:49:25.040 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Apr 30 12:49:25.047534 coreos-metadata[1679]: Apr 30 12:49:25.047 INFO Fetch successful Apr 30 12:49:25.057976 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (1740) Apr 30 12:49:25.074709 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 30 12:49:25.076543 setup-nvidia[1688]: Downloading Flatcar Container Linux Developer Container for version: 4230.1.1 Apr 30 12:49:25.080572 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 12:49:25.121248 sshd_keygen[1716]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 12:49:25.143938 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 12:49:25.158133 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 12:49:25.163145 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Apr 30 12:49:25.167880 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 12:49:25.168079 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 12:49:25.174549 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 12:49:25.187909 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 12:49:25.193814 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Apr 30 12:49:25.201066 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 12:49:25.206163 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 30 12:49:25.211062 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 12:49:25.277536 locksmithd[1751]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 12:49:25.652853 setup-nvidia[1842]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 12:49:25.653226 setup-nvidia[1842]: Dload Upload Total Spent Left Speed Apr 30 12:49:26.231792 containerd[1711]: time="2025-04-30T12:49:26.231709200Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 30 12:49:26.249729 containerd[1711]: time="2025-04-30T12:49:26.249696000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251527 containerd[1711]: time="2025-04-30T12:49:26.251192200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251527 containerd[1711]: time="2025-04-30T12:49:26.251394300Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 12:49:26.251527 containerd[1711]: time="2025-04-30T12:49:26.251429000Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 12:49:26.251645 containerd[1711]: time="2025-04-30T12:49:26.251574600Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 12:49:26.251645 containerd[1711]: time="2025-04-30T12:49:26.251614500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251720 containerd[1711]: time="2025-04-30T12:49:26.251690200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251775 containerd[1711]: time="2025-04-30T12:49:26.251720800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251986 containerd[1711]: time="2025-04-30T12:49:26.251955200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:26.251986 containerd[1711]: time="2025-04-30T12:49:26.251981800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252084 containerd[1711]: time="2025-04-30T12:49:26.251997800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252084 containerd[1711]: time="2025-04-30T12:49:26.252007600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252136 containerd[1711]: time="2025-04-30T12:49:26.252098500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252335 containerd[1711]: time="2025-04-30T12:49:26.252313400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252461 containerd[1711]: time="2025-04-30T12:49:26.252440700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:49:26.252461 containerd[1711]: time="2025-04-30T12:49:26.252459600Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 12:49:26.252574 containerd[1711]: time="2025-04-30T12:49:26.252553400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 12:49:26.252633 containerd[1711]: time="2025-04-30T12:49:26.252616400Z" level=info msg="metadata content store policy set" policy=shared Apr 30 12:49:26.267285 containerd[1711]: time="2025-04-30T12:49:26.267252800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 12:49:26.267336 containerd[1711]: time="2025-04-30T12:49:26.267301900Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 12:49:26.267336 containerd[1711]: time="2025-04-30T12:49:26.267319100Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 12:49:26.267336 containerd[1711]: time="2025-04-30T12:49:26.267334000Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 12:49:26.267414 containerd[1711]: time="2025-04-30T12:49:26.267347500Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 12:49:26.267485 containerd[1711]: time="2025-04-30T12:49:26.267462600Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 12:49:26.267693 containerd[1711]: time="2025-04-30T12:49:26.267671200Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 12:49:26.267804 containerd[1711]: time="2025-04-30T12:49:26.267783100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 12:49:26.267853 containerd[1711]: time="2025-04-30T12:49:26.267804800Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 12:49:26.267887 containerd[1711]: time="2025-04-30T12:49:26.267836600Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 12:49:26.267887 containerd[1711]: time="2025-04-30T12:49:26.267866200Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267887 containerd[1711]: time="2025-04-30T12:49:26.267879200Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267890400Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267903900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267916600Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267928700Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267939800Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.267958 containerd[1711]: time="2025-04-30T12:49:26.267950000Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.267972600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.267986700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.267998300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268010500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268022000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268034000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268045700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268056700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268069300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268093 containerd[1711]: time="2025-04-30T12:49:26.268082900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268096100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268107900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268119000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268131500Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268151200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268164000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268173800Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268218500Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268235800Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268245400Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268256900Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268265700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268276500Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 12:49:26.268312 containerd[1711]: time="2025-04-30T12:49:26.268285500Z" level=info msg="NRI interface is disabled by configuration." Apr 30 12:49:26.268618 containerd[1711]: time="2025-04-30T12:49:26.268296300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 12:49:26.268646 containerd[1711]: time="2025-04-30T12:49:26.268556900Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Apr 30 12:49:26.268646 containerd[1711]: time="2025-04-30T12:49:26.268604000Z" level=info msg="Connect containerd service" Apr 30 12:49:26.268646 containerd[1711]: time="2025-04-30T12:49:26.268639300Z" level=info msg="using legacy CRI server" Apr 30 12:49:26.268646 containerd[1711]: time="2025-04-30T12:49:26.268650400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 12:49:26.268864 containerd[1711]: time="2025-04-30T12:49:26.268764200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 12:49:26.269353 containerd[1711]: time="2025-04-30T12:49:26.269324700Z" 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" Apr 30 12:49:26.269493 containerd[1711]: time="2025-04-30T12:49:26.269456500Z" level=info msg="Start subscribing containerd event" Apr 30 12:49:26.269556 containerd[1711]: time="2025-04-30T12:49:26.269504900Z" level=info msg="Start recovering state" Apr 30 12:49:26.269589 containerd[1711]: time="2025-04-30T12:49:26.269563900Z" level=info msg="Start event monitor" Apr 30 12:49:26.269616 containerd[1711]: time="2025-04-30T12:49:26.269592600Z" level=info msg="Start snapshots syncer" Apr 30 12:49:26.269616 containerd[1711]: time="2025-04-30T12:49:26.269602600Z" level=info msg="Start cni network conf syncer for default" Apr 30 12:49:26.269616 containerd[1711]: time="2025-04-30T12:49:26.269610400Z" level=info msg="Start streaming server" Apr 30 12:49:26.269684 containerd[1711]: time="2025-04-30T12:49:26.269624500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 12:49:26.269684 containerd[1711]: time="2025-04-30T12:49:26.269674700Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 12:49:26.269757 containerd[1711]: time="2025-04-30T12:49:26.269737800Z" level=info msg="containerd successfully booted in 0.039214s" Apr 30 12:49:26.269824 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 12:49:27.410606 waagent[1837]: 2025-04-30T12:49:27.410513Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Apr 30 12:49:27.415147 waagent[1837]: 2025-04-30T12:49:27.415081Z INFO Daemon Daemon OS: flatcar 4230.1.1 Apr 30 12:49:27.418948 waagent[1837]: 2025-04-30T12:49:27.418896Z INFO Daemon Daemon Python: 3.11.11 Apr 30 12:49:27.422569 waagent[1837]: 2025-04-30T12:49:27.422517Z INFO Daemon Daemon Run daemon Apr 30 12:49:27.426020 waagent[1837]: 2025-04-30T12:49:27.425965Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.1' Apr 30 12:49:27.433186 waagent[1837]: 2025-04-30T12:49:27.433132Z INFO Daemon Daemon Using waagent for provisioning Apr 30 12:49:27.437618 waagent[1837]: 2025-04-30T12:49:27.437568Z INFO Daemon Daemon Activate resource disk Apr 30 12:49:27.441602 waagent[1837]: 2025-04-30T12:49:27.441549Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Apr 30 12:49:27.449702 waagent[1837]: 2025-04-30T12:49:27.449646Z INFO Daemon Daemon Found device: sda Apr 30 12:49:27.459164 waagent[1837]: 2025-04-30T12:49:27.459104Z INFO Daemon Daemon Examining partition table Apr 30 12:49:27.473999 waagent[1837]: 2025-04-30T12:49:27.473945Z INFO Daemon Daemon GPT not detected, determining filesystem Apr 30 12:49:27.503033 waagent[1837]: 2025-04-30T12:49:27.502972Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Apr 30 12:49:27.507784 waagent[1837]: 2025-04-30T12:49:27.507731Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Apr 30 12:49:27.840879 kernel: sda: sda1 Apr 30 12:49:27.841002 waagent[1837]: 2025-04-30T12:49:27.840864Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Apr 30 12:49:27.855714 waagent[1837]: 2025-04-30T12:49:27.855653Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Apr 30 12:49:27.855853 kernel: sda: sda1 Apr 30 12:49:30.834729 login[1840]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Apr 30 12:49:30.835491 login[1839]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 12:49:30.846766 systemd-logind[1692]: New session 2 of user core. Apr 30 12:49:30.847801 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 12:49:30.862048 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 12:49:30.872266 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 12:49:30.874535 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 12:49:30.893528 (systemd)[1917]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 12:49:30.895653 systemd-logind[1692]: New session c1 of user core. Apr 30 12:49:31.179299 systemd[1917]: Queued start job for default target default.target. Apr 30 12:49:31.195930 systemd[1917]: Created slice app.slice - User Application Slice. Apr 30 12:49:31.195965 systemd[1917]: Reached target paths.target - Paths. Apr 30 12:49:31.196022 systemd[1917]: Reached target timers.target - Timers. Apr 30 12:49:31.197343 systemd[1917]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 12:49:31.208008 systemd[1917]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:49:31.208128 systemd[1917]: Reached target sockets.target - Sockets. Apr 30 12:49:31.208181 systemd[1917]: Reached target basic.target - Basic System. Apr 30 12:49:31.208226 systemd[1917]: Reached target default.target - Main User Target. Apr 30 12:49:31.208261 systemd[1917]: Startup finished in 306ms. Apr 30 12:49:31.208336 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 12:49:31.209701 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 12:49:31.836617 login[1840]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 30 12:49:31.841075 systemd-logind[1692]: New session 1 of user core. Apr 30 12:49:31.856964 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 12:49:32.999721 waagent[1837]: 2025-04-30T12:49:32.999626Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Apr 30 12:49:33.022857 kernel: EXT4-fs (sda1): mounted filesystem eac1585b-fd19-4bc0-ac96-d5df6c7a2394 r/w with ordered data mode. Quota mode: none. Apr 30 12:49:33.023052 waagent[1837]: 2025-04-30T12:49:33.022994Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Apr 30 12:49:33.025280 waagent[1837]: 2025-04-30T12:49:33.025229Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 30 12:49:33.025619 waagent[1837]: 2025-04-30T12:49:33.025574Z INFO Daemon Daemon Running default provisioning handler Apr 30 12:49:33.033962 waagent[1837]: 2025-04-30T12:49:33.032887Z 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. Apr 30 12:49:33.035181 waagent[1837]: 2025-04-30T12:49:33.035134Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Apr 30 12:49:33.035553 waagent[1837]: 2025-04-30T12:49:33.035512Z INFO Daemon Daemon cloud-init is enabled: False Apr 30 12:49:33.037130 waagent[1837]: 2025-04-30T12:49:33.037082Z INFO Daemon Daemon Copying ovf-env.xml Apr 30 12:49:33.263629 waagent[1837]: 2025-04-30T12:49:33.263485Z INFO Daemon Daemon Successfully mounted dvd Apr 30 12:49:33.304659 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Apr 30 12:49:33.304942 waagent[1837]: 2025-04-30T12:49:33.304692Z INFO Daemon Daemon Detect protocol endpoint Apr 30 12:49:33.306463 waagent[1837]: 2025-04-30T12:49:33.306412Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 30 12:49:33.307257 waagent[1837]: 2025-04-30T12:49:33.307216Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Apr 30 12:49:33.307992 waagent[1837]: 2025-04-30T12:49:33.307955Z INFO Daemon Daemon Test for route to 168.63.129.16 Apr 30 12:49:33.308927 waagent[1837]: 2025-04-30T12:49:33.308883Z INFO Daemon Daemon Route to 168.63.129.16 exists Apr 30 12:49:33.309543 waagent[1837]: 2025-04-30T12:49:33.309504Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Apr 30 12:49:33.366288 waagent[1837]: 2025-04-30T12:49:33.366233Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Apr 30 12:49:33.368206 waagent[1837]: 2025-04-30T12:49:33.368161Z INFO Daemon Daemon Wire protocol version:2012-11-30 Apr 30 12:49:33.369616 waagent[1837]: 2025-04-30T12:49:33.369568Z INFO Daemon Daemon Server preferred version:2015-04-05 Apr 30 12:49:33.494971 waagent[1837]: 2025-04-30T12:49:33.494868Z INFO Daemon Daemon Initializing goal state during protocol detection Apr 30 12:49:33.496731 waagent[1837]: 2025-04-30T12:49:33.496677Z INFO Daemon Daemon Forcing an update of the goal state. Apr 30 12:49:33.501322 waagent[1837]: 2025-04-30T12:49:33.501265Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Apr 30 12:49:33.527980 waagent[1837]: 2025-04-30T12:49:33.527888Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.166 Apr 30 12:49:33.530060 waagent[1837]: 2025-04-30T12:49:33.530007Z INFO Daemon Apr 30 12:49:33.530459 waagent[1837]: 2025-04-30T12:49:33.530415Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: d1038b7b-0595-4e46-a429-0256db30f4d5 eTag: 4432687961135355141 source: Fabric] Apr 30 12:49:33.532121 waagent[1837]: 2025-04-30T12:49:33.532073Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Apr 30 12:49:33.533200 waagent[1837]: 2025-04-30T12:49:33.533152Z INFO Daemon Apr 30 12:49:33.533417 waagent[1837]: 2025-04-30T12:49:33.533376Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Apr 30 12:49:33.556480 waagent[1837]: 2025-04-30T12:49:33.556438Z INFO Daemon Daemon Downloading artifacts profile blob Apr 30 12:49:33.694484 waagent[1837]: 2025-04-30T12:49:33.694410Z INFO Daemon Downloaded certificate {'thumbprint': '84D67176ED6AB4C86A88E5B580056124619A1BB2', 'hasPrivateKey': True} Apr 30 12:49:33.696592 waagent[1837]: 2025-04-30T12:49:33.696539Z INFO Daemon Fetch goal state completed Apr 30 12:49:33.734048 waagent[1837]: 2025-04-30T12:49:33.734000Z INFO Daemon Daemon Starting provisioning Apr 30 12:49:33.735735 waagent[1837]: 2025-04-30T12:49:33.735664Z INFO Daemon Daemon Handle ovf-env.xml. Apr 30 12:49:33.737293 waagent[1837]: 2025-04-30T12:49:33.737230Z INFO Daemon Daemon Set hostname [ci-4230.1.1-a-2d897ebf50] Apr 30 12:49:33.783302 waagent[1837]: 2025-04-30T12:49:33.783232Z INFO Daemon Daemon Publish hostname [ci-4230.1.1-a-2d897ebf50] Apr 30 12:49:33.785215 waagent[1837]: 2025-04-30T12:49:33.785162Z INFO Daemon Daemon Examine /proc/net/route for primary interface Apr 30 12:49:33.787686 waagent[1837]: 2025-04-30T12:49:33.785965Z INFO Daemon Daemon Primary interface is [eth0] Apr 30 12:49:33.795016 systemd-networkd[1401]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:49:33.795026 systemd-networkd[1401]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:49:33.795058 systemd-networkd[1401]: eth0: DHCP lease lost Apr 30 12:49:33.796965 waagent[1837]: 2025-04-30T12:49:33.796902Z INFO Daemon Daemon Create user account if not exists Apr 30 12:49:33.824796 waagent[1837]: 2025-04-30T12:49:33.798673Z INFO Daemon Daemon User core already exists, skip useradd Apr 30 12:49:33.824796 waagent[1837]: 2025-04-30T12:49:33.800331Z INFO Daemon Daemon Configure sudoer Apr 30 12:49:33.824796 waagent[1837]: 2025-04-30T12:49:33.801373Z INFO Daemon Daemon Configure sshd Apr 30 12:49:33.824796 waagent[1837]: 2025-04-30T12:49:33.802879Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Apr 30 12:49:33.824796 waagent[1837]: 2025-04-30T12:49:33.803411Z INFO Daemon Daemon Deploy ssh public key. Apr 30 12:49:33.879875 systemd-networkd[1401]: eth0: DHCPv4 address 10.200.4.30/24, gateway 10.200.4.1 acquired from 168.63.129.16 Apr 30 12:49:48.686141 chronyd[1696]: Selected source PHC0 Apr 30 12:49:50.488345 setup-nvidia[1842]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 480M 0 303k 0 0 287k 0 0:28:27 0:00:01 0:28:26 288k\u000d 2 480M 2 12.7M 0 0 6239k 0 0:01:18 0:00:02 0:01:16 6240k\u000d 7 480M 7 34.0M 0 0 11.4M 0 0:00:42 0:00:02 0:00:40 11.4M\u000d 11 480M 11 57.3M 0 0 14.2M 0 0:00:33 0:00:04 0:00:29 14.2M\u000d 17 480M 17 81.6M 0 0 16.0M 0 0:00:29 0:00:05 0:00:24 16.0M\u000d 21 480M 21 103M 0 0 17.2M 0 0:00:27 0:00:05 0:00:22 20.9M\u000d 26 480M 26 128M 0 0 18.2M 0 0:00:26 0:00:07 0:00:19 23.3M\u000d 31 480M 31 151M 0 0 18.8M 0 0:00:25 0:00:08 0:00:17 23.1M\u000d 32 480M 32 155M 0 0 17.2M 0 0:00:27 0:00:08 0:00:19 19.7M\u000d 32 480M 32 155M 0 0 15.5M 0 0:00:30 0:00:10 0:00:20 15.0M\u000d 33 480M 33 161M 0 0 14.6M 0 0:00:32 0:00:11 0:00:21 11.5M\u000d 38 480M 38 185M 0 0 15.3M 0 0:00:31 0:00:12 0:00:19 11.3M\u000d 42 480M 42 204M 0 0 15.7M 0 0:00:30 0:00:12 0:00:18 10.7M\u000d 47 480M 47 230M 0 0 16.3M 0 0:00:29 0:00:14 0:00:15 14.7M\u000d 53 480M 53 254M 0 0 16.8M 0 0:00:28 0:00:15 0:00:13 19.5M\u000d 57 480M 57 275M 0 0 17.2M 0 0:00:27 0:00:15 0:00:12 22.9M\u000d 62 480M 62 300M 0 0 17.6M 0 0:00:27 0:00:17 0:00:10 23.1M\u000d 67 480M 67 325M 0 0 17.9M 0 0:00:26 0:00:18 0:00:08 23.6M\u000d 71 480M 71 344M 0 0 18.1M 0 0:00:26 0:00:18 0:00:08 23.1M\u000d 76 480M 76 369M 0 0 18.4M 0 0:00:26 0:00:20 0:00:06 23.0M\u000d 81 480M 81 393M 0 0 18.6M 0 0:00:25 0:00:21 0:00:04 23.2M\u000d 86 480M 86 414M 0 0 18.8M 0 0:00:25 0:00:21 0:00:04 23.0M\u000d 91 480M 91 438M 0 0 19.0M 0 0:00:25 0:00:23 0:00:02 22.8M\u000d 96 480M 96 462M 0 0 19.2M 0 0:00:24 0:00:24 --:--:-- 23.1M\u000d100 480M 100 480M 0 0 19.3M 0 0:00:24 0:00:24 --:--:-- 23.1M Apr 30 12:49:55.018869 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Apr 30 12:50:02.840148 setup-nvidia[1688]: Downloading NVIDIA 535.230.02 Driver Apr 30 12:50:02.844545 setup-nvidia[1986]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 12:50:02.844779 setup-nvidia[1986]: Dload Upload Total Spent Left Speed Apr 30 12:50:03.884981 waagent[1837]: 2025-04-30T12:50:03.884917Z INFO Daemon Daemon Provisioning complete Apr 30 12:50:03.896411 waagent[1837]: 2025-04-30T12:50:03.896360Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Apr 30 12:50:03.898122 waagent[1837]: 2025-04-30T12:50:03.898075Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Apr 30 12:50:03.898881 waagent[1837]: 2025-04-30T12:50:03.898827Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Apr 30 12:50:04.060705 waagent[1987]: 2025-04-30T12:50:04.060613Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Apr 30 12:50:04.061070 waagent[1987]: 2025-04-30T12:50:04.060784Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.1 Apr 30 12:50:04.061070 waagent[1987]: 2025-04-30T12:50:04.060886Z INFO ExtHandler ExtHandler Python: 3.11.11 Apr 30 12:50:04.126616 waagent[1987]: 2025-04-30T12:50:04.126524Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Apr 30 12:50:04.126826 waagent[1987]: 2025-04-30T12:50:04.126781Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:50:04.126934 waagent[1987]: 2025-04-30T12:50:04.126893Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:50:04.135670 waagent[1987]: 2025-04-30T12:50:04.135565Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Apr 30 12:50:04.145039 waagent[1987]: 2025-04-30T12:50:04.144993Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.166 Apr 30 12:50:04.145480 waagent[1987]: 2025-04-30T12:50:04.145431Z INFO ExtHandler Apr 30 12:50:04.145566 waagent[1987]: 2025-04-30T12:50:04.145528Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b0e615ce-1921-4626-9dd3-c004ea80876d eTag: 4432687961135355141 source: Fabric] Apr 30 12:50:04.145906 waagent[1987]: 2025-04-30T12:50:04.145862Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Apr 30 12:50:04.146567 waagent[1987]: 2025-04-30T12:50:04.146519Z INFO ExtHandler Apr 30 12:50:04.146650 waagent[1987]: 2025-04-30T12:50:04.146613Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Apr 30 12:50:04.150002 waagent[1987]: 2025-04-30T12:50:04.149963Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Apr 30 12:50:04.212282 waagent[1987]: 2025-04-30T12:50:04.212196Z INFO ExtHandler Downloaded certificate {'thumbprint': '84D67176ED6AB4C86A88E5B580056124619A1BB2', 'hasPrivateKey': True} Apr 30 12:50:04.212877 waagent[1987]: 2025-04-30T12:50:04.212807Z INFO ExtHandler Fetch goal state completed Apr 30 12:50:04.225261 waagent[1987]: 2025-04-30T12:50:04.225207Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1987 Apr 30 12:50:04.225413 waagent[1987]: 2025-04-30T12:50:04.225368Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Apr 30 12:50:04.227178 waagent[1987]: 2025-04-30T12:50:04.227132Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.1', '', 'Flatcar Container Linux by Kinvolk'] Apr 30 12:50:04.227546 waagent[1987]: 2025-04-30T12:50:04.227502Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Apr 30 12:50:04.239632 setup-nvidia[1986]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 57 326M 57 188M 0 0 240M 0 0:00:01 --:--:-- 0:00:01 240M\u000d100 326M 100 326M 0 0 233M 0 0:00:01 0:00:01 --:--:-- 233M Apr 30 12:50:04.247491 waagent[1987]: 2025-04-30T12:50:04.247451Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Apr 30 12:50:04.247662 waagent[1987]: 2025-04-30T12:50:04.247621Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Apr 30 12:50:04.254043 waagent[1987]: 2025-04-30T12:50:04.253998Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Apr 30 12:50:04.261538 systemd[1]: Reload requested from client PID 2000 ('systemctl') (unit waagent.service)... Apr 30 12:50:04.261553 systemd[1]: Reloading... Apr 30 12:50:04.290964 setup-nvidia[1688]: Extract the NVIDIA Driver Installer 535.230.02 Apr 30 12:50:04.291164 setup-nvidia[1688]: /opt/nvidia/workdir/nvidia-workdir / Apr 30 12:50:04.309620 setup-nvidia[2019]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Apr 30 12:50:04.323869 zram_generator::config[2062]: No configuration found. Apr 30 12:50:04.458423 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. Apr 30 12:50:04.610897 systemd[1]: Reloading finished in 348 ms. Apr 30 12:50:04.624916 waagent[1987]: 2025-04-30T12:50:04.624808Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Apr 30 12:50:04.630827 systemd[1]: Reload requested from client PID 2118 ('systemctl') (unit waagent.service)... Apr 30 12:50:04.630856 systemd[1]: Reloading... Apr 30 12:50:04.633042 setup-nvidia[2019]: Verifying archive integrity... OK Apr 30 12:50:04.698869 zram_generator::config[2162]: No configuration found. Apr 30 12:50:04.838977 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. Apr 30 12:50:04.992253 systemd[1]: Reloading finished in 360 ms. Apr 30 12:50:05.007691 waagent[1987]: 2025-04-30T12:50:05.007600Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Apr 30 12:50:05.007798 waagent[1987]: 2025-04-30T12:50:05.007757Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Apr 30 12:50:05.413388 waagent[1987]: 2025-04-30T12:50:05.413307Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Apr 30 12:50:05.413963 waagent[1987]: 2025-04-30T12:50:05.413906Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Apr 30 12:50:05.414721 waagent[1987]: 2025-04-30T12:50:05.414670Z INFO ExtHandler ExtHandler Starting env monitor service. Apr 30 12:50:05.415201 waagent[1987]: 2025-04-30T12:50:05.415133Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Apr 30 12:50:05.415364 waagent[1987]: 2025-04-30T12:50:05.415310Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:50:05.415638 waagent[1987]: 2025-04-30T12:50:05.415588Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 30 12:50:05.415935 waagent[1987]: 2025-04-30T12:50:05.415890Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:50:05.415989 waagent[1987]: 2025-04-30T12:50:05.415943Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 30 12:50:05.416092 waagent[1987]: 2025-04-30T12:50:05.416030Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Apr 30 12:50:05.416207 waagent[1987]: 2025-04-30T12:50:05.416159Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Apr 30 12:50:05.416929 waagent[1987]: 2025-04-30T12:50:05.416873Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Apr 30 12:50:05.417059 waagent[1987]: 2025-04-30T12:50:05.416992Z INFO EnvHandler ExtHandler Configure routes Apr 30 12:50:05.417174 waagent[1987]: 2025-04-30T12:50:05.417108Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Apr 30 12:50:05.417291 waagent[1987]: 2025-04-30T12:50:05.417243Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Apr 30 12:50:05.417704 waagent[1987]: 2025-04-30T12:50:05.417661Z INFO EnvHandler ExtHandler Gateway:None Apr 30 12:50:05.417796 waagent[1987]: 2025-04-30T12:50:05.417751Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Apr 30 12:50:05.417942 waagent[1987]: 2025-04-30T12:50:05.417892Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Apr 30 12:50:05.417942 waagent[1987]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Apr 30 12:50:05.417942 waagent[1987]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Apr 30 12:50:05.417942 waagent[1987]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Apr 30 12:50:05.417942 waagent[1987]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:50:05.417942 waagent[1987]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:50:05.417942 waagent[1987]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 30 12:50:05.418564 waagent[1987]: 2025-04-30T12:50:05.418520Z INFO EnvHandler ExtHandler Routes:None Apr 30 12:50:05.423443 waagent[1987]: 2025-04-30T12:50:05.423391Z INFO ExtHandler ExtHandler Apr 30 12:50:05.423759 waagent[1987]: 2025-04-30T12:50:05.423714Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 094b6405-c78e-4f6b-946c-d2622494e7c8 correlation 14d6bbff-ca9a-4e2d-9f9d-9d0e48dc53c5 created: 2025-04-30T12:47:41.088539Z] Apr 30 12:50:05.424815 waagent[1987]: 2025-04-30T12:50:05.424766Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Apr 30 12:50:05.426548 waagent[1987]: 2025-04-30T12:50:05.426498Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Apr 30 12:50:05.450781 setup-nvidia[2019]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Apr 30 12:50:05.458548 waagent[1987]: 2025-04-30T12:50:05.458496Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: AA581297-89A8-4200-9CEF-AFD66F091BC1;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Apr 30 12:50:05.464516 waagent[1987]: 2025-04-30T12:50:05.464457Z INFO MonitorHandler ExtHandler Network interfaces: Apr 30 12:50:05.464516 waagent[1987]: Executing ['ip', '-a', '-o', 'link']: Apr 30 12:50:05.464516 waagent[1987]: 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 Apr 30 12:50:05.464516 waagent[1987]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:2f:2e:87 brd ff:ff:ff:ff:ff:ff Apr 30 12:50:05.464516 waagent[1987]: Executing ['ip', '-4', '-a', '-o', 'address']: Apr 30 12:50:05.464516 waagent[1987]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Apr 30 12:50:05.464516 waagent[1987]: 2: eth0 inet 10.200.4.30/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Apr 30 12:50:05.464516 waagent[1987]: Executing ['ip', '-6', '-a', '-o', 'address']: Apr 30 12:50:05.464516 waagent[1987]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Apr 30 12:50:05.464516 waagent[1987]: 2: eth0 inet6 fe80::7e1e:52ff:fe2f:2e87/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Apr 30 12:50:05.619369 waagent[1987]: 2025-04-30T12:50:05.619295Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Apr 30 12:50:05.619369 waagent[1987]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.619369 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.619369 waagent[1987]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.619369 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.619369 waagent[1987]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.619369 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.619369 waagent[1987]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 30 12:50:05.619369 waagent[1987]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 30 12:50:05.619369 waagent[1987]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 30 12:50:05.623336 waagent[1987]: 2025-04-30T12:50:05.623279Z INFO EnvHandler ExtHandler Current Firewall rules: Apr 30 12:50:05.623336 waagent[1987]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.623336 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.623336 waagent[1987]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.623336 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.623336 waagent[1987]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 30 12:50:05.623336 waagent[1987]: pkts bytes target prot opt in out source destination Apr 30 12:50:05.623336 waagent[1987]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 30 12:50:05.623336 waagent[1987]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 30 12:50:05.623336 waagent[1987]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 30 12:50:05.623603 waagent[1987]: 2025-04-30T12:50:05.623564Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Apr 30 12:50:05.623761 waagent[1987]: 2025-04-30T12:50:05.623724Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Apr 30 12:50:07.247963 setup-nvidia[2229]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Apr 30 12:50:07.322601 setup-nvidia[1688]: / Apr 30 12:50:07.322859 setup-nvidia[1688]: Spawn system-nspawn container to install the NVIDIA drivers Apr 30 12:50:07.451439 sudo[2263]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.1.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Apr 30 12:50:07.453219 sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 30 12:50:07.460471 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Apr 30 12:50:07.477095 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Apr 30 12:50:07.487895 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Apr 30 12:50:07.492756 systemd[1]: Starting user@0.service - User Manager for UID 0... Apr 30 12:50:07.499006 (systemd)[2266]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Apr 30 12:50:07.501202 systemd-logind[1692]: New session c3 of user root. Apr 30 12:50:07.675655 systemd[2266]: Queued start job for default target default.target. Apr 30 12:50:07.683951 systemd[2266]: Created slice app.slice - User Application Slice. Apr 30 12:50:07.683981 systemd[2266]: Reached target paths.target - Paths. Apr 30 12:50:07.684027 systemd[2266]: Reached target timers.target - Timers. Apr 30 12:50:07.685177 systemd[2266]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 12:50:07.695811 systemd[2266]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:50:07.696002 systemd[2266]: Reached target sockets.target - Sockets. Apr 30 12:50:07.696864 systemd[2266]: Reached target basic.target - Basic System. Apr 30 12:50:07.696942 systemd[2266]: Reached target default.target - Main User Target. Apr 30 12:50:07.696970 systemd[1]: Started user@0.service - User Manager for UID 0. Apr 30 12:50:07.696981 systemd[2266]: Startup finished in 190ms. Apr 30 12:50:07.699749 systemd[1]: Started session-c2.scope - Session c2 of User root. Apr 30 12:50:07.778864 kernel: loop6: detected capacity change from 0 to 12644352 Apr 30 12:50:07.783863 kernel: loop6: p9 Apr 30 12:50:07.863981 setup-nvidia[2275]: ░ Spawning container flatcardevelopercontainer-4230.1.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.1.1.bin. Apr 30 12:50:07.875865 kernel: EXT4-fs (loop6p9): mounted filesystem 274cd76a-6c6d-4a84-abd5-d946fe9a9770 ro without journal. Quota mode: none. Apr 30 12:50:10.472193 update_engine[1697]: I20250430 12:50:10.472110 1697 update_attempter.cc:509] Updating boot flags... Apr 30 12:50:10.537866 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (2220) Apr 30 12:50:10.593873 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 40 scanned by (udev-worker) (2218) Apr 30 12:50:28.426603 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 12:50:28.442243 systemd[1]: Started sshd@0-10.200.4.30:22-10.200.16.10:57438.service - OpenSSH per-connection server daemon (10.200.16.10:57438). Apr 30 12:50:33.281525 sshd[3453]: Accepted publickey for core from 10.200.16.10 port 57438 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:33.282973 sshd-session[3453]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:33.289564 systemd-logind[1692]: New session 3 of user core. Apr 30 12:50:33.300970 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 12:50:33.815769 systemd[1]: Started sshd@1-10.200.4.30:22-10.200.16.10:49904.service - OpenSSH per-connection server daemon (10.200.16.10:49904). Apr 30 12:50:34.135492 kernel: hv_netvsc 7c1e522f-2e87-7c1e-522f-2e877c1e522f eth0: nvsp_rndis_pkt_complete error status: 2 Apr 30 12:50:34.231186 kernel: hv_netvsc 7c1e522f-2e87-7c1e-522f-2e877c1e522f eth0: nvsp_rndis_pkt_complete error status: 2 Apr 30 12:50:34.236867 kernel: hv_netvsc 7c1e522f-2e87-7c1e-522f-2e877c1e522f eth0: nvsp_rndis_pkt_complete error status: 2 Apr 30 12:50:34.341196 kernel: hv_netvsc 7c1e522f-2e87-7c1e-522f-2e877c1e522f eth0: nvsp_rndis_pkt_complete error status: 2 Apr 30 12:50:34.423424 sshd[3458]: Accepted publickey for core from 10.200.16.10 port 49904 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:34.424906 sshd-session[3458]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:34.429536 systemd-logind[1692]: New session 4 of user core. Apr 30 12:50:35.061735 kernel: hv_netvsc 7c1e522f-2e87-7c1e-522f-2e877c1e522f eth0: nvsp_rndis_pkt_complete error status: 2 Apr 30 12:50:34.443095 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 12:50:34.855250 sshd-session[3458]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:35.062535 sshd[3460]: Connection closed by 10.200.16.10 port 49904 Apr 30 12:50:34.859401 systemd[1]: sshd@1-10.200.4.30:22-10.200.16.10:49904.service: Deactivated successfully. Apr 30 12:50:34.861323 systemd[1]: session-4.scope: Deactivated successfully. Apr 30 12:50:34.862071 systemd-logind[1692]: Session 4 logged out. Waiting for processes to exit. Apr 30 12:50:34.863022 systemd-logind[1692]: Removed session 4. Apr 30 12:50:34.961506 systemd[1]: Started sshd@2-10.200.4.30:22-10.200.16.10:49920.service - OpenSSH per-connection server daemon (10.200.16.10:49920). Apr 30 12:50:35.567406 sshd[3466]: Accepted publickey for core from 10.200.16.10 port 49920 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:35.568776 sshd-session[3466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:35.573429 systemd-logind[1692]: New session 5 of user core. Apr 30 12:50:35.583972 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 12:50:36.019808 sshd[3468]: Connection closed by 10.200.16.10 port 49920 Apr 30 12:50:36.020144 sshd-session[3466]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:36.023468 systemd[1]: sshd@2-10.200.4.30:22-10.200.16.10:49920.service: Deactivated successfully. Apr 30 12:50:36.025119 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 12:50:36.025753 systemd-logind[1692]: Session 5 logged out. Waiting for processes to exit. Apr 30 12:50:36.026528 systemd-logind[1692]: Removed session 5. Apr 30 12:50:46.130051 systemd[1]: Started sshd@3-10.200.4.30:22-10.200.16.10:37256.service - OpenSSH per-connection server daemon (10.200.16.10:37256). Apr 30 12:50:46.739550 sshd[3596]: Accepted publickey for core from 10.200.16.10 port 37256 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:46.740909 sshd-session[3596]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:46.745562 systemd-logind[1692]: New session 6 of user core. Apr 30 12:50:46.755083 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 30 12:50:47.209679 sshd[3598]: Connection closed by 10.200.16.10 port 37256 Apr 30 12:50:47.210451 sshd-session[3596]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:47.214542 systemd[1]: sshd@3-10.200.4.30:22-10.200.16.10:37256.service: Deactivated successfully. Apr 30 12:50:47.216423 systemd[1]: session-6.scope: Deactivated successfully. Apr 30 12:50:47.217102 systemd-logind[1692]: Session 6 logged out. Waiting for processes to exit. Apr 30 12:50:47.217987 systemd-logind[1692]: Removed session 6. Apr 30 12:50:47.327351 systemd[1]: Started sshd@4-10.200.4.30:22-10.200.16.10:37260.service - OpenSSH per-connection server daemon (10.200.16.10:37260). Apr 30 12:50:48.035462 sshd[3604]: Accepted publickey for core from 10.200.16.10 port 37260 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:50:51.807763 sshd-session[3604]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:50:51.813449 systemd-logind[1692]: New session 7 of user core. Apr 30 12:50:51.824310 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 30 12:50:52.195036 sshd[3630]: Connection closed by 10.200.16.10 port 37260 Apr 30 12:50:52.195718 sshd-session[3604]: pam_unix(sshd:session): session closed for user core Apr 30 12:50:52.199318 systemd[1]: sshd@4-10.200.4.30:22-10.200.16.10:37260.service: Deactivated successfully. Apr 30 12:50:52.201179 systemd[1]: session-7.scope: Deactivated successfully. Apr 30 12:50:52.201827 systemd-logind[1692]: Session 7 logged out. Waiting for processes to exit. Apr 30 12:50:52.202664 systemd-logind[1692]: Removed session 7. Apr 30 12:51:02.301557 systemd[1]: Started sshd@5-10.200.4.30:22-10.200.16.10:37914.service - OpenSSH per-connection server daemon (10.200.16.10:37914). Apr 30 12:51:04.102657 sshd[5600]: Accepted publickey for core from 10.200.16.10 port 37914 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:02.911223 sshd-session[5600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:02.915752 systemd-logind[1692]: New session 8 of user core. Apr 30 12:51:04.103258 sshd[5602]: Connection closed by 10.200.16.10 port 37914 Apr 30 12:51:04.103349 sshd[5608]: Accepted publickey for core from 10.200.16.10 port 37928 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:03.362358 sshd-session[5600]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:02.932971 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 30 12:51:04.075778 sshd-session[5608]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:03.365615 systemd[1]: sshd@5-10.200.4.30:22-10.200.16.10:37914.service: Deactivated successfully. Apr 30 12:51:03.367317 systemd[1]: session-8.scope: Deactivated successfully. Apr 30 12:51:03.367944 systemd-logind[1692]: Session 8 logged out. Waiting for processes to exit. Apr 30 12:51:03.368698 systemd-logind[1692]: Removed session 8. Apr 30 12:51:03.471142 systemd[1]: Started sshd@6-10.200.4.30:22-10.200.16.10:37928.service - OpenSSH per-connection server daemon (10.200.16.10:37928). Apr 30 12:51:04.079988 systemd-logind[1692]: New session 9 of user core. Apr 30 12:51:04.094963 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 30 12:51:06.551467 sshd[5610]: Connection closed by 10.200.16.10 port 37928 Apr 30 12:51:06.551549 sshd-session[5608]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:06.559133 systemd[1]: sshd@6-10.200.4.30:22-10.200.16.10:37928.service: Deactivated successfully. Apr 30 12:51:06.560816 systemd[1]: session-9.scope: Deactivated successfully. Apr 30 12:51:06.561456 systemd-logind[1692]: Session 9 logged out. Waiting for processes to exit. Apr 30 12:51:06.562284 systemd-logind[1692]: Removed session 9. Apr 30 12:51:14.639077 systemd[1]: Started sshd@7-10.200.4.30:22-10.200.16.10:50180.service - OpenSSH per-connection server daemon (10.200.16.10:50180). Apr 30 12:51:15.257488 sshd[7117]: Accepted publickey for core from 10.200.16.10 port 50180 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:15.259133 sshd-session[7117]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:15.264924 systemd-logind[1692]: New session 10 of user core. Apr 30 12:51:15.278000 systemd[1]: Started session-10.scope - Session 10 of User core. Apr 30 12:51:15.714681 sshd[7293]: Connection closed by 10.200.16.10 port 50180 Apr 30 12:51:15.716668 sshd-session[7117]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:15.720499 systemd-logind[1692]: Session 10 logged out. Waiting for processes to exit. Apr 30 12:51:15.720719 systemd[1]: sshd@7-10.200.4.30:22-10.200.16.10:50180.service: Deactivated successfully. Apr 30 12:51:15.722548 systemd[1]: session-10.scope: Deactivated successfully. Apr 30 12:51:15.723400 systemd-logind[1692]: Removed session 10. Apr 30 12:51:15.825119 systemd[1]: Started sshd@8-10.200.4.30:22-10.200.16.10:50196.service - OpenSSH per-connection server daemon (10.200.16.10:50196). Apr 30 12:51:16.446540 sshd[7490]: Accepted publickey for core from 10.200.16.10 port 50196 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:16.447987 sshd-session[7490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:16.454880 systemd-logind[1692]: New session 11 of user core. Apr 30 12:51:16.460148 systemd[1]: Started session-11.scope - Session 11 of User core. Apr 30 12:51:16.906948 sshd[7744]: Connection closed by 10.200.16.10 port 50196 Apr 30 12:51:16.907359 sshd-session[7490]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:16.910381 systemd-logind[1692]: Session 11 logged out. Waiting for processes to exit. Apr 30 12:51:16.911312 systemd[1]: sshd@8-10.200.4.30:22-10.200.16.10:50196.service: Deactivated successfully. Apr 30 12:51:16.913769 systemd[1]: session-11.scope: Deactivated successfully. Apr 30 12:51:16.919599 systemd-logind[1692]: Removed session 11. Apr 30 12:51:27.034567 systemd[1]: Started sshd@9-10.200.4.30:22-10.200.16.10:41680.service - OpenSSH per-connection server daemon (10.200.16.10:41680). Apr 30 12:51:27.640540 sshd[11050]: Accepted publickey for core from 10.200.16.10 port 41680 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:27.642093 sshd-session[11050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:27.648770 systemd-logind[1692]: New session 12 of user core. Apr 30 12:51:27.658040 systemd[1]: Started session-12.scope - Session 12 of User core. Apr 30 12:51:28.067252 sshd[11398]: Connection closed by 10.200.16.10 port 41680 Apr 30 12:51:28.067632 sshd-session[11050]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:28.071021 systemd[1]: sshd@9-10.200.4.30:22-10.200.16.10:41680.service: Deactivated successfully. Apr 30 12:51:28.072951 systemd[1]: session-12.scope: Deactivated successfully. Apr 30 12:51:28.073705 systemd-logind[1692]: Session 12 logged out. Waiting for processes to exit. Apr 30 12:51:28.074728 systemd-logind[1692]: Removed session 12. Apr 30 12:51:28.174685 systemd[1]: Started sshd@10-10.200.4.30:22-10.200.16.10:41688.service - OpenSSH per-connection server daemon (10.200.16.10:41688). Apr 30 12:51:28.783736 sshd[11501]: Accepted publickey for core from 10.200.16.10 port 41688 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:28.785258 sshd-session[11501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:28.792138 systemd-logind[1692]: New session 13 of user core. Apr 30 12:51:28.801016 systemd[1]: Started session-13.scope - Session 13 of User core. Apr 30 12:51:29.220942 sshd[11528]: Connection closed by 10.200.16.10 port 41688 Apr 30 12:51:29.221508 sshd-session[11501]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:29.224458 systemd-logind[1692]: Session 13 logged out. Waiting for processes to exit. Apr 30 12:51:29.226227 systemd[1]: sshd@10-10.200.4.30:22-10.200.16.10:41688.service: Deactivated successfully. Apr 30 12:51:29.228438 systemd[1]: session-13.scope: Deactivated successfully. Apr 30 12:51:29.230014 systemd-logind[1692]: Removed session 13. Apr 30 12:51:39.338158 systemd[1]: Started sshd@11-10.200.4.30:22-10.200.16.10:40850.service - OpenSSH per-connection server daemon (10.200.16.10:40850). Apr 30 12:51:39.950874 sshd[11954]: Accepted publickey for core from 10.200.16.10 port 40850 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:39.952788 sshd-session[11954]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:39.959688 systemd-logind[1692]: New session 14 of user core. Apr 30 12:51:39.966993 systemd[1]: Started session-14.scope - Session 14 of User core. Apr 30 12:51:40.406881 sshd[11974]: Connection closed by 10.200.16.10 port 40850 Apr 30 12:51:40.408930 sshd-session[11954]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:40.415522 systemd-logind[1692]: Session 14 logged out. Waiting for processes to exit. Apr 30 12:51:40.417122 systemd[1]: sshd@11-10.200.4.30:22-10.200.16.10:40850.service: Deactivated successfully. Apr 30 12:51:40.419126 systemd[1]: session-14.scope: Deactivated successfully. Apr 30 12:51:40.425654 systemd-logind[1692]: Removed session 14. Apr 30 12:51:40.520315 systemd[1]: Started sshd@12-10.200.4.30:22-10.200.16.10:40864.service - OpenSSH per-connection server daemon (10.200.16.10:40864). Apr 30 12:51:41.142063 sshd[11986]: Accepted publickey for core from 10.200.16.10 port 40864 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:41.143933 sshd-session[11986]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:41.152176 systemd-logind[1692]: New session 15 of user core. Apr 30 12:51:41.161028 systemd[1]: Started session-15.scope - Session 15 of User core. Apr 30 12:51:41.579874 sshd[12001]: Connection closed by 10.200.16.10 port 40864 Apr 30 12:51:41.581889 sshd-session[11986]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:41.584672 systemd[1]: sshd@12-10.200.4.30:22-10.200.16.10:40864.service: Deactivated successfully. Apr 30 12:51:41.586748 systemd[1]: session-15.scope: Deactivated successfully. Apr 30 12:51:41.588683 systemd-logind[1692]: Session 15 logged out. Waiting for processes to exit. Apr 30 12:51:41.589972 systemd-logind[1692]: Removed session 15. Apr 30 12:51:51.698180 systemd[1]: Started sshd@13-10.200.4.30:22-10.200.16.10:36676.service - OpenSSH per-connection server daemon (10.200.16.10:36676). Apr 30 12:51:52.320435 sshd[12330]: Accepted publickey for core from 10.200.16.10 port 36676 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:52.322343 sshd-session[12330]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:52.329075 systemd-logind[1692]: New session 16 of user core. Apr 30 12:51:52.338494 systemd[1]: Started session-16.scope - Session 16 of User core. Apr 30 12:51:52.777816 sshd[12362]: Connection closed by 10.200.16.10 port 36676 Apr 30 12:51:52.779787 sshd-session[12330]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:52.783090 systemd[1]: sshd@13-10.200.4.30:22-10.200.16.10:36676.service: Deactivated successfully. Apr 30 12:51:52.784779 systemd[1]: session-16.scope: Deactivated successfully. Apr 30 12:51:52.785687 systemd-logind[1692]: Session 16 logged out. Waiting for processes to exit. Apr 30 12:51:52.787772 systemd-logind[1692]: Removed session 16. Apr 30 12:51:52.898198 systemd[1]: Started sshd@14-10.200.4.30:22-10.200.16.10:36682.service - OpenSSH per-connection server daemon (10.200.16.10:36682). Apr 30 12:51:53.515388 sshd[12381]: Accepted publickey for core from 10.200.16.10 port 36682 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:51:53.516831 sshd-session[12381]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:51:53.523227 systemd-logind[1692]: New session 17 of user core. Apr 30 12:51:53.529452 systemd[1]: Started session-17.scope - Session 17 of User core. Apr 30 12:51:53.974092 sshd[12400]: Connection closed by 10.200.16.10 port 36682 Apr 30 12:51:53.974706 sshd-session[12381]: pam_unix(sshd:session): session closed for user core Apr 30 12:51:53.978207 systemd[1]: sshd@14-10.200.4.30:22-10.200.16.10:36682.service: Deactivated successfully. Apr 30 12:51:53.979990 systemd[1]: session-17.scope: Deactivated successfully. Apr 30 12:51:53.981259 systemd-logind[1692]: Session 17 logged out. Waiting for processes to exit. Apr 30 12:51:53.982477 systemd-logind[1692]: Removed session 17. Apr 30 12:52:04.092616 systemd[1]: Started sshd@15-10.200.4.30:22-10.200.16.10:37342.service - OpenSSH per-connection server daemon (10.200.16.10:37342). Apr 30 12:52:04.693745 sshd[12691]: Accepted publickey for core from 10.200.16.10 port 37342 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:04.695099 sshd-session[12691]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:04.699327 systemd-logind[1692]: New session 18 of user core. Apr 30 12:52:04.711994 systemd[1]: Started session-18.scope - Session 18 of User core. Apr 30 12:52:05.120299 sshd[12693]: Connection closed by 10.200.16.10 port 37342 Apr 30 12:52:05.120688 sshd-session[12691]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:05.123929 systemd[1]: sshd@15-10.200.4.30:22-10.200.16.10:37342.service: Deactivated successfully. Apr 30 12:52:05.125592 systemd[1]: session-18.scope: Deactivated successfully. Apr 30 12:52:05.126256 systemd-logind[1692]: Session 18 logged out. Waiting for processes to exit. Apr 30 12:52:05.127053 systemd-logind[1692]: Removed session 18. Apr 30 12:52:05.228233 systemd[1]: Started sshd@16-10.200.4.30:22-10.200.16.10:37356.service - OpenSSH per-connection server daemon (10.200.16.10:37356). Apr 30 12:52:05.833356 sshd[12700]: Accepted publickey for core from 10.200.16.10 port 37356 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:05.834731 sshd-session[12700]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:05.838856 systemd-logind[1692]: New session 19 of user core. Apr 30 12:52:05.849964 systemd[1]: Started session-19.scope - Session 19 of User core. Apr 30 12:52:06.287128 sshd[12702]: Connection closed by 10.200.16.10 port 37356 Apr 30 12:52:06.287630 sshd-session[12700]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:06.290943 systemd[1]: sshd@16-10.200.4.30:22-10.200.16.10:37356.service: Deactivated successfully. Apr 30 12:52:06.294583 systemd[1]: session-19.scope: Deactivated successfully. Apr 30 12:52:06.296889 systemd-logind[1692]: Session 19 logged out. Waiting for processes to exit. Apr 30 12:52:06.297857 systemd-logind[1692]: Removed session 19. Apr 30 12:52:09.885716 setup-nvidia[2275]: \u000d Apr 30 12:52:09.885716 setup-nvidia[2275]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Apr 30 12:52:09.885716 setup-nvidia[2275]: frequently when this kernel module was built against the wrong or Apr 30 12:52:09.885716 setup-nvidia[2275]: improperly configured kernel sources, with a version of gcc that differs Apr 30 12:52:09.885716 setup-nvidia[2275]: from the one used to build the target kernel, or if another driver, such Apr 30 12:52:09.885716 setup-nvidia[2275]: as nouveau, is present and prevents the NVIDIA kernel module from Apr 30 12:52:09.885716 setup-nvidia[2275]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Apr 30 12:52:09.885716 setup-nvidia[2275]: installed in this system is supported by this NVIDIA Linux graphics Apr 30 12:52:09.885716 setup-nvidia[2275]: driver release. Apr 30 12:52:09.885716 setup-nvidia[2275]: \u000d Apr 30 12:52:09.885716 setup-nvidia[2275]: Please see the log entries 'Kernel module load error' and 'Kernel Apr 30 12:52:09.885716 setup-nvidia[2275]: messages' at the end of the file Apr 30 12:52:09.885716 setup-nvidia[2275]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Apr 30 12:52:09.885716 setup-nvidia[2275]: information. Apr 30 12:52:09.885716 setup-nvidia[2275]: \u000d Apr 30 12:52:09.900068 setup-nvidia[2275]: dmesg: read kernel buffer failed: Operation not permitted Apr 30 12:52:09.921000 setup-nvidia[2275]: \u000d Apr 30 12:52:09.921000 setup-nvidia[2275]: ERROR: Installation has failed. Please see the file Apr 30 12:52:09.921000 setup-nvidia[2275]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Apr 30 12:52:09.921000 setup-nvidia[2275]: details. You may find suggestions on fixing installation problems in Apr 30 12:52:09.921000 setup-nvidia[2275]: the README available on the Linux driver download page at Apr 30 12:52:09.921000 setup-nvidia[2275]: www.nvidia.com. Apr 30 12:52:09.921000 setup-nvidia[2275]: \u000d Apr 30 12:52:09.921394 setup-nvidia[2275]: Last 50 lines of nvidia-installer.log: Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Apr 30 12:52:09.922211 setup-nvidia[2275]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Apr 30 12:52:09.922211 setup-nvidia[2275]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Apr 30 12:52:09.922211 setup-nvidia[2275]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Apr 30 12:52:09.922211 setup-nvidia[2275]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Apr 30 12:52:09.922211 setup-nvidia[2275]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Apr 30 12:52:09.922211 setup-nvidia[2275]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Apr 30 12:52:09.922211 setup-nvidia[2275]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Apr 30 12:52:09.922211 setup-nvidia[2275]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Apr 30 12:52:09.922211 setup-nvidia[2275]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Apr 30 12:52:09.922211 setup-nvidia[2275]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Apr 30 12:52:09.922211 setup-nvidia[2275]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Apr 30 12:52:09.922211 setup-nvidia[2275]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Apr 30 12:52:09.922211 setup-nvidia[2275]: make[2]: Leaving directory '/usr/lib/modules/6.6.88-flatcar/build' Apr 30 12:52:09.922211 setup-nvidia[2275]: make[1]: Leaving directory '/usr/lib/modules/6.6.88-flatcar/source' Apr 30 12:52:09.922211 setup-nvidia[2275]: -> done. Apr 30 12:52:09.922211 setup-nvidia[2275]: -> Kernel module compilation complete. Apr 30 12:52:09.922211 setup-nvidia[2275]: -> Unable to determine if Secure Boot is enabled: No such file or directory Apr 30 12:52:09.922211 setup-nvidia[2275]: 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. Apr 30 12:52:09.922211 setup-nvidia[2275]: \u000d Apr 30 12:52:09.923335 setup-nvidia[2275]: 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. Apr 30 12:52:09.923335 setup-nvidia[2275]: -> Kernel module load error: Operation not permitted Apr 30 12:52:09.923335 setup-nvidia[2275]: -> Kernel messages: Apr 30 12:52:09.923335 setup-nvidia[2275]: 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. Apr 30 12:52:16.395413 systemd[1]: Started sshd@17-10.200.4.30:22-10.200.16.10:33328.service - OpenSSH per-connection server daemon (10.200.16.10:33328). Apr 30 12:52:16.998005 sshd[12917]: Accepted publickey for core from 10.200.16.10 port 33328 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:16.999471 sshd-session[12917]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:17.003557 systemd-logind[1692]: New session 20 of user core. Apr 30 12:52:17.014971 systemd[1]: Started session-20.scope - Session 20 of User core. Apr 30 12:52:17.457138 sshd[12919]: Connection closed by 10.200.16.10 port 33328 Apr 30 12:52:17.457546 sshd-session[12917]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:17.460769 systemd[1]: sshd@17-10.200.4.30:22-10.200.16.10:33328.service: Deactivated successfully. Apr 30 12:52:17.462438 systemd[1]: session-20.scope: Deactivated successfully. Apr 30 12:52:17.463133 systemd-logind[1692]: Session 20 logged out. Waiting for processes to exit. Apr 30 12:52:17.463921 systemd-logind[1692]: Removed session 20. Apr 30 12:52:17.565095 systemd[1]: Started sshd@18-10.200.4.30:22-10.200.16.10:33332.service - OpenSSH per-connection server daemon (10.200.16.10:33332). Apr 30 12:52:18.170115 sshd[12925]: Accepted publickey for core from 10.200.16.10 port 33332 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:18.171501 sshd-session[12925]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:18.175704 systemd-logind[1692]: New session 21 of user core. Apr 30 12:52:18.190970 systemd[1]: Started session-21.scope - Session 21 of User core. Apr 30 12:52:18.624488 sshd[12927]: Connection closed by 10.200.16.10 port 33332 Apr 30 12:52:18.624911 sshd-session[12925]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:18.628001 systemd[1]: sshd@18-10.200.4.30:22-10.200.16.10:33332.service: Deactivated successfully. Apr 30 12:52:18.629618 systemd[1]: session-21.scope: Deactivated successfully. Apr 30 12:52:18.630246 systemd-logind[1692]: Session 21 logged out. Waiting for processes to exit. Apr 30 12:52:18.631016 systemd-logind[1692]: Removed session 21. Apr 30 12:52:25.675868 kernel: EXT4-fs (loop6p9): unmounting filesystem 274cd76a-6c6d-4a84-abd5-d946fe9a9770. Apr 30 12:52:25.676942 setup-nvidia[2275]: Container flatcardevelopercontainer-4230.1.1.bin exited successfully. Apr 30 12:52:25.679419 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.1.1.bin.mount: Deactivated successfully. Apr 30 12:52:25.735695 sudo[2263]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:25.737526 systemd[1]: session-c2.scope: Deactivated successfully. Apr 30 12:52:25.737795 systemd[1]: session-c2.scope: Consumed 5min 11.132s CPU time, 882.4M memory peak. Apr 30 12:52:26.143868 setup-nvidia[1688]: /opt/nvidia / Apr 30 12:52:26.144857 setup-nvidia[1688]: / Apr 30 12:52:26.160775 setup-nvidia[12950]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 12:52:26.182859 kernel: IPMI message handler: version 39.2 Apr 30 12:52:26.185857 kernel: ipmi device interface Apr 30 12:52:26.186890 setup-nvidia[1688]: /opt/nvidia/current/usr/lib/modules/6.6.88-flatcar/video / Apr 30 12:52:26.238853 kernel: nvidia: loading out-of-tree module taints kernel. Apr 30 12:52:26.238988 kernel: nvidia: module license 'NVIDIA' taints kernel. Apr 30 12:52:26.239010 kernel: Disabling lock debugging due to kernel taint Apr 30 12:52:26.246495 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Apr 30 12:52:26.246537 kernel: nvidia: module license taints kernel. Apr 30 12:52:26.633870 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Apr 30 12:52:27.192358 kernel: Apr 30 12:52:27.192504 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Apr 30 12:52:27.238512 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Apr 30 12:52:27.265862 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Apr 30 12:52:27.284877 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Apr 30 12:52:27.328864 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Apr 30 12:52:27.328931 setup-nvidia[1688]: / Apr 30 12:52:28.732436 systemd[1]: Started sshd@19-10.200.4.30:22-10.200.16.10:47302.service - OpenSSH per-connection server daemon (10.200.16.10:47302). Apr 30 12:52:29.337854 sshd[12985]: Accepted publickey for core from 10.200.16.10 port 47302 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:29.339376 sshd-session[12985]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:29.343551 systemd-logind[1692]: New session 22 of user core. Apr 30 12:52:29.352969 systemd[1]: Started session-22.scope - Session 22 of User core. Apr 30 12:52:29.470886 setup-nvidia[12980]: Wed Apr 30 12:52:29 2025 Apr 30 12:52:29.470886 setup-nvidia[12980]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:29.470886 setup-nvidia[12980]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Apr 30 12:52:29.470886 setup-nvidia[12980]: |-----------------------------------------+----------------------+----------------------+ Apr 30 12:52:29.470886 setup-nvidia[12980]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Apr 30 12:52:29.470886 setup-nvidia[12980]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Apr 30 12:52:29.470886 setup-nvidia[12980]: | | | MIG M. | Apr 30 12:52:29.470886 setup-nvidia[12980]: |=========================================+======================+======================| Apr 30 12:52:29.478218 setup-nvidia[12980]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Apr 30 12:52:29.478218 setup-nvidia[12980]: | N/A 29C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Apr 30 12:52:29.478218 setup-nvidia[12980]: | | | N/A | Apr 30 12:52:29.478218 setup-nvidia[12980]: +-----------------------------------------+----------------------+----------------------+ Apr 30 12:52:29.478323 setup-nvidia[12980]: Apr 30 12:52:29.478323 setup-nvidia[12980]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:29.478323 setup-nvidia[12980]: | Processes: | Apr 30 12:52:29.478323 setup-nvidia[12980]: | GPU GI CI PID Type Process name GPU Memory | Apr 30 12:52:29.478323 setup-nvidia[12980]: | ID ID Usage | Apr 30 12:52:29.478323 setup-nvidia[12980]: |=======================================================================================| Apr 30 12:52:29.478469 setup-nvidia[12980]: | No running processes found | Apr 30 12:52:29.478469 setup-nvidia[12980]: +---------------------------------------------------------------------------------------+ Apr 30 12:52:29.790163 sshd[12987]: Connection closed by 10.200.16.10 port 47302 Apr 30 12:52:29.790604 sshd-session[12985]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:29.794037 systemd[1]: sshd@19-10.200.4.30:22-10.200.16.10:47302.service: Deactivated successfully. Apr 30 12:52:29.795712 systemd[1]: session-22.scope: Deactivated successfully. Apr 30 12:52:29.796350 systemd-logind[1692]: Session 22 logged out. Waiting for processes to exit. Apr 30 12:52:29.797884 systemd-logind[1692]: Removed session 22. Apr 30 12:52:29.856666 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 12:52:29.858804 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 12:52:29.859174 systemd[1]: Startup finished in 1.085s (firmware) + 34.038s (loader) + 1.921s (kernel) + 15.393s (initrd) + 3min 39.075s (userspace) = 4min 31.514s. Apr 30 12:52:29.900248 systemd[1]: Started sshd@20-10.200.4.30:22-10.200.16.10:54070.service - OpenSSH per-connection server daemon (10.200.16.10:54070). Apr 30 12:52:30.506538 sshd[12995]: Accepted publickey for core from 10.200.16.10 port 54070 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:30.507977 sshd-session[12995]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:30.512328 systemd-logind[1692]: New session 23 of user core. Apr 30 12:52:30.526973 systemd[1]: Started session-23.scope - Session 23 of User core. Apr 30 12:52:30.960258 sshd[12997]: Connection closed by 10.200.16.10 port 54070 Apr 30 12:52:30.960662 sshd-session[12995]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:30.963955 systemd[1]: sshd@20-10.200.4.30:22-10.200.16.10:54070.service: Deactivated successfully. Apr 30 12:52:30.965644 systemd[1]: session-23.scope: Deactivated successfully. Apr 30 12:52:30.966272 systemd-logind[1692]: Session 23 logged out. Waiting for processes to exit. Apr 30 12:52:30.967046 systemd-logind[1692]: Removed session 23. Apr 30 12:52:35.788284 systemd[1]: Stopping user@0.service - User Manager for UID 0... Apr 30 12:52:35.789097 systemd[2266]: Activating special unit exit.target... Apr 30 12:52:35.789187 systemd[2266]: Stopped target default.target - Main User Target. Apr 30 12:52:35.789229 systemd[2266]: Stopped target basic.target - Basic System. Apr 30 12:52:35.789259 systemd[2266]: Stopped target paths.target - Paths. Apr 30 12:52:35.789287 systemd[2266]: Stopped target sockets.target - Sockets. Apr 30 12:52:35.789315 systemd[2266]: Stopped target timers.target - Timers. Apr 30 12:52:35.789670 systemd[2266]: Closed dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:52:35.789901 systemd[2266]: Removed slice app.slice - User Application Slice. Apr 30 12:52:35.789935 systemd[2266]: Reached target shutdown.target - Shutdown. Apr 30 12:52:35.790085 systemd[2266]: Finished systemd-exit.service - Exit the Session. Apr 30 12:52:35.790125 systemd[2266]: Reached target exit.target - Exit the Session. Apr 30 12:52:35.804696 (sd-pam)[2267]: pam_unix(systemd-user:session): session closed for user root Apr 30 12:52:35.806307 systemd[1]: user@0.service: Deactivated successfully. Apr 30 12:52:35.806666 systemd[1]: Stopped user@0.service - User Manager for UID 0. Apr 30 12:52:35.820003 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Apr 30 12:52:35.828577 systemd[1]: run-user-0.mount: Deactivated successfully. Apr 30 12:52:35.829433 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Apr 30 12:52:35.829619 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Apr 30 12:52:35.831468 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Apr 30 12:52:35.831557 systemd[1]: user-0.slice: Consumed 5min 11.402s CPU time, 891.3M memory peak. Apr 30 12:52:35.831807 systemd-logind[1692]: Removed session c3. Apr 30 12:52:41.067719 systemd[1]: Started sshd@21-10.200.4.30:22-10.200.16.10:34986.service - OpenSSH per-connection server daemon (10.200.16.10:34986). Apr 30 12:52:41.678054 sshd[13007]: Accepted publickey for core from 10.200.16.10 port 34986 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:41.679348 sshd-session[13007]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:41.683426 systemd-logind[1692]: New session 24 of user core. Apr 30 12:52:41.695964 systemd[1]: Started session-24.scope - Session 24 of User core. Apr 30 12:52:42.105016 sshd[13009]: Connection closed by 10.200.16.10 port 34986 Apr 30 12:52:42.105430 sshd-session[13007]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:42.108659 systemd[1]: sshd@21-10.200.4.30:22-10.200.16.10:34986.service: Deactivated successfully. Apr 30 12:52:42.110339 systemd[1]: session-24.scope: Deactivated successfully. Apr 30 12:52:42.110987 systemd-logind[1692]: Session 24 logged out. Waiting for processes to exit. Apr 30 12:52:42.111743 systemd-logind[1692]: Removed session 24. Apr 30 12:52:42.211214 systemd[1]: Started sshd@22-10.200.4.30:22-10.200.16.10:34990.service - OpenSSH per-connection server daemon (10.200.16.10:34990). Apr 30 12:52:42.813361 sshd[13015]: Accepted publickey for core from 10.200.16.10 port 34990 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:42.814758 sshd-session[13015]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:42.818862 systemd-logind[1692]: New session 25 of user core. Apr 30 12:52:42.827640 systemd[1]: Started session-25.scope - Session 25 of User core. Apr 30 12:52:43.262179 sshd[13017]: Connection closed by 10.200.16.10 port 34990 Apr 30 12:52:43.262480 sshd-session[13015]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:43.265733 systemd[1]: sshd@22-10.200.4.30:22-10.200.16.10:34990.service: Deactivated successfully. Apr 30 12:52:43.267415 systemd[1]: session-25.scope: Deactivated successfully. Apr 30 12:52:43.268068 systemd-logind[1692]: Session 25 logged out. Waiting for processes to exit. Apr 30 12:52:43.268824 systemd-logind[1692]: Removed session 25. Apr 30 12:52:43.379059 systemd[1]: Started sshd@23-10.200.4.30:22-10.200.16.10:35000.service - OpenSSH per-connection server daemon (10.200.16.10:35000). Apr 30 12:52:43.979545 sshd[13023]: Accepted publickey for core from 10.200.16.10 port 35000 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:43.980994 sshd-session[13023]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:43.985168 systemd-logind[1692]: New session 26 of user core. Apr 30 12:52:43.995969 systemd[1]: Started session-26.scope - Session 26 of User core. Apr 30 12:52:44.432940 sshd[13025]: Connection closed by 10.200.16.10 port 35000 Apr 30 12:52:44.433331 sshd-session[13023]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:44.436480 systemd[1]: sshd@23-10.200.4.30:22-10.200.16.10:35000.service: Deactivated successfully. Apr 30 12:52:44.438167 systemd[1]: session-26.scope: Deactivated successfully. Apr 30 12:52:44.438785 systemd-logind[1692]: Session 26 logged out. Waiting for processes to exit. Apr 30 12:52:44.439537 systemd-logind[1692]: Removed session 26. Apr 30 12:52:44.541137 systemd[1]: Started sshd@24-10.200.4.30:22-10.200.16.10:35016.service - OpenSSH per-connection server daemon (10.200.16.10:35016). Apr 30 12:52:45.142164 sshd[13031]: Accepted publickey for core from 10.200.16.10 port 35016 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:45.143513 sshd-session[13031]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:45.147624 systemd-logind[1692]: New session 27 of user core. Apr 30 12:52:45.157976 systemd[1]: Started session-27.scope - Session 27 of User core. Apr 30 12:52:45.474027 sudo[13034]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Apr 30 12:52:45.474341 sudo[13034]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:45.518173 sudo[13034]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:45.617200 sshd[13033]: Connection closed by 10.200.16.10 port 35016 Apr 30 12:52:45.617582 sshd-session[13031]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:45.620812 systemd[1]: sshd@24-10.200.4.30:22-10.200.16.10:35016.service: Deactivated successfully. Apr 30 12:52:45.622488 systemd[1]: session-27.scope: Deactivated successfully. Apr 30 12:52:45.623144 systemd-logind[1692]: Session 27 logged out. Waiting for processes to exit. Apr 30 12:52:45.623966 systemd-logind[1692]: Removed session 27. Apr 30 12:52:45.723336 systemd[1]: Started sshd@25-10.200.4.30:22-10.200.16.10:35024.service - OpenSSH per-connection server daemon (10.200.16.10:35024). Apr 30 12:52:46.323915 sshd[13040]: Accepted publickey for core from 10.200.16.10 port 35024 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:46.325376 sshd-session[13040]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:46.329480 systemd-logind[1692]: New session 28 of user core. Apr 30 12:52:46.339975 systemd[1]: Started session-28.scope - Session 28 of User core. Apr 30 12:52:46.654084 sudo[13045]: 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 Apr 30 12:52:46.654389 sudo[13045]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:46.657666 sudo[13045]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:46.663244 sudo[13044]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Apr 30 12:52:46.663540 sudo[13044]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:46.681098 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 12:52:46.706532 augenrules[13069]: No rules Apr 30 12:52:46.707765 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 12:52:46.708032 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 12:52:46.708769 sudo[13044]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:46.807617 sshd[13042]: Connection closed by 10.200.16.10 port 35024 Apr 30 12:52:46.808012 sshd-session[13040]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:46.811405 systemd[1]: sshd@25-10.200.4.30:22-10.200.16.10:35024.service: Deactivated successfully. Apr 30 12:52:46.813117 systemd[1]: session-28.scope: Deactivated successfully. Apr 30 12:52:46.813749 systemd-logind[1692]: Session 28 logged out. Waiting for processes to exit. Apr 30 12:52:46.814521 systemd-logind[1692]: Removed session 28. Apr 30 12:52:46.916127 systemd[1]: Started sshd@26-10.200.4.30:22-10.200.16.10:35032.service - OpenSSH per-connection server daemon (10.200.16.10:35032). Apr 30 12:52:47.521706 sshd[13078]: Accepted publickey for core from 10.200.16.10 port 35032 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:47.523060 sshd-session[13078]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:47.527598 systemd-logind[1692]: New session 29 of user core. Apr 30 12:52:47.544962 systemd[1]: Started session-29.scope - Session 29 of User core. Apr 30 12:52:47.861150 sudo[13081]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Apr 30 12:52:47.861465 sudo[13081]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:47.864797 sudo[13081]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:47.987947 sshd[13080]: Connection closed by 10.200.16.10 port 35032 Apr 30 12:52:47.988370 sshd-session[13078]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:47.991507 systemd[1]: sshd@26-10.200.4.30:22-10.200.16.10:35032.service: Deactivated successfully. Apr 30 12:52:47.993403 systemd[1]: session-29.scope: Deactivated successfully. Apr 30 12:52:47.994054 systemd-logind[1692]: Session 29 logged out. Waiting for processes to exit. Apr 30 12:52:47.994798 systemd-logind[1692]: Removed session 29. Apr 30 12:52:48.094118 systemd[1]: Started sshd@27-10.200.4.30:22-10.200.16.10:35034.service - OpenSSH per-connection server daemon (10.200.16.10:35034). Apr 30 12:52:48.700501 sshd[13087]: Accepted publickey for core from 10.200.16.10 port 35034 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:48.701876 sshd-session[13087]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:48.706085 systemd-logind[1692]: New session 30 of user core. Apr 30 12:52:48.723019 systemd[1]: Started session-30.scope - Session 30 of User core. Apr 30 12:52:49.033194 sudo[13090]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Apr 30 12:52:49.033507 sudo[13090]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:58.356565 sudo[13090]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:58.478037 sshd[13089]: Connection closed by 10.200.16.10 port 35034 Apr 30 12:52:58.478317 sshd-session[13087]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:58.482078 systemd[1]: sshd@27-10.200.4.30:22-10.200.16.10:35034.service: Deactivated successfully. Apr 30 12:52:58.483735 systemd[1]: session-30.scope: Deactivated successfully. Apr 30 12:52:58.484368 systemd-logind[1692]: Session 30 logged out. Waiting for processes to exit. Apr 30 12:52:58.485142 systemd-logind[1692]: Removed session 30. Apr 30 12:52:58.585210 systemd[1]: Started sshd@28-10.200.4.30:22-10.200.16.10:34032.service - OpenSSH per-connection server daemon (10.200.16.10:34032). Apr 30 12:52:59.321706 sshd[13096]: Accepted publickey for core from 10.200.16.10 port 34032 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:52:59.323093 sshd-session[13096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:59.327262 systemd-logind[1692]: New session 31 of user core. Apr 30 12:52:59.338968 systemd[1]: Started session-31.scope - Session 31 of User core. Apr 30 12:52:59.801441 sshd[13098]: Connection closed by 10.200.16.10 port 34032 Apr 30 12:52:59.801822 sshd-session[13096]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:59.805058 systemd[1]: sshd@28-10.200.4.30:22-10.200.16.10:34032.service: Deactivated successfully. Apr 30 12:52:59.806728 systemd[1]: session-31.scope: Deactivated successfully. Apr 30 12:52:59.807361 systemd-logind[1692]: Session 31 logged out. Waiting for processes to exit. Apr 30 12:52:59.808116 systemd-logind[1692]: Removed session 31. Apr 30 12:52:59.908092 systemd[1]: Started sshd@29-10.200.4.30:22-10.200.16.10:52990.service - OpenSSH per-connection server daemon (10.200.16.10:52990). Apr 30 12:53:00.513121 sshd[13114]: Accepted publickey for core from 10.200.16.10 port 52990 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:53:00.514451 sshd-session[13114]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:53:00.518583 systemd-logind[1692]: New session 32 of user core. Apr 30 12:53:00.528968 systemd[1]: Started session-32.scope - Session 32 of User core. Apr 30 12:53:01.688058 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 30 12:53:01.688237 (dockerd)[13137]: 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 Apr 30 12:53:05.300863 dockerd[13137]: time="2025-04-30T12:53:05.300797833Z" level=info msg="Starting up" Apr 30 12:53:06.294093 dockerd[13137]: time="2025-04-30T12:53:06.294030765Z" level=info msg="Loading containers: start." Apr 30 12:53:06.489868 kernel: Initializing XFRM netlink socket Apr 30 12:53:06.547161 systemd-networkd[1401]: docker0: Link UP Apr 30 12:53:06.593137 dockerd[13137]: time="2025-04-30T12:53:06.593094352Z" level=info msg="Loading containers: done." Apr 30 12:53:06.611578 dockerd[13137]: time="2025-04-30T12:53:06.611534036Z" 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 Apr 30 12:53:06.611698 dockerd[13137]: time="2025-04-30T12:53:06.611613235Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Apr 30 12:53:06.611734 dockerd[13137]: time="2025-04-30T12:53:06.611712334Z" level=info msg="Daemon has completed initialization" Apr 30 12:53:06.657270 dockerd[13137]: time="2025-04-30T12:53:06.657220399Z" level=info msg="API listen on /run/docker.sock" Apr 30 12:53:06.657344 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 30 12:53:07.668443 systemd[1]: var-lib-docker-overlay2-9a2d0c85099c6cc7c6ea7bf0faa1f71d55c4b76918c61e051d8f70fb230700da\x2dinit-merged.mount: Deactivated successfully. Apr 30 12:53:07.702243 systemd[1]: var-lib-docker-overlay2-9a2d0c85099c6cc7c6ea7bf0faa1f71d55c4b76918c61e051d8f70fb230700da-merged.mount: Deactivated successfully. Apr 30 12:53:07.729634 kernel: docker0: port 1(vethc5d1ab5) entered blocking state Apr 30 12:53:07.729700 kernel: docker0: port 1(vethc5d1ab5) entered disabled state Apr 30 12:53:07.729724 kernel: vethc5d1ab5: entered allmulticast mode Apr 30 12:53:07.732573 kernel: vethc5d1ab5: entered promiscuous mode Apr 30 12:53:07.735617 systemd-networkd[1401]: vethc5d1ab5: Link UP Apr 30 12:53:08.698441 containerd[1711]: time="2025-04-30T12:53:08.698344122Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:08.698441 containerd[1711]: time="2025-04-30T12:53:08.698405521Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:08.698441 containerd[1711]: time="2025-04-30T12:53:08.698418421Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:08.698927 containerd[1711]: time="2025-04-30T12:53:08.698494720Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:08.723974 systemd[1]: Started docker-38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5.scope - libcontainer container 38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5. Apr 30 12:53:08.774871 kernel: eth0: renamed from vethe8615e5 Apr 30 12:53:08.795962 kernel: docker0: port 1(vethc5d1ab5) entered blocking state Apr 30 12:53:08.796158 kernel: docker0: port 1(vethc5d1ab5) entered forwarding state Apr 30 12:53:08.796349 systemd-networkd[1401]: vethc5d1ab5: Gained carrier Apr 30 12:53:08.796619 systemd-networkd[1401]: docker0: Gained carrier Apr 30 12:53:10.243994 systemd-networkd[1401]: vethc5d1ab5: Gained IPv6LL Apr 30 12:53:10.755980 systemd-networkd[1401]: docker0: Gained IPv6LL Apr 30 12:53:12.071658 systemd[1]: docker-38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5.scope: Deactivated successfully. Apr 30 12:53:12.086391 dockerd[13137]: time="2025-04-30T12:53:12.086335073Z" level=info msg="ignoring event" container=38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Apr 30 12:53:12.086728 containerd[1711]: time="2025-04-30T12:53:12.086334473Z" level=info msg="shim disconnected" id=38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5 namespace=moby Apr 30 12:53:12.086728 containerd[1711]: time="2025-04-30T12:53:12.086395772Z" level=warning msg="cleaning up after shim disconnected" id=38862a90a462feef14e4ba5aba6d6b3e6cd6eb596151367a9d5ba461c550fae5 namespace=moby Apr 30 12:53:12.086728 containerd[1711]: time="2025-04-30T12:53:12.086405972Z" level=info msg="cleaning up dead shim" namespace=moby Apr 30 12:53:12.115378 systemd-networkd[1401]: vethc5d1ab5: Lost carrier Apr 30 12:53:12.115859 kernel: docker0: port 1(vethc5d1ab5) entered disabled state Apr 30 12:53:12.115899 kernel: vethe8615e5: renamed from eth0 Apr 30 12:53:12.140920 systemd-networkd[1401]: vethc5d1ab5: Link DOWN Apr 30 12:53:12.144857 kernel: docker0: port 1(vethc5d1ab5) entered disabled state Apr 30 12:53:12.152373 kernel: vethc5d1ab5 (unregistering): left allmulticast mode Apr 30 12:53:12.152425 kernel: vethc5d1ab5 (unregistering): left promiscuous mode Apr 30 12:53:12.155856 kernel: docker0: port 1(vethc5d1ab5) entered disabled state Apr 30 12:53:12.187581 systemd[1]: run-docker-netns-e48d78ca1d6a.mount: Deactivated successfully. Apr 30 12:53:12.189858 systemd[1]: var-lib-docker-overlay2-9a2d0c85099c6cc7c6ea7bf0faa1f71d55c4b76918c61e051d8f70fb230700da-merged.mount: Deactivated successfully. Apr 30 12:53:12.338610 sshd[13116]: Connection closed by 10.200.16.10 port 52990 Apr 30 12:53:12.338957 sshd-session[13114]: pam_unix(sshd:session): session closed for user core Apr 30 12:53:12.342360 systemd[1]: sshd@29-10.200.4.30:22-10.200.16.10:52990.service: Deactivated successfully. Apr 30 12:53:12.344035 systemd[1]: session-32.scope: Deactivated successfully. Apr 30 12:53:12.344674 systemd-logind[1692]: Session 32 logged out. Waiting for processes to exit. Apr 30 12:53:12.345444 systemd-logind[1692]: Removed session 32. Apr 30 12:53:12.445092 systemd[1]: Started sshd@30-10.200.4.30:22-10.200.16.10:33488.service - OpenSSH per-connection server daemon (10.200.16.10:33488). Apr 30 12:53:13.050504 sshd[13412]: Accepted publickey for core from 10.200.16.10 port 33488 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:53:13.051828 sshd-session[13412]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:53:13.057254 systemd-logind[1692]: New session 33 of user core. Apr 30 12:53:13.068999 systemd[1]: Started session-33.scope - Session 33 of User core. Apr 30 12:53:13.124093 systemd-networkd[1401]: docker0: Lost carrier Apr 30 12:53:13.434294 systemd[1]: var-lib-docker-overlay2-c75ea562742635f063fe251dccedba4fb0796a0443c0140c4b515d70270b657c\x2dinit-merged.mount: Deactivated successfully. Apr 30 12:53:13.489954 kernel: docker0: port 1(vetha429b53) entered blocking state Apr 30 12:53:13.490051 kernel: docker0: port 1(vetha429b53) entered disabled state Apr 30 12:53:13.492909 kernel: vetha429b53: entered allmulticast mode Apr 30 12:53:13.495892 kernel: vetha429b53: entered promiscuous mode Apr 30 12:53:13.499475 kernel: docker0: port 1(vetha429b53) entered blocking state Apr 30 12:53:13.503038 kernel: docker0: port 1(vetha429b53) entered forwarding state Apr 30 12:53:13.503756 systemd-networkd[1401]: vetha429b53: Link UP Apr 30 12:53:13.503918 kernel: docker0: port 1(vetha429b53) entered disabled state Apr 30 12:53:13.565014 containerd[1711]: time="2025-04-30T12:53:13.564910337Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:13.565430 containerd[1711]: time="2025-04-30T12:53:13.565031236Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:13.565430 containerd[1711]: time="2025-04-30T12:53:13.565053436Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:13.565430 containerd[1711]: time="2025-04-30T12:53:13.565133835Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:13.586971 systemd[1]: Started docker-58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da.scope - libcontainer container 58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da. Apr 30 12:53:13.639869 kernel: eth0: renamed from vethc6fdfdb Apr 30 12:53:13.658014 kernel: docker0: port 1(vetha429b53) entered blocking state Apr 30 12:53:13.658066 kernel: docker0: port 1(vetha429b53) entered forwarding state Apr 30 12:53:13.658360 systemd-networkd[1401]: vetha429b53: Gained carrier Apr 30 12:53:13.658598 systemd-networkd[1401]: docker0: Gained carrier Apr 30 12:53:13.693701 systemd[1]: docker-58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da.scope: Deactivated successfully. Apr 30 12:53:13.711769 dockerd[13137]: time="2025-04-30T12:53:13.711718296Z" level=info msg="ignoring event" container=58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Apr 30 12:53:13.712077 containerd[1711]: time="2025-04-30T12:53:13.711715996Z" level=info msg="shim disconnected" id=58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da namespace=moby Apr 30 12:53:13.712077 containerd[1711]: time="2025-04-30T12:53:13.711776196Z" level=warning msg="cleaning up after shim disconnected" id=58a80f8356c994f3e6ff68f86ad161babbcc3231ae4a3600bf190f66abcb95da namespace=moby Apr 30 12:53:13.712077 containerd[1711]: time="2025-04-30T12:53:13.711785696Z" level=info msg="cleaning up dead shim" namespace=moby Apr 30 12:53:13.741609 systemd-networkd[1401]: vetha429b53: Lost carrier Apr 30 12:53:13.741859 kernel: docker0: port 1(vetha429b53) entered disabled state Apr 30 12:53:13.741902 kernel: vethc6fdfdb: renamed from eth0 Apr 30 12:53:13.771294 systemd-networkd[1401]: vetha429b53: Link DOWN Apr 30 12:53:13.778789 kernel: docker0: port 1(vetha429b53) entered disabled state Apr 30 12:53:13.778848 kernel: vetha429b53 (unregistering): left allmulticast mode Apr 30 12:53:13.782206 kernel: vetha429b53 (unregistering): left promiscuous mode Apr 30 12:53:13.785573 kernel: docker0: port 1(vetha429b53) entered disabled state Apr 30 12:53:13.950292 sshd[13414]: Connection closed by 10.200.16.10 port 33488 Apr 30 12:53:13.950635 sshd-session[13412]: pam_unix(sshd:session): session closed for user core Apr 30 12:53:13.953963 systemd[1]: sshd@30-10.200.4.30:22-10.200.16.10:33488.service: Deactivated successfully. Apr 30 12:53:13.955633 systemd[1]: session-33.scope: Deactivated successfully. Apr 30 12:53:13.956261 systemd-logind[1692]: Session 33 logged out. Waiting for processes to exit. Apr 30 12:53:13.957034 systemd-logind[1692]: Removed session 33. Apr 30 12:53:14.057244 systemd[1]: Started sshd@31-10.200.4.30:22-10.200.16.10:33494.service - OpenSSH per-connection server daemon (10.200.16.10:33494). Apr 30 12:53:14.132052 systemd-networkd[1401]: docker0: Lost carrier Apr 30 12:53:14.431448 systemd[1]: var-lib-docker-overlay2-c75ea562742635f063fe251dccedba4fb0796a0443c0140c4b515d70270b657c-merged.mount: Deactivated successfully. Apr 30 12:53:14.664336 sshd[13519]: Accepted publickey for core from 10.200.16.10 port 33494 ssh2: RSA SHA256:ZntMQNTYUGCfY6/gwjDGxfjsWdPuNX2EWVZpN0ztt1A Apr 30 12:53:14.665639 sshd-session[13519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:53:14.669685 systemd-logind[1692]: New session 34 of user core. Apr 30 12:53:14.684963 systemd[1]: Started session-34.scope - Session 34 of User core. Apr 30 12:53:15.052893 ntpdate[13533]: ntpdate 4.2.8p17@1.4004-o Tue Apr 29 21:38:48 UTC 2025 (1) Apr 30 12:53:21.771216 ntpdate[13533]: adjust time server 82.219.4.30 offset -0.025840 sec Apr 30 12:53:21.894245 sshd[13521]: Connection closed by 10.200.16.10 port 33494 Apr 30 12:53:21.894635 sshd-session[13519]: pam_unix(sshd:session): session closed for user core Apr 30 12:53:21.897929 systemd[1]: sshd@31-10.200.4.30:22-10.200.16.10:33494.service: Deactivated successfully. Apr 30 12:53:21.899608 systemd[1]: session-34.scope: Deactivated successfully. Apr 30 12:53:21.900257 systemd-logind[1692]: Session 34 logged out. Waiting for processes to exit. Apr 30 12:53:21.901102 systemd-logind[1692]: Removed session 34.