Mar 25 01:31:59.210008 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Mar 24 23:38:35 -00 2025 Mar 25 01:31:59.210037 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.210047 kernel: BIOS-provided physical RAM map: Mar 25 01:31:59.210066 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 25 01:31:59.210073 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 25 01:31:59.210079 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 25 01:31:59.210087 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 25 01:31:59.210098 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 25 01:31:59.210105 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 25 01:31:59.210112 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 25 01:31:59.210119 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Mar 25 01:31:59.210126 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Mar 25 01:31:59.210132 kernel: printk: bootconsole [earlyser0] enabled Mar 25 01:31:59.210139 kernel: NX (Execute Disable) protection: active Mar 25 01:31:59.210151 kernel: APIC: Static calls initialized Mar 25 01:31:59.210158 kernel: efi: EFI v2.7 by Microsoft Mar 25 01:31:59.210166 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bea98 RNG=0x3ffd1018 Mar 25 01:31:59.210174 kernel: random: crng init done Mar 25 01:31:59.210182 kernel: secureboot: Secure boot disabled Mar 25 01:31:59.210190 kernel: SMBIOS 3.1.0 present. Mar 25 01:31:59.210197 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 25 01:31:59.210205 kernel: Hypervisor detected: Microsoft Hyper-V Mar 25 01:31:59.210213 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Mar 25 01:31:59.210221 kernel: Hyper-V: Host Build 10.0.20348.1799-1-0 Mar 25 01:31:59.210231 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 25 01:31:59.210238 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 25 01:31:59.210246 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 25 01:31:59.210254 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 25 01:31:59.210262 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 25 01:31:59.210270 kernel: tsc: Detected 2593.993 MHz processor Mar 25 01:31:59.210278 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 25 01:31:59.210286 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 25 01:31:59.210294 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Mar 25 01:31:59.210304 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Mar 25 01:31:59.210312 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 25 01:31:59.210320 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 25 01:31:59.210327 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Mar 25 01:31:59.210335 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 25 01:31:59.210342 kernel: Using GB pages for direct mapping Mar 25 01:31:59.210350 kernel: ACPI: Early table checksum verification disabled Mar 25 01:31:59.210362 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 25 01:31:59.210373 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210381 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210390 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 25 01:31:59.210398 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 25 01:31:59.210406 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210414 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210425 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210434 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210442 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210450 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210459 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 25 01:31:59.210467 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 25 01:31:59.210475 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 25 01:31:59.210483 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 25 01:31:59.210494 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 25 01:31:59.210502 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 25 01:31:59.210510 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 25 01:31:59.210519 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Mar 25 01:31:59.210527 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Mar 25 01:31:59.210535 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 25 01:31:59.210543 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 25 01:31:59.210551 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 25 01:31:59.210559 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 25 01:31:59.210570 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Mar 25 01:31:59.210578 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Mar 25 01:31:59.210586 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Mar 25 01:31:59.210595 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Mar 25 01:31:59.210603 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 25 01:31:59.210611 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Mar 25 01:31:59.210619 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Mar 25 01:31:59.210628 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Mar 25 01:31:59.210636 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Mar 25 01:31:59.210647 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Mar 25 01:31:59.210655 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Mar 25 01:31:59.210663 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Mar 25 01:31:59.210671 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Mar 25 01:31:59.210680 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Mar 25 01:31:59.210688 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Mar 25 01:31:59.210696 kernel: Zone ranges: Mar 25 01:31:59.210705 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 25 01:31:59.210713 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 25 01:31:59.210723 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Mar 25 01:31:59.210732 kernel: Movable zone start for each node Mar 25 01:31:59.210740 kernel: Early memory node ranges Mar 25 01:31:59.210748 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 25 01:31:59.210756 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 25 01:31:59.210764 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 25 01:31:59.210772 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Mar 25 01:31:59.210781 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Mar 25 01:31:59.210789 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Mar 25 01:31:59.210799 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 25 01:31:59.210808 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 25 01:31:59.210816 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 25 01:31:59.210824 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 25 01:31:59.210832 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 25 01:31:59.210840 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Mar 25 01:31:59.210849 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 25 01:31:59.210857 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 25 01:31:59.210865 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 25 01:31:59.210876 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Mar 25 01:31:59.210884 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 25 01:31:59.210892 kernel: Booting paravirtualized kernel on Hyper-V Mar 25 01:31:59.210901 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 25 01:31:59.210909 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Mar 25 01:31:59.210917 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Mar 25 01:31:59.210926 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Mar 25 01:31:59.210934 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Mar 25 01:31:59.210942 kernel: Hyper-V: PV spinlocks enabled Mar 25 01:31:59.210953 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 25 01:31:59.210962 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.210971 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 25 01:31:59.210979 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Mar 25 01:31:59.210987 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Mar 25 01:31:59.210995 kernel: Fallback order for Node 0: 0 Mar 25 01:31:59.211004 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Mar 25 01:31:59.211012 kernel: Policy zone: Normal Mar 25 01:31:59.211023 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 25 01:31:59.211039 kernel: software IO TLB: area num 8. Mar 25 01:31:59.211048 kernel: Memory: 115333940K/117439364K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43592K init, 1472K bss, 2105168K reserved, 0K cma-reserved) Mar 25 01:31:59.211068 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 25 01:31:59.211076 kernel: Kernel/User page tables isolation: enabled Mar 25 01:31:59.211085 kernel: ftrace: allocating 37985 entries in 149 pages Mar 25 01:31:59.211093 kernel: ftrace: allocated 149 pages with 4 groups Mar 25 01:31:59.211102 kernel: Dynamic Preempt: voluntary Mar 25 01:31:59.211110 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 25 01:31:59.211120 kernel: rcu: RCU event tracing is enabled. Mar 25 01:31:59.211128 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Mar 25 01:31:59.211137 kernel: Trampoline variant of Tasks RCU enabled. Mar 25 01:31:59.211149 kernel: Rude variant of Tasks RCU enabled. Mar 25 01:31:59.211157 kernel: Tracing variant of Tasks RCU enabled. Mar 25 01:31:59.211166 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 25 01:31:59.211175 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Mar 25 01:31:59.211186 kernel: Using NULL legacy PIC Mar 25 01:31:59.211195 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Mar 25 01:31:59.211206 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 25 01:31:59.211215 kernel: Console: colour dummy device 80x25 Mar 25 01:31:59.211224 kernel: printk: console [tty1] enabled Mar 25 01:31:59.211232 kernel: printk: console [ttyS0] enabled Mar 25 01:31:59.211241 kernel: printk: bootconsole [earlyser0] disabled Mar 25 01:31:59.211250 kernel: ACPI: Core revision 20230628 Mar 25 01:31:59.211258 kernel: Failed to register legacy timer interrupt Mar 25 01:31:59.211267 kernel: APIC: Switch to symmetric I/O mode setup Mar 25 01:31:59.211278 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 25 01:31:59.211287 kernel: Hyper-V: Using IPI hypercalls Mar 25 01:31:59.211303 kernel: APIC: send_IPI() replaced with hv_send_ipi() Mar 25 01:31:59.211312 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Mar 25 01:31:59.211321 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Mar 25 01:31:59.211330 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Mar 25 01:31:59.211338 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Mar 25 01:31:59.211347 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Mar 25 01:31:59.211356 kernel: Hyper-V: Using enlightened APIC (xapic mode) Mar 25 01:31:59.211368 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Mar 25 01:31:59.211377 kernel: APIC: read() replaced with hv_apic_read() Mar 25 01:31:59.211385 kernel: APIC: write() replaced with hv_apic_write() Mar 25 01:31:59.211394 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Mar 25 01:31:59.211403 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Mar 25 01:31:59.211412 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Mar 25 01:31:59.211421 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Mar 25 01:31:59.211429 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 25 01:31:59.211438 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 25 01:31:59.211449 kernel: Spectre V2 : Mitigation: Retpolines Mar 25 01:31:59.211457 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 25 01:31:59.211466 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 25 01:31:59.211475 kernel: Speculative Store Bypass: Vulnerable Mar 25 01:31:59.211483 kernel: MDS: Mitigation: Clear CPU buffers Mar 25 01:31:59.211492 kernel: TAA: Mitigation: Clear CPU buffers Mar 25 01:31:59.211500 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 25 01:31:59.211573 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 25 01:31:59.211585 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 25 01:31:59.211594 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 25 01:31:59.211602 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 25 01:31:59.211616 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Mar 25 01:31:59.211625 kernel: Freeing SMP alternatives memory: 32K Mar 25 01:31:59.211638 kernel: pid_max: default: 32768 minimum: 301 Mar 25 01:31:59.211649 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 25 01:31:59.211657 kernel: landlock: Up and running. Mar 25 01:31:59.211666 kernel: SELinux: Initializing. Mar 25 01:31:59.211675 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.211683 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.211696 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Mar 25 01:31:59.211705 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.211714 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.211726 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Mar 25 01:31:59.211735 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Mar 25 01:31:59.211744 kernel: signal: max sigframe size: 1776 Mar 25 01:31:59.211753 kernel: rcu: Hierarchical SRCU implementation. Mar 25 01:31:59.211762 kernel: rcu: Max phase no-delay instances is 400. Mar 25 01:31:59.211771 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 25 01:31:59.211779 kernel: smp: Bringing up secondary CPUs ... Mar 25 01:31:59.211788 kernel: smpboot: x86: Booting SMP configuration: Mar 25 01:31:59.211797 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Mar 25 01:31:59.211808 kernel: smp: Brought up 1 node, 6 CPUs Mar 25 01:31:59.211817 kernel: smpboot: Max logical packages: 1 Mar 25 01:31:59.211825 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Mar 25 01:31:59.211834 kernel: devtmpfs: initialized Mar 25 01:31:59.211843 kernel: x86/mm: Memory block size: 1024MB Mar 25 01:31:59.211852 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 25 01:31:59.211861 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 25 01:31:59.211869 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Mar 25 01:31:59.211878 kernel: pinctrl core: initialized pinctrl subsystem Mar 25 01:31:59.211889 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 25 01:31:59.211898 kernel: audit: initializing netlink subsys (disabled) Mar 25 01:31:59.211907 kernel: audit: type=2000 audit(1742866317.028:1): state=initialized audit_enabled=0 res=1 Mar 25 01:31:59.211916 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 25 01:31:59.211924 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 25 01:31:59.211933 kernel: cpuidle: using governor menu Mar 25 01:31:59.211942 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 25 01:31:59.211951 kernel: dca service started, version 1.12.1 Mar 25 01:31:59.211959 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Mar 25 01:31:59.211971 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 25 01:31:59.211979 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 25 01:31:59.211988 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 25 01:31:59.211997 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 25 01:31:59.212005 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 25 01:31:59.212014 kernel: ACPI: Added _OSI(Module Device) Mar 25 01:31:59.212023 kernel: ACPI: Added _OSI(Processor Device) Mar 25 01:31:59.212032 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 25 01:31:59.212041 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 25 01:31:59.212063 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 25 01:31:59.212073 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 25 01:31:59.212082 kernel: ACPI: Interpreter enabled Mar 25 01:31:59.212090 kernel: ACPI: PM: (supports S0 S5) Mar 25 01:31:59.212099 kernel: ACPI: Using IOAPIC for interrupt routing Mar 25 01:31:59.212108 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 25 01:31:59.212117 kernel: PCI: Ignoring E820 reservations for host bridge windows Mar 25 01:31:59.212125 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 25 01:31:59.212134 kernel: iommu: Default domain type: Translated Mar 25 01:31:59.212146 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 25 01:31:59.212155 kernel: efivars: Registered efivars operations Mar 25 01:31:59.212163 kernel: PCI: Using ACPI for IRQ routing Mar 25 01:31:59.212172 kernel: PCI: System does not support PCI Mar 25 01:31:59.212181 kernel: vgaarb: loaded Mar 25 01:31:59.212190 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 25 01:31:59.212198 kernel: VFS: Disk quotas dquot_6.6.0 Mar 25 01:31:59.212207 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 25 01:31:59.212215 kernel: pnp: PnP ACPI init Mar 25 01:31:59.212227 kernel: pnp: PnP ACPI: found 3 devices Mar 25 01:31:59.212236 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 25 01:31:59.212244 kernel: NET: Registered PF_INET protocol family Mar 25 01:31:59.212253 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.212262 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 25 01:31:59.212271 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 25 01:31:59.212280 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 25 01:31:59.212288 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.212300 kernel: TCP: Hash tables configured (established 524288 bind 65536) Mar 25 01:31:59.212309 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.212317 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Mar 25 01:31:59.212326 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 25 01:31:59.212335 kernel: NET: Registered PF_XDP protocol family Mar 25 01:31:59.212343 kernel: PCI: CLS 0 bytes, default 64 Mar 25 01:31:59.212352 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 25 01:31:59.212361 kernel: software IO TLB: mapped [mem 0x000000003b5be000-0x000000003f5be000] (64MB) Mar 25 01:31:59.212370 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 25 01:31:59.212381 kernel: Initialise system trusted keyrings Mar 25 01:31:59.212389 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Mar 25 01:31:59.212398 kernel: Key type asymmetric registered Mar 25 01:31:59.212406 kernel: Asymmetric key parser 'x509' registered Mar 25 01:31:59.212415 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 25 01:31:59.212424 kernel: io scheduler mq-deadline registered Mar 25 01:31:59.212432 kernel: io scheduler kyber registered Mar 25 01:31:59.212441 kernel: io scheduler bfq registered Mar 25 01:31:59.212449 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 25 01:31:59.212458 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 25 01:31:59.212469 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 25 01:31:59.212478 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 25 01:31:59.212487 kernel: i8042: PNP: No PS/2 controller found. Mar 25 01:31:59.212655 kernel: rtc_cmos 00:02: registered as rtc0 Mar 25 01:31:59.212765 kernel: rtc_cmos 00:02: setting system clock to 2025-03-25T01:31:58 UTC (1742866318) Mar 25 01:31:59.212866 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 25 01:31:59.212879 kernel: intel_pstate: CPU model not supported Mar 25 01:31:59.212892 kernel: efifb: probing for efifb Mar 25 01:31:59.212901 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 25 01:31:59.212910 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 25 01:31:59.212918 kernel: efifb: scrolling: redraw Mar 25 01:31:59.212927 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 25 01:31:59.212936 kernel: Console: switching to colour frame buffer device 128x48 Mar 25 01:31:59.212945 kernel: fb0: EFI VGA frame buffer device Mar 25 01:31:59.212953 kernel: pstore: Using crash dump compression: deflate Mar 25 01:31:59.212962 kernel: pstore: Registered efi_pstore as persistent store backend Mar 25 01:31:59.212974 kernel: NET: Registered PF_INET6 protocol family Mar 25 01:31:59.212982 kernel: Segment Routing with IPv6 Mar 25 01:31:59.212991 kernel: In-situ OAM (IOAM) with IPv6 Mar 25 01:31:59.213000 kernel: NET: Registered PF_PACKET protocol family Mar 25 01:31:59.213009 kernel: Key type dns_resolver registered Mar 25 01:31:59.213017 kernel: IPI shorthand broadcast: enabled Mar 25 01:31:59.213026 kernel: sched_clock: Marking stable (1626026300, 99787200)->(2049078500, -323265000) Mar 25 01:31:59.213035 kernel: registered taskstats version 1 Mar 25 01:31:59.213044 kernel: Loading compiled-in X.509 certificates Mar 25 01:31:59.213066 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: eff01054e94a599f8e404b9a9482f4e2220f5386' Mar 25 01:31:59.213075 kernel: Key type .fscrypt registered Mar 25 01:31:59.213084 kernel: Key type fscrypt-provisioning registered Mar 25 01:31:59.213093 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 25 01:31:59.213101 kernel: ima: Allocated hash algorithm: sha1 Mar 25 01:31:59.213110 kernel: ima: No architecture policies found Mar 25 01:31:59.213119 kernel: clk: Disabling unused clocks Mar 25 01:31:59.213127 kernel: Freeing unused kernel image (initmem) memory: 43592K Mar 25 01:31:59.213136 kernel: Write protecting the kernel read-only data: 40960k Mar 25 01:31:59.213148 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 25 01:31:59.213157 kernel: Run /init as init process Mar 25 01:31:59.213166 kernel: with arguments: Mar 25 01:31:59.213174 kernel: /init Mar 25 01:31:59.213183 kernel: with environment: Mar 25 01:31:59.213191 kernel: HOME=/ Mar 25 01:31:59.213200 kernel: TERM=linux Mar 25 01:31:59.213208 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 25 01:31:59.213218 systemd[1]: Successfully made /usr/ read-only. Mar 25 01:31:59.213233 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) Mar 25 01:31:59.213243 systemd[1]: Detected virtualization microsoft. Mar 25 01:31:59.213252 systemd[1]: Detected architecture x86-64. Mar 25 01:31:59.213261 systemd[1]: Running in initrd. Mar 25 01:31:59.213270 systemd[1]: No hostname configured, using default hostname. Mar 25 01:31:59.213280 systemd[1]: Hostname set to . Mar 25 01:31:59.213289 systemd[1]: Initializing machine ID from random generator. Mar 25 01:31:59.213300 systemd[1]: Queued start job for default target initrd.target. Mar 25 01:31:59.213310 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:31:59.213319 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:31:59.213329 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 25 01:31:59.213338 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:31:59.213348 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 25 01:31:59.213358 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 25 01:31:59.213371 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 25 01:31:59.213380 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 25 01:31:59.213389 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:31:59.213399 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:31:59.213408 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:31:59.213417 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:31:59.213427 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:31:59.213436 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:31:59.213448 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:31:59.213457 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:31:59.213466 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 25 01:31:59.213476 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 25 01:31:59.213485 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:31:59.213494 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:31:59.213503 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:31:59.213513 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:31:59.213522 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 25 01:31:59.213534 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:31:59.213543 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 25 01:31:59.213552 systemd[1]: Starting systemd-fsck-usr.service... Mar 25 01:31:59.213562 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:31:59.213571 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:31:59.213601 systemd-journald[204]: Collecting audit messages is disabled. Mar 25 01:31:59.213627 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:31:59.213638 systemd-journald[204]: Journal started Mar 25 01:31:59.213658 systemd-journald[204]: Runtime Journal (/run/log/journal/13965f341717422a87adc13658d0fe47) is 8M, max 2.2G, 2.1G free. Mar 25 01:31:59.222073 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:31:59.225877 systemd-modules-load[206]: Inserted module 'overlay' Mar 25 01:31:59.226239 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 25 01:31:59.227104 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:31:59.227838 systemd[1]: Finished systemd-fsck-usr.service. Mar 25 01:31:59.233973 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:31:59.237033 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:31:59.242299 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:31:59.243833 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:31:59.296224 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:31:59.297979 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:31:59.311294 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:31:59.317340 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:31:59.344074 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 25 01:31:59.347610 systemd-modules-load[206]: Inserted module 'br_netfilter' Mar 25 01:31:59.351123 kernel: Bridge firewalling registered Mar 25 01:31:59.359347 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:31:59.360760 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:31:59.376685 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:31:59.386989 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 25 01:31:59.396127 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:31:59.397510 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:31:59.412621 dracut-cmdline[240]: dracut-dracut-053 Mar 25 01:31:59.412621 dracut-cmdline[240]: 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=e7a00b7ee8d97e8d255663e9d3fa92277da8316702fb7f6d664fd7b137c307e9 Mar 25 01:31:59.464426 systemd-resolved[243]: Positive Trust Anchors: Mar 25 01:31:59.464445 systemd-resolved[243]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:31:59.464475 systemd-resolved[243]: 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 Mar 25 01:31:59.467327 systemd-resolved[243]: Defaulting to hostname 'linux'. Mar 25 01:31:59.468409 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:31:59.473349 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:31:59.568079 kernel: SCSI subsystem initialized Mar 25 01:31:59.579075 kernel: Loading iSCSI transport class v2.0-870. Mar 25 01:31:59.591075 kernel: iscsi: registered transport (tcp) Mar 25 01:31:59.614569 kernel: iscsi: registered transport (qla4xxx) Mar 25 01:31:59.614599 kernel: QLogic iSCSI HBA Driver Mar 25 01:31:59.656701 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 25 01:31:59.658386 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 25 01:31:59.714076 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 25 01:31:59.714124 kernel: device-mapper: uevent: version 1.0.3 Mar 25 01:31:59.721127 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 25 01:31:59.763072 kernel: raid6: avx2x4 gen() 30799 MB/s Mar 25 01:31:59.783074 kernel: raid6: avx2x2 gen() 30339 MB/s Mar 25 01:31:59.804126 kernel: raid6: avx2x1 gen() 27544 MB/s Mar 25 01:31:59.804159 kernel: raid6: using algorithm avx2x4 gen() 30799 MB/s Mar 25 01:31:59.825999 kernel: raid6: .... xor() 9974 MB/s, rmw enabled Mar 25 01:31:59.826027 kernel: raid6: using avx2x2 recovery algorithm Mar 25 01:31:59.850076 kernel: xor: automatically using best checksumming function avx Mar 25 01:31:59.998081 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 25 01:32:00.013181 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:32:00.015748 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:00.051158 systemd-udevd[427]: Using default interface naming scheme 'v255'. Mar 25 01:32:00.056763 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:00.066691 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 25 01:32:00.094126 dracut-pre-trigger[431]: rd.md=0: removing MD RAID activation Mar 25 01:32:00.124910 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:32:00.128304 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:32:00.206782 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:00.221489 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 25 01:32:00.234406 kernel: cryptd: max_cpu_qlen set to 1000 Mar 25 01:32:00.245024 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 25 01:32:00.265740 kernel: AVX2 version of gcm_enc/dec engaged. Mar 25 01:32:00.265769 kernel: AES CTR mode by8 optimization enabled Mar 25 01:32:00.265782 kernel: hv_vmbus: Vmbus version:4.0 Mar 25 01:32:00.265802 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 25 01:32:00.258511 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:32:00.275160 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 25 01:32:00.280282 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:00.289508 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:32:00.302593 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 25 01:32:00.302645 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 25 01:32:00.302924 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:32:00.313554 kernel: PTP clock support registered Mar 25 01:32:00.313575 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 Mar 25 01:32:00.303166 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:00.327437 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:32:00.338086 kernel: hv_vmbus: registering driver hid_hyperv Mar 25 01:32:00.344269 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 25 01:32:00.351072 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 25 01:32:00.351451 kernel: hv_vmbus: registering driver hv_storvsc Mar 25 01:32:00.352364 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 25 01:32:00.363071 kernel: scsi host0: storvsc_host_t Mar 25 01:32:00.367902 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:32:01.068092 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 25 01:32:01.068317 kernel: scsi host1: storvsc_host_t Mar 25 01:32:01.068483 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 25 01:32:01.068649 kernel: hv_vmbus: registering driver hv_netvsc Mar 25 01:32:01.068665 kernel: hv_utils: Registering HyperV Utility Driver Mar 25 01:32:01.068685 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 25 01:32:01.068856 kernel: hv_vmbus: registering driver hv_utils Mar 25 01:32:01.068879 kernel: hv_vmbus: registering driver hv_pci Mar 25 01:32:01.068891 kernel: hv_utils: Heartbeat IC version 3.0 Mar 25 01:32:01.068902 kernel: hv_utils: Shutdown IC version 3.2 Mar 25 01:32:01.068913 kernel: hv_utils: TimeSync IC version 4.0 Mar 25 01:32:01.068924 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Mar 25 01:32:01.113757 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Mar 25 01:32:01.113925 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Mar 25 01:32:01.114107 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Mar 25 01:32:01.114239 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Mar 25 01:32:01.114365 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Mar 25 01:32:01.114516 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Mar 25 01:32:01.114653 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 25 01:32:01.114789 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 25 01:32:01.114923 kernel: pci 0001:00:00.0: Enabling HDA controller Mar 25 01:32:01.115887 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 25 01:32:01.116081 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 25 01:32:01.116097 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 25 01:32:01.116245 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Mar 25 01:32:01.116377 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Mar 25 01:32:01.116515 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 25 01:32:01.145059 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Mar 25 01:32:01.158194 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Mar 25 01:32:01.158358 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Mar 25 01:32:01.158496 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 25 01:32:01.158635 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 25 01:32:01.158768 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Mar 25 01:32:01.158900 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 25 01:32:01.159051 kernel: sd 0:0:0:1: [sdb] Write Protect is off Mar 25 01:32:01.159191 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 25 01:32:01.159327 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Mar 25 01:32:01.159459 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:01.159473 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 25 01:32:01.159603 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 25 01:32:01.159735 kernel: sdb: sdb1 Mar 25 01:32:01.159748 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Mar 25 01:32:00.368131 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:01.042034 systemd-resolved[243]: Clock change detected. Flushing caches. Mar 25 01:32:01.093478 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:01.118560 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:01.163865 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:01.171350 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:32:01.205207 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:01.206559 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:32:01.253175 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:01.649967 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by (udev-worker) (495) Mar 25 01:32:01.652669 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 25 01:32:01.665571 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 25 01:32:01.691085 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 25 01:32:01.729971 kernel: BTRFS: device fsid 6d9424cd-1432-492b-b006-b311869817e2 devid 1 transid 39 /dev/sda3 scanned by (udev-worker) (483) Mar 25 01:32:01.749142 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 25 01:32:01.749273 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 25 01:32:01.770707 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 25 01:32:01.804960 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:01.815959 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:02.821963 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 25 01:32:02.822276 disk-uuid[639]: The operation has completed successfully. Mar 25 01:32:04.374069 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 25 01:32:04.374178 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 25 01:32:04.388470 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 25 01:32:04.408719 sh[726]: Success Mar 25 01:32:04.448974 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 25 01:32:04.681938 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 25 01:32:04.692008 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 25 01:32:04.715239 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 25 01:32:04.735465 kernel: BTRFS info (device dm-0): first mount of filesystem 6d9424cd-1432-492b-b006-b311869817e2 Mar 25 01:32:04.735507 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:04.740371 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 25 01:32:04.744090 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 25 01:32:04.747248 kernel: BTRFS info (device dm-0): using free space tree Mar 25 01:32:05.282102 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 25 01:32:05.284022 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 25 01:32:05.286434 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 25 01:32:05.293251 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 25 01:32:05.335697 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:05.335734 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:05.338748 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:05.365992 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:05.373964 kernel: BTRFS info (device sda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:05.381044 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 25 01:32:05.386153 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 25 01:32:05.416654 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:32:05.418187 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:32:05.464255 systemd-networkd[907]: lo: Link UP Mar 25 01:32:05.464266 systemd-networkd[907]: lo: Gained carrier Mar 25 01:32:05.465435 systemd-networkd[907]: Enumeration completed Mar 25 01:32:05.465508 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:32:05.465854 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:05.465858 systemd-networkd[907]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:05.466666 systemd-networkd[907]: eth0: Link UP Mar 25 01:32:05.466841 systemd-networkd[907]: eth0: Gained carrier Mar 25 01:32:05.466850 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:05.474226 systemd[1]: Reached target network.target - Network. Mar 25 01:32:05.529984 systemd-networkd[907]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:06.703061 systemd-networkd[907]: eth0: Gained IPv6LL Mar 25 01:32:06.834589 ignition[884]: Ignition 2.20.0 Mar 25 01:32:06.834601 ignition[884]: Stage: fetch-offline Mar 25 01:32:06.834652 ignition[884]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:06.834664 ignition[884]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:06.834770 ignition[884]: parsed url from cmdline: "" Mar 25 01:32:06.834774 ignition[884]: no config URL provided Mar 25 01:32:06.834780 ignition[884]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:32:06.834790 ignition[884]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:32:06.834800 ignition[884]: failed to fetch config: resource requires networking Mar 25 01:32:06.834992 ignition[884]: Ignition finished successfully Mar 25 01:32:06.865909 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:32:06.875889 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 25 01:32:06.906867 ignition[920]: Ignition 2.20.0 Mar 25 01:32:06.906878 ignition[920]: Stage: fetch Mar 25 01:32:06.907066 ignition[920]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:06.907080 ignition[920]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:06.907175 ignition[920]: parsed url from cmdline: "" Mar 25 01:32:06.907179 ignition[920]: no config URL provided Mar 25 01:32:06.907185 ignition[920]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:32:06.907195 ignition[920]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:32:06.907220 ignition[920]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 25 01:32:06.972366 ignition[920]: GET result: OK Mar 25 01:32:06.972393 ignition[920]: failed to retrieve userdata from IMDS, falling back to custom data: not a config (empty) Mar 25 01:32:07.011455 ignition[920]: opening config device: "/dev/sr0" Mar 25 01:32:07.011920 ignition[920]: getting drive status for "/dev/sr0" Mar 25 01:32:07.011980 ignition[920]: drive status: OK Mar 25 01:32:07.012006 ignition[920]: mounting config device Mar 25 01:32:07.012017 ignition[920]: op(1): [started] mounting "/dev/sr0" at "/tmp/ignition-azure3079071034" Mar 25 01:32:07.029958 kernel: UDF-fs: INFO Mounting volume 'UDF Volume', timestamp 2025/03/26 00:00 (1000) Mar 25 01:32:07.030206 ignition[920]: op(1): [finished] mounting "/dev/sr0" at "/tmp/ignition-azure3079071034" Mar 25 01:32:07.030216 ignition[920]: checking for config drive Mar 25 01:32:07.032054 systemd[1]: tmp-ignition\x2dazure3079071034.mount: Deactivated successfully. Mar 25 01:32:07.031264 ignition[920]: reading config Mar 25 01:32:07.039429 unknown[920]: fetched base config from "system" Mar 25 01:32:07.031641 ignition[920]: op(2): [started] unmounting "/dev/sr0" at "/tmp/ignition-azure3079071034" Mar 25 01:32:07.039454 unknown[920]: fetched base config from "system" Mar 25 01:32:07.035315 ignition[920]: op(2): [finished] unmounting "/dev/sr0" at "/tmp/ignition-azure3079071034" Mar 25 01:32:07.039464 unknown[920]: fetched user config from "azure" Mar 25 01:32:07.035331 ignition[920]: config has been read from custom data Mar 25 01:32:07.058245 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 25 01:32:07.035343 ignition[920]: parsing config with SHA512: d5ce31af04bccb35431af59862489f612c022796cabf891b46b24c4dae13e8ea30c1ec142740d9ff91a93ccb8e5da9dd6ee50a167502856a91a893c0c1d60e4d Mar 25 01:32:07.061716 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 25 01:32:07.039746 ignition[920]: fetch: fetch complete Mar 25 01:32:07.039752 ignition[920]: fetch: fetch passed Mar 25 01:32:07.039801 ignition[920]: Ignition finished successfully Mar 25 01:32:07.103677 ignition[930]: Ignition 2.20.0 Mar 25 01:32:07.103689 ignition[930]: Stage: kargs Mar 25 01:32:07.103861 ignition[930]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:07.103873 ignition[930]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:07.104431 ignition[930]: kargs: kargs passed Mar 25 01:32:07.104475 ignition[930]: Ignition finished successfully Mar 25 01:32:07.124053 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 25 01:32:07.132034 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 25 01:32:07.160009 ignition[939]: Ignition 2.20.0 Mar 25 01:32:07.160021 ignition[939]: Stage: disks Mar 25 01:32:07.160188 ignition[939]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:07.160201 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:07.160738 ignition[939]: disks: disks passed Mar 25 01:32:07.160781 ignition[939]: Ignition finished successfully Mar 25 01:32:07.172981 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 25 01:32:07.174838 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 25 01:32:07.189880 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 25 01:32:07.201586 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:32:07.210393 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:32:07.218155 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:32:07.226743 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 25 01:32:07.305896 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 25 01:32:07.311374 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 25 01:32:07.326063 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 25 01:32:07.436957 kernel: EXT4-fs (sda9): mounted filesystem 4e6dca82-2e50-453c-be25-61f944b72008 r/w with ordered data mode. Quota mode: none. Mar 25 01:32:07.437619 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 25 01:32:07.438279 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 25 01:32:07.472727 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:32:07.473605 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 25 01:32:07.495478 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 25 01:32:07.520558 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (963) Mar 25 01:32:07.520583 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:07.520597 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:07.520609 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:07.495612 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 25 01:32:07.495644 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:32:07.545757 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:07.526755 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 25 01:32:07.538570 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 25 01:32:07.562106 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:32:08.558135 coreos-metadata[965]: Mar 25 01:32:08.558 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 25 01:32:08.566084 coreos-metadata[965]: Mar 25 01:32:08.566 INFO Fetch successful Mar 25 01:32:08.569968 coreos-metadata[965]: Mar 25 01:32:08.566 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 25 01:32:08.577867 coreos-metadata[965]: Mar 25 01:32:08.577 INFO Fetch successful Mar 25 01:32:08.589137 coreos-metadata[965]: Mar 25 01:32:08.589 INFO wrote hostname ci-4284.0.0-a-be0372a1cb to /sysroot/etc/hostname Mar 25 01:32:08.596511 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 25 01:32:08.690157 initrd-setup-root[993]: cut: /sysroot/etc/passwd: No such file or directory Mar 25 01:32:08.746980 initrd-setup-root[1000]: cut: /sysroot/etc/group: No such file or directory Mar 25 01:32:08.779029 initrd-setup-root[1007]: cut: /sysroot/etc/shadow: No such file or directory Mar 25 01:32:08.786369 initrd-setup-root[1014]: cut: /sysroot/etc/gshadow: No such file or directory Mar 25 01:32:10.186414 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 25 01:32:10.191872 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 25 01:32:10.205732 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 25 01:32:10.231157 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 25 01:32:10.240056 kernel: BTRFS info (device sda6): last unmount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:10.259121 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 25 01:32:10.273160 ignition[1084]: INFO : Ignition 2.20.0 Mar 25 01:32:10.273160 ignition[1084]: INFO : Stage: mount Mar 25 01:32:10.278827 ignition[1084]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:10.278827 ignition[1084]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:10.278827 ignition[1084]: INFO : mount: mount passed Mar 25 01:32:10.278827 ignition[1084]: INFO : Ignition finished successfully Mar 25 01:32:10.283841 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 25 01:32:10.288676 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 25 01:32:10.324579 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:32:10.351955 kernel: BTRFS: device label OEM devid 1 transid 18 /dev/sda6 scanned by mount (1097) Mar 25 01:32:10.360961 kernel: BTRFS info (device sda6): first mount of filesystem a72930ba-1354-475c-94df-b83a66efea67 Mar 25 01:32:10.360990 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 25 01:32:10.364103 kernel: BTRFS info (device sda6): using free space tree Mar 25 01:32:10.369958 kernel: BTRFS info (device sda6): auto enabling async discard Mar 25 01:32:10.371121 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:32:10.398933 ignition[1114]: INFO : Ignition 2.20.0 Mar 25 01:32:10.398933 ignition[1114]: INFO : Stage: files Mar 25 01:32:10.405009 ignition[1114]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:10.405009 ignition[1114]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:10.405009 ignition[1114]: DEBUG : files: compiled without relabeling support, skipping Mar 25 01:32:10.437397 ignition[1114]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 25 01:32:10.437397 ignition[1114]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 25 01:32:10.527745 ignition[1114]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 25 01:32:10.533727 ignition[1114]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 25 01:32:10.533727 ignition[1114]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 25 01:32:10.528438 unknown[1114]: wrote ssh authorized keys file for user: core Mar 25 01:32:10.548927 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:32:10.548927 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:32:10.548927 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:32:10.572295 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:32:10.572295 ignition[1114]: INFO : files: files passed Mar 25 01:32:10.572295 ignition[1114]: INFO : Ignition finished successfully Mar 25 01:32:10.565985 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 25 01:32:10.573754 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 25 01:32:10.606191 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 25 01:32:10.608875 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 25 01:32:10.608986 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 25 01:32:10.633015 initrd-setup-root-after-ignition[1147]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.639284 initrd-setup-root-after-ignition[1147]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.634844 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:32:10.660014 initrd-setup-root-after-ignition[1151]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:32:10.644343 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 25 01:32:10.651179 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 25 01:32:10.704286 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 25 01:32:10.704397 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 25 01:32:10.708363 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 25 01:32:10.717679 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 25 01:32:10.725586 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 25 01:32:10.726373 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 25 01:32:10.771802 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:32:10.773184 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 25 01:32:10.810146 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:32:10.810348 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:10.819305 systemd[1]: Stopped target timers.target - Timer Units. Mar 25 01:32:10.828221 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 25 01:32:10.828345 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:32:10.843424 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 25 01:32:10.855785 systemd[1]: Stopped target basic.target - Basic System. Mar 25 01:32:10.855998 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 25 01:32:10.862915 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:32:10.871312 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 25 01:32:10.880301 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 25 01:32:10.888859 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:32:10.896705 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 25 01:32:10.910298 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 25 01:32:10.914121 systemd[1]: Stopped target swap.target - Swaps. Mar 25 01:32:10.921732 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 25 01:32:10.921861 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:32:10.935726 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:32:10.939874 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:32:10.948162 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 25 01:32:10.948259 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:32:10.962189 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 25 01:32:10.962306 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 25 01:32:10.975469 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 25 01:32:10.975595 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:32:10.979280 systemd[1]: ignition-files.service: Deactivated successfully. Mar 25 01:32:10.979391 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 25 01:32:10.996159 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 25 01:32:10.996272 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 25 01:32:11.002155 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 25 01:32:11.027211 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 25 01:32:11.029782 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 25 01:32:11.054554 ignition[1171]: INFO : Ignition 2.20.0 Mar 25 01:32:11.054554 ignition[1171]: INFO : Stage: umount Mar 25 01:32:11.054554 ignition[1171]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:32:11.054554 ignition[1171]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 25 01:32:11.054554 ignition[1171]: INFO : umount: umount passed Mar 25 01:32:11.054554 ignition[1171]: INFO : Ignition finished successfully Mar 25 01:32:11.029908 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:11.041689 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 25 01:32:11.041812 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:32:11.049686 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 25 01:32:11.049781 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 25 01:32:11.055745 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 25 01:32:11.057146 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 25 01:32:11.057258 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 25 01:32:11.061859 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 25 01:32:11.061911 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 25 01:32:11.070720 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 25 01:32:11.070768 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 25 01:32:11.074725 systemd[1]: Stopped target network.target - Network. Mar 25 01:32:11.083167 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 25 01:32:11.083219 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:32:11.089486 systemd[1]: Stopped target paths.target - Path Units. Mar 25 01:32:11.100810 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 25 01:32:11.106043 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:32:11.114395 systemd[1]: Stopped target slices.target - Slice Units. Mar 25 01:32:11.118030 systemd[1]: Stopped target sockets.target - Socket Units. Mar 25 01:32:11.126027 systemd[1]: iscsid.socket: Deactivated successfully. Mar 25 01:32:11.126069 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:32:11.129704 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 25 01:32:11.129740 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:32:11.137031 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 25 01:32:11.137080 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 25 01:32:11.144387 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 25 01:32:11.144431 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 25 01:32:11.152862 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 25 01:32:11.160438 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 25 01:32:11.172093 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 25 01:32:11.172193 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 25 01:32:11.184743 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 25 01:32:11.185084 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 25 01:32:11.185187 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 25 01:32:11.204108 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 25 01:32:11.204379 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 25 01:32:11.204473 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 25 01:32:11.206718 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 25 01:32:11.206811 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 25 01:32:11.220847 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 25 01:32:11.220911 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:32:11.226872 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 25 01:32:11.226931 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 25 01:32:11.238923 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 25 01:32:11.245453 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 25 01:32:11.245511 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:32:11.252491 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 25 01:32:11.252539 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:32:11.268178 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 25 01:32:11.268225 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 25 01:32:11.271863 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 25 01:32:11.271909 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:32:11.288034 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:11.298188 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 25 01:32:11.298259 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:11.324237 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 25 01:32:11.324381 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:11.326487 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 25 01:32:11.326540 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 25 01:32:11.339434 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 25 01:32:11.339472 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:32:11.347006 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 25 01:32:11.347054 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:32:11.359188 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 25 01:32:11.359240 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 25 01:32:11.370509 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:32:11.370561 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:32:11.384514 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 25 01:32:11.387289 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 25 01:32:11.387344 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:32:11.400196 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 25 01:32:11.400342 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:32:11.404109 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 25 01:32:11.404181 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:32:11.413962 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:32:11.414035 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:11.577131 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Mar 25 01:32:11.431328 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 25 01:32:11.431394 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:32:11.431770 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 25 01:32:11.431869 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 25 01:32:11.434738 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 25 01:32:11.434826 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 25 01:32:11.443144 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 25 01:32:11.451329 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 25 01:32:11.479539 systemd[1]: Switching root. Mar 25 01:32:11.614700 systemd-journald[204]: Journal stopped Mar 25 01:32:17.226859 kernel: SELinux: policy capability network_peer_controls=1 Mar 25 01:32:17.226893 kernel: SELinux: policy capability open_perms=1 Mar 25 01:32:17.226906 kernel: SELinux: policy capability extended_socket_class=1 Mar 25 01:32:17.226915 kernel: SELinux: policy capability always_check_network=0 Mar 25 01:32:17.226924 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 25 01:32:17.226933 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 25 01:32:17.226959 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 25 01:32:17.226974 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 25 01:32:17.226984 kernel: audit: type=1403 audit(1742866332.908:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 25 01:32:17.226995 systemd[1]: Successfully loaded SELinux policy in 218.984ms. Mar 25 01:32:17.227007 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.229ms. Mar 25 01:32:17.227019 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) Mar 25 01:32:17.227029 systemd[1]: Detected virtualization microsoft. Mar 25 01:32:17.227039 systemd[1]: Detected architecture x86-64. Mar 25 01:32:17.227053 systemd[1]: Detected first boot. Mar 25 01:32:17.227064 systemd[1]: Hostname set to . Mar 25 01:32:17.227075 systemd[1]: Initializing machine ID from random generator. Mar 25 01:32:17.227086 zram_generator::config[1217]: No configuration found. Mar 25 01:32:17.227097 kernel: Guest personality initialized and is inactive Mar 25 01:32:17.227109 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Mar 25 01:32:17.227119 kernel: Initialized host personality Mar 25 01:32:17.227131 kernel: NET: Registered PF_VSOCK protocol family Mar 25 01:32:17.227141 systemd[1]: Populated /etc with preset unit settings. Mar 25 01:32:17.227153 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 25 01:32:17.227163 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 25 01:32:17.227173 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 25 01:32:17.227184 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 25 01:32:17.227201 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 25 01:32:17.227213 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 25 01:32:17.227224 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 25 01:32:17.227234 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 25 01:32:17.227253 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 25 01:32:17.227266 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 25 01:32:17.227277 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 25 01:32:17.227292 systemd[1]: Created slice user.slice - User and Session Slice. Mar 25 01:32:17.227303 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:32:17.227314 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:32:17.227325 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 25 01:32:17.227336 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 25 01:32:17.227347 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 25 01:32:17.227362 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:32:17.227373 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 25 01:32:17.227384 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:32:17.227398 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 25 01:32:17.227409 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 25 01:32:17.227420 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 25 01:32:17.227431 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 25 01:32:17.227442 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:32:17.227456 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:32:17.227468 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:32:17.227481 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:32:17.227493 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 25 01:32:17.227503 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 25 01:32:17.227514 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 25 01:32:17.227526 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:32:17.227540 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:32:17.227551 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:32:17.227562 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 25 01:32:17.227574 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 25 01:32:17.227585 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 25 01:32:17.227596 systemd[1]: Mounting media.mount - External Media Directory... Mar 25 01:32:17.227607 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:17.227618 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 25 01:32:17.227632 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 25 01:32:17.227644 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 25 01:32:17.227655 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). Mar 25 01:32:17.227667 systemd[1]: Reached target machines.target - Containers. Mar 25 01:32:17.227678 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 25 01:32:17.227689 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:32:17.227704 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:32:17.227716 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 25 01:32:17.227730 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:32:17.227742 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:32:17.227753 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:32:17.227764 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 25 01:32:17.227775 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:32:17.229275 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 25 01:32:17.229288 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 25 01:32:17.229299 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 25 01:32:17.229310 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 25 01:32:17.229327 systemd[1]: Stopped systemd-fsck-usr.service. Mar 25 01:32:17.229340 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). Mar 25 01:32:17.229351 kernel: fuse: init (API version 7.39) Mar 25 01:32:17.229362 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:32:17.229373 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:32:17.229384 kernel: loop: module loaded Mar 25 01:32:17.229394 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 25 01:32:17.229433 systemd-journald[1324]: Collecting audit messages is disabled. Mar 25 01:32:17.229459 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 25 01:32:17.229471 systemd-journald[1324]: Journal started Mar 25 01:32:17.229502 systemd-journald[1324]: Runtime Journal (/run/log/journal/904b13d2b22040fbb4162955da228eeb) is 8M, max 2.2G, 2.1G free. Mar 25 01:32:16.472651 systemd[1]: Queued start job for default target multi-user.target. Mar 25 01:32:16.484240 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 25 01:32:16.484670 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 25 01:32:17.243064 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 25 01:32:17.249963 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:32:17.259845 systemd[1]: verity-setup.service: Deactivated successfully. Mar 25 01:32:17.259884 systemd[1]: Stopped verity-setup.service. Mar 25 01:32:17.271959 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:17.296244 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:32:17.300333 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 25 01:32:17.304675 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 25 01:32:17.309235 systemd[1]: Mounted media.mount - External Media Directory. Mar 25 01:32:17.313288 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 25 01:32:17.317793 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 25 01:32:17.322225 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 25 01:32:17.326419 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 25 01:32:17.331439 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:32:17.336869 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 25 01:32:17.337074 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 25 01:32:17.342340 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:32:17.342527 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:32:17.347382 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:32:17.347565 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:32:17.352727 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 25 01:32:17.352953 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 25 01:32:17.357960 kernel: ACPI: bus type drm_connector registered Mar 25 01:32:17.359827 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:32:17.360021 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:32:17.364662 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:32:17.364838 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:32:17.369373 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:32:17.374070 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 25 01:32:17.379434 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 25 01:32:17.393388 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 25 01:32:17.401599 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 25 01:32:17.421460 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 25 01:32:17.427546 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 25 01:32:17.427585 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:32:17.432661 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 25 01:32:17.438690 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 25 01:32:17.447663 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 25 01:32:17.451747 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:32:17.453476 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 25 01:32:17.458711 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 25 01:32:17.463353 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:32:17.464253 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 25 01:32:17.468633 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:32:17.469577 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:32:17.474558 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 25 01:32:17.480150 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:32:17.486621 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 25 01:32:17.491845 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:32:17.497625 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 25 01:32:17.502422 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 25 01:32:17.507859 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 25 01:32:17.516358 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 25 01:32:17.522103 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 25 01:32:17.522906 systemd-journald[1324]: Time spent on flushing to /var/log/journal/904b13d2b22040fbb4162955da228eeb is 13.902ms for 956 entries. Mar 25 01:32:17.522906 systemd-journald[1324]: System Journal (/var/log/journal/904b13d2b22040fbb4162955da228eeb) is 8M, max 2.6G, 2.6G free. Mar 25 01:32:17.575241 systemd-journald[1324]: Received client request to flush runtime journal. Mar 25 01:32:17.530592 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 25 01:32:17.545881 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 25 01:32:17.553894 udevadm[1367]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 25 01:32:17.576148 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 25 01:32:17.592963 kernel: loop0: detected capacity change from 0 to 151640 Mar 25 01:32:17.645139 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 25 01:32:17.645896 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 25 01:32:17.667601 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:32:17.724097 systemd-tmpfiles[1360]: ACLs are not supported, ignoring. Mar 25 01:32:17.724118 systemd-tmpfiles[1360]: ACLs are not supported, ignoring. Mar 25 01:32:17.729662 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:32:17.736100 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 25 01:32:17.992168 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 25 01:32:17.998063 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:32:18.027969 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Mar 25 01:32:18.027990 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Mar 25 01:32:18.032578 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:32:18.312976 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 25 01:32:18.398971 kernel: loop1: detected capacity change from 0 to 109808 Mar 25 01:32:18.773968 kernel: loop2: detected capacity change from 0 to 28424 Mar 25 01:32:19.160971 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 25 01:32:19.171860 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:32:19.211338 systemd-udevd[1388]: Using default interface naming scheme 'v255'. Mar 25 01:32:19.481972 kernel: loop3: detected capacity change from 0 to 151640 Mar 25 01:32:19.494965 kernel: loop4: detected capacity change from 0 to 109808 Mar 25 01:32:19.504967 kernel: loop5: detected capacity change from 0 to 28424 Mar 25 01:32:19.510338 (sd-merge)[1390]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 25 01:32:19.510921 (sd-merge)[1390]: Merged extensions into '/usr'. Mar 25 01:32:19.514363 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 25 01:32:19.520761 systemd[1]: Starting ensure-sysext.service... Mar 25 01:32:19.524255 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:32:19.571493 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 25 01:32:19.571794 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 25 01:32:19.572719 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 25 01:32:19.573032 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Mar 25 01:32:19.573103 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Mar 25 01:32:19.576850 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:32:19.576864 systemd-tmpfiles[1393]: Skipping /boot Mar 25 01:32:19.588984 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:32:19.588996 systemd-tmpfiles[1393]: Skipping /boot Mar 25 01:32:19.596712 systemd[1]: Reload requested from client PID 1392 ('systemctl') (unit ensure-sysext.service)... Mar 25 01:32:19.596729 systemd[1]: Reloading... Mar 25 01:32:19.637974 zram_generator::config[1425]: No configuration found. Mar 25 01:32:19.733637 kernel: hv_vmbus: registering driver hv_balloon Mar 25 01:32:19.733724 kernel: hv_vmbus: registering driver hyperv_fb Mar 25 01:32:19.733757 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 25 01:32:19.743007 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 25 01:32:19.747740 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 25 01:32:19.751071 kernel: mousedev: PS/2 mouse device common for all mice Mar 25 01:32:19.751116 kernel: Console: switching to colour dummy device 80x25 Mar 25 01:32:19.758563 kernel: Console: switching to colour frame buffer device 128x48 Mar 25 01:32:19.778559 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. Mar 25 01:32:19.808971 kernel: EDAC MC: Ver: 3.0.0 Mar 25 01:32:19.829007 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1493) Mar 25 01:32:19.882752 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 25 01:32:19.883004 systemd[1]: Reloading finished in 285 ms. Mar 25 01:32:19.905358 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:32:19.927399 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:32:19.958028 systemd[1]: Finished ensure-sysext.service. Mar 25 01:32:19.971090 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 25 01:32:20.006419 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 25 01:32:20.011217 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:20.012436 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:32:20.030846 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 25 01:32:20.035506 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:32:20.036450 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 25 01:32:20.041852 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:32:20.046915 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:32:20.051824 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:32:20.057329 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:32:20.063375 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:32:20.064532 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 25 01:32:20.069566 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). Mar 25 01:32:20.070737 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 25 01:32:20.077513 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:32:20.083304 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:32:20.087604 systemd[1]: Reached target time-set.target - System Time Set. Mar 25 01:32:20.092833 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 25 01:32:20.109182 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:32:20.109301 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 25 01:32:20.110361 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:32:20.110556 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:32:20.111124 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:32:20.111475 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:32:20.113095 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:32:20.135132 lvm[1598]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:32:20.113287 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:32:20.113760 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:32:20.113934 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:32:20.118500 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:32:20.118656 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:32:20.120065 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 25 01:32:20.154156 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 25 01:32:20.163502 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 25 01:32:20.168836 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 25 01:32:20.174856 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:32:20.180573 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 25 01:32:20.203839 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 25 01:32:20.208069 lvm[1632]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:32:20.283789 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 25 01:32:20.287047 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 25 01:32:20.363179 augenrules[1649]: No rules Mar 25 01:32:20.364553 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:32:20.364789 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:32:20.401039 systemd-networkd[1611]: lo: Link UP Mar 25 01:32:20.401049 systemd-networkd[1611]: lo: Gained carrier Mar 25 01:32:20.402400 systemd-networkd[1611]: Enumeration completed Mar 25 01:32:20.402497 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:32:20.402773 systemd-networkd[1611]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:20.402783 systemd-networkd[1611]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:20.403316 systemd-networkd[1611]: eth0: Link UP Mar 25 01:32:20.403326 systemd-networkd[1611]: eth0: Gained carrier Mar 25 01:32:20.403340 systemd-networkd[1611]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:20.404036 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 25 01:32:20.405070 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 25 01:32:20.455989 systemd-networkd[1611]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:20.469260 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 25 01:32:20.500619 systemd-resolved[1612]: Positive Trust Anchors: Mar 25 01:32:20.500635 systemd-resolved[1612]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:32:20.500667 systemd-resolved[1612]: 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 Mar 25 01:32:20.513973 systemd-resolved[1612]: Using system hostname 'ci-4284.0.0-a-be0372a1cb'. Mar 25 01:32:20.515750 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:32:20.515985 systemd[1]: Reached target network.target - Network. Mar 25 01:32:20.516651 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:32:20.583184 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 25 01:32:20.583514 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). Mar 25 01:32:20.765560 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:32:22.319079 systemd-networkd[1611]: eth0: Gained IPv6LL Mar 25 01:32:22.321438 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 25 01:32:22.327216 systemd[1]: Reached target network-online.target - Network is Online. Mar 25 01:32:24.269868 ldconfig[1353]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:32:24.283316 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 25 01:32:24.289855 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 25 01:32:24.311925 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 25 01:32:24.316716 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:32:24.321150 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 25 01:32:24.326458 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 25 01:32:24.331933 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 25 01:32:24.336569 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 25 01:32:24.341928 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 25 01:32:24.347262 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 25 01:32:24.347302 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:32:24.350885 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:32:24.386810 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 25 01:32:24.392289 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 25 01:32:24.399354 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 25 01:32:24.404577 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 25 01:32:24.409594 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 25 01:32:24.415482 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 25 01:32:24.420104 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 25 01:32:24.425444 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 25 01:32:24.430017 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:32:24.433969 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:32:24.437877 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:32:24.437926 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:32:24.440350 systemd[1]: Starting chronyd.service - NTP client/server... Mar 25 01:32:24.445012 systemd[1]: Starting containerd.service - containerd container runtime... Mar 25 01:32:24.458838 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 25 01:32:24.464155 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 25 01:32:24.468712 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 25 01:32:24.473723 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 25 01:32:24.476309 jq[1676]: false Mar 25 01:32:24.477661 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). Mar 25 01:32:24.477726 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 25 01:32:24.478735 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 25 01:32:24.483534 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 25 01:32:24.484599 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 25 01:32:24.485332 KVP[1678]: KVP starting; pid is:1678 Mar 25 01:32:24.487851 KVP[1678]: KVP LIC Version: 3.1 Mar 25 01:32:24.489961 kernel: hv_utils: KVP IC version 4.0 Mar 25 01:32:24.491744 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 25 01:32:24.496439 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 25 01:32:24.502021 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 25 01:32:24.502537 (chronyd)[1669]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 25 01:32:24.508184 chronyd[1688]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 25 01:32:24.508894 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 25 01:32:24.513667 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 25 01:32:24.514113 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 25 01:32:24.514832 systemd[1]: Starting update-engine.service - Update Engine... Mar 25 01:32:24.520074 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 25 01:32:24.525332 chronyd[1688]: Timezone right/UTC failed leap second check, ignoring Mar 25 01:32:24.525487 chronyd[1688]: Loaded seccomp filter (level 2) Mar 25 01:32:24.526513 systemd[1]: Started chronyd.service - NTP client/server. Mar 25 01:32:24.527711 jq[1698]: true Mar 25 01:32:24.530553 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 25 01:32:24.530767 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 25 01:32:24.531159 systemd[1]: motdgen.service: Deactivated successfully. Mar 25 01:32:24.531376 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 25 01:32:24.535862 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 25 01:32:24.536097 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 25 01:32:24.553352 (ntainerd)[1701]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 25 01:32:24.556031 jq[1700]: true Mar 25 01:32:24.569801 extend-filesystems[1677]: Found loop3 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found loop4 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found loop5 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda1 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda2 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda3 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found usr Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda4 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda6 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda7 Mar 25 01:32:24.572755 extend-filesystems[1677]: Found sda9 Mar 25 01:32:24.572755 extend-filesystems[1677]: Checking size of /dev/sda9 Mar 25 01:32:24.609324 update_engine[1696]: I20250325 01:32:24.586324 1696 main.cc:92] Flatcar Update Engine starting Mar 25 01:32:24.597838 systemd-logind[1689]: New seat seat0. Mar 25 01:32:24.599115 systemd-logind[1689]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 25 01:32:24.599291 systemd[1]: Started systemd-logind.service - User Login Management. Mar 25 01:32:24.700826 dbus-daemon[1672]: [system] SELinux support is enabled Mar 25 01:32:24.701036 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 25 01:32:24.703339 update_engine[1696]: I20250325 01:32:24.703269 1696 update_check_scheduler.cc:74] Next update check in 5m47s Mar 25 01:32:24.708178 dbus-daemon[1672]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 25 01:32:24.708672 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). Mar 25 01:32:24.708714 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 25 01:32:24.714001 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). Mar 25 01:32:24.714031 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 25 01:32:24.718878 systemd[1]: Started update-engine.service - Update Engine. Mar 25 01:32:24.724654 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 25 01:32:24.730262 extend-filesystems[1677]: Old size kept for /dev/sda9 Mar 25 01:32:24.735184 extend-filesystems[1677]: Found sdb Mar 25 01:32:24.735184 extend-filesystems[1677]: Found sdb1 Mar 25 01:32:24.735184 extend-filesystems[1677]: Found sr0 Mar 25 01:32:24.748011 setup-nvidia[1681]: Downloading Flatcar Container Linux Developer Container for version: 4284.0.0 Mar 25 01:32:24.748222 bash[1727]: Updated "/home/core/.ssh/authorized_keys" Mar 25 01:32:24.738315 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 25 01:32:24.748369 coreos-metadata[1671]: Mar 25 01:32:24.736 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 25 01:32:24.748369 coreos-metadata[1671]: Mar 25 01:32:24.739 INFO Fetch successful Mar 25 01:32:24.748369 coreos-metadata[1671]: Mar 25 01:32:24.739 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 25 01:32:24.748369 coreos-metadata[1671]: Mar 25 01:32:24.743 INFO Fetch successful Mar 25 01:32:24.748369 coreos-metadata[1671]: Mar 25 01:32:24.744 INFO Fetching http://168.63.129.16/machine/bd2f0cd1-e0f2-4454-9fdc-043453582737/becfc346%2D005f%2D42e4%2D81a1%2Db71fed46a6db.%5Fci%2D4284.0.0%2Da%2Dbe0372a1cb?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 25 01:32:24.738539 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 25 01:32:24.748641 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 25 01:32:24.757436 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 25 01:32:24.761971 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1737) Mar 25 01:32:24.781381 coreos-metadata[1671]: Mar 25 01:32:24.781 INFO Fetch successful Mar 25 01:32:24.781476 coreos-metadata[1671]: Mar 25 01:32:24.781 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 25 01:32:24.789045 coreos-metadata[1671]: Mar 25 01:32:24.788 INFO Fetch successful Mar 25 01:32:24.825688 sshd_keygen[1697]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 25 01:32:24.828831 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 25 01:32:24.833653 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 25 01:32:24.850936 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 25 01:32:24.856820 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 25 01:32:24.861294 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 25 01:32:24.883436 systemd[1]: issuegen.service: Deactivated successfully. Mar 25 01:32:24.883640 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 25 01:32:24.889827 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 25 01:32:24.897429 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 25 01:32:24.935227 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 25 01:32:24.941597 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 25 01:32:24.946657 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 25 01:32:24.951250 systemd[1]: Reached target getty.target - Login Prompts. Mar 25 01:32:24.998077 locksmithd[1741]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 25 01:32:25.893587 containerd[1701]: time="2025-03-25T01:32:25Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Mar 25 01:32:25.894677 containerd[1701]: time="2025-03-25T01:32:25.894644600Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 25 01:32:25.902683 containerd[1701]: time="2025-03-25T01:32:25.902646400Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.2µs" Mar 25 01:32:25.902683 containerd[1701]: time="2025-03-25T01:32:25.902676800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 25 01:32:25.902767 containerd[1701]: time="2025-03-25T01:32:25.902696300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 25 01:32:25.903229 containerd[1701]: time="2025-03-25T01:32:25.903055100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 25 01:32:25.903229 containerd[1701]: time="2025-03-25T01:32:25.903164600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 25 01:32:25.903379 containerd[1701]: time="2025-03-25T01:32:25.903208000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903534 containerd[1701]: time="2025-03-25T01:32:25.903506200Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903565 containerd[1701]: time="2025-03-25T01:32:25.903531700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903773 containerd[1701]: time="2025-03-25T01:32:25.903748600Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903773 containerd[1701]: time="2025-03-25T01:32:25.903770400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903836 containerd[1701]: time="2025-03-25T01:32:25.903783600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903836 containerd[1701]: time="2025-03-25T01:32:25.903792100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 25 01:32:25.903896 containerd[1701]: time="2025-03-25T01:32:25.903877300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904142 containerd[1701]: time="2025-03-25T01:32:25.904119300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904173 containerd[1701]: time="2025-03-25T01:32:25.904157700Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:32:25.904173 containerd[1701]: time="2025-03-25T01:32:25.904169600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 25 01:32:25.904215 containerd[1701]: time="2025-03-25T01:32:25.904197500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 25 01:32:25.904402 containerd[1701]: time="2025-03-25T01:32:25.904382500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 25 01:32:25.904465 containerd[1701]: time="2025-03-25T01:32:25.904447800Z" level=info msg="metadata content store policy set" policy=shared Mar 25 01:32:25.906510 setup-nvidia[1842]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 25 01:32:25.909166 setup-nvidia[1842]: Dload Upload Total Spent Left Speed Mar 25 01:32:25.924493 containerd[1701]: time="2025-03-25T01:32:25.924463400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 25 01:32:25.924552 containerd[1701]: time="2025-03-25T01:32:25.924522900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 25 01:32:25.924552 containerd[1701]: time="2025-03-25T01:32:25.924545400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 25 01:32:25.924616 containerd[1701]: time="2025-03-25T01:32:25.924559000Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 25 01:32:25.924616 containerd[1701]: time="2025-03-25T01:32:25.924571800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 25 01:32:25.924616 containerd[1701]: time="2025-03-25T01:32:25.924583100Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 25 01:32:25.924616 containerd[1701]: time="2025-03-25T01:32:25.924600800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 25 01:32:25.924616 containerd[1701]: time="2025-03-25T01:32:25.924614900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 25 01:32:25.924716 containerd[1701]: time="2025-03-25T01:32:25.924625300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 25 01:32:25.924716 containerd[1701]: time="2025-03-25T01:32:25.924637300Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 25 01:32:25.924716 containerd[1701]: time="2025-03-25T01:32:25.924646700Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 25 01:32:25.924716 containerd[1701]: time="2025-03-25T01:32:25.924657700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 25 01:32:25.924798 containerd[1701]: time="2025-03-25T01:32:25.924778400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 25 01:32:25.924825 containerd[1701]: time="2025-03-25T01:32:25.924802000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 25 01:32:25.924825 containerd[1701]: time="2025-03-25T01:32:25.924815100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 25 01:32:25.924869 containerd[1701]: time="2025-03-25T01:32:25.924826500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 25 01:32:25.924869 containerd[1701]: time="2025-03-25T01:32:25.924842300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 25 01:32:25.924869 containerd[1701]: time="2025-03-25T01:32:25.924852500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 25 01:32:25.924869 containerd[1701]: time="2025-03-25T01:32:25.924864300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 25 01:32:25.924975 containerd[1701]: time="2025-03-25T01:32:25.924874500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 25 01:32:25.924975 containerd[1701]: time="2025-03-25T01:32:25.924885500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 25 01:32:25.924975 containerd[1701]: time="2025-03-25T01:32:25.924896000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 25 01:32:25.924975 containerd[1701]: time="2025-03-25T01:32:25.924905600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 25 01:32:25.925064 containerd[1701]: time="2025-03-25T01:32:25.924980100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 25 01:32:25.925064 containerd[1701]: time="2025-03-25T01:32:25.924995700Z" level=info msg="Start snapshots syncer" Mar 25 01:32:25.925064 containerd[1701]: time="2025-03-25T01:32:25.925018500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 25 01:32:25.925270 containerd[1701]: time="2025-03-25T01:32:25.925230100Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Mar 25 01:32:25.925391 containerd[1701]: time="2025-03-25T01:32:25.925290300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 25 01:32:25.925391 containerd[1701]: time="2025-03-25T01:32:25.925357700Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 25 01:32:25.925494 containerd[1701]: time="2025-03-25T01:32:25.925469800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 25 01:32:25.925528 containerd[1701]: time="2025-03-25T01:32:25.925518000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 25 01:32:25.925553 containerd[1701]: time="2025-03-25T01:32:25.925534000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 25 01:32:25.925553 containerd[1701]: time="2025-03-25T01:32:25.925544900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 25 01:32:25.925599 containerd[1701]: time="2025-03-25T01:32:25.925556100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 25 01:32:25.925599 containerd[1701]: time="2025-03-25T01:32:25.925568400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 25 01:32:25.925599 containerd[1701]: time="2025-03-25T01:32:25.925579000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 25 01:32:25.925663 containerd[1701]: time="2025-03-25T01:32:25.925600500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 25 01:32:25.925663 containerd[1701]: time="2025-03-25T01:32:25.925612600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 25 01:32:25.925663 containerd[1701]: time="2025-03-25T01:32:25.925632800Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 25 01:32:25.925725 containerd[1701]: time="2025-03-25T01:32:25.925664600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:32:25.925725 containerd[1701]: time="2025-03-25T01:32:25.925679700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:32:25.925725 containerd[1701]: time="2025-03-25T01:32:25.925688500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:32:25.925725 containerd[1701]: time="2025-03-25T01:32:25.925699400Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:32:25.925725 containerd[1701]: time="2025-03-25T01:32:25.925721300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925742200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925755900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925773100Z" level=info msg="runtime interface created" Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925779000Z" level=info msg="created NRI interface" Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925787200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925797400Z" level=info msg="Connect containerd service" Mar 25 01:32:25.925837 containerd[1701]: time="2025-03-25T01:32:25.925823700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 25 01:32:25.926454 containerd[1701]: time="2025-03-25T01:32:25.926426000Z" 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" Mar 25 01:32:26.919918 waagent[1825]: 2025-03-25T01:32:26.919833Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Mar 25 01:32:26.924105 waagent[1825]: 2025-03-25T01:32:26.924062Z INFO Daemon Daemon OS: flatcar 4284.0.0 Mar 25 01:32:26.927512 waagent[1825]: 2025-03-25T01:32:26.927461Z INFO Daemon Daemon Python: 3.11.11 Mar 25 01:32:26.931206 waagent[1825]: 2025-03-25T01:32:26.931148Z INFO Daemon Daemon Run daemon Mar 25 01:32:26.934491 waagent[1825]: 2025-03-25T01:32:26.934443Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4284.0.0' Mar 25 01:32:26.941552 waagent[1825]: 2025-03-25T01:32:26.941498Z INFO Daemon Daemon Using waagent for provisioning Mar 25 01:32:26.945769 waagent[1825]: 2025-03-25T01:32:26.945723Z INFO Daemon Daemon Activate resource disk Mar 25 01:32:26.949571 waagent[1825]: 2025-03-25T01:32:26.949523Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 25 01:32:26.957081 waagent[1825]: 2025-03-25T01:32:26.957037Z INFO Daemon Daemon Found device: sdb Mar 25 01:32:26.966183 waagent[1825]: 2025-03-25T01:32:26.966132Z INFO Daemon Daemon Examining partition table Mar 25 01:32:26.991246 waagent[1825]: 2025-03-25T01:32:26.991199Z INFO Daemon Daemon GPT not detected, determining filesystem Mar 25 01:32:27.071530 waagent[1825]: 2025-03-25T01:32:27.071450Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Mar 25 01:32:27.076164 waagent[1825]: 2025-03-25T01:32:27.076111Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Mar 25 01:32:27.198641 containerd[1701]: time="2025-03-25T01:32:27.198549400Z" level=info msg="Start subscribing containerd event" Mar 25 01:32:27.198641 containerd[1701]: time="2025-03-25T01:32:27.198609600Z" level=info msg="Start recovering state" Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198766200Z" level=info msg="Start event monitor" Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198764900Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198846400Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198795300Z" level=info msg="Start cni network conf syncer for default" Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198895400Z" level=info msg="Start streaming server" Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198904800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198912800Z" level=info msg="runtime interface starting up..." Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198918700Z" level=info msg="starting plugins..." Mar 25 01:32:27.199014 containerd[1701]: time="2025-03-25T01:32:27.198938200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 25 01:32:27.199209 containerd[1701]: time="2025-03-25T01:32:27.199094100Z" level=info msg="containerd successfully booted in 1.306114s" Mar 25 01:32:27.199196 systemd[1]: Started containerd.service - containerd container runtime. Mar 25 01:32:27.451039 kernel: sdb: sdb1 Mar 25 01:32:27.466125 waagent[1825]: 2025-03-25T01:32:27.466050Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Mar 25 01:32:27.480246 waagent[1825]: 2025-03-25T01:32:27.480199Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Mar 25 01:32:27.480991 kernel: sdb: sdb1 Mar 25 01:32:33.115124 login[1827]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Mar 25 01:32:33.116965 login[1828]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 25 01:32:33.124584 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 25 01:32:33.126568 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 25 01:32:33.132799 systemd-logind[1689]: New session 1 of user core. Mar 25 01:32:33.192988 waagent[1825]: 2025-03-25T01:32:33.192900Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Mar 25 01:32:33.207180 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 25 01:32:33.210042 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 25 01:32:33.210959 kernel: EXT4-fs (sdb1): mounted filesystem 5d2ad883-7fff-4987-9ce2-d0e85e73436f r/w with ordered data mode. Quota mode: none. Mar 25 01:32:33.211178 waagent[1825]: 2025-03-25T01:32:33.211121Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Mar 25 01:32:33.211959 waagent[1825]: 2025-03-25T01:32:33.211910Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 25 01:32:33.212185 waagent[1825]: 2025-03-25T01:32:33.212152Z INFO Daemon Daemon Running default provisioning handler Mar 25 01:32:33.228759 waagent[1825]: 2025-03-25T01:32:33.228709Z 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. Mar 25 01:32:33.229391 waagent[1825]: 2025-03-25T01:32:33.229353Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 25 01:32:33.229712 waagent[1825]: 2025-03-25T01:32:33.229679Z INFO Daemon Daemon cloud-init is enabled: False Mar 25 01:32:33.231132 waagent[1825]: 2025-03-25T01:32:33.231100Z INFO Daemon Daemon Copying ovf-env.xml Mar 25 01:32:33.242591 waagent[1825]: 2025-03-25T01:32:33.242546Z INFO Daemon Daemon Successfully mounted dvd Mar 25 01:32:33.269342 (systemd)[1930]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 25 01:32:33.270529 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 25 01:32:33.270863 waagent[1825]: 2025-03-25T01:32:33.270808Z INFO Daemon Daemon Detect protocol endpoint Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.271078Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.271810Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.273232Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.274081Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.274670Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.291831Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.293315Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 25 01:32:33.305495 waagent[1825]: 2025-03-25T01:32:33.293901Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 25 01:32:33.273391 systemd-logind[1689]: New session c1 of user core. Mar 25 01:32:33.417781 waagent[1825]: 2025-03-25T01:32:33.417634Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 25 01:32:33.431252 waagent[1825]: 2025-03-25T01:32:33.418005Z INFO Daemon Daemon Forcing an update of the goal state. Mar 25 01:32:33.431252 waagent[1825]: 2025-03-25T01:32:33.421688Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 25 01:32:33.455449 waagent[1825]: 2025-03-25T01:32:33.455408Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.455980Z INFO Daemon Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.456332Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 3c3921f7-db9f-456f-9beb-807170dfa06c eTag: 1195789702592424801 source: Fabric] Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.457306Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.458008Z INFO Daemon Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.458470Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 25 01:32:33.483319 waagent[1825]: 2025-03-25T01:32:33.462424Z INFO Daemon Daemon Downloading artifacts profile blob Mar 25 01:32:33.508077 systemd[1930]: Queued start job for default target default.target. Mar 25 01:32:33.518967 systemd[1930]: Created slice app.slice - User Application Slice. Mar 25 01:32:33.518997 systemd[1930]: Reached target paths.target - Paths. Mar 25 01:32:33.519044 systemd[1930]: Reached target timers.target - Timers. Mar 25 01:32:33.520571 systemd[1930]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:32:33.531791 systemd[1930]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:32:33.531911 systemd[1930]: Reached target sockets.target - Sockets. Mar 25 01:32:33.531980 systemd[1930]: Reached target basic.target - Basic System. Mar 25 01:32:33.532030 systemd[1930]: Reached target default.target - Main User Target. Mar 25 01:32:33.532061 systemd[1930]: Startup finished in 251ms. Mar 25 01:32:33.532124 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 25 01:32:33.533532 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 25 01:32:33.603717 waagent[1825]: 2025-03-25T01:32:33.603656Z INFO Daemon Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:32:33.604274 waagent[1825]: 2025-03-25T01:32:33.604234Z INFO Daemon Downloaded certificate {'thumbprint': '42FC7B35605ED3259A1F5E7D69C7F12846B43679', 'hasPrivateKey': False} Mar 25 01:32:33.604917 waagent[1825]: 2025-03-25T01:32:33.604879Z INFO Daemon Fetch goal state completed Mar 25 01:32:33.642604 waagent[1825]: 2025-03-25T01:32:33.642555Z INFO Daemon Daemon Starting provisioning Mar 25 01:32:33.642853 waagent[1825]: 2025-03-25T01:32:33.642783Z INFO Daemon Daemon Handle ovf-env.xml. Mar 25 01:32:33.644317 waagent[1825]: 2025-03-25T01:32:33.644276Z INFO Daemon Daemon Set hostname [ci-4284.0.0-a-be0372a1cb] Mar 25 01:32:33.660166 waagent[1825]: 2025-03-25T01:32:33.660127Z INFO Daemon Daemon Publish hostname [ci-4284.0.0-a-be0372a1cb] Mar 25 01:32:33.660503 waagent[1825]: 2025-03-25T01:32:33.660464Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 25 01:32:33.665002 waagent[1825]: 2025-03-25T01:32:33.664958Z INFO Daemon Daemon Primary interface is [eth0] Mar 25 01:32:33.684038 systemd-networkd[1611]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:32:33.684048 systemd-networkd[1611]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:32:33.684076 systemd-networkd[1611]: eth0: DHCP lease lost Mar 25 01:32:33.684525 waagent[1825]: 2025-03-25T01:32:33.684476Z INFO Daemon Daemon Create user account if not exists Mar 25 01:32:33.684785 waagent[1825]: 2025-03-25T01:32:33.684748Z INFO Daemon Daemon User core already exists, skip useradd Mar 25 01:32:33.686282 waagent[1825]: 2025-03-25T01:32:33.686234Z INFO Daemon Daemon Configure sudoer Mar 25 01:32:33.689216 waagent[1825]: 2025-03-25T01:32:33.689165Z INFO Daemon Daemon Configure sshd Mar 25 01:32:33.689874 waagent[1825]: 2025-03-25T01:32:33.689824Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 25 01:32:33.691236 waagent[1825]: 2025-03-25T01:32:33.691194Z INFO Daemon Daemon Deploy ssh public key. Mar 25 01:32:33.751983 systemd-networkd[1611]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 25 01:32:33.760392 waagent[1825]: 2025-03-25T01:32:33.760341Z INFO Daemon Daemon Decode custom data Mar 25 01:32:33.760602 waagent[1825]: 2025-03-25T01:32:33.760563Z INFO Daemon Daemon Save custom data Mar 25 01:32:34.115555 login[1827]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 25 01:32:34.119854 systemd-logind[1689]: New session 2 of user core. Mar 25 01:32:34.136061 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 25 01:32:34.839771 waagent[1825]: 2025-03-25T01:32:34.839702Z INFO Daemon Daemon Provisioning complete Mar 25 01:32:34.848480 waagent[1825]: 2025-03-25T01:32:34.848431Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 25 01:32:34.848726 waagent[1825]: 2025-03-25T01:32:34.848679Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 25 01:32:34.850143 waagent[1825]: 2025-03-25T01:32:34.850105Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Mar 25 01:32:34.996161 waagent[1981]: 2025-03-25T01:32:34.996087Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Mar 25 01:32:34.996477 waagent[1981]: 2025-03-25T01:32:34.996228Z INFO ExtHandler ExtHandler OS: flatcar 4284.0.0 Mar 25 01:32:34.996477 waagent[1981]: 2025-03-25T01:32:34.996299Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 25 01:32:34.996477 waagent[1981]: 2025-03-25T01:32:34.996369Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Mar 25 01:32:35.055876 waagent[1981]: 2025-03-25T01:32:35.055818Z INFO ExtHandler ExtHandler Distro: flatcar-4284.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 25 01:32:35.056081 waagent[1981]: 2025-03-25T01:32:35.056042Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:32:35.056165 waagent[1981]: 2025-03-25T01:32:35.056131Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:32:35.062709 waagent[1981]: 2025-03-25T01:32:35.062656Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 25 01:32:35.067205 waagent[1981]: 2025-03-25T01:32:35.067162Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 25 01:32:35.067642 waagent[1981]: 2025-03-25T01:32:35.067601Z INFO ExtHandler Mar 25 01:32:35.067726 waagent[1981]: 2025-03-25T01:32:35.067694Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 067ada6d-a4f3-46e2-b7b0-6887675cf3d9 eTag: 1195789702592424801 source: Fabric] Mar 25 01:32:35.068067 waagent[1981]: 2025-03-25T01:32:35.068027Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 25 01:32:35.068711 waagent[1981]: 2025-03-25T01:32:35.068671Z INFO ExtHandler Mar 25 01:32:35.068847 waagent[1981]: 2025-03-25T01:32:35.068802Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 25 01:32:35.072110 waagent[1981]: 2025-03-25T01:32:35.072073Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 25 01:32:35.139757 waagent[1981]: 2025-03-25T01:32:35.139627Z INFO ExtHandler Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:32:35.140157 waagent[1981]: 2025-03-25T01:32:35.140115Z INFO ExtHandler Downloaded certificate {'thumbprint': '42FC7B35605ED3259A1F5E7D69C7F12846B43679', 'hasPrivateKey': False} Mar 25 01:32:35.140625 waagent[1981]: 2025-03-25T01:32:35.140584Z INFO ExtHandler Fetch goal state completed Mar 25 01:32:35.153405 waagent[1981]: 2025-03-25T01:32:35.153341Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Mar 25 01:32:35.158738 waagent[1981]: 2025-03-25T01:32:35.158683Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 1981 Mar 25 01:32:35.158877 waagent[1981]: 2025-03-25T01:32:35.158840Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 25 01:32:35.159258 waagent[1981]: 2025-03-25T01:32:35.159208Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Mar 25 01:32:35.160933 waagent[1981]: 2025-03-25T01:32:35.160883Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 25 01:32:35.161435 waagent[1981]: 2025-03-25T01:32:35.161394Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 25 01:32:35.161583 waagent[1981]: 2025-03-25T01:32:35.161546Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 25 01:32:35.162249 waagent[1981]: 2025-03-25T01:32:35.162210Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 25 01:32:35.218412 waagent[1981]: 2025-03-25T01:32:35.218368Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 25 01:32:35.218615 waagent[1981]: 2025-03-25T01:32:35.218576Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 25 01:32:35.225550 waagent[1981]: 2025-03-25T01:32:35.225514Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 25 01:32:35.233066 systemd[1]: Reload requested from client PID 1998 ('systemctl') (unit waagent.service)... Mar 25 01:32:35.233082 systemd[1]: Reloading... Mar 25 01:32:35.301023 zram_generator::config[2038]: No configuration found. Mar 25 01:32:35.439077 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. Mar 25 01:32:35.583258 systemd[1]: Reloading finished in 349 ms. Mar 25 01:32:35.598689 waagent[1981]: 2025-03-25T01:32:35.598626Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 25 01:32:35.598807 waagent[1981]: 2025-03-25T01:32:35.598772Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 25 01:32:36.212164 waagent[1981]: 2025-03-25T01:32:36.212074Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.212434Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.213209Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.213533Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.213728Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.214223Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.214273Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.214603Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.214677Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.214938Z INFO EnvHandler ExtHandler Configure routes Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.215157Z INFO EnvHandler ExtHandler Gateway:None Mar 25 01:32:36.216744 waagent[1981]: 2025-03-25T01:32:36.215195Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 25 01:32:36.216744 waagent[1981]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 25 01:32:36.216744 waagent[1981]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Mar 25 01:32:36.216744 waagent[1981]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 25 01:32:36.216744 waagent[1981]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:32:36.216744 waagent[1981]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:32:36.216744 waagent[1981]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.215371Z INFO EnvHandler ExtHandler Routes:None Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.215868Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.216005Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.216583Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.216637Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 25 01:32:36.217145 waagent[1981]: 2025-03-25T01:32:36.216769Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 25 01:32:36.222725 waagent[1981]: 2025-03-25T01:32:36.222674Z INFO ExtHandler ExtHandler Mar 25 01:32:36.222986 waagent[1981]: 2025-03-25T01:32:36.222936Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 472d88ba-9170-4101-9bdd-859baa8bf5df correlation 4a05c912-f91a-4053-84fd-79f9d8e9cb0e created: 2025-03-25T01:31:13.458084Z] Mar 25 01:32:36.224032 waagent[1981]: 2025-03-25T01:32:36.223990Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 25 01:32:36.226350 waagent[1981]: 2025-03-25T01:32:36.226314Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 25 01:32:36.310640 waagent[1981]: 2025-03-25T01:32:36.310584Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 8A78208F-3254-48B2-B67A-AE44B0168AF3;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Mar 25 01:32:36.376786 waagent[1981]: 2025-03-25T01:32:36.376712Z INFO MonitorHandler ExtHandler Network interfaces: Mar 25 01:32:36.376786 waagent[1981]: Executing ['ip', '-a', '-o', 'link']: Mar 25 01:32:36.376786 waagent[1981]: 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 Mar 25 01:32:36.376786 waagent[1981]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:e0:a3:ab brd ff:ff:ff:ff:ff:ff Mar 25 01:32:36.376786 waagent[1981]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 25 01:32:36.376786 waagent[1981]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 25 01:32:36.376786 waagent[1981]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 25 01:32:36.376786 waagent[1981]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 25 01:32:36.376786 waagent[1981]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 25 01:32:36.376786 waagent[1981]: 2: eth0 inet6 fe80::6245:bdff:fee0:a3ab/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 25 01:32:36.498207 waagent[1981]: 2025-03-25T01:32:36.498095Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 25 01:32:36.498207 waagent[1981]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.498207 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.498207 waagent[1981]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.498207 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.498207 waagent[1981]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.498207 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.498207 waagent[1981]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 25 01:32:36.498207 waagent[1981]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 25 01:32:36.498207 waagent[1981]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 25 01:32:36.501534 waagent[1981]: 2025-03-25T01:32:36.501495Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 25 01:32:36.501534 waagent[1981]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.501534 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.501534 waagent[1981]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.501534 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.501534 waagent[1981]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 25 01:32:36.501534 waagent[1981]: pkts bytes target prot opt in out source destination Mar 25 01:32:36.501534 waagent[1981]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 25 01:32:36.501534 waagent[1981]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 25 01:32:36.501534 waagent[1981]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 25 01:32:36.501782 waagent[1981]: 2025-03-25T01:32:36.501750Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Mar 25 01:32:36.501934 waagent[1981]: 2025-03-25T01:32:36.501905Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 25 01:32:48.092641 setup-nvidia[1842]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 483M 0 128k 0 0 148k 0 0:55:29 --:--:-- 0:55:29 148k\u000d 2 483M 2 10.2M 0 0 5756k 0 0:01:26 0:00:01 0:01:25 5755k\u000d 7 483M 7 36.3M 0 0 12.8M 0 0:00:37 0:00:02 0:00:35 12.8M\u000d 12 483M 12 62.4M 0 0 15.7M 0 0:00:30 0:00:03 0:00:27 15.7M\u000d 17 483M 17 86.3M 0 0 17.4M 0 0:00:27 0:00:04 0:00:23 17.4M\u000d 22 483M 22 110M 0 0 18.5M 0 0:00:26 0:00:05 0:00:21 21.7M\u000d 25 483M 25 124M 0 0 17.5M 0 0:00:27 0:00:07 0:00:20 21.7M\u000d 29 483M 29 144M 0 0 18.2M 0 0:00:26 0:00:07 0:00:19 21.2M\u000d 34 483M 34 168M 0 0 18.8M 0 0:00:25 0:00:08 0:00:17 21.3M\u000d 39 483M 39 190M 0 0 19.2M 0 0:00:25 0:00:09 0:00:16 21.0M\u000d 44 483M 44 214M 0 0 19.7M 0 0:00:24 0:00:10 0:00:14 21.1M\u000d 49 483M 49 238M 0 0 20.1M 0 0:00:24 0:00:11 0:00:13 23.9M\u000d 54 483M 54 262M 0 0 20.4M 0 0:00:23 0:00:12 0:00:11 23.8M\u000d 58 483M 58 284M 0 0 20.5M 0 0:00:23 0:00:13 0:00:10 23.6M\u000d 64 483M 64 309M 0 0 20.9M 0 0:00:23 0:00:14 0:00:09 24.1M\u000d 69 483M 69 336M 0 0 21.2M 0 0:00:22 0:00:15 0:00:07 24.4M\u000d 74 483M 74 359M 0 0 21.2M 0 0:00:22 0:00:16 0:00:06 23.6M\u000d 79 483M 79 382M 0 0 21.3M 0 0:00:22 0:00:17 0:00:05 23.6M\u000d 83 483M 83 405M 0 0 21.3M 0 0:00:22 0:00:18 0:00:04 23.5M\u000d 88 483M 88 427M 0 0 21.4M 0 0:00:22 0:00:19 0:00:03 23.1M\u000d 93 483M 93 451M 0 0 21.6M 0 0:00:22 0:00:20 0:00:02 22.7M\u000d 98 483M 98 475M 0 0 21.7M 0 0:00:22 0:00:21 0:00:01 23.4M\u000d100 483M 100 483M 0 0 21.8M 0 0:00:22 0:00:22 --:--:-- 23.8M Mar 25 01:32:48.304424 chronyd[1688]: Selected source PHC0 Mar 25 01:33:00.792169 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 25 01:33:00.793618 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:54328.service - OpenSSH per-connection server daemon (10.200.16.10:54328). Mar 25 01:33:01.671257 sshd[2141]: Accepted publickey for core from 10.200.16.10 port 54328 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:01.672640 sshd-session[2141]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:01.680299 systemd-logind[1689]: New session 3 of user core. Mar 25 01:33:01.691183 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 25 01:33:01.775614 setup-nvidia[1681]: Downloading NVIDIA 535.230.02 Driver Mar 25 01:33:01.780025 setup-nvidia[2145]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 25 01:33:01.780243 setup-nvidia[2145]: Dload Upload Total Spent Left Speed Mar 25 01:33:02.225507 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:54342.service - OpenSSH per-connection server daemon (10.200.16.10:54342). Mar 25 01:33:02.542976 kernel: hv_netvsc 6045bde0-a3ab-6045-bde0-a3ab6045bde0 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:02.548847 kernel: hv_netvsc 6045bde0-a3ab-6045-bde0-a3ab6045bde0 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:02.646047 kernel: hv_netvsc 6045bde0-a3ab-6045-bde0-a3ab6045bde0 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:02.657995 kernel: hv_netvsc 6045bde0-a3ab-6045-bde0-a3ab6045bde0 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:02.754434 kernel: hv_netvsc 6045bde0-a3ab-6045-bde0-a3ab6045bde0 eth0: nvsp_rndis_pkt_complete error status: 2 Mar 25 01:33:02.856287 sshd[2148]: Accepted publickey for core from 10.200.16.10 port 54342 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:02.857466 sshd-session[2148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:02.862754 systemd-logind[1689]: New session 4 of user core. Mar 25 01:33:02.875057 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 25 01:33:03.171968 setup-nvidia[2145]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 66 326M 66 217M 0 0 227M 0 0:00:01 --:--:-- 0:00:01 227M\u000d100 326M 100 326M 0 0 234M 0 0:00:01 0:00:01 --:--:-- 234M Mar 25 01:33:03.191410 setup-nvidia[1681]: Extract the NVIDIA Driver Installer 535.230.02 Mar 25 01:33:03.191410 setup-nvidia[1681]: /opt/nvidia/workdir/nvidia-workdir / Mar 25 01:33:03.209525 setup-nvidia[2153]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Mar 25 01:33:03.299711 sshd[2150]: Connection closed by 10.200.16.10 port 54342 Mar 25 01:33:03.300101 sshd-session[2148]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:03.303453 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:54342.service: Deactivated successfully. Mar 25 01:33:03.305190 systemd[1]: session-4.scope: Deactivated successfully. Mar 25 01:33:03.305823 systemd-logind[1689]: Session 4 logged out. Waiting for processes to exit. Mar 25 01:33:03.306637 systemd-logind[1689]: Removed session 4. Mar 25 01:33:03.411536 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:54346.service - OpenSSH per-connection server daemon (10.200.16.10:54346). Mar 25 01:33:03.479867 setup-nvidia[2153]: Verifying archive integrity... OK Mar 25 01:33:04.040749 sshd[2177]: Accepted publickey for core from 10.200.16.10 port 54346 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:04.042116 sshd-session[2177]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:04.046230 systemd-logind[1689]: New session 5 of user core. Mar 25 01:33:04.062104 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 25 01:33:04.218572 setup-nvidia[2153]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Mar 25 01:33:04.485804 sshd[2183]: Connection closed by 10.200.16.10 port 54346 Mar 25 01:33:04.486194 sshd-session[2177]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:04.489459 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:54346.service: Deactivated successfully. Mar 25 01:33:04.491098 systemd[1]: session-5.scope: Deactivated successfully. Mar 25 01:33:04.491974 systemd-logind[1689]: Session 5 logged out. Waiting for processes to exit. Mar 25 01:33:04.492771 systemd-logind[1689]: Removed session 5. Mar 25 01:33:06.022250 setup-nvidia[2187]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Mar 25 01:33:06.147361 setup-nvidia[1681]: / Mar 25 01:33:06.147644 setup-nvidia[1681]: Spawn system-nspawn container to install the NVIDIA drivers Mar 25 01:33:06.342619 sudo[2198]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4284.0.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Mar 25 01:33:06.344477 sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 25 01:33:06.354152 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Mar 25 01:33:06.355515 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 25 01:33:06.383316 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 25 01:33:06.385859 systemd[1]: Starting user@0.service - User Manager for UID 0... Mar 25 01:33:06.392530 (systemd)[2201]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Mar 25 01:33:06.394792 systemd-logind[1689]: New session c3 of user root. Mar 25 01:33:06.564848 systemd[2201]: Queued start job for default target default.target. Mar 25 01:33:06.581027 systemd[2201]: Created slice app.slice - User Application Slice. Mar 25 01:33:06.581057 systemd[2201]: Reached target paths.target - Paths. Mar 25 01:33:06.581134 systemd[2201]: Reached target timers.target - Timers. Mar 25 01:33:06.582313 systemd[2201]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:33:06.592933 systemd[2201]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:33:06.593116 systemd[2201]: Reached target sockets.target - Sockets. Mar 25 01:33:06.593171 systemd[2201]: Reached target basic.target - Basic System. Mar 25 01:33:06.593217 systemd[2201]: Reached target default.target - Main User Target. Mar 25 01:33:06.593247 systemd[2201]: Startup finished in 191ms. Mar 25 01:33:06.593326 systemd[1]: Started user@0.service - User Manager for UID 0. Mar 25 01:33:06.597859 systemd[1]: Started session-c2.scope - Session c2 of User root. Mar 25 01:33:06.659963 kernel: loop6: detected capacity change from 0 to 12644352 Mar 25 01:33:06.708963 kernel: loop6: p9 Mar 25 01:33:06.861590 setup-nvidia[2210]: ░ Spawning container flatcardevelopercontainer-4284.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4284.0.0.bin. Mar 25 01:33:06.913975 kernel: EXT4-fs (loop6p9): mounted filesystem 5775d2e2-188b-4921-88b6-682c3efa3461 ro without journal. Quota mode: none. Mar 25 01:33:07.876212 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Mar 25 01:33:09.603519 update_engine[1696]: I20250325 01:33:09.603435 1696 update_attempter.cc:509] Updating boot flags... Mar 25 01:33:09.642967 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2242) Mar 25 01:33:09.754020 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2242) Mar 25 01:33:09.849972 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2242) Mar 25 01:33:14.597431 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:59606.service - OpenSSH per-connection server daemon (10.200.16.10:59606). Mar 25 01:33:15.225040 sshd[2438]: Accepted publickey for core from 10.200.16.10 port 59606 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:15.226488 sshd-session[2438]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:15.230816 systemd-logind[1689]: New session 6 of user core. Mar 25 01:33:15.242063 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 25 01:33:15.669034 sshd[2442]: Connection closed by 10.200.16.10 port 59606 Mar 25 01:33:15.669332 sshd-session[2438]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:15.672374 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:59606.service: Deactivated successfully. Mar 25 01:33:15.674243 systemd[1]: session-6.scope: Deactivated successfully. Mar 25 01:33:15.675070 systemd-logind[1689]: Session 6 logged out. Waiting for processes to exit. Mar 25 01:33:15.675842 systemd-logind[1689]: Removed session 6. Mar 25 01:33:15.780259 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:59610.service - OpenSSH per-connection server daemon (10.200.16.10:59610). Mar 25 01:33:16.410397 sshd[2485]: Accepted publickey for core from 10.200.16.10 port 59610 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:16.411708 sshd-session[2485]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:16.415970 systemd-logind[1689]: New session 7 of user core. Mar 25 01:33:16.424064 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 25 01:33:16.854528 sshd[2516]: Connection closed by 10.200.16.10 port 59610 Mar 25 01:33:16.854863 sshd-session[2485]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:16.858172 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:59610.service: Deactivated successfully. Mar 25 01:33:16.859894 systemd[1]: session-7.scope: Deactivated successfully. Mar 25 01:33:16.861031 systemd-logind[1689]: Session 7 logged out. Waiting for processes to exit. Mar 25 01:33:16.861890 systemd-logind[1689]: Removed session 7. Mar 25 01:33:26.967414 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:37964.service - OpenSSH per-connection server daemon (10.200.16.10:37964). Mar 25 01:33:27.595832 sshd[3458]: Accepted publickey for core from 10.200.16.10 port 37964 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:27.597242 sshd-session[3458]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:27.601466 systemd-logind[1689]: New session 8 of user core. Mar 25 01:33:27.617124 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 25 01:33:28.040777 sshd[3460]: Connection closed by 10.200.16.10 port 37964 Mar 25 01:33:28.041241 sshd-session[3458]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:28.044386 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:37964.service: Deactivated successfully. Mar 25 01:33:28.045988 systemd[1]: session-8.scope: Deactivated successfully. Mar 25 01:33:28.046625 systemd-logind[1689]: Session 8 logged out. Waiting for processes to exit. Mar 25 01:33:28.047425 systemd-logind[1689]: Removed session 8. Mar 25 01:33:28.154382 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:37972.service - OpenSSH per-connection server daemon (10.200.16.10:37972). Mar 25 01:33:28.805961 sshd[3466]: Accepted publickey for core from 10.200.16.10 port 37972 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:28.807561 sshd-session[3466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:28.811736 systemd-logind[1689]: New session 9 of user core. Mar 25 01:33:28.824080 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 25 01:33:29.261509 sshd[3468]: Connection closed by 10.200.16.10 port 37972 Mar 25 01:33:29.261870 sshd-session[3466]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:29.265105 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:37972.service: Deactivated successfully. Mar 25 01:33:29.266646 systemd[1]: session-9.scope: Deactivated successfully. Mar 25 01:33:29.267285 systemd-logind[1689]: Session 9 logged out. Waiting for processes to exit. Mar 25 01:33:29.268109 systemd-logind[1689]: Removed session 9. Mar 25 01:33:39.376221 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:50898.service - OpenSSH per-connection server daemon (10.200.16.10:50898). Mar 25 01:33:40.017446 sshd[5915]: Accepted publickey for core from 10.200.16.10 port 50898 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:40.019267 sshd-session[5915]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:40.024398 systemd-logind[1689]: New session 10 of user core. Mar 25 01:33:40.033746 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 25 01:33:40.467109 sshd[6077]: Connection closed by 10.200.16.10 port 50898 Mar 25 01:33:40.467500 sshd-session[5915]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:40.470090 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:50898.service: Deactivated successfully. Mar 25 01:33:40.471695 systemd[1]: session-10.scope: Deactivated successfully. Mar 25 01:33:40.473230 systemd-logind[1689]: Session 10 logged out. Waiting for processes to exit. Mar 25 01:33:40.474151 systemd-logind[1689]: Removed session 10. Mar 25 01:33:40.578538 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:50906.service - OpenSSH per-connection server daemon (10.200.16.10:50906). Mar 25 01:33:41.213191 sshd[6262]: Accepted publickey for core from 10.200.16.10 port 50906 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:41.215971 sshd-session[6262]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:41.222805 systemd-logind[1689]: New session 11 of user core. Mar 25 01:33:41.232107 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 25 01:33:41.663158 sshd[6391]: Connection closed by 10.200.16.10 port 50906 Mar 25 01:33:41.663885 sshd-session[6262]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:41.668242 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:50906.service: Deactivated successfully. Mar 25 01:33:41.670448 systemd[1]: session-11.scope: Deactivated successfully. Mar 25 01:33:41.671326 systemd-logind[1689]: Session 11 logged out. Waiting for processes to exit. Mar 25 01:33:41.672284 systemd-logind[1689]: Removed session 11. Mar 25 01:33:51.782137 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:54308.service - OpenSSH per-connection server daemon (10.200.16.10:54308). Mar 25 01:33:52.419468 sshd[9475]: Accepted publickey for core from 10.200.16.10 port 54308 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:52.421319 sshd-session[9475]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:52.427509 systemd-logind[1689]: New session 12 of user core. Mar 25 01:33:52.437160 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 25 01:33:52.865498 sshd[9685]: Connection closed by 10.200.16.10 port 54308 Mar 25 01:33:52.866066 sshd-session[9475]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:52.871870 systemd-logind[1689]: Session 12 logged out. Waiting for processes to exit. Mar 25 01:33:52.872877 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:54308.service: Deactivated successfully. Mar 25 01:33:52.875701 systemd[1]: session-12.scope: Deactivated successfully. Mar 25 01:33:52.877829 systemd-logind[1689]: Removed session 12. Mar 25 01:33:52.980230 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:54314.service - OpenSSH per-connection server daemon (10.200.16.10:54314). Mar 25 01:33:53.619980 sshd[9827]: Accepted publickey for core from 10.200.16.10 port 54314 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:33:53.622543 sshd-session[9827]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:33:53.632179 systemd-logind[1689]: New session 13 of user core. Mar 25 01:33:53.638490 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 25 01:33:54.063011 sshd[10177]: Connection closed by 10.200.16.10 port 54314 Mar 25 01:33:54.063380 sshd-session[9827]: pam_unix(sshd:session): session closed for user core Mar 25 01:33:54.066346 systemd-logind[1689]: Session 13 logged out. Waiting for processes to exit. Mar 25 01:33:54.069622 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:54314.service: Deactivated successfully. Mar 25 01:33:54.072127 systemd[1]: session-13.scope: Deactivated successfully. Mar 25 01:33:54.073644 systemd-logind[1689]: Removed session 13. Mar 25 01:34:04.179193 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:48138.service - OpenSSH per-connection server daemon (10.200.16.10:48138). Mar 25 01:34:04.822293 sshd[11760]: Accepted publickey for core from 10.200.16.10 port 48138 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:04.825221 sshd-session[11760]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:04.831963 systemd-logind[1689]: New session 14 of user core. Mar 25 01:34:04.841116 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 25 01:34:05.267903 sshd[11786]: Connection closed by 10.200.16.10 port 48138 Mar 25 01:34:05.269974 sshd-session[11760]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:05.272981 systemd-logind[1689]: Session 14 logged out. Waiting for processes to exit. Mar 25 01:34:05.275021 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:48138.service: Deactivated successfully. Mar 25 01:34:05.276593 systemd[1]: session-14.scope: Deactivated successfully. Mar 25 01:34:05.279058 systemd-logind[1689]: Removed session 14. Mar 25 01:34:05.404822 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:48148.service - OpenSSH per-connection server daemon (10.200.16.10:48148). Mar 25 01:34:06.059473 sshd[11816]: Accepted publickey for core from 10.200.16.10 port 48148 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:06.061872 sshd-session[11816]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:06.072426 systemd-logind[1689]: New session 15 of user core. Mar 25 01:34:06.084189 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 25 01:34:06.503291 sshd[11854]: Connection closed by 10.200.16.10 port 48148 Mar 25 01:34:06.503642 sshd-session[11816]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:06.506685 systemd-logind[1689]: Session 15 logged out. Waiting for processes to exit. Mar 25 01:34:06.507785 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:48148.service: Deactivated successfully. Mar 25 01:34:06.510076 systemd[1]: session-15.scope: Deactivated successfully. Mar 25 01:34:06.514601 systemd-logind[1689]: Removed session 15. Mar 25 01:34:16.616579 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:56484.service - OpenSSH per-connection server daemon (10.200.16.10:56484). Mar 25 01:34:17.262566 sshd[12148]: Accepted publickey for core from 10.200.16.10 port 56484 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:17.264432 sshd-session[12148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:17.272178 systemd-logind[1689]: New session 16 of user core. Mar 25 01:34:17.284561 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 25 01:34:17.721464 sshd[12170]: Connection closed by 10.200.16.10 port 56484 Mar 25 01:34:17.721868 sshd-session[12148]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:17.724804 systemd-logind[1689]: Session 16 logged out. Waiting for processes to exit. Mar 25 01:34:17.726665 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:56484.service: Deactivated successfully. Mar 25 01:34:17.728315 systemd[1]: session-16.scope: Deactivated successfully. Mar 25 01:34:17.730028 systemd-logind[1689]: Removed session 16. Mar 25 01:34:17.838188 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:56486.service - OpenSSH per-connection server daemon (10.200.16.10:56486). Mar 25 01:34:18.483072 sshd[12205]: Accepted publickey for core from 10.200.16.10 port 56486 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:18.484866 sshd-session[12205]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:18.489599 systemd-logind[1689]: New session 17 of user core. Mar 25 01:34:18.501097 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 25 01:34:18.932521 sshd[12226]: Connection closed by 10.200.16.10 port 56486 Mar 25 01:34:18.935818 sshd-session[12205]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:18.939148 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:56486.service: Deactivated successfully. Mar 25 01:34:18.941727 systemd[1]: session-17.scope: Deactivated successfully. Mar 25 01:34:18.943356 systemd-logind[1689]: Session 17 logged out. Waiting for processes to exit. Mar 25 01:34:18.944451 systemd-logind[1689]: Removed session 17. Mar 25 01:34:29.048267 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:44524.service - OpenSSH per-connection server daemon (10.200.16.10:44524). Mar 25 01:34:29.699003 sshd[12518]: Accepted publickey for core from 10.200.16.10 port 44524 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:29.700456 sshd-session[12518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:29.707227 systemd-logind[1689]: New session 18 of user core. Mar 25 01:34:29.715157 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 25 01:34:30.148573 sshd[12551]: Connection closed by 10.200.16.10 port 44524 Mar 25 01:34:30.152166 sshd-session[12518]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:30.155116 systemd-logind[1689]: Session 18 logged out. Waiting for processes to exit. Mar 25 01:34:30.156657 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:44524.service: Deactivated successfully. Mar 25 01:34:30.158195 systemd[1]: session-18.scope: Deactivated successfully. Mar 25 01:34:30.159679 systemd-logind[1689]: Removed session 18. Mar 25 01:34:30.264178 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:44534.service - OpenSSH per-connection server daemon (10.200.16.10:44534). Mar 25 01:34:30.905028 sshd[12582]: Accepted publickey for core from 10.200.16.10 port 44534 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:30.906827 sshd-session[12582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:30.913221 systemd-logind[1689]: New session 19 of user core. Mar 25 01:34:30.920519 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 25 01:34:31.357434 sshd[12624]: Connection closed by 10.200.16.10 port 44534 Mar 25 01:34:31.359398 sshd-session[12582]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:31.362705 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:44534.service: Deactivated successfully. Mar 25 01:34:31.364242 systemd[1]: session-19.scope: Deactivated successfully. Mar 25 01:34:31.365433 systemd-logind[1689]: Session 19 logged out. Waiting for processes to exit. Mar 25 01:34:31.366350 systemd-logind[1689]: Removed session 19. Mar 25 01:34:39.663784 setup-nvidia[2210]: \u000d Mar 25 01:34:39.663784 setup-nvidia[2210]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Mar 25 01:34:39.663784 setup-nvidia[2210]: frequently when this kernel module was built against the wrong or Mar 25 01:34:39.663784 setup-nvidia[2210]: improperly configured kernel sources, with a version of gcc that differs Mar 25 01:34:39.663784 setup-nvidia[2210]: from the one used to build the target kernel, or if another driver, such Mar 25 01:34:39.663784 setup-nvidia[2210]: as nouveau, is present and prevents the NVIDIA kernel module from Mar 25 01:34:39.663784 setup-nvidia[2210]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Mar 25 01:34:39.663784 setup-nvidia[2210]: installed in this system is supported by this NVIDIA Linux graphics Mar 25 01:34:39.663784 setup-nvidia[2210]: driver release. Mar 25 01:34:39.663784 setup-nvidia[2210]: \u000d Mar 25 01:34:39.663784 setup-nvidia[2210]: Please see the log entries 'Kernel module load error' and 'Kernel Mar 25 01:34:39.663784 setup-nvidia[2210]: messages' at the end of the file Mar 25 01:34:39.663784 setup-nvidia[2210]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Mar 25 01:34:39.663784 setup-nvidia[2210]: information. Mar 25 01:34:39.663784 setup-nvidia[2210]: \u000d Mar 25 01:34:39.678296 setup-nvidia[2210]: dmesg: read kernel buffer failed: Operation not permitted Mar 25 01:34:39.696728 setup-nvidia[2210]: \u000d Mar 25 01:34:39.696728 setup-nvidia[2210]: ERROR: Installation has failed. Please see the file Mar 25 01:34:39.696728 setup-nvidia[2210]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Mar 25 01:34:39.696728 setup-nvidia[2210]: details. You may find suggestions on fixing installation problems in Mar 25 01:34:39.696728 setup-nvidia[2210]: the README available on the Linux driver download page at Mar 25 01:34:39.696728 setup-nvidia[2210]: www.nvidia.com. Mar 25 01:34:39.696728 setup-nvidia[2210]: \u000d Mar 25 01:34:39.697141 setup-nvidia[2210]: Last 50 lines of nvidia-installer.log: Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Mar 25 01:34:39.697921 setup-nvidia[2210]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Mar 25 01:34:39.697921 setup-nvidia[2210]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Mar 25 01:34:39.697921 setup-nvidia[2210]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Mar 25 01:34:39.697921 setup-nvidia[2210]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Mar 25 01:34:39.697921 setup-nvidia[2210]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Mar 25 01:34:39.697921 setup-nvidia[2210]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Mar 25 01:34:39.697921 setup-nvidia[2210]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Mar 25 01:34:39.697921 setup-nvidia[2210]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Mar 25 01:34:39.697921 setup-nvidia[2210]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Mar 25 01:34:39.697921 setup-nvidia[2210]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Mar 25 01:34:39.697921 setup-nvidia[2210]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Mar 25 01:34:39.697921 setup-nvidia[2210]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Mar 25 01:34:39.697921 setup-nvidia[2210]: make[2]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/build' Mar 25 01:34:39.697921 setup-nvidia[2210]: make[1]: Leaving directory '/usr/lib/modules/6.6.83-flatcar/source' Mar 25 01:34:39.697921 setup-nvidia[2210]: -> done. Mar 25 01:34:39.697921 setup-nvidia[2210]: -> Kernel module compilation complete. Mar 25 01:34:39.697921 setup-nvidia[2210]: -> Unable to determine if Secure Boot is enabled: No such file or directory Mar 25 01:34:39.697921 setup-nvidia[2210]: 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. Mar 25 01:34:39.697921 setup-nvidia[2210]: \u000d Mar 25 01:34:39.699100 setup-nvidia[2210]: 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. Mar 25 01:34:39.699100 setup-nvidia[2210]: -> Kernel module load error: Operation not permitted Mar 25 01:34:39.699100 setup-nvidia[2210]: -> Kernel messages: Mar 25 01:34:39.699100 setup-nvidia[2210]: 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. Mar 25 01:34:41.469306 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:45530.service - OpenSSH per-connection server daemon (10.200.16.10:45530). Mar 25 01:34:42.096129 sshd[12880]: Accepted publickey for core from 10.200.16.10 port 45530 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:42.097616 sshd-session[12880]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:42.101663 systemd-logind[1689]: New session 20 of user core. Mar 25 01:34:42.108063 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 25 01:34:42.538914 sshd[12882]: Connection closed by 10.200.16.10 port 45530 Mar 25 01:34:42.539268 sshd-session[12880]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:42.542372 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:45530.service: Deactivated successfully. Mar 25 01:34:42.543913 systemd[1]: session-20.scope: Deactivated successfully. Mar 25 01:34:42.544586 systemd-logind[1689]: Session 20 logged out. Waiting for processes to exit. Mar 25 01:34:42.545371 systemd-logind[1689]: Removed session 20. Mar 25 01:34:42.652113 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:45542.service - OpenSSH per-connection server daemon (10.200.16.10:45542). Mar 25 01:34:43.300967 sshd[12888]: Accepted publickey for core from 10.200.16.10 port 45542 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:43.302344 sshd-session[12888]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:43.306529 systemd-logind[1689]: New session 21 of user core. Mar 25 01:34:43.323067 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 25 01:34:43.744149 sshd[12890]: Connection closed by 10.200.16.10 port 45542 Mar 25 01:34:43.744502 sshd-session[12888]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:43.747509 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:45542.service: Deactivated successfully. Mar 25 01:34:43.749097 systemd[1]: session-21.scope: Deactivated successfully. Mar 25 01:34:43.749729 systemd-logind[1689]: Session 21 logged out. Waiting for processes to exit. Mar 25 01:34:43.750491 systemd-logind[1689]: Removed session 21. Mar 25 01:34:49.278549 waagent[1981]: 2025-03-25T01:34:49.278480Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 25 01:34:49.285514 waagent[1981]: 2025-03-25T01:34:49.285469Z INFO ExtHandler Mar 25 01:34:49.285608 waagent[1981]: 2025-03-25T01:34:49.285573Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b40d735b-6c2d-4648-a5ae-78e8417a7eef eTag: 1578013805157524696 source: Fabric] Mar 25 01:34:49.285972 waagent[1981]: 2025-03-25T01:34:49.285918Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 25 01:34:49.286666 waagent[1981]: 2025-03-25T01:34:49.286626Z INFO ExtHandler Mar 25 01:34:49.286740 waagent[1981]: 2025-03-25T01:34:49.286710Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 25 01:34:49.338587 waagent[1981]: 2025-03-25T01:34:49.338532Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 25 01:34:49.413758 waagent[1981]: 2025-03-25T01:34:49.413686Z INFO ExtHandler Downloaded certificate {'thumbprint': '9B38170886A4114503B0128EE8C99D3908D34A54', 'hasPrivateKey': True} Mar 25 01:34:49.414135 waagent[1981]: 2025-03-25T01:34:49.414096Z INFO ExtHandler Downloaded certificate {'thumbprint': '42FC7B35605ED3259A1F5E7D69C7F12846B43679', 'hasPrivateKey': False} Mar 25 01:34:49.414571 waagent[1981]: 2025-03-25T01:34:49.414534Z INFO ExtHandler Fetch goal state completed Mar 25 01:34:49.414953 waagent[1981]: 2025-03-25T01:34:49.414910Z INFO ExtHandler ExtHandler Mar 25 01:34:49.415042 waagent[1981]: 2025-03-25T01:34:49.415010Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 84fd6626-6831-4d16-a520-d41f94d2fb51 correlation 4a05c912-f91a-4053-84fd-79f9d8e9cb0e created: 2025-03-25T01:34:41.723114Z] Mar 25 01:34:49.415365 waagent[1981]: 2025-03-25T01:34:49.415329Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 25 01:34:49.415954 waagent[1981]: 2025-03-25T01:34:49.415913Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 1 ms] Mar 25 01:34:53.859465 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:52262.service - OpenSSH per-connection server daemon (10.200.16.10:52262). Mar 25 01:34:54.509187 sshd[12904]: Accepted publickey for core from 10.200.16.10 port 52262 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:54.510622 sshd-session[12904]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:54.514887 systemd-logind[1689]: New session 22 of user core. Mar 25 01:34:54.531080 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 25 01:34:54.953493 sshd[12906]: Connection closed by 10.200.16.10 port 52262 Mar 25 01:34:54.953791 sshd-session[12904]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:54.957030 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:52262.service: Deactivated successfully. Mar 25 01:34:54.958914 systemd[1]: session-22.scope: Deactivated successfully. Mar 25 01:34:54.959685 systemd-logind[1689]: Session 22 logged out. Waiting for processes to exit. Mar 25 01:34:54.960520 systemd-logind[1689]: Removed session 22. Mar 25 01:34:55.068620 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:52276.service - OpenSSH per-connection server daemon (10.200.16.10:52276). Mar 25 01:34:55.082960 kernel: EXT4-fs (loop6p9): unmounting filesystem 5775d2e2-188b-4921-88b6-682c3efa3461. Mar 25 01:34:55.083217 setup-nvidia[2210]: Container flatcardevelopercontainer-4284.0.0.bin exited successfully. Mar 25 01:34:55.085387 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4284.0.0.bin.mount: Deactivated successfully. Mar 25 01:34:55.153088 sudo[2198]: pam_unix(sudo:session): session closed for user root Mar 25 01:34:55.154900 systemd[1]: session-c2.scope: Deactivated successfully. Mar 25 01:34:55.155195 systemd[1]: session-c2.scope: Consumed 5min 11.836s CPU time, 882.2M memory peak. Mar 25 01:34:55.563378 setup-nvidia[1681]: /opt/nvidia / Mar 25 01:34:55.564391 setup-nvidia[1681]: / Mar 25 01:34:55.580163 setup-nvidia[12931]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:34:55.609965 kernel: IPMI message handler: version 39.2 Mar 25 01:34:55.612967 kernel: ipmi device interface Mar 25 01:34:55.619759 setup-nvidia[1681]: /opt/nvidia/current/usr/lib/modules/6.6.83-flatcar/video / Mar 25 01:34:55.670640 kernel: nvidia: loading out-of-tree module taints kernel. Mar 25 01:34:55.670772 kernel: nvidia: module license 'NVIDIA' taints kernel. Mar 25 01:34:55.670809 kernel: Disabling lock debugging due to kernel taint Mar 25 01:34:55.678053 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Mar 25 01:34:55.678097 kernel: nvidia: module license taints kernel. Mar 25 01:34:55.715712 sshd[12912]: Accepted publickey for core from 10.200.16.10 port 52276 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:34:55.717125 sshd-session[12912]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:34:55.721596 systemd-logind[1689]: New session 23 of user core. Mar 25 01:34:55.737068 systemd[1]: Started session-23.scope - Session 23 of User core. Mar 25 01:34:56.080966 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Mar 25 01:34:56.160591 sshd[12938]: Connection closed by 10.200.16.10 port 52276 Mar 25 01:34:56.160981 sshd-session[12912]: pam_unix(sshd:session): session closed for user core Mar 25 01:34:56.163975 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:52276.service: Deactivated successfully. Mar 25 01:34:56.165578 systemd[1]: session-23.scope: Deactivated successfully. Mar 25 01:34:56.166218 systemd-logind[1689]: Session 23 logged out. Waiting for processes to exit. Mar 25 01:34:56.166992 systemd-logind[1689]: Removed session 23. Mar 25 01:34:56.482227 kernel: Mar 25 01:34:56.482364 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Mar 25 01:34:56.684445 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Mar 25 01:34:56.709966 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Mar 25 01:34:56.728974 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Mar 25 01:34:56.770977 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Mar 25 01:34:56.771023 setup-nvidia[1681]: / Mar 25 01:34:58.558898 setup-nvidia[12968]: Tue Mar 25 01:34:58 2025 Mar 25 01:34:58.558898 setup-nvidia[12968]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:58.558898 setup-nvidia[12968]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Mar 25 01:34:58.558898 setup-nvidia[12968]: |-----------------------------------------+----------------------+----------------------+ Mar 25 01:34:58.558898 setup-nvidia[12968]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Mar 25 01:34:58.558898 setup-nvidia[12968]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Mar 25 01:34:58.558898 setup-nvidia[12968]: | | | MIG M. | Mar 25 01:34:58.558898 setup-nvidia[12968]: |=========================================+======================+======================| Mar 25 01:34:58.562446 setup-nvidia[12968]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Mar 25 01:34:58.562446 setup-nvidia[12968]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Mar 25 01:34:58.562446 setup-nvidia[12968]: | | | N/A | Mar 25 01:34:58.562446 setup-nvidia[12968]: +-----------------------------------------+----------------------+----------------------+ Mar 25 01:34:58.562555 setup-nvidia[12968]: Mar 25 01:34:58.562555 setup-nvidia[12968]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:58.562555 setup-nvidia[12968]: | Processes: | Mar 25 01:34:58.562555 setup-nvidia[12968]: | GPU GI CI PID Type Process name GPU Memory | Mar 25 01:34:58.562555 setup-nvidia[12968]: | ID ID Usage | Mar 25 01:34:58.562555 setup-nvidia[12968]: |=======================================================================================| Mar 25 01:34:58.562695 setup-nvidia[12968]: | No running processes found | Mar 25 01:34:58.562695 setup-nvidia[12968]: +---------------------------------------------------------------------------------------+ Mar 25 01:34:58.933160 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 25 01:34:58.933865 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 25 01:34:58.934065 systemd[1]: Startup finished in 1.145s (firmware) + 24.226s (loader) + 1.820s (kernel) + 13.269s (initrd) + 2min 46.243s (userspace) = 3min 26.705s. Mar 25 01:35:05.207378 systemd[1]: Stopping user@0.service - User Manager for UID 0... Mar 25 01:35:05.208200 systemd[2201]: Activating special unit exit.target... Mar 25 01:35:05.208284 systemd[2201]: Stopped target default.target - Main User Target. Mar 25 01:35:05.208325 systemd[2201]: Stopped target basic.target - Basic System. Mar 25 01:35:05.208355 systemd[2201]: Stopped target paths.target - Paths. Mar 25 01:35:05.208383 systemd[2201]: Stopped target sockets.target - Sockets. Mar 25 01:35:05.208410 systemd[2201]: Stopped target timers.target - Timers. Mar 25 01:35:05.208765 systemd[2201]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:35:05.208991 systemd[2201]: Removed slice app.slice - User Application Slice. Mar 25 01:35:05.209024 systemd[2201]: Reached target shutdown.target - Shutdown. Mar 25 01:35:05.209178 systemd[2201]: Finished systemd-exit.service - Exit the Session. Mar 25 01:35:05.209216 systemd[2201]: Reached target exit.target - Exit the Session. Mar 25 01:35:05.222927 (sd-pam)[2202]: pam_unix(systemd-user:session): session closed for user root Mar 25 01:35:05.224323 systemd[1]: user@0.service: Deactivated successfully. Mar 25 01:35:05.224678 systemd[1]: Stopped user@0.service - User Manager for UID 0. Mar 25 01:35:05.226363 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Mar 25 01:35:05.252492 systemd[1]: run-user-0.mount: Deactivated successfully. Mar 25 01:35:05.253377 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Mar 25 01:35:05.253557 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Mar 25 01:35:05.255206 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Mar 25 01:35:05.255295 systemd[1]: user-0.slice: Consumed 5min 12.145s CPU time, 891.4M memory peak. Mar 25 01:35:05.255658 systemd-logind[1689]: Removed session c3. Mar 25 01:35:06.273406 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:55694.service - OpenSSH per-connection server daemon (10.200.16.10:55694). Mar 25 01:35:06.911438 sshd[12979]: Accepted publickey for core from 10.200.16.10 port 55694 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:06.912786 sshd-session[12979]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:06.916932 systemd-logind[1689]: New session 24 of user core. Mar 25 01:35:06.929073 systemd[1]: Started session-24.scope - Session 24 of User core. Mar 25 01:35:07.366359 sshd[12981]: Connection closed by 10.200.16.10 port 55694 Mar 25 01:35:07.366735 sshd-session[12979]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:07.370216 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:55694.service: Deactivated successfully. Mar 25 01:35:07.371971 systemd[1]: session-24.scope: Deactivated successfully. Mar 25 01:35:07.372595 systemd-logind[1689]: Session 24 logged out. Waiting for processes to exit. Mar 25 01:35:07.373356 systemd-logind[1689]: Removed session 24. Mar 25 01:35:07.478296 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:55710.service - OpenSSH per-connection server daemon (10.200.16.10:55710). Mar 25 01:35:08.105962 sshd[12987]: Accepted publickey for core from 10.200.16.10 port 55710 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:08.107328 sshd-session[12987]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:08.111498 systemd-logind[1689]: New session 25 of user core. Mar 25 01:35:08.126252 systemd[1]: Started session-25.scope - Session 25 of User core. Mar 25 01:35:08.560053 sshd[12989]: Connection closed by 10.200.16.10 port 55710 Mar 25 01:35:08.560431 sshd-session[12987]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:08.563697 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:55710.service: Deactivated successfully. Mar 25 01:35:08.565309 systemd[1]: session-25.scope: Deactivated successfully. Mar 25 01:35:08.565925 systemd-logind[1689]: Session 25 logged out. Waiting for processes to exit. Mar 25 01:35:08.566693 systemd-logind[1689]: Removed session 25. Mar 25 01:35:08.671579 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:55712.service - OpenSSH per-connection server daemon (10.200.16.10:55712). Mar 25 01:35:09.307750 sshd[12995]: Accepted publickey for core from 10.200.16.10 port 55712 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:09.309090 sshd-session[12995]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:09.313108 systemd-logind[1689]: New session 26 of user core. Mar 25 01:35:09.322064 systemd[1]: Started session-26.scope - Session 26 of User core. Mar 25 01:35:09.753777 sshd[12997]: Connection closed by 10.200.16.10 port 55712 Mar 25 01:35:09.754139 sshd-session[12995]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:09.757085 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:55712.service: Deactivated successfully. Mar 25 01:35:09.758619 systemd[1]: session-26.scope: Deactivated successfully. Mar 25 01:35:09.759270 systemd-logind[1689]: Session 26 logged out. Waiting for processes to exit. Mar 25 01:35:09.760046 systemd-logind[1689]: Removed session 26. Mar 25 01:35:09.864206 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:49834.service - OpenSSH per-connection server daemon (10.200.16.10:49834). Mar 25 01:35:10.508163 sshd[13003]: Accepted publickey for core from 10.200.16.10 port 49834 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:10.509549 sshd-session[13003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:10.513815 systemd-logind[1689]: New session 27 of user core. Mar 25 01:35:10.523069 systemd[1]: Started session-27.scope - Session 27 of User core. Mar 25 01:35:10.854006 sudo[13006]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 25 01:35:10.854314 sudo[13006]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:35:10.929207 sudo[13006]: pam_unix(sudo:session): session closed for user root Mar 25 01:35:11.034545 sshd[13005]: Connection closed by 10.200.16.10 port 49834 Mar 25 01:35:11.034994 sshd-session[13003]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:11.038542 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:49834.service: Deactivated successfully. Mar 25 01:35:11.040138 systemd[1]: session-27.scope: Deactivated successfully. Mar 25 01:35:11.040776 systemd-logind[1689]: Session 27 logged out. Waiting for processes to exit. Mar 25 01:35:11.041597 systemd-logind[1689]: Removed session 27. Mar 25 01:35:11.145448 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:49848.service - OpenSSH per-connection server daemon (10.200.16.10:49848). Mar 25 01:35:11.784658 sshd[13012]: Accepted publickey for core from 10.200.16.10 port 49848 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:11.786051 sshd-session[13012]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:11.790462 systemd-logind[1689]: New session 28 of user core. Mar 25 01:35:11.803063 systemd[1]: Started session-28.scope - Session 28 of User core. Mar 25 01:35:12.130928 sudo[13016]: 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 Mar 25 01:35:12.131244 sudo[13016]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:35:12.134420 sudo[13016]: pam_unix(sudo:session): session closed for user root Mar 25 01:35:12.139929 sudo[13015]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Mar 25 01:35:12.140237 sudo[13015]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:35:12.150113 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:35:12.189142 augenrules[13038]: No rules Mar 25 01:35:12.190339 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:35:12.190571 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:35:12.191381 sudo[13015]: pam_unix(sudo:session): session closed for user root Mar 25 01:35:12.294001 sshd[13014]: Connection closed by 10.200.16.10 port 49848 Mar 25 01:35:12.294352 sshd-session[13012]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:12.297769 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:49848.service: Deactivated successfully. Mar 25 01:35:12.299318 systemd[1]: session-28.scope: Deactivated successfully. Mar 25 01:35:12.299935 systemd-logind[1689]: Session 28 logged out. Waiting for processes to exit. Mar 25 01:35:12.300698 systemd-logind[1689]: Removed session 28. Mar 25 01:35:12.404958 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:49860.service - OpenSSH per-connection server daemon (10.200.16.10:49860). Mar 25 01:35:13.033202 sshd[13047]: Accepted publickey for core from 10.200.16.10 port 49860 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:13.034517 sshd-session[13047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:13.038629 systemd-logind[1689]: New session 29 of user core. Mar 25 01:35:13.048070 systemd[1]: Started session-29.scope - Session 29 of User core. Mar 25 01:35:13.378454 sudo[13050]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Mar 25 01:35:13.378762 sudo[13050]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:35:13.381970 sudo[13050]: pam_unix(sudo:session): session closed for user root Mar 25 01:35:13.483777 sshd[13049]: Connection closed by 10.200.16.10 port 49860 Mar 25 01:35:13.484110 sshd-session[13047]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:13.487084 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:49860.service: Deactivated successfully. Mar 25 01:35:13.488612 systemd[1]: session-29.scope: Deactivated successfully. Mar 25 01:35:13.489290 systemd-logind[1689]: Session 29 logged out. Waiting for processes to exit. Mar 25 01:35:13.490045 systemd-logind[1689]: Removed session 29. Mar 25 01:35:13.600249 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:49864.service - OpenSSH per-connection server daemon (10.200.16.10:49864). Mar 25 01:35:14.239387 sshd[13056]: Accepted publickey for core from 10.200.16.10 port 49864 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:14.240734 sshd-session[13056]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:14.244878 systemd-logind[1689]: New session 30 of user core. Mar 25 01:35:14.262064 systemd[1]: Started session-30.scope - Session 30 of User core. Mar 25 01:35:14.584516 sudo[13059]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Mar 25 01:35:14.584819 sudo[13059]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:35:24.622787 sudo[13059]: pam_unix(sudo:session): session closed for user root Mar 25 01:35:24.750988 sshd[13058]: Connection closed by 10.200.16.10 port 49864 Mar 25 01:35:24.751286 sshd-session[13056]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:24.754500 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:49864.service: Deactivated successfully. Mar 25 01:35:24.756068 systemd[1]: session-30.scope: Deactivated successfully. Mar 25 01:35:24.756671 systemd-logind[1689]: Session 30 logged out. Waiting for processes to exit. Mar 25 01:35:24.757435 systemd-logind[1689]: Removed session 30. Mar 25 01:35:24.874091 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:49710.service - OpenSSH per-connection server daemon (10.200.16.10:49710). Mar 25 01:35:25.520127 sshd[13065]: Accepted publickey for core from 10.200.16.10 port 49710 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:25.521461 sshd-session[13065]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:25.525538 systemd-logind[1689]: New session 31 of user core. Mar 25 01:35:25.537063 systemd[1]: Started session-31.scope - Session 31 of User core. Mar 25 01:35:26.014397 sshd[13067]: Connection closed by 10.200.16.10 port 49710 Mar 25 01:35:26.014765 sshd-session[13065]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:26.017974 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:49710.service: Deactivated successfully. Mar 25 01:35:26.019572 systemd[1]: session-31.scope: Deactivated successfully. Mar 25 01:35:26.020455 systemd-logind[1689]: Session 31 logged out. Waiting for processes to exit. Mar 25 01:35:26.021523 systemd-logind[1689]: Removed session 31. Mar 25 01:35:26.125640 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:49726.service - OpenSSH per-connection server daemon (10.200.16.10:49726). Mar 25 01:35:26.755411 sshd[13083]: Accepted publickey for core from 10.200.16.10 port 49726 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:26.756772 sshd-session[13083]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:26.760919 systemd-logind[1689]: New session 32 of user core. Mar 25 01:35:26.774061 systemd[1]: Started session-32.scope - Session 32 of User core. Mar 25 01:35:27.749094 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 25 01:35:27.766276 (dockerd)[13105]: 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 Mar 25 01:35:29.181252 dockerd[13105]: time="2025-03-25T01:35:29.181183398Z" level=info msg="Starting up" Mar 25 01:35:29.182956 dockerd[13105]: time="2025-03-25T01:35:29.182922032Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Mar 25 01:35:29.510361 dockerd[13105]: time="2025-03-25T01:35:29.510323070Z" level=info msg="Loading containers: start." Mar 25 01:35:29.768968 kernel: Initializing XFRM netlink socket Mar 25 01:35:29.827355 systemd-networkd[1611]: docker0: Link UP Mar 25 01:35:29.924179 dockerd[13105]: time="2025-03-25T01:35:29.924141045Z" level=info msg="Loading containers: done." Mar 25 01:35:29.937093 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck909061601-merged.mount: Deactivated successfully. Mar 25 01:35:29.950735 dockerd[13105]: time="2025-03-25T01:35:29.950695042Z" 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 Mar 25 01:35:29.950812 dockerd[13105]: time="2025-03-25T01:35:29.950770739Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Mar 25 01:35:29.950905 dockerd[13105]: time="2025-03-25T01:35:29.950881335Z" level=info msg="Daemon has completed initialization" Mar 25 01:35:30.026583 dockerd[13105]: time="2025-03-25T01:35:30.026518179Z" level=info msg="API listen on /run/docker.sock" Mar 25 01:35:30.026679 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 25 01:35:31.153821 systemd[1]: var-lib-docker-overlay2-93727f9bde0b591f8a9088c3464ea3f513e17f2d5547188022068e4e3a46b6cb\x2dinit-merged.mount: Deactivated successfully. Mar 25 01:35:31.244573 kernel: docker0: port 1(veth096a9b7) entered blocking state Mar 25 01:35:31.244676 kernel: docker0: port 1(veth096a9b7) entered disabled state Mar 25 01:35:31.244701 kernel: veth096a9b7: entered allmulticast mode Mar 25 01:35:31.249613 kernel: veth096a9b7: entered promiscuous mode Mar 25 01:35:31.250216 systemd-networkd[1611]: veth096a9b7: Link UP Mar 25 01:35:31.370286 containerd[1701]: time="2025-03-25T01:35:31.370233543Z" level=info msg="connecting to shim 596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6" address="unix:///run/containerd/s/70de26cb89cfa3452899cd82fd7cdc836ab6297eb10e773fa44fc5bd0a85061b" namespace=moby protocol=ttrpc version=3 Mar 25 01:35:31.394074 systemd[1]: Started docker-596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6.scope - libcontainer container 596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6. Mar 25 01:35:31.453975 kernel: eth0: renamed from vetha44867a Mar 25 01:35:31.467613 kernel: docker0: port 1(veth096a9b7) entered blocking state Mar 25 01:35:31.467680 kernel: docker0: port 1(veth096a9b7) entered forwarding state Mar 25 01:35:31.468022 systemd-networkd[1611]: veth096a9b7: Gained carrier Mar 25 01:35:31.468302 systemd-networkd[1611]: docker0: Gained carrier Mar 25 01:35:32.975184 systemd-networkd[1611]: docker0: Gained IPv6LL Mar 25 01:35:33.359091 systemd-networkd[1611]: veth096a9b7: Gained IPv6LL Mar 25 01:35:34.537786 systemd[1]: docker-596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6.scope: Deactivated successfully. Mar 25 01:35:34.550458 containerd[1701]: time="2025-03-25T01:35:34.550422965Z" level=info msg="shim disconnected" id=596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6 namespace=moby Mar 25 01:35:34.550781 dockerd[13105]: time="2025-03-25T01:35:34.550437464Z" level=info msg="ignoring event" container=596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Mar 25 01:35:34.551033 containerd[1701]: time="2025-03-25T01:35:34.550979344Z" level=warning msg="cleaning up after shim disconnected" id=596a4972b8a15415f3a0bdb31d69fb031fb08a2fcb7904cfc3363c6d7b1cf1b6 namespace=moby Mar 25 01:35:34.551033 containerd[1701]: time="2025-03-25T01:35:34.550998543Z" level=info msg="cleaning up dead shim" namespace=moby Mar 25 01:35:34.588966 kernel: docker0: port 1(veth096a9b7) entered disabled state Mar 25 01:35:34.589053 kernel: vetha44867a: renamed from eth0 Mar 25 01:35:34.589096 systemd-networkd[1611]: veth096a9b7: Lost carrier Mar 25 01:35:34.632090 systemd-networkd[1611]: veth096a9b7: Link DOWN Mar 25 01:35:34.635978 kernel: docker0: port 1(veth096a9b7) entered disabled state Mar 25 01:35:34.642391 kernel: veth096a9b7 (unregistering): left allmulticast mode Mar 25 01:35:34.642444 kernel: veth096a9b7 (unregistering): left promiscuous mode Mar 25 01:35:34.645691 kernel: docker0: port 1(veth096a9b7) entered disabled state Mar 25 01:35:34.687953 systemd[1]: run-docker-netns-ac9b54101faf.mount: Deactivated successfully. Mar 25 01:35:34.694064 systemd[1]: var-lib-docker-overlay2-93727f9bde0b591f8a9088c3464ea3f513e17f2d5547188022068e4e3a46b6cb-merged.mount: Deactivated successfully. Mar 25 01:35:34.864292 sshd[13085]: Connection closed by 10.200.16.10 port 49726 Mar 25 01:35:34.864587 sshd-session[13083]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:34.868004 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:49726.service: Deactivated successfully. Mar 25 01:35:34.869574 systemd[1]: session-32.scope: Deactivated successfully. Mar 25 01:35:34.870206 systemd-logind[1689]: Session 32 logged out. Waiting for processes to exit. Mar 25 01:35:34.870972 systemd-logind[1689]: Removed session 32. Mar 25 01:35:34.978224 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:39676.service - OpenSSH per-connection server daemon (10.200.16.10:39676). Mar 25 01:35:35.599154 systemd-networkd[1611]: docker0: Lost carrier Mar 25 01:35:35.614056 sshd[13395]: Accepted publickey for core from 10.200.16.10 port 39676 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:35.615392 sshd-session[13395]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:35.619836 systemd-logind[1689]: New session 33 of user core. Mar 25 01:35:35.632380 systemd[1]: Started session-33.scope - Session 33 of User core. Mar 25 01:35:36.011403 systemd[1]: var-lib-docker-overlay2-5116a1df416cd545e79cd54defacab4236b266d7462661d0d4c8369899f03e61\x2dinit-merged.mount: Deactivated successfully. Mar 25 01:35:36.100996 kernel: docker0: port 1(veth286d6d5) entered blocking state Mar 25 01:35:36.101117 kernel: docker0: port 1(veth286d6d5) entered disabled state Mar 25 01:35:36.103719 kernel: veth286d6d5: entered allmulticast mode Mar 25 01:35:36.106626 kernel: veth286d6d5: entered promiscuous mode Mar 25 01:35:36.107475 systemd-networkd[1611]: veth286d6d5: Link UP Mar 25 01:35:36.113230 kernel: docker0: port 1(veth286d6d5) entered blocking state Mar 25 01:35:36.113269 kernel: docker0: port 1(veth286d6d5) entered forwarding state Mar 25 01:35:36.113964 kernel: docker0: port 1(veth286d6d5) entered disabled state Mar 25 01:35:36.214713 containerd[1701]: time="2025-03-25T01:35:36.214653227Z" level=info msg="connecting to shim ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19" address="unix:///run/containerd/s/8bf16ae483bf0331666cfa6f8959cee5c255a5bd892e41d43f9b2c4763cc41f0" namespace=moby protocol=ttrpc version=3 Mar 25 01:35:36.240066 systemd[1]: Started docker-ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19.scope - libcontainer container ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19. Mar 25 01:35:36.293965 kernel: eth0: renamed from vethf01c1b8 Mar 25 01:35:36.311635 kernel: docker0: port 1(veth286d6d5) entered blocking state Mar 25 01:35:36.311699 kernel: docker0: port 1(veth286d6d5) entered forwarding state Mar 25 01:35:36.312077 systemd-networkd[1611]: veth286d6d5: Gained carrier Mar 25 01:35:36.312330 systemd-networkd[1611]: docker0: Gained carrier Mar 25 01:35:36.356840 systemd[1]: docker-ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19.scope: Deactivated successfully. Mar 25 01:35:36.388731 containerd[1701]: time="2025-03-25T01:35:36.388691655Z" level=info msg="shim disconnected" id=ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19 namespace=moby Mar 25 01:35:36.388837 dockerd[13105]: time="2025-03-25T01:35:36.388751153Z" level=info msg="ignoring event" container=ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Mar 25 01:35:36.389184 containerd[1701]: time="2025-03-25T01:35:36.389150938Z" level=warning msg="cleaning up after shim disconnected" id=ba4cf7db47be5f60227620c07e2b50d3a5a540422365fc3295114cdab8f4ec19 namespace=moby Mar 25 01:35:36.389255 containerd[1701]: time="2025-03-25T01:35:36.389176837Z" level=info msg="cleaning up dead shim" namespace=moby Mar 25 01:35:36.422798 systemd-networkd[1611]: veth286d6d5: Lost carrier Mar 25 01:35:36.422958 kernel: docker0: port 1(veth286d6d5) entered disabled state Mar 25 01:35:36.422997 kernel: vethf01c1b8: renamed from eth0 Mar 25 01:35:36.463400 systemd-networkd[1611]: veth286d6d5: Link DOWN Mar 25 01:35:36.466962 kernel: docker0: port 1(veth286d6d5) entered disabled state Mar 25 01:35:36.473998 kernel: veth286d6d5 (unregistering): left allmulticast mode Mar 25 01:35:36.474057 kernel: veth286d6d5 (unregistering): left promiscuous mode Mar 25 01:35:36.477227 kernel: docker0: port 1(veth286d6d5) entered disabled state Mar 25 01:35:36.607121 systemd-networkd[1611]: docker0: Lost carrier Mar 25 01:35:36.645895 sshd[13397]: Connection closed by 10.200.16.10 port 39676 Mar 25 01:35:36.646265 sshd-session[13395]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:36.649447 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:39676.service: Deactivated successfully. Mar 25 01:35:36.651023 systemd[1]: session-33.scope: Deactivated successfully. Mar 25 01:35:36.651668 systemd-logind[1689]: Session 33 logged out. Waiting for processes to exit. Mar 25 01:35:36.652414 systemd-logind[1689]: Removed session 33. Mar 25 01:35:36.766140 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:39682.service - OpenSSH per-connection server daemon (10.200.16.10:39682). Mar 25 01:35:37.008564 systemd[1]: var-lib-docker-overlay2-5116a1df416cd545e79cd54defacab4236b266d7462661d0d4c8369899f03e61-merged.mount: Deactivated successfully. Mar 25 01:35:37.411913 sshd[13510]: Accepted publickey for core from 10.200.16.10 port 39682 ssh2: RSA SHA256:8y0SvI5GFG4T9nNdiZtlD2BxXWYd1OVNSNpaMHT1LqA Mar 25 01:35:37.413234 sshd-session[13510]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:35:37.417410 systemd-logind[1689]: New session 34 of user core. Mar 25 01:35:37.429074 systemd[1]: Started session-34.scope - Session 34 of User core. Mar 25 01:35:37.841908 ntpdate[13523]: ntpdate 4.2.8p17@1.4004-o Mon Mar 24 23:09:42 UTC 2025 (1) Mar 25 01:35:44.666227 ntpdate[13523]: adjust time server 193.1.8.106 offset -0.003388 sec Mar 25 01:35:44.769826 sshd[13512]: Connection closed by 10.200.16.10 port 39682 Mar 25 01:35:44.770233 sshd-session[13510]: pam_unix(sshd:session): session closed for user core Mar 25 01:35:44.773533 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:39682.service: Deactivated successfully. Mar 25 01:35:44.775206 systemd[1]: session-34.scope: Deactivated successfully. Mar 25 01:35:44.775923 systemd-logind[1689]: Session 34 logged out. Waiting for processes to exit. Mar 25 01:35:44.776717 systemd-logind[1689]: Removed session 34.