May 13 23:56:13.216466 kernel: Linux version 6.6.89-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 Tue May 13 22:08:35 -00 2025 May 13 23:56:13.216496 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=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:56:13.216506 kernel: BIOS-provided physical RAM map: May 13 23:56:13.216513 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 13 23:56:13.216520 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 13 23:56:13.216526 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 13 23:56:13.216534 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved May 13 23:56:13.216544 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data May 13 23:56:13.216551 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 13 23:56:13.216558 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 13 23:56:13.216564 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable May 13 23:56:13.216571 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable May 13 23:56:13.216578 kernel: printk: bootconsole [earlyser0] enabled May 13 23:56:13.216585 kernel: NX (Execute Disable) protection: active May 13 23:56:13.216596 kernel: APIC: Static calls initialized May 13 23:56:13.216603 kernel: efi: EFI v2.7 by Microsoft May 13 23:56:13.216611 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee6da98 RNG=0x3ffd1018 May 13 23:56:13.216619 kernel: random: crng init done May 13 23:56:13.216626 kernel: secureboot: Secure boot disabled May 13 23:56:13.216637 kernel: SMBIOS 3.1.0 present. May 13 23:56:13.216645 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 May 13 23:56:13.216653 kernel: Hypervisor detected: Microsoft Hyper-V May 13 23:56:13.216660 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 May 13 23:56:13.216685 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 May 13 23:56:13.216697 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 13 23:56:13.216704 kernel: Hyper-V: Using hypercall for remote TLB flush May 13 23:56:13.216712 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 13 23:56:13.216720 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 13 23:56:13.216728 kernel: tsc: Marking TSC unstable due to running on Hyper-V May 13 23:56:13.216736 kernel: tsc: Detected 2593.992 MHz processor May 13 23:56:13.216744 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 13 23:56:13.216752 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 13 23:56:13.216760 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 May 13 23:56:13.216770 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs May 13 23:56:13.216778 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 13 23:56:13.216786 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved May 13 23:56:13.216793 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved May 13 23:56:13.216801 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 May 13 23:56:13.216809 kernel: Using GB pages for direct mapping May 13 23:56:13.216816 kernel: ACPI: Early table checksum verification disabled May 13 23:56:13.216828 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 13 23:56:13.216839 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216847 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216855 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) May 13 23:56:13.216863 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 13 23:56:13.216871 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216879 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216890 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216898 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216906 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216915 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216923 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:13.216931 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 13 23:56:13.216939 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] May 13 23:56:13.216947 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 13 23:56:13.216958 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 13 23:56:13.216966 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 13 23:56:13.216974 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 13 23:56:13.216982 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] May 13 23:56:13.216990 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] May 13 23:56:13.216998 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 13 23:56:13.217006 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] May 13 23:56:13.217014 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 May 13 23:56:13.217022 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 May 13 23:56:13.217033 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 May 13 23:56:13.217041 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 May 13 23:56:13.217049 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 May 13 23:56:13.217057 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 May 13 23:56:13.217065 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 13 23:56:13.217073 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug May 13 23:56:13.217082 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug May 13 23:56:13.217090 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug May 13 23:56:13.217098 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug May 13 23:56:13.217109 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug May 13 23:56:13.217117 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug May 13 23:56:13.217134 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug May 13 23:56:13.217142 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] May 13 23:56:13.217150 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] May 13 23:56:13.217159 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] May 13 23:56:13.217167 kernel: Zone ranges: May 13 23:56:13.217175 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 13 23:56:13.217183 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 13 23:56:13.217195 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] May 13 23:56:13.217203 kernel: Movable zone start for each node May 13 23:56:13.217211 kernel: Early memory node ranges May 13 23:56:13.217219 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 13 23:56:13.217227 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 13 23:56:13.217235 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 13 23:56:13.217244 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] May 13 23:56:13.217252 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] May 13 23:56:13.217260 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] May 13 23:56:13.217270 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 13 23:56:13.217279 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 13 23:56:13.217287 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges May 13 23:56:13.217295 kernel: ACPI: PM-Timer IO Port: 0x408 May 13 23:56:13.217303 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) May 13 23:56:13.217311 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 May 13 23:56:13.217319 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 13 23:56:13.217327 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 13 23:56:13.217335 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 13 23:56:13.217346 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs May 13 23:56:13.217354 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 13 23:56:13.217362 kernel: Booting paravirtualized kernel on Hyper-V May 13 23:56:13.217371 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 13 23:56:13.217379 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 May 13 23:56:13.217392 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 May 13 23:56:13.217400 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 May 13 23:56:13.217408 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - May 13 23:56:13.217416 kernel: Hyper-V: PV spinlocks enabled May 13 23:56:13.217427 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 13 23:56:13.217436 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=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:56:13.217445 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 23:56:13.217453 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) May 13 23:56:13.217461 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) May 13 23:56:13.217469 kernel: Fallback order for Node 0: 0 May 13 23:56:13.217484 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 May 13 23:56:13.217493 kernel: Policy zone: Normal May 13 23:56:13.217510 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 23:56:13.217538 kernel: software IO TLB: area num 8. May 13 23:56:13.217555 kernel: Memory: 115333884K/117439364K available (14336K kernel code, 2296K rwdata, 25068K rodata, 43604K init, 1468K bss, 2105224K reserved, 0K cma-reserved) May 13 23:56:13.217568 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 May 13 23:56:13.217576 kernel: Kernel/User page tables isolation: enabled May 13 23:56:13.217585 kernel: ftrace: allocating 37993 entries in 149 pages May 13 23:56:13.217593 kernel: ftrace: allocated 149 pages with 4 groups May 13 23:56:13.217602 kernel: Dynamic Preempt: voluntary May 13 23:56:13.217610 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 23:56:13.217620 kernel: rcu: RCU event tracing is enabled. May 13 23:56:13.217628 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. May 13 23:56:13.217637 kernel: Trampoline variant of Tasks RCU enabled. May 13 23:56:13.217648 kernel: Rude variant of Tasks RCU enabled. May 13 23:56:13.217657 kernel: Tracing variant of Tasks RCU enabled. May 13 23:56:13.217674 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 23:56:13.217683 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 May 13 23:56:13.217695 kernel: Using NULL legacy PIC May 13 23:56:13.217704 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 May 13 23:56:13.217712 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 13 23:56:13.217721 kernel: Console: colour dummy device 80x25 May 13 23:56:13.217729 kernel: printk: console [tty1] enabled May 13 23:56:13.217738 kernel: printk: console [ttyS0] enabled May 13 23:56:13.217747 kernel: printk: bootconsole [earlyser0] disabled May 13 23:56:13.217755 kernel: ACPI: Core revision 20230628 May 13 23:56:13.217764 kernel: Failed to register legacy timer interrupt May 13 23:56:13.217772 kernel: APIC: Switch to symmetric I/O mode setup May 13 23:56:13.217784 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 13 23:56:13.217792 kernel: Hyper-V: Using IPI hypercalls May 13 23:56:13.217801 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 13 23:56:13.217809 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 13 23:56:13.217818 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 13 23:56:13.217827 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 13 23:56:13.217835 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 13 23:56:13.217844 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 13 23:56:13.217852 kernel: Hyper-V: Using enlightened APIC (xapic mode) May 13 23:56:13.217863 kernel: APIC: eoi() replaced with hv_apic_eoi_write() May 13 23:56:13.217872 kernel: APIC: read() replaced with hv_apic_read() May 13 23:56:13.217881 kernel: APIC: write() replaced with hv_apic_write() May 13 23:56:13.217889 kernel: APIC: icr_write() replaced with hv_apic_icr_write() May 13 23:56:13.217898 kernel: APIC: icr_read() replaced with hv_apic_icr_read() May 13 23:56:13.217907 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) May 13 23:56:13.217915 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 May 13 23:56:13.217924 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 May 13 23:56:13.217933 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 13 23:56:13.217943 kernel: Spectre V2 : Mitigation: Retpolines May 13 23:56:13.217952 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 13 23:56:13.217960 kernel: Speculative Store Bypass: Vulnerable May 13 23:56:13.217968 kernel: MDS: Mitigation: Clear CPU buffers May 13 23:56:13.217977 kernel: TAA: Mitigation: Clear CPU buffers May 13 23:56:13.217985 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 13 23:56:13.217994 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 13 23:56:13.218002 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 13 23:56:13.218010 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 13 23:56:13.218019 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 13 23:56:13.218027 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 13 23:56:13.218038 kernel: Freeing SMP alternatives memory: 32K May 13 23:56:13.218047 kernel: pid_max: default: 32768 minimum: 301 May 13 23:56:13.218055 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 13 23:56:13.218064 kernel: landlock: Up and running. May 13 23:56:13.218072 kernel: SELinux: Initializing. May 13 23:56:13.218081 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 13 23:56:13.218089 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 13 23:56:13.218098 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) May 13 23:56:13.218106 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 13 23:56:13.218115 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 13 23:56:13.218127 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 13 23:56:13.218135 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 13 23:56:13.218144 kernel: signal: max sigframe size: 1776 May 13 23:56:13.218153 kernel: rcu: Hierarchical SRCU implementation. May 13 23:56:13.218161 kernel: rcu: Max phase no-delay instances is 400. May 13 23:56:13.218170 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 13 23:56:13.218178 kernel: smp: Bringing up secondary CPUs ... May 13 23:56:13.218187 kernel: smpboot: x86: Booting SMP configuration: May 13 23:56:13.218195 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 May 13 23:56:13.218206 kernel: smp: Brought up 1 node, 6 CPUs May 13 23:56:13.218215 kernel: smpboot: Max logical packages: 1 May 13 23:56:13.218224 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) May 13 23:56:13.218232 kernel: devtmpfs: initialized May 13 23:56:13.218241 kernel: x86/mm: Memory block size: 1024MB May 13 23:56:13.218249 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 13 23:56:13.218258 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 23:56:13.218267 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) May 13 23:56:13.218275 kernel: pinctrl core: initialized pinctrl subsystem May 13 23:56:13.218286 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 23:56:13.218295 kernel: audit: initializing netlink subsys (disabled) May 13 23:56:13.218304 kernel: audit: type=2000 audit(1747180571.028:1): state=initialized audit_enabled=0 res=1 May 13 23:56:13.218312 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 23:56:13.218321 kernel: thermal_sys: Registered thermal governor 'user_space' May 13 23:56:13.218330 kernel: cpuidle: using governor menu May 13 23:56:13.218338 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 23:56:13.218347 kernel: dca service started, version 1.12.1 May 13 23:56:13.218355 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 13 23:56:13.218366 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 13 23:56:13.218375 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 13 23:56:13.218384 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 13 23:56:13.218392 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 13 23:56:13.218401 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 13 23:56:13.218409 kernel: ACPI: Added _OSI(Module Device) May 13 23:56:13.218418 kernel: ACPI: Added _OSI(Processor Device) May 13 23:56:13.218426 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 23:56:13.218435 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 23:56:13.218446 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 23:56:13.218458 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 13 23:56:13.218466 kernel: ACPI: Interpreter enabled May 13 23:56:13.218475 kernel: ACPI: PM: (supports S0 S5) May 13 23:56:13.218483 kernel: ACPI: Using IOAPIC for interrupt routing May 13 23:56:13.218492 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 13 23:56:13.218501 kernel: PCI: Ignoring E820 reservations for host bridge windows May 13 23:56:13.218509 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 13 23:56:13.218518 kernel: iommu: Default domain type: Translated May 13 23:56:13.218526 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 13 23:56:13.218538 kernel: efivars: Registered efivars operations May 13 23:56:13.218546 kernel: PCI: Using ACPI for IRQ routing May 13 23:56:13.218555 kernel: PCI: System does not support PCI May 13 23:56:13.218563 kernel: vgaarb: loaded May 13 23:56:13.218572 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page May 13 23:56:13.218581 kernel: VFS: Disk quotas dquot_6.6.0 May 13 23:56:13.218589 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 23:56:13.218598 kernel: pnp: PnP ACPI init May 13 23:56:13.218609 kernel: pnp: PnP ACPI: found 3 devices May 13 23:56:13.218618 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 13 23:56:13.218626 kernel: NET: Registered PF_INET protocol family May 13 23:56:13.218635 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 23:56:13.218644 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) May 13 23:56:13.218652 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 23:56:13.218661 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 13 23:56:13.218678 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:13.218687 kernel: TCP: Hash tables configured (established 524288 bind 65536) May 13 23:56:13.218698 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:13.218707 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:13.218716 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 23:56:13.218725 kernel: NET: Registered PF_XDP protocol family May 13 23:56:13.218733 kernel: PCI: CLS 0 bytes, default 64 May 13 23:56:13.218742 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 13 23:56:13.218750 kernel: software IO TLB: mapped [mem 0x000000003ad78000-0x000000003ed78000] (64MB) May 13 23:56:13.218759 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 13 23:56:13.218768 kernel: Initialise system trusted keyrings May 13 23:56:13.218779 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 May 13 23:56:13.218788 kernel: Key type asymmetric registered May 13 23:56:13.218796 kernel: Asymmetric key parser 'x509' registered May 13 23:56:13.218804 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 13 23:56:13.218813 kernel: io scheduler mq-deadline registered May 13 23:56:13.218822 kernel: io scheduler kyber registered May 13 23:56:13.218830 kernel: io scheduler bfq registered May 13 23:56:13.218839 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 13 23:56:13.218847 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 23:56:13.218859 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 13 23:56:13.218867 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 13 23:56:13.218876 kernel: i8042: PNP: No PS/2 controller found. May 13 23:56:13.219034 kernel: rtc_cmos 00:02: registered as rtc0 May 13 23:56:13.219146 kernel: rtc_cmos 00:02: setting system clock to 2025-05-13T23:56:12 UTC (1747180572) May 13 23:56:13.219248 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 13 23:56:13.219261 kernel: intel_pstate: CPU model not supported May 13 23:56:13.219270 kernel: efifb: probing for efifb May 13 23:56:13.219283 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 13 23:56:13.219292 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 13 23:56:13.219301 kernel: efifb: scrolling: redraw May 13 23:56:13.219309 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 13 23:56:13.219318 kernel: Console: switching to colour frame buffer device 128x48 May 13 23:56:13.219327 kernel: fb0: EFI VGA frame buffer device May 13 23:56:13.219335 kernel: pstore: Using crash dump compression: deflate May 13 23:56:13.219344 kernel: pstore: Registered efi_pstore as persistent store backend May 13 23:56:13.219353 kernel: NET: Registered PF_INET6 protocol family May 13 23:56:13.219364 kernel: Segment Routing with IPv6 May 13 23:56:13.219373 kernel: In-situ OAM (IOAM) with IPv6 May 13 23:56:13.219381 kernel: NET: Registered PF_PACKET protocol family May 13 23:56:13.219390 kernel: Key type dns_resolver registered May 13 23:56:13.219398 kernel: IPI shorthand broadcast: enabled May 13 23:56:13.219407 kernel: sched_clock: Marking stable (1756005100, 102249900)->(2190684600, -332429600) May 13 23:56:13.219416 kernel: registered taskstats version 1 May 13 23:56:13.219424 kernel: Loading compiled-in X.509 certificates May 13 23:56:13.219433 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 166efda032ca4d6e9037c569aca9b53585ee6f94' May 13 23:56:13.219444 kernel: Key type .fscrypt registered May 13 23:56:13.219452 kernel: Key type fscrypt-provisioning registered May 13 23:56:13.219461 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 23:56:13.219470 kernel: ima: Allocated hash algorithm: sha1 May 13 23:56:13.219478 kernel: ima: No architecture policies found May 13 23:56:13.219487 kernel: clk: Disabling unused clocks May 13 23:56:13.219495 kernel: Freeing unused kernel image (initmem) memory: 43604K May 13 23:56:13.219504 kernel: Write protecting the kernel read-only data: 40960k May 13 23:56:13.219512 kernel: Freeing unused kernel image (rodata/data gap) memory: 1556K May 13 23:56:13.219523 kernel: Run /init as init process May 13 23:56:13.219532 kernel: with arguments: May 13 23:56:13.219540 kernel: /init May 13 23:56:13.219549 kernel: with environment: May 13 23:56:13.219557 kernel: HOME=/ May 13 23:56:13.219565 kernel: TERM=linux May 13 23:56:13.219574 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 23:56:13.219584 systemd[1]: Successfully made /usr/ read-only. May 13 23:56:13.219598 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) May 13 23:56:13.219607 systemd[1]: Detected virtualization microsoft. May 13 23:56:13.219616 systemd[1]: Detected architecture x86-64. May 13 23:56:13.219625 systemd[1]: Running in initrd. May 13 23:56:13.219633 systemd[1]: No hostname configured, using default hostname. May 13 23:56:13.219642 systemd[1]: Hostname set to . May 13 23:56:13.219651 systemd[1]: Initializing machine ID from random generator. May 13 23:56:13.219660 systemd[1]: Queued start job for default target initrd.target. May 13 23:56:13.219685 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:13.219695 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:13.219704 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 13 23:56:13.219714 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:56:13.219723 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 13 23:56:13.219733 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 13 23:56:13.219743 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 13 23:56:13.219755 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 13 23:56:13.219764 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:13.219773 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:13.219782 systemd[1]: Reached target paths.target - Path Units. May 13 23:56:13.219791 systemd[1]: Reached target slices.target - Slice Units. May 13 23:56:13.219800 systemd[1]: Reached target swap.target - Swaps. May 13 23:56:13.219809 systemd[1]: Reached target timers.target - Timer Units. May 13 23:56:13.219818 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:56:13.219830 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:56:13.219839 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 13 23:56:13.219848 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 13 23:56:13.219857 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:13.219867 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:56:13.219876 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:13.219884 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:56:13.219894 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 13 23:56:13.219903 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:56:13.219914 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 13 23:56:13.219924 systemd[1]: Starting systemd-fsck-usr.service... May 13 23:56:13.219932 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:56:13.219941 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:56:13.219950 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:13.219980 systemd-journald[204]: Collecting audit messages is disabled. May 13 23:56:13.220005 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 13 23:56:13.220015 systemd-journald[204]: Journal started May 13 23:56:13.220035 systemd-journald[204]: Runtime Journal (/run/log/journal/d970fa5820d041e590cfa7d361ebd102) is 8M, max 2.2G, 2.1G free. May 13 23:56:13.229687 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:56:13.229702 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:13.230226 systemd[1]: Finished systemd-fsck-usr.service. May 13 23:56:13.249535 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:13.250388 systemd-modules-load[206]: Inserted module 'overlay' May 13 23:56:13.260130 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:13.264044 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:56:13.273849 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:56:13.283863 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:56:13.285415 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:56:13.300070 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:13.310288 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 13 23:56:13.314453 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:13.328733 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:13.345031 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 13 23:56:13.349610 systemd-modules-load[206]: Inserted module 'br_netfilter' May 13 23:56:13.351154 kernel: Bridge firewalling registered May 13 23:56:13.350610 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:56:13.352800 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:56:13.370547 dracut-cmdline[233]: dracut-dracut-053 May 13 23:56:13.379398 dracut-cmdline[233]: 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=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:56:13.410512 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:13.413333 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:56:13.461360 systemd-resolved[298]: Positive Trust Anchors: May 13 23:56:13.466430 kernel: SCSI subsystem initialized May 13 23:56:13.461375 systemd-resolved[298]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:56:13.461406 systemd-resolved[298]: 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 May 13 23:56:13.464125 systemd-resolved[298]: Defaulting to hostname 'linux'. May 13 23:56:13.465196 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:56:13.523234 kernel: Loading iSCSI transport class v2.0-870. May 13 23:56:13.472468 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:13.536725 kernel: iscsi: registered transport (tcp) May 13 23:56:13.560403 kernel: iscsi: registered transport (qla4xxx) May 13 23:56:13.560452 kernel: QLogic iSCSI HBA Driver May 13 23:56:13.600341 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 13 23:56:13.603250 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 13 23:56:13.656556 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 13 23:56:13.656592 kernel: device-mapper: uevent: version 1.0.3 May 13 23:56:13.661124 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 13 23:56:13.703686 kernel: raid6: avx2x4 gen() 31018 MB/s May 13 23:56:13.722691 kernel: raid6: avx2x2 gen() 29651 MB/s May 13 23:56:13.743888 kernel: raid6: avx2x1 gen() 27960 MB/s May 13 23:56:13.743919 kernel: raid6: using algorithm avx2x4 gen() 31018 MB/s May 13 23:56:13.766945 kernel: raid6: .... xor() 9651 MB/s, rmw enabled May 13 23:56:13.766983 kernel: raid6: using avx2x2 recovery algorithm May 13 23:56:13.789694 kernel: xor: automatically using best checksumming function avx May 13 23:56:13.941699 kernel: Btrfs loaded, zoned=no, fsverity=no May 13 23:56:13.952261 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 13 23:56:13.956563 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:13.992415 systemd-udevd[426]: Using default interface naming scheme 'v255'. May 13 23:56:13.997854 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:13.999793 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 13 23:56:14.040688 dracut-pre-trigger[432]: rd.md=0: removing MD RAID activation May 13 23:56:14.071961 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:56:14.080977 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:56:14.162429 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:14.166499 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 13 23:56:14.190691 kernel: cryptd: max_cpu_qlen set to 1000 May 13 23:56:14.199521 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 13 23:56:14.201879 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:56:14.219579 kernel: hv_vmbus: Vmbus version:4.0 May 13 23:56:14.219652 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:14.221169 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:56:14.239066 kernel: AVX2 version of gcm_enc/dec engaged. May 13 23:56:14.234859 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 13 23:56:14.245260 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:56:14.245447 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:14.263396 kernel: AES CTR mode by8 optimization enabled May 13 23:56:14.263426 kernel: hv_vmbus: registering driver hyperv_keyboard May 13 23:56:14.266985 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:14.283259 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 May 13 23:56:14.283284 kernel: hid: raw HID events driver (C) Jiri Kosina May 13 23:56:14.292462 kernel: pps_core: LinuxPPS API ver. 1 registered May 13 23:56:14.292502 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 13 23:56:14.294842 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:14.295952 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:14.308527 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:14.313170 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:14.319738 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:14.337889 kernel: hv_vmbus: registering driver hv_pci May 13 23:56:14.337923 kernel: hv_vmbus: registering driver hid_hyperv May 13 23:56:14.337935 kernel: PTP clock support registered May 13 23:56:14.337946 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 May 13 23:56:14.340688 kernel: hv_vmbus: registering driver hv_netvsc May 13 23:56:14.340720 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 May 13 23:56:14.340877 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] May 13 23:56:14.341034 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] May 13 23:56:14.341161 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] May 13 23:56:14.341283 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 May 13 23:56:14.339752 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 13 23:56:14.987106 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 13 23:56:14.987133 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] May 13 23:56:14.987358 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 13 23:56:14.987504 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] May 13 23:56:14.987649 kernel: hv_vmbus: registering driver hv_storvsc May 13 23:56:14.987672 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] May 13 23:56:14.987825 kernel: hv_utils: Registering HyperV Utility Driver May 13 23:56:14.987839 kernel: scsi host0: storvsc_host_t May 13 23:56:14.987980 kernel: scsi host1: storvsc_host_t May 13 23:56:14.988117 kernel: pci 0001:00:00.0: Enabling HDA controller May 13 23:56:14.988260 kernel: hv_vmbus: registering driver hv_utils May 13 23:56:14.988277 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 13 23:56:14.988424 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 13 23:56:14.988450 kernel: hv_utils: Heartbeat IC version 3.0 May 13 23:56:14.988467 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 13 23:56:14.988487 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 May 13 23:56:14.988620 kernel: hv_utils: Shutdown IC version 3.2 May 13 23:56:14.988633 kernel: hv_utils: TimeSync IC version 4.0 May 13 23:56:14.988645 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] May 13 23:56:14.988800 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] May 13 23:56:14.988938 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] May 13 23:56:14.952668 systemd-resolved[298]: Clock change detected. Flushing caches. May 13 23:56:15.001021 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:15.004442 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:15.027736 kernel: sr 1:0:0:2: [sr0] scsi-1 drive May 13 23:56:15.027968 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 13 23:56:15.031738 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 May 13 23:56:15.044526 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) May 13 23:56:15.044921 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks May 13 23:56:15.045360 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 13 23:56:15.050640 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:15.092343 kernel: sd 1:0:0:1: [sdb] Write Protect is off May 13 23:56:15.092547 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 May 13 23:56:15.092707 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA May 13 23:56:15.092872 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks May 13 23:56:15.093018 kernel: sdb: sdb1 May 13 23:56:15.093031 kernel: sd 1:0:0:0: [sda] Write Protect is off May 13 23:56:15.093172 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk May 13 23:56:15.093312 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 13 23:56:15.093455 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 13 23:56:15.093593 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:15.093606 kernel: sd 1:0:0:0: [sda] Attached SCSI disk May 13 23:56:15.670570 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 13 23:56:15.696740 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (498) May 13 23:56:15.717636 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 13 23:56:15.868747 kernel: BTRFS: device fsid d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 devid 1 transid 39 /dev/sda3 scanned by (udev-worker) (485) May 13 23:56:15.888304 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 13 23:56:15.889831 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 13 23:56:15.902004 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 13 23:56:15.933673 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 13 23:56:15.945673 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:15.949741 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:16.950743 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:16.950992 disk-uuid[638]: The operation has completed successfully. May 13 23:56:20.211711 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 23:56:20.211838 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 13 23:56:20.216236 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 13 23:56:20.247854 sh[725]: Success May 13 23:56:20.285747 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" May 13 23:56:20.568090 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 13 23:56:20.571377 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 13 23:56:20.592300 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 13 23:56:20.611190 kernel: BTRFS info (device dm-0): first mount of filesystem d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 May 13 23:56:20.611233 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:20.616048 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 13 23:56:20.619816 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 13 23:56:20.623032 kernel: BTRFS info (device dm-0): using free space tree May 13 23:56:21.180231 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 13 23:56:21.182403 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 13 23:56:21.183330 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 13 23:56:21.208460 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 13 23:56:21.240349 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:21.240404 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:21.244473 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:21.263756 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:21.271752 kernel: BTRFS info (device sda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:21.276465 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 13 23:56:21.278972 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 13 23:56:21.321522 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:56:21.333557 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:56:21.379773 systemd-networkd[906]: lo: Link UP May 13 23:56:21.379784 systemd-networkd[906]: lo: Gained carrier May 13 23:56:21.380896 systemd-networkd[906]: Enumeration completed May 13 23:56:21.381255 systemd-networkd[906]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:21.381259 systemd-networkd[906]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:21.382159 systemd-networkd[906]: eth0: Link UP May 13 23:56:21.382472 systemd-networkd[906]: eth0: Gained carrier May 13 23:56:21.382481 systemd-networkd[906]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:21.382611 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:56:21.389895 systemd[1]: Reached target network.target - Network. May 13 23:56:21.426767 systemd-networkd[906]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:56:22.845885 ignition[869]: Ignition 2.20.0 May 13 23:56:22.845898 ignition[869]: Stage: fetch-offline May 13 23:56:22.845952 ignition[869]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:22.845965 ignition[869]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:22.846080 ignition[869]: parsed url from cmdline: "" May 13 23:56:22.846084 ignition[869]: no config URL provided May 13 23:56:22.846090 ignition[869]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:56:22.846101 ignition[869]: no config at "/usr/lib/ignition/user.ign" May 13 23:56:22.846107 ignition[869]: failed to fetch config: resource requires networking May 13 23:56:22.846309 ignition[869]: Ignition finished successfully May 13 23:56:22.873707 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:56:22.881472 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 13 23:56:22.909605 ignition[919]: Ignition 2.20.0 May 13 23:56:22.909617 ignition[919]: Stage: fetch May 13 23:56:22.909784 ignition[919]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:22.909796 ignition[919]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:22.909900 ignition[919]: parsed url from cmdline: "" May 13 23:56:22.909905 ignition[919]: no config URL provided May 13 23:56:22.909911 ignition[919]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:56:22.909921 ignition[919]: no config at "/usr/lib/ignition/user.ign" May 13 23:56:22.909949 ignition[919]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 13 23:56:22.989626 ignition[919]: GET result: OK May 13 23:56:22.989677 ignition[919]: config has been read from IMDS userdata May 13 23:56:22.989689 ignition[919]: parsing config with SHA512: 61cfd5212aa969fd0b1d7558fc3f6c31a0f3ac425af324331bed64b13342a383f9e098cd08dc8bad070926ae8c725a5925c022f6d34f3388717634b6df30376d May 13 23:56:22.993570 unknown[919]: fetched base config from "system" May 13 23:56:22.993970 ignition[919]: fetch: fetch complete May 13 23:56:22.993579 unknown[919]: fetched base config from "system" May 13 23:56:22.993976 ignition[919]: fetch: fetch passed May 13 23:56:22.993586 unknown[919]: fetched user config from "azure" May 13 23:56:22.994032 ignition[919]: Ignition finished successfully May 13 23:56:23.000386 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 13 23:56:23.003551 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 13 23:56:23.045375 ignition[929]: Ignition 2.20.0 May 13 23:56:23.045388 ignition[929]: Stage: kargs May 13 23:56:23.045538 ignition[929]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:23.045549 ignition[929]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:23.046144 ignition[929]: kargs: kargs passed May 13 23:56:23.053941 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 13 23:56:23.046191 ignition[929]: Ignition finished successfully May 13 23:56:23.062539 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 13 23:56:23.098852 ignition[939]: Ignition 2.20.0 May 13 23:56:23.098865 ignition[939]: Stage: disks May 13 23:56:23.099013 ignition[939]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:23.099024 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:23.104379 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 13 23:56:23.099859 ignition[939]: disks: disks passed May 13 23:56:23.110824 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 13 23:56:23.099906 ignition[939]: Ignition finished successfully May 13 23:56:23.119120 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 13 23:56:23.127897 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:56:23.134980 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:56:23.136441 systemd[1]: Reached target basic.target - Basic System. May 13 23:56:23.148782 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 13 23:56:23.235275 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks May 13 23:56:23.240626 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 13 23:56:23.251089 systemd[1]: Mounting sysroot.mount - /sysroot... May 13 23:56:23.366784 kernel: EXT4-fs (sda9): mounted filesystem c413e98b-da35-46b1-9852-45706e1b1f52 r/w with ordered data mode. Quota mode: none. May 13 23:56:23.367852 systemd[1]: Mounted sysroot.mount - /sysroot. May 13 23:56:23.374684 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 13 23:56:23.402823 systemd-networkd[906]: eth0: Gained IPv6LL May 13 23:56:23.413745 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:56:23.416157 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 13 23:56:23.442277 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 13 23:56:23.443841 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 13 23:56:23.480496 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (962) May 13 23:56:23.480522 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:23.480543 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:23.480556 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:23.443874 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:56:23.459394 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 13 23:56:23.483244 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 13 23:56:23.503795 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:23.504968 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:56:24.407108 initrd-setup-root[987]: cut: /sysroot/etc/passwd: No such file or directory May 13 23:56:24.425613 initrd-setup-root[994]: cut: /sysroot/etc/group: No such file or directory May 13 23:56:24.440156 initrd-setup-root[1001]: cut: /sysroot/etc/shadow: No such file or directory May 13 23:56:24.455448 initrd-setup-root[1008]: cut: /sysroot/etc/gshadow: No such file or directory May 13 23:56:24.478458 coreos-metadata[964]: May 13 23:56:24.478 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 13 23:56:24.486944 coreos-metadata[964]: May 13 23:56:24.486 INFO Fetch successful May 13 23:56:24.490755 coreos-metadata[964]: May 13 23:56:24.487 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 13 23:56:24.498771 coreos-metadata[964]: May 13 23:56:24.494 INFO Fetch successful May 13 23:56:24.507068 coreos-metadata[964]: May 13 23:56:24.507 INFO wrote hostname ci-4284.0.0-n-ae86755abb to /sysroot/etc/hostname May 13 23:56:24.514840 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:56:25.788997 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 13 23:56:25.797772 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 13 23:56:25.800794 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 13 23:56:25.831903 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 13 23:56:25.840600 kernel: BTRFS info (device sda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:25.852918 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 13 23:56:25.864010 ignition[1082]: INFO : Ignition 2.20.0 May 13 23:56:25.864010 ignition[1082]: INFO : Stage: mount May 13 23:56:25.869709 ignition[1082]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:25.869709 ignition[1082]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:25.869709 ignition[1082]: INFO : mount: mount passed May 13 23:56:25.869709 ignition[1082]: INFO : Ignition finished successfully May 13 23:56:25.870678 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 13 23:56:25.872810 systemd[1]: Starting ignition-files.service - Ignition (files)... May 13 23:56:25.912365 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:56:25.930744 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1095) May 13 23:56:25.939735 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:25.939766 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:25.943288 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:25.948736 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:25.950091 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:56:25.978170 ignition[1112]: INFO : Ignition 2.20.0 May 13 23:56:25.978170 ignition[1112]: INFO : Stage: files May 13 23:56:25.984585 ignition[1112]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:25.984585 ignition[1112]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:25.984585 ignition[1112]: DEBUG : files: compiled without relabeling support, skipping May 13 23:56:25.997782 ignition[1112]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 13 23:56:25.997782 ignition[1112]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 13 23:56:26.136387 ignition[1112]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 13 23:56:26.141961 ignition[1112]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 13 23:56:26.147131 ignition[1112]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 13 23:56:26.142387 unknown[1112]: wrote ssh authorized keys file for user: core May 13 23:56:26.189412 ignition[1112]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:56:26.196731 ignition[1112]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:56:26.196731 ignition[1112]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 23:56:26.196731 ignition[1112]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 23:56:26.196731 ignition[1112]: INFO : files: files passed May 13 23:56:26.196731 ignition[1112]: INFO : Ignition finished successfully May 13 23:56:26.198042 systemd[1]: Finished ignition-files.service - Ignition (files). May 13 23:56:26.208830 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 13 23:56:26.231062 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 13 23:56:26.236778 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 23:56:26.236880 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 13 23:56:26.270121 initrd-setup-root-after-ignition[1145]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.270121 initrd-setup-root-after-ignition[1145]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.282631 initrd-setup-root-after-ignition[1149]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.289894 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:56:26.290884 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 13 23:56:26.305005 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 13 23:56:26.366985 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 23:56:26.367236 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 13 23:56:26.369566 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 13 23:56:26.388937 systemd[1]: Reached target initrd.target - Initrd Default Target. May 13 23:56:26.390597 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 13 23:56:26.391608 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 13 23:56:26.431601 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:56:26.434616 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 13 23:56:26.473633 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:26.475253 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:26.483718 systemd[1]: Stopped target timers.target - Timer Units. May 13 23:56:26.495669 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 23:56:26.495830 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:56:26.507337 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 13 23:56:26.515909 systemd[1]: Stopped target basic.target - Basic System. May 13 23:56:26.523466 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 13 23:56:26.531489 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:56:26.541054 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 13 23:56:26.550216 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 13 23:56:26.558590 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:56:26.567520 systemd[1]: Stopped target sysinit.target - System Initialization. May 13 23:56:26.577079 systemd[1]: Stopped target local-fs.target - Local File Systems. May 13 23:56:26.585049 systemd[1]: Stopped target swap.target - Swaps. May 13 23:56:26.591983 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 23:56:26.592147 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 13 23:56:26.601218 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:26.608194 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:26.616604 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 13 23:56:26.616754 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:26.626212 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 23:56:26.626374 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 13 23:56:26.642976 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 23:56:26.643121 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:56:26.651673 systemd[1]: ignition-files.service: Deactivated successfully. May 13 23:56:26.651806 systemd[1]: Stopped ignition-files.service - Ignition (files). May 13 23:56:26.659532 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 13 23:56:26.659654 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:56:26.669807 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 13 23:56:26.677358 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 23:56:26.677501 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:26.697338 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 13 23:56:26.702908 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 23:56:26.738440 ignition[1169]: INFO : Ignition 2.20.0 May 13 23:56:26.738440 ignition[1169]: INFO : Stage: umount May 13 23:56:26.738440 ignition[1169]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:26.738440 ignition[1169]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:26.738440 ignition[1169]: INFO : umount: umount passed May 13 23:56:26.738440 ignition[1169]: INFO : Ignition finished successfully May 13 23:56:26.703047 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:26.710447 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 23:56:26.710568 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:56:26.728965 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 23:56:26.729982 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 23:56:26.730082 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 13 23:56:26.734634 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 23:56:26.734732 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 13 23:56:26.740369 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 23:56:26.740438 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 13 23:56:26.747872 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 23:56:26.747923 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 13 23:56:26.754602 systemd[1]: ignition-fetch.service: Deactivated successfully. May 13 23:56:26.754665 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 13 23:56:26.762586 systemd[1]: Stopped target network.target - Network. May 13 23:56:26.771284 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 23:56:26.771360 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:56:26.778831 systemd[1]: Stopped target paths.target - Path Units. May 13 23:56:26.786846 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 23:56:26.790766 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:26.794633 systemd[1]: Stopped target slices.target - Slice Units. May 13 23:56:26.804024 systemd[1]: Stopped target sockets.target - Socket Units. May 13 23:56:26.811568 systemd[1]: iscsid.socket: Deactivated successfully. May 13 23:56:26.811618 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:56:26.818836 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 23:56:26.818879 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:56:26.825626 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 23:56:26.825685 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 13 23:56:26.836375 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 13 23:56:26.836422 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 13 23:56:26.844218 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 13 23:56:26.852534 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 13 23:56:26.860275 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 23:56:26.860368 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 13 23:56:26.868031 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 23:56:26.868185 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 13 23:56:26.879694 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 13 23:56:26.879959 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 23:56:26.880057 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 13 23:56:26.891641 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 13 23:56:26.892793 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 23:56:26.892840 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:26.894656 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 23:56:26.894718 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 13 23:56:26.904613 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 13 23:56:26.911575 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 23:56:26.911635 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:56:26.917609 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 23:56:26.917659 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:26.930762 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 23:56:26.930811 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 13 23:56:26.940016 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 13 23:56:26.940064 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:26.949084 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:26.950823 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 13 23:56:26.950896 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:26.983473 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 23:56:26.983658 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:26.987218 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 23:56:26.987265 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 13 23:56:26.995927 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 23:56:26.995968 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:27.004572 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 23:56:27.004627 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 13 23:56:27.013126 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 23:56:27.013180 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 13 23:56:27.030179 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:56:27.030234 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:27.056058 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 13 23:56:27.064590 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 13 23:56:27.064679 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:27.074235 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:27.074284 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:27.083049 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 13 23:56:27.083114 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:27.087907 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 23:56:27.088008 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 13 23:56:27.095104 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 23:56:27.095387 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 13 23:56:27.100214 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 13 23:56:27.109801 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 13 23:56:27.155245 systemd[1]: Switching root. May 13 23:56:27.342747 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). May 13 23:56:27.342811 systemd-journald[204]: Journal stopped May 13 23:56:32.994503 kernel: SELinux: policy capability network_peer_controls=1 May 13 23:56:32.994538 kernel: SELinux: policy capability open_perms=1 May 13 23:56:32.994551 kernel: SELinux: policy capability extended_socket_class=1 May 13 23:56:32.994560 kernel: SELinux: policy capability always_check_network=0 May 13 23:56:32.994568 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 23:56:32.994577 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 23:56:32.994587 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 23:56:32.994599 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 23:56:32.994609 kernel: audit: type=1403 audit(1747180588.066:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 13 23:56:32.994621 systemd[1]: Successfully loaded SELinux policy in 133.253ms. May 13 23:56:32.994632 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.195ms. May 13 23:56:32.994648 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) May 13 23:56:32.994658 systemd[1]: Detected virtualization microsoft. May 13 23:56:32.994669 systemd[1]: Detected architecture x86-64. May 13 23:56:32.994682 systemd[1]: Detected first boot. May 13 23:56:32.994694 systemd[1]: Hostname set to . May 13 23:56:32.994704 systemd[1]: Initializing machine ID from random generator. May 13 23:56:32.994715 zram_generator::config[1216]: No configuration found. May 13 23:56:32.994737 kernel: Guest personality initialized and is inactive May 13 23:56:32.994751 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 13 23:56:32.994761 kernel: Initialized host personality May 13 23:56:32.994771 kernel: NET: Registered PF_VSOCK protocol family May 13 23:56:32.994781 systemd[1]: Populated /etc with preset unit settings. May 13 23:56:32.994793 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 13 23:56:32.994803 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 13 23:56:32.994814 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 13 23:56:32.994849 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 13 23:56:32.994892 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 13 23:56:32.994910 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 13 23:56:32.994921 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 13 23:56:32.994932 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 13 23:56:32.994943 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 13 23:56:32.994958 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 13 23:56:32.994969 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 13 23:56:32.994983 systemd[1]: Created slice user.slice - User and Session Slice. May 13 23:56:32.994993 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:32.995004 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:32.995015 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 13 23:56:32.995026 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 13 23:56:32.995037 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 13 23:56:32.995053 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:56:32.995064 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 13 23:56:32.995076 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:32.995089 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 13 23:56:32.995100 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 13 23:56:32.995111 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 13 23:56:32.995122 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 13 23:56:32.995133 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:32.995143 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:56:32.995154 systemd[1]: Reached target slices.target - Slice Units. May 13 23:56:32.995168 systemd[1]: Reached target swap.target - Swaps. May 13 23:56:32.995179 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 13 23:56:32.995190 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 13 23:56:32.995204 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 13 23:56:32.995216 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:32.995230 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:56:32.995242 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:32.995253 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 13 23:56:32.995264 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 13 23:56:32.995275 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 13 23:56:32.995286 systemd[1]: Mounting media.mount - External Media Directory... May 13 23:56:32.995298 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:32.995309 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 13 23:56:32.995323 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 13 23:56:32.995334 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 13 23:56:32.995346 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). May 13 23:56:32.995357 systemd[1]: Reached target machines.target - Containers. May 13 23:56:32.995368 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 13 23:56:32.995379 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:32.995390 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:56:32.995402 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 13 23:56:32.995415 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:32.995426 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:56:32.995437 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:32.995451 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 13 23:56:32.995463 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:32.995474 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 13 23:56:32.995485 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 13 23:56:32.995496 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 13 23:56:32.995507 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 13 23:56:32.995521 systemd[1]: Stopped systemd-fsck-usr.service. May 13 23:56:32.995533 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). May 13 23:56:32.995545 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:56:32.995556 kernel: loop: module loaded May 13 23:56:32.995567 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:56:32.995578 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 13 23:56:32.995589 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 13 23:56:32.995602 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 13 23:56:32.995614 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:56:32.995625 systemd[1]: verity-setup.service: Deactivated successfully. May 13 23:56:32.995636 systemd[1]: Stopped verity-setup.service. May 13 23:56:32.995647 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:32.995658 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 13 23:56:32.995669 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 13 23:56:32.995680 systemd[1]: Mounted media.mount - External Media Directory. May 13 23:56:32.995698 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 13 23:56:32.995709 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 13 23:56:32.995754 systemd-journald[1323]: Collecting audit messages is disabled. May 13 23:56:32.995780 kernel: ACPI: bus type drm_connector registered May 13 23:56:32.995795 systemd-journald[1323]: Journal started May 13 23:56:32.995818 systemd-journald[1323]: Runtime Journal (/run/log/journal/de3e88704b894ed2925c55130b0b41da) is 8M, max 2.2G, 2.1G free. May 13 23:56:32.238461 systemd[1]: Queued start job for default target multi-user.target. May 13 23:56:32.254837 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 13 23:56:32.255321 systemd[1]: systemd-journald.service: Deactivated successfully. May 13 23:56:33.002314 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:56:33.005903 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 13 23:56:33.007734 kernel: fuse: init (API version 7.39) May 13 23:56:33.012027 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 13 23:56:33.017428 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:33.023039 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 23:56:33.023234 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 13 23:56:33.028371 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:33.028582 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:33.034108 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:56:33.034297 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:56:33.039294 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:33.039487 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:33.045394 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 23:56:33.045596 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 13 23:56:33.050425 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:33.050621 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:33.055274 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:56:33.060758 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 13 23:56:33.067149 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 13 23:56:33.075168 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 13 23:56:33.080676 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:33.095654 systemd[1]: Reached target network-pre.target - Preparation for Network. May 13 23:56:33.101599 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 13 23:56:33.116310 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 13 23:56:33.120945 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 23:56:33.120984 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:56:33.125966 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 13 23:56:33.132937 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 13 23:56:33.138697 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 13 23:56:33.142893 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:33.144268 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 13 23:56:33.149579 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 13 23:56:33.151095 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:56:33.152012 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 13 23:56:33.159828 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:56:33.161136 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:56:33.166344 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 13 23:56:33.172303 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 13 23:56:33.177311 systemd-journald[1323]: Time spent on flushing to /var/log/journal/de3e88704b894ed2925c55130b0b41da is 14.039ms for 935 entries. May 13 23:56:33.177311 systemd-journald[1323]: System Journal (/var/log/journal/de3e88704b894ed2925c55130b0b41da) is 8M, max 2.6G, 2.6G free. May 13 23:56:33.227165 systemd-journald[1323]: Received client request to flush runtime journal. May 13 23:56:33.177399 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 13 23:56:33.189656 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 13 23:56:33.194795 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 13 23:56:33.200346 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 13 23:56:33.205965 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 13 23:56:33.215947 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 13 23:56:33.226399 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 13 23:56:33.231592 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 13 23:56:33.238021 udevadm[1362]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 13 23:56:33.265749 kernel: loop0: detected capacity change from 0 to 151640 May 13 23:56:33.290188 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 23:56:33.290938 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 13 23:56:33.374234 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:33.632153 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 13 23:56:33.638108 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:56:33.701149 systemd-tmpfiles[1376]: ACLs are not supported, ignoring. May 13 23:56:33.701172 systemd-tmpfiles[1376]: ACLs are not supported, ignoring. May 13 23:56:33.706883 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:33.986749 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 23:56:34.031762 kernel: loop1: detected capacity change from 0 to 28424 May 13 23:56:34.347500 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 13 23:56:34.354064 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:34.392821 systemd-udevd[1382]: Using default interface naming scheme 'v255'. May 13 23:56:34.728759 kernel: loop2: detected capacity change from 0 to 109808 May 13 23:56:34.740050 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:34.749961 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:56:34.766360 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 13 23:56:34.834754 kernel: hv_vmbus: registering driver hyperv_fb May 13 23:56:34.834847 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 13 23:56:34.841806 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 13 23:56:34.843790 kernel: mousedev: PS/2 mouse device common for all mice May 13 23:56:34.846744 kernel: Console: switching to colour dummy device 80x25 May 13 23:56:34.848693 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 13 23:56:34.854169 kernel: Console: switching to colour frame buffer device 128x48 May 13 23:56:34.876745 kernel: hv_vmbus: registering driver hv_balloon May 13 23:56:34.880743 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 13 23:56:34.889519 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:34.900257 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:34.900461 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:34.907859 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:34.924444 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 13 23:56:34.949737 kernel: EDAC MC: Ver: 3.0.0 May 13 23:56:34.969789 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1395) May 13 23:56:35.038363 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 13 23:56:35.041310 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 13 23:56:35.066676 systemd-networkd[1399]: lo: Link UP May 13 23:56:35.066685 systemd-networkd[1399]: lo: Gained carrier May 13 23:56:35.068192 systemd-networkd[1399]: Enumeration completed May 13 23:56:35.068297 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:56:35.068584 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:35.068594 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:35.069359 systemd-networkd[1399]: eth0: Link UP May 13 23:56:35.069369 systemd-networkd[1399]: eth0: Gained carrier May 13 23:56:35.069383 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:35.071081 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 13 23:56:35.072176 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 13 23:56:35.110769 systemd-networkd[1399]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:56:35.114825 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 13 23:56:35.188800 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 13 23:56:35.191780 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 13 23:56:35.215213 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 13 23:56:35.254743 kernel: loop3: detected capacity change from 0 to 151640 May 13 23:56:35.270744 kernel: loop4: detected capacity change from 0 to 28424 May 13 23:56:35.283744 kernel: loop5: detected capacity change from 0 to 109808 May 13 23:56:35.290355 lvm[1515]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:56:35.294663 (sd-merge)[1517]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 13 23:56:35.295200 (sd-merge)[1517]: Merged extensions into '/usr'. May 13 23:56:35.299474 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 13 23:56:35.302570 systemd[1]: Starting ensure-sysext.service... May 13 23:56:35.304015 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:56:35.335394 systemd[1]: Reload requested from client PID 1519 ('systemctl') (unit ensure-sysext.service)... May 13 23:56:35.335412 systemd[1]: Reloading... May 13 23:56:35.374786 zram_generator::config[1553]: No configuration found. May 13 23:56:35.375272 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 23:56:35.375563 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 13 23:56:35.376474 systemd-tmpfiles[1520]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 23:56:35.376775 systemd-tmpfiles[1520]: ACLs are not supported, ignoring. May 13 23:56:35.376846 systemd-tmpfiles[1520]: ACLs are not supported, ignoring. May 13 23:56:35.383229 systemd-tmpfiles[1520]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:56:35.383245 systemd-tmpfiles[1520]: Skipping /boot May 13 23:56:35.395295 systemd-tmpfiles[1520]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:56:35.395311 systemd-tmpfiles[1520]: Skipping /boot May 13 23:56:35.513362 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. May 13 23:56:35.647350 systemd[1]: Reloading finished in 311 ms. May 13 23:56:35.670758 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:35.675961 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 13 23:56:35.700295 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:35.721086 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:35.742666 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.744316 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:56:35.767983 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 13 23:56:35.773223 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:35.774521 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 13 23:56:35.782117 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:35.788310 lvm[1621]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:56:35.790624 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:35.796875 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:35.800710 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:35.800854 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). May 13 23:56:35.803306 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 13 23:56:35.809850 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:56:35.815952 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 13 23:56:35.821873 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.824536 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:35.824825 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:35.829993 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:35.830184 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:35.837004 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:35.837190 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:35.842390 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 13 23:56:35.865243 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 13 23:56:35.874493 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 13 23:56:35.884093 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.884375 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:35.885757 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:35.908549 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:56:35.913825 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:35.920523 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:35.924982 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:35.925072 augenrules[1658]: No rules May 13 23:56:35.925314 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). May 13 23:56:35.925502 systemd[1]: Reached target time-set.target - System Time Set. May 13 23:56:35.929935 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.931695 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:56:35.932150 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:56:35.936678 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:35.936883 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:35.942151 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:56:35.942338 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:56:35.947189 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:35.947367 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:35.952715 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:35.952917 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:35.960796 systemd[1]: Finished ensure-sysext.service. May 13 23:56:35.967165 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:56:35.967228 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:56:36.033748 systemd-resolved[1631]: Positive Trust Anchors: May 13 23:56:36.033767 systemd-resolved[1631]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:56:36.033799 systemd-resolved[1631]: 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 May 13 23:56:36.064832 systemd-resolved[1631]: Using system hostname 'ci-4284.0.0-n-ae86755abb'. May 13 23:56:36.067502 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:56:36.072199 systemd[1]: Reached target network.target - Network. May 13 23:56:36.075871 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:36.386912 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 13 23:56:36.392326 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). May 13 23:56:36.906876 systemd-networkd[1399]: eth0: Gained IPv6LL May 13 23:56:36.909280 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 13 23:56:36.914663 systemd[1]: Reached target network-online.target - Network is Online. May 13 23:56:40.527242 ldconfig[1354]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 23:56:40.548758 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 13 23:56:40.555830 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 13 23:56:40.589325 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 13 23:56:40.594584 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:56:40.599028 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 13 23:56:40.604775 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 13 23:56:40.610064 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 13 23:56:40.614504 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 13 23:56:40.619704 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 13 23:56:40.624695 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 13 23:56:40.624753 systemd[1]: Reached target paths.target - Path Units. May 13 23:56:40.628455 systemd[1]: Reached target timers.target - Timer Units. May 13 23:56:40.633463 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 13 23:56:40.639250 systemd[1]: Starting docker.socket - Docker Socket for the API... May 13 23:56:40.645837 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 13 23:56:40.651149 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 13 23:56:40.656118 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 13 23:56:40.663557 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 13 23:56:40.668003 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 13 23:56:40.673486 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 13 23:56:40.677890 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:56:40.682055 systemd[1]: Reached target basic.target - Basic System. May 13 23:56:40.685693 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 13 23:56:40.685758 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 13 23:56:40.688547 systemd[1]: Starting chronyd.service - NTP client/server... May 13 23:56:40.694033 systemd[1]: Starting containerd.service - containerd container runtime... May 13 23:56:40.712158 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 13 23:56:40.717399 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 13 23:56:40.721939 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 13 23:56:40.727095 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 13 23:56:40.731090 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). May 13 23:56:40.731133 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). May 13 23:56:40.732097 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. May 13 23:56:40.736351 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). May 13 23:56:40.737406 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 13 23:56:40.738852 KVP[1685]: KVP starting; pid is:1685 May 13 23:56:40.742308 jq[1680]: false May 13 23:56:40.742470 KVP[1685]: KVP LIC Version: 3.1 May 13 23:56:40.743740 kernel: hv_utils: KVP IC version 4.0 May 13 23:56:40.744335 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 13 23:56:40.749923 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 13 23:56:40.756380 (chronyd)[1676]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 13 23:56:40.756556 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 13 23:56:40.763519 systemd[1]: Starting systemd-logind.service - User Login Management... May 13 23:56:40.765742 chronyd[1697]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 13 23:56:40.768642 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 23:56:40.769413 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 13 23:56:40.770157 systemd[1]: Starting update-engine.service - Update Engine... May 13 23:56:40.772654 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 13 23:56:40.773046 extend-filesystems[1684]: Found loop3 May 13 23:56:40.781158 extend-filesystems[1684]: Found loop4 May 13 23:56:40.781158 extend-filesystems[1684]: Found loop5 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda May 13 23:56:40.781158 extend-filesystems[1684]: Found sda1 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda2 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda3 May 13 23:56:40.781158 extend-filesystems[1684]: Found usr May 13 23:56:40.781158 extend-filesystems[1684]: Found sda4 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda6 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda7 May 13 23:56:40.781158 extend-filesystems[1684]: Found sda9 May 13 23:56:40.781158 extend-filesystems[1684]: Checking size of /dev/sda9 May 13 23:56:40.834168 extend-filesystems[1684]: Old size kept for /dev/sda9 May 13 23:56:40.834168 extend-filesystems[1684]: Found sdb May 13 23:56:40.834168 extend-filesystems[1684]: Found sdb1 May 13 23:56:40.834168 extend-filesystems[1684]: Found sr0 May 13 23:56:40.823077 chronyd[1697]: Timezone right/UTC failed leap second check, ignoring May 13 23:56:40.797287 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 23:56:40.823244 chronyd[1697]: Loaded seccomp filter (level 2) May 13 23:56:40.797517 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 13 23:56:40.797879 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 23:56:40.847041 jq[1700]: true May 13 23:56:40.798070 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 13 23:56:40.847239 jq[1705]: true May 13 23:56:40.807043 systemd[1]: motdgen.service: Deactivated successfully. May 13 23:56:40.807281 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 13 23:56:40.815352 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 23:56:40.815573 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 13 23:56:40.824233 systemd[1]: Started chronyd.service - NTP client/server. May 13 23:56:40.838285 (ntainerd)[1719]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 13 23:56:40.859383 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1739) May 13 23:56:40.871739 update_engine[1699]: I20250513 23:56:40.870616 1699 main.cc:92] Flatcar Update Engine starting May 13 23:56:40.894380 dbus-daemon[1679]: [system] SELinux support is enabled May 13 23:56:40.897810 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 13 23:56:40.909229 update_engine[1699]: I20250513 23:56:40.897961 1699 update_check_scheduler.cc:74] Next update check in 6m49s May 13 23:56:40.911966 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). May 13 23:56:40.912023 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 13 23:56:40.918347 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). May 13 23:56:40.918385 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 13 23:56:40.924434 systemd[1]: Started update-engine.service - Update Engine. May 13 23:56:40.929767 systemd-logind[1694]: New seat seat0. May 13 23:56:40.930422 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 13 23:56:40.932408 systemd-logind[1694]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 13 23:56:40.934633 systemd[1]: Started systemd-logind.service - User Login Management. May 13 23:56:40.950181 sshd_keygen[1703]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 23:56:40.975637 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 13 23:56:40.981446 setup-nvidia[1688]: Downloading Flatcar Container Linux Developer Container for version: 4284.0.0+nightly-20250513-2100 May 13 23:56:40.983700 systemd[1]: Starting issuegen.service - Generate /run/issue... May 13 23:56:40.988286 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 13 23:56:40.989105 bash[1756]: Updated "/home/core/.ssh/authorized_keys" May 13 23:56:40.995962 coreos-metadata[1678]: May 13 23:56:40.993 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 13 23:56:40.995962 coreos-metadata[1678]: May 13 23:56:40.995 INFO Fetch successful May 13 23:56:40.997695 coreos-metadata[1678]: May 13 23:56:40.995 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 13 23:56:40.996072 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 13 23:56:41.000209 coreos-metadata[1678]: May 13 23:56:41.000 INFO Fetch successful May 13 23:56:41.000511 coreos-metadata[1678]: May 13 23:56:41.000 INFO Fetching http://168.63.129.16/machine/e3135437-809d-47de-b350-e2fcd201f350/e3e6be20%2D0c19%2D4f14%2D8143%2D1f4f5a8b8929.%5Fci%2D4284.0.0%2Dn%2Dae86755abb?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 13 23:56:41.003673 systemd[1]: issuegen.service: Deactivated successfully. May 13 23:56:41.003928 systemd[1]: Finished issuegen.service - Generate /run/issue. May 13 23:56:41.012625 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 13 23:56:41.018829 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 13 23:56:41.020180 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 13 23:56:41.036896 coreos-metadata[1678]: May 13 23:56:41.036 INFO Fetch successful May 13 23:56:41.037009 coreos-metadata[1678]: May 13 23:56:41.036 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 13 23:56:41.044692 coreos-metadata[1678]: May 13 23:56:41.044 INFO Fetch successful May 13 23:56:41.051558 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 13 23:56:41.063415 systemd[1]: Started getty@tty1.service - Getty on tty1. May 13 23:56:41.069231 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 13 23:56:41.073905 systemd[1]: Reached target getty.target - Login Prompts. May 13 23:56:41.094951 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 13 23:56:41.099790 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 13 23:56:41.135384 locksmithd[1800]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 23:56:41.688385 setup-nvidia[1849]: % Total % Received % Xferd Average Speed Time Time Time Current May 13 23:56:41.688787 setup-nvidia[1849]: Dload Upload Total Spent Left Speed May 13 23:56:41.994774 containerd[1719]: time="2025-05-13T23:56:41Z" 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 May 13 23:56:41.995790 containerd[1719]: time="2025-05-13T23:56:41.995759300Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 May 13 23:56:42.003899 containerd[1719]: time="2025-05-13T23:56:42.003793200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.8µs" May 13 23:56:42.003899 containerd[1719]: time="2025-05-13T23:56:42.003848900Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 13 23:56:42.003899 containerd[1719]: time="2025-05-13T23:56:42.003880000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 13 23:56:42.004082 containerd[1719]: time="2025-05-13T23:56:42.004060100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 13 23:56:42.004126 containerd[1719]: time="2025-05-13T23:56:42.004110600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 13 23:56:42.004172 containerd[1719]: time="2025-05-13T23:56:42.004147200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:56:42.004253 containerd[1719]: time="2025-05-13T23:56:42.004226900Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:56:42.004286 containerd[1719]: time="2025-05-13T23:56:42.004252400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:56:42.004663 containerd[1719]: time="2025-05-13T23:56:42.004630800Z" 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 May 13 23:56:42.004698 containerd[1719]: time="2025-05-13T23:56:42.004661800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:56:42.004698 containerd[1719]: time="2025-05-13T23:56:42.004677200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:56:42.004698 containerd[1719]: time="2025-05-13T23:56:42.004686900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 13 23:56:42.004820 containerd[1719]: time="2025-05-13T23:56:42.004796800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 13 23:56:42.005045 containerd[1719]: time="2025-05-13T23:56:42.005022100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:56:42.005079 containerd[1719]: time="2025-05-13T23:56:42.005061500Z" 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 May 13 23:56:42.005079 containerd[1719]: time="2025-05-13T23:56:42.005073700Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 13 23:56:42.005121 containerd[1719]: time="2025-05-13T23:56:42.005102100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 13 23:56:42.005351 containerd[1719]: time="2025-05-13T23:56:42.005326300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 13 23:56:42.005421 containerd[1719]: time="2025-05-13T23:56:42.005401000Z" level=info msg="metadata content store policy set" policy=shared May 13 23:56:42.018614 containerd[1719]: time="2025-05-13T23:56:42.018575100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 13 23:56:42.018681 containerd[1719]: time="2025-05-13T23:56:42.018623800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 13 23:56:42.018681 containerd[1719]: time="2025-05-13T23:56:42.018641400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 13 23:56:42.018681 containerd[1719]: time="2025-05-13T23:56:42.018656700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 13 23:56:42.018681 containerd[1719]: time="2025-05-13T23:56:42.018671800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018683500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018697100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018716800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018746400Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018762100Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018775800Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 13 23:56:42.018790 containerd[1719]: time="2025-05-13T23:56:42.018789100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 13 23:56:42.018987 containerd[1719]: time="2025-05-13T23:56:42.018932700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 13 23:56:42.018987 containerd[1719]: time="2025-05-13T23:56:42.018957000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 13 23:56:42.018987 containerd[1719]: time="2025-05-13T23:56:42.018970600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 13 23:56:42.018987 containerd[1719]: time="2025-05-13T23:56:42.018983700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.018995800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019008100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019020500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019032600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019045400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019056800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 13 23:56:42.019081 containerd[1719]: time="2025-05-13T23:56:42.019067300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 13 23:56:42.019232 containerd[1719]: time="2025-05-13T23:56:42.019130400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 13 23:56:42.019232 containerd[1719]: time="2025-05-13T23:56:42.019182400Z" level=info msg="Start snapshots syncer" May 13 23:56:42.019232 containerd[1719]: time="2025-05-13T23:56:42.019210300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 13 23:56:42.019512 containerd[1719]: time="2025-05-13T23:56:42.019468400Z" 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\"}" May 13 23:56:42.019635 containerd[1719]: time="2025-05-13T23:56:42.019533100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 13 23:56:42.019635 containerd[1719]: time="2025-05-13T23:56:42.019619100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 13 23:56:42.019789 containerd[1719]: time="2025-05-13T23:56:42.019765400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 13 23:56:42.019822 containerd[1719]: time="2025-05-13T23:56:42.019796700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 13 23:56:42.019822 containerd[1719]: time="2025-05-13T23:56:42.019810000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 13 23:56:42.019877 containerd[1719]: time="2025-05-13T23:56:42.019822300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 13 23:56:42.019877 containerd[1719]: time="2025-05-13T23:56:42.019837400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 13 23:56:42.019877 containerd[1719]: time="2025-05-13T23:56:42.019848400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 13 23:56:42.019877 containerd[1719]: time="2025-05-13T23:56:42.019859700Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019883500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019897000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019908000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019941400Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019956700Z" 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 May 13 23:56:42.019970 containerd[1719]: time="2025-05-13T23:56:42.019965700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.019975500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.019984500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.019994700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.020006800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.020024700Z" level=info msg="runtime interface created" May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.020031100Z" level=info msg="created NRI interface" May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.020040000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 13 23:56:42.020089 containerd[1719]: time="2025-05-13T23:56:42.020051500Z" level=info msg="Connect containerd service" May 13 23:56:42.020248 containerd[1719]: time="2025-05-13T23:56:42.020077700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 13 23:56:42.020840 containerd[1719]: time="2025-05-13T23:56:42.020806300Z" 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" May 13 23:56:43.366433 containerd[1719]: time="2025-05-13T23:56:43.366384900Z" level=info msg="Start subscribing containerd event" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366439100Z" level=info msg="Start recovering state" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366545600Z" level=info msg="Start event monitor" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366563500Z" level=info msg="Start cni network conf syncer for default" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366572800Z" level=info msg="Start streaming server" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366581200Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366588500Z" level=info msg="runtime interface starting up..." May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366595800Z" level=info msg="starting plugins..." May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366610500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366614800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366705900Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 23:56:43.367001 containerd[1719]: time="2025-05-13T23:56:43.366794800Z" level=info msg="containerd successfully booted in 1.372846s" May 13 23:56:43.366906 systemd[1]: Started containerd.service - containerd container runtime. May 13 23:56:43.867295 waagent[1828]: 2025-05-13T23:56:43.867170Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 13 23:56:43.871606 waagent[1828]: 2025-05-13T23:56:43.871557Z INFO Daemon Daemon OS: flatcar 4284.0.0 May 13 23:56:43.874942 waagent[1828]: 2025-05-13T23:56:43.874895Z INFO Daemon Daemon Python: 3.11.11 May 13 23:56:43.878268 waagent[1828]: 2025-05-13T23:56:43.878222Z INFO Daemon Daemon Run daemon May 13 23:56:43.881231 waagent[1828]: 2025-05-13T23:56:43.881188Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4284.0.0' May 13 23:56:43.887758 waagent[1828]: 2025-05-13T23:56:43.887698Z INFO Daemon Daemon Using waagent for provisioning May 13 23:56:43.891729 waagent[1828]: 2025-05-13T23:56:43.891679Z INFO Daemon Daemon Activate resource disk May 13 23:56:43.895254 waagent[1828]: 2025-05-13T23:56:43.895207Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 13 23:56:43.905696 waagent[1828]: 2025-05-13T23:56:43.905652Z INFO Daemon Daemon Found device: sdb May 13 23:56:43.914665 waagent[1828]: 2025-05-13T23:56:43.914606Z INFO Daemon Daemon Examining partition table May 13 23:56:43.956477 waagent[1828]: 2025-05-13T23:56:43.956396Z INFO Daemon Daemon GPT not detected, determining filesystem May 13 23:56:44.018828 waagent[1828]: 2025-05-13T23:56:44.018750Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded May 13 23:56:44.023418 waagent[1828]: 2025-05-13T23:56:44.023373Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 May 13 23:56:44.539746 kernel: sdb: sdb1 May 13 23:56:44.563018 waagent[1828]: 2025-05-13T23:56:44.562942Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded May 13 23:56:44.578748 kernel: sdb: sdb1 May 13 23:56:44.578948 waagent[1828]: 2025-05-13T23:56:44.578889Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] May 13 23:56:48.292452 login[1836]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 13 23:56:48.340255 login[1835]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 13 23:56:48.351582 systemd-logind[1694]: New session 1 of user core. May 13 23:56:48.352603 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 13 23:56:48.353690 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 13 23:56:48.459224 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 13 23:56:48.461482 systemd[1]: Starting user@500.service - User Manager for UID 500... May 13 23:56:48.545161 (systemd)[1938]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 23:56:48.547391 systemd-logind[1694]: New session c1 of user core. May 13 23:56:49.294382 login[1836]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 13 23:56:49.299035 systemd-logind[1694]: New session 2 of user core. May 13 23:56:50.024601 systemd[1938]: Queued start job for default target default.target. May 13 23:56:50.039813 systemd[1938]: Created slice app.slice - User Application Slice. May 13 23:56:50.039842 systemd[1938]: Reached target paths.target - Paths. May 13 23:56:50.039890 systemd[1938]: Reached target timers.target - Timers. May 13 23:56:50.041341 systemd[1938]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:56:50.052286 systemd[1938]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:56:50.052410 systemd[1938]: Reached target sockets.target - Sockets. May 13 23:56:50.052464 systemd[1938]: Reached target basic.target - Basic System. May 13 23:56:50.052508 systemd[1938]: Reached target default.target - Main User Target. May 13 23:56:50.052538 systemd[1938]: Startup finished in 1.497s. May 13 23:56:50.052621 systemd[1]: Started user@500.service - User Manager for UID 500. May 13 23:56:50.054075 systemd[1]: Started session-1.scope - Session 1 of User core. May 13 23:56:50.054814 systemd[1]: Started session-2.scope - Session 2 of User core. May 13 23:56:50.193170 waagent[1828]: 2025-05-13T23:56:50.193091Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] May 13 23:56:50.213747 kernel: EXT4-fs (sdb1): mounted filesystem 3b93ea50-3bec-4f92-8684-c0cb31ff1317 r/w with ordered data mode. Quota mode: none. May 13 23:56:50.215488 waagent[1828]: 2025-05-13T23:56:50.215422Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 May 13 23:56:50.216904 waagent[1828]: 2025-05-13T23:56:50.216858Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 13 23:56:50.217092 waagent[1828]: 2025-05-13T23:56:50.217056Z INFO Daemon Daemon Running default provisioning handler May 13 23:56:50.237322 waagent[1828]: 2025-05-13T23:56:50.237244Z 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. May 13 23:56:50.247195 waagent[1828]: 2025-05-13T23:56:50.247145Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 13 23:56:50.249064 waagent[1828]: 2025-05-13T23:56:50.249014Z INFO Daemon Daemon cloud-init is enabled: False May 13 23:56:50.249775 waagent[1828]: 2025-05-13T23:56:50.249740Z INFO Daemon Daemon Copying ovf-env.xml May 13 23:56:50.410151 waagent[1828]: 2025-05-13T23:56:50.410064Z INFO Daemon Daemon Successfully mounted dvd May 13 23:56:50.438991 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 13 23:56:50.439155 waagent[1828]: 2025-05-13T23:56:50.438981Z INFO Daemon Daemon Detect protocol endpoint May 13 23:56:50.442799 waagent[1828]: 2025-05-13T23:56:50.442637Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 13 23:56:50.445846 waagent[1828]: 2025-05-13T23:56:50.445788Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 13 23:56:50.447374 waagent[1828]: 2025-05-13T23:56:50.447331Z INFO Daemon Daemon Test for route to 168.63.129.16 May 13 23:56:50.449018 waagent[1828]: 2025-05-13T23:56:50.448978Z INFO Daemon Daemon Route to 168.63.129.16 exists May 13 23:56:50.449660 waagent[1828]: 2025-05-13T23:56:50.449624Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 13 23:56:50.461205 waagent[1828]: 2025-05-13T23:56:50.461154Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 13 23:56:50.462901 waagent[1828]: 2025-05-13T23:56:50.462873Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 13 23:56:50.463485 waagent[1828]: 2025-05-13T23:56:50.463451Z INFO Daemon Daemon Server preferred version:2015-04-05 May 13 23:56:50.577050 waagent[1828]: 2025-05-13T23:56:50.576953Z INFO Daemon Daemon Initializing goal state during protocol detection May 13 23:56:50.582148 waagent[1828]: 2025-05-13T23:56:50.582095Z INFO Daemon Daemon Forcing an update of the goal state. May 13 23:56:50.586870 waagent[1828]: 2025-05-13T23:56:50.586819Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 13 23:56:50.620739 waagent[1828]: 2025-05-13T23:56:50.620668Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 13 23:56:50.622940 waagent[1828]: 2025-05-13T23:56:50.622892Z INFO Daemon May 13 23:56:50.623171 waagent[1828]: 2025-05-13T23:56:50.623130Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 437b086c-a251-41f9-8de2-35fb5d97993e eTag: 2381843377357121049 source: Fabric] May 13 23:56:50.624893 waagent[1828]: 2025-05-13T23:56:50.624839Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 13 23:56:50.626449 waagent[1828]: 2025-05-13T23:56:50.626401Z INFO Daemon May 13 23:56:50.627631 waagent[1828]: 2025-05-13T23:56:50.627595Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 13 23:56:50.632885 waagent[1828]: 2025-05-13T23:56:50.632848Z INFO Daemon Daemon Downloading artifacts profile blob May 13 23:56:50.781698 waagent[1828]: 2025-05-13T23:56:50.781615Z INFO Daemon Downloaded certificate {'thumbprint': 'F56FBEC571DB38DC6C6826F13DE77EF1B4FF434C', 'hasPrivateKey': False} May 13 23:56:50.783566 waagent[1828]: 2025-05-13T23:56:50.783515Z INFO Daemon Downloaded certificate {'thumbprint': '6261ABF1ADBCD1D642762E6667CA7E4AFC11A01F', 'hasPrivateKey': True} May 13 23:56:50.785037 waagent[1828]: 2025-05-13T23:56:50.784987Z INFO Daemon Fetch goal state completed May 13 23:56:50.824085 waagent[1828]: 2025-05-13T23:56:50.824026Z INFO Daemon Daemon Starting provisioning May 13 23:56:50.827798 waagent[1828]: 2025-05-13T23:56:50.827488Z INFO Daemon Daemon Handle ovf-env.xml. May 13 23:56:50.830935 waagent[1828]: 2025-05-13T23:56:50.830876Z INFO Daemon Daemon Set hostname [ci-4284.0.0-n-ae86755abb] May 13 23:56:50.906196 waagent[1828]: 2025-05-13T23:56:50.906121Z INFO Daemon Daemon Publish hostname [ci-4284.0.0-n-ae86755abb] May 13 23:56:50.908098 waagent[1828]: 2025-05-13T23:56:50.908049Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 13 23:56:50.908786 waagent[1828]: 2025-05-13T23:56:50.908734Z INFO Daemon Daemon Primary interface is [eth0] May 13 23:56:50.926791 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:50.926803 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:50.926834 systemd-networkd[1399]: eth0: DHCP lease lost May 13 23:56:50.927237 waagent[1828]: 2025-05-13T23:56:50.927169Z INFO Daemon Daemon Create user account if not exists May 13 23:56:50.928880 waagent[1828]: 2025-05-13T23:56:50.928819Z INFO Daemon Daemon User core already exists, skip useradd May 13 23:56:50.929540 waagent[1828]: 2025-05-13T23:56:50.929504Z INFO Daemon Daemon Configure sudoer May 13 23:56:50.933755 waagent[1828]: 2025-05-13T23:56:50.933686Z INFO Daemon Daemon Configure sshd May 13 23:56:50.934377 waagent[1828]: 2025-05-13T23:56:50.934334Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 13 23:56:50.935649 waagent[1828]: 2025-05-13T23:56:50.935611Z INFO Daemon Daemon Deploy ssh public key. May 13 23:56:51.004763 systemd-networkd[1399]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:56:52.135433 waagent[1828]: 2025-05-13T23:56:52.135373Z INFO Daemon Daemon Provisioning complete May 13 23:56:52.145729 waagent[1828]: 2025-05-13T23:56:52.145674Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 13 23:56:52.158594 waagent[1828]: 2025-05-13T23:56:52.158521Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 13 23:56:52.160146 waagent[1828]: 2025-05-13T23:56:52.160092Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 13 23:56:52.353507 waagent[2000]: 2025-05-13T23:56:52.353407Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 13 23:56:52.353888 waagent[2000]: 2025-05-13T23:56:52.353572Z INFO ExtHandler ExtHandler OS: flatcar 4284.0.0 May 13 23:56:52.353888 waagent[2000]: 2025-05-13T23:56:52.353642Z INFO ExtHandler ExtHandler Python: 3.11.11 May 13 23:56:52.353888 waagent[2000]: 2025-05-13T23:56:52.353710Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 13 23:56:52.452867 waagent[2000]: 2025-05-13T23:56:52.452716Z 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; May 13 23:56:52.453027 waagent[2000]: 2025-05-13T23:56:52.452989Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:56:52.453104 waagent[2000]: 2025-05-13T23:56:52.453073Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:56:52.461844 waagent[2000]: 2025-05-13T23:56:52.461779Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 13 23:56:52.466898 waagent[2000]: 2025-05-13T23:56:52.466853Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 13 23:56:52.467332 waagent[2000]: 2025-05-13T23:56:52.467293Z INFO ExtHandler May 13 23:56:52.467410 waagent[2000]: 2025-05-13T23:56:52.467378Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 91a6b011-9100-42ab-b4d1-7fa09971aa13 eTag: 2381843377357121049 source: Fabric] May 13 23:56:52.467719 waagent[2000]: 2025-05-13T23:56:52.467681Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 13 23:56:52.468337 waagent[2000]: 2025-05-13T23:56:52.468301Z INFO ExtHandler May 13 23:56:52.468406 waagent[2000]: 2025-05-13T23:56:52.468379Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 13 23:56:52.471843 waagent[2000]: 2025-05-13T23:56:52.471798Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 13 23:56:52.548066 waagent[2000]: 2025-05-13T23:56:52.547989Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F56FBEC571DB38DC6C6826F13DE77EF1B4FF434C', 'hasPrivateKey': False} May 13 23:56:52.548449 waagent[2000]: 2025-05-13T23:56:52.548410Z INFO ExtHandler Downloaded certificate {'thumbprint': '6261ABF1ADBCD1D642762E6667CA7E4AFC11A01F', 'hasPrivateKey': True} May 13 23:56:52.549080 waagent[2000]: 2025-05-13T23:56:52.548864Z INFO ExtHandler Fetch goal state completed May 13 23:56:52.561034 waagent[2000]: 2025-05-13T23:56:52.560983Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 13 23:56:52.566171 waagent[2000]: 2025-05-13T23:56:52.566126Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2000 May 13 23:56:52.566302 waagent[2000]: 2025-05-13T23:56:52.566268Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 13 23:56:52.566637 waagent[2000]: 2025-05-13T23:56:52.566596Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 13 23:56:52.568259 waagent[2000]: 2025-05-13T23:56:52.568187Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 13 23:56:52.568729 waagent[2000]: 2025-05-13T23:56:52.568687Z 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 May 13 23:56:52.568884 waagent[2000]: 2025-05-13T23:56:52.568850Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 13 23:56:52.569481 waagent[2000]: 2025-05-13T23:56:52.569445Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 13 23:56:52.760583 waagent[2000]: 2025-05-13T23:56:52.760474Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 13 23:56:52.760709 waagent[2000]: 2025-05-13T23:56:52.760680Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 13 23:56:52.767368 waagent[2000]: 2025-05-13T23:56:52.767339Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 13 23:56:52.774698 systemd[1]: Reload requested from client PID 2017 ('systemctl') (unit waagent.service)... May 13 23:56:52.774715 systemd[1]: Reloading... May 13 23:56:52.842090 zram_generator::config[2060]: No configuration found. May 13 23:56:52.980210 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. May 13 23:56:53.128571 systemd[1]: Reloading finished in 353 ms. May 13 23:56:53.144456 waagent[2000]: 2025-05-13T23:56:53.144371Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 13 23:56:53.144564 waagent[2000]: 2025-05-13T23:56:53.144520Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 13 23:56:53.933654 waagent[2000]: 2025-05-13T23:56:53.933567Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 13 23:56:53.934066 waagent[2000]: 2025-05-13T23:56:53.933945Z 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] May 13 23:56:53.934777 waagent[2000]: 2025-05-13T23:56:53.934711Z INFO ExtHandler ExtHandler Starting env monitor service. May 13 23:56:53.935012 waagent[2000]: 2025-05-13T23:56:53.934967Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:56:53.935340 waagent[2000]: 2025-05-13T23:56:53.935300Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:56:53.935389 waagent[2000]: 2025-05-13T23:56:53.935337Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 13 23:56:53.935824 waagent[2000]: 2025-05-13T23:56:53.935771Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 13 23:56:53.935933 waagent[2000]: 2025-05-13T23:56:53.935883Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 13 23:56:53.935993 waagent[2000]: 2025-05-13T23:56:53.935951Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:56:53.936894 waagent[2000]: 2025-05-13T23:56:53.936817Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 13 23:56:53.937074 waagent[2000]: 2025-05-13T23:56:53.937011Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 13 23:56:53.938432 waagent[2000]: 2025-05-13T23:56:53.938294Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:56:53.938579 waagent[2000]: 2025-05-13T23:56:53.938532Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 13 23:56:53.939812 waagent[2000]: 2025-05-13T23:56:53.939767Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 13 23:56:53.939812 waagent[2000]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 13 23:56:53.939812 waagent[2000]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 13 23:56:53.939812 waagent[2000]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 13 23:56:53.939812 waagent[2000]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 13 23:56:53.939812 waagent[2000]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 13 23:56:53.939812 waagent[2000]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 13 23:56:53.939978 waagent[2000]: 2025-05-13T23:56:53.939908Z INFO EnvHandler ExtHandler Configure routes May 13 23:56:53.940048 waagent[2000]: 2025-05-13T23:56:53.940003Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 13 23:56:53.940411 waagent[2000]: 2025-05-13T23:56:53.940367Z INFO EnvHandler ExtHandler Gateway:None May 13 23:56:53.941277 waagent[2000]: 2025-05-13T23:56:53.941238Z INFO EnvHandler ExtHandler Routes:None May 13 23:56:53.946241 waagent[2000]: 2025-05-13T23:56:53.946194Z INFO ExtHandler ExtHandler May 13 23:56:53.946565 waagent[2000]: 2025-05-13T23:56:53.946525Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 20e86db7-26f1-4f26-9d66-b368a38bbb2d correlation 00999028-b796-424a-b934-d460182b33ba created: 2025-05-13T23:55:10.684555Z] May 13 23:56:53.947792 waagent[2000]: 2025-05-13T23:56:53.947740Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 13 23:56:53.949910 waagent[2000]: 2025-05-13T23:56:53.949860Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] May 13 23:56:54.013863 waagent[2000]: 2025-05-13T23:56:54.013810Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6BAD5675-6F01-494D-92D2-9AE26682D904;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 13 23:56:54.070221 waagent[2000]: 2025-05-13T23:56:54.070142Z INFO MonitorHandler ExtHandler Network interfaces: May 13 23:56:54.070221 waagent[2000]: Executing ['ip', '-a', '-o', 'link']: May 13 23:56:54.070221 waagent[2000]: 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 May 13 23:56:54.070221 waagent[2000]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:fb:ad:51 brd ff:ff:ff:ff:ff:ff May 13 23:56:54.070221 waagent[2000]: Executing ['ip', '-4', '-a', '-o', 'address']: May 13 23:56:54.070221 waagent[2000]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 13 23:56:54.070221 waagent[2000]: 2: eth0 inet 10.200.8.13/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 13 23:56:54.070221 waagent[2000]: Executing ['ip', '-6', '-a', '-o', 'address']: May 13 23:56:54.070221 waagent[2000]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 13 23:56:54.070221 waagent[2000]: 2: eth0 inet6 fe80::7e1e:52ff:fefb:ad51/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 13 23:56:54.444998 waagent[2000]: 2025-05-13T23:56:54.444919Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 13 23:56:54.444998 waagent[2000]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.444998 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.444998 waagent[2000]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.444998 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.444998 waagent[2000]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.444998 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.444998 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 13 23:56:54.444998 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 13 23:56:54.444998 waagent[2000]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 13 23:56:54.448605 waagent[2000]: 2025-05-13T23:56:54.448564Z INFO EnvHandler ExtHandler Current Firewall rules: May 13 23:56:54.448605 waagent[2000]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.448605 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.448605 waagent[2000]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.448605 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.448605 waagent[2000]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:56:54.448605 waagent[2000]: pkts bytes target prot opt in out source destination May 13 23:56:54.448605 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 13 23:56:54.448605 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 13 23:56:54.448605 waagent[2000]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 13 23:56:54.448884 waagent[2000]: 2025-05-13T23:56:54.448851Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 May 13 23:56:54.449036 waagent[2000]: 2025-05-13T23:56:54.449006Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 13 23:56:57.814696 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 13 23:56:57.820474 systemd[1]: Started sshd@0-10.200.8.13:22-10.200.16.10:59874.service - OpenSSH per-connection server daemon (10.200.16.10:59874). May 13 23:56:58.831845 sshd[2150]: Accepted publickey for core from 10.200.16.10 port 59874 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:56:58.833217 sshd-session[2150]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:56:58.837438 systemd-logind[1694]: New session 3 of user core. May 13 23:56:58.864930 systemd[1]: Started session-3.scope - Session 3 of User core. May 13 23:56:59.388293 systemd[1]: Started sshd@1-10.200.8.13:22-10.200.16.10:47670.service - OpenSSH per-connection server daemon (10.200.16.10:47670). May 13 23:56:59.788258 kernel: hv_netvsc 7c1e52fb-ad51-7c1e-52fb-ad517c1e52fb eth0: nvsp_rndis_pkt_complete error status: 2 May 13 23:57:00.022125 sshd[2155]: Accepted publickey for core from 10.200.16.10 port 47670 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:00.023650 sshd-session[2155]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:00.028260 systemd-logind[1694]: New session 4 of user core. May 13 23:57:00.036865 systemd[1]: Started session-4.scope - Session 4 of User core. May 13 23:57:00.465845 sshd[2157]: Connection closed by 10.200.16.10 port 47670 May 13 23:57:00.466244 sshd-session[2155]: pam_unix(sshd:session): session closed for user core May 13 23:57:00.469691 systemd[1]: sshd@1-10.200.8.13:22-10.200.16.10:47670.service: Deactivated successfully. May 13 23:57:00.471537 systemd[1]: session-4.scope: Deactivated successfully. May 13 23:57:00.472272 systemd-logind[1694]: Session 4 logged out. Waiting for processes to exit. May 13 23:57:00.473140 systemd-logind[1694]: Removed session 4. May 13 23:57:00.576188 systemd[1]: Started sshd@2-10.200.8.13:22-10.200.16.10:47684.service - OpenSSH per-connection server daemon (10.200.16.10:47684). May 13 23:57:01.208251 sshd[2163]: Accepted publickey for core from 10.200.16.10 port 47684 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:01.209684 sshd-session[2163]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:01.214657 systemd-logind[1694]: New session 5 of user core. May 13 23:57:01.221839 systemd[1]: Started session-5.scope - Session 5 of User core. May 13 23:57:01.659288 sshd[2165]: Connection closed by 10.200.16.10 port 47684 May 13 23:57:01.659683 sshd-session[2163]: pam_unix(sshd:session): session closed for user core May 13 23:57:01.663018 systemd[1]: sshd@2-10.200.8.13:22-10.200.16.10:47684.service: Deactivated successfully. May 13 23:57:01.664609 systemd[1]: session-5.scope: Deactivated successfully. May 13 23:57:01.665254 systemd-logind[1694]: Session 5 logged out. Waiting for processes to exit. May 13 23:57:01.666039 systemd-logind[1694]: Removed session 5. May 13 23:57:04.604273 chronyd[1697]: Selected source PHC0 May 13 23:57:05.683694 setup-nvidia[1849]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 542M 0 160k 0 0 162k 0 0:56:49 --:--:-- 0:56:49 162k\u000d 1 542M 1 10.4M 0 0 5470k 0 0:01:41 0:00:01 0:01:40 5470k\u000d 6 542M 6 34.5M 0 0 11.7M 0 0:00:46 0:00:02 0:00:44 11.7M\u000d 10 542M 10 59.6M 0 0 15.1M 0 0:00:35 0:00:03 0:00:32 15.1M\u000d 15 542M 15 86.2M 0 0 17.0M 0 0:00:31 0:00:05 0:00:26 17.0M\u000d 20 542M 20 110M 0 0 18.2M 0 0:00:29 0:00:06 0:00:23 21.7M\u000d 24 542M 24 134M 0 0 19.1M 0 0:00:28 0:00:07 0:00:21 24.4M\u000d 28 542M 28 152M 0 0 19.0M 0 0:00:28 0:00:08 0:00:20 23.3M\u000d 32 542M 32 176M 0 0 19.6M 0 0:00:27 0:00:08 0:00:19 23.0M\u000d 36 542M 36 200M 0 0 20.0M 0 0:00:27 0:00:09 0:00:18 23.1M\u000d 41 542M 41 223M 0 0 20.3M 0 0:00:26 0:00:10 0:00:16 22.9M\u000d 45 542M 45 248M 0 0 20.7M 0 0:00:26 0:00:11 0:00:15 22.9M\u000d 50 542M 50 273M 0 0 21.1M 0 0:00:25 0:00:12 0:00:13 24.4M\u000d 54 542M 54 297M 0 0 21.3M 0 0:00:25 0:00:13 0:00:12 24.5M\u000d 59 542M 59 323M 0 0 21.6M 0 0:00:24 0:00:14 0:00:10 24.9M\u000d 64 542M 64 350M 0 0 21.8M 0 0:00:24 0:00:16 0:00:08 24.9M\u000d 69 542M 69 375M 0 0 22.0M 0 0:00:24 0:00:17 0:00:07 25.0M\u000d 72 542M 72 395M 0 0 21.9M 0 0:00:24 0:00:18 0:00:06 24.1M\u000d 77 542M 77 419M 0 0 22.0M 0 0:00:24 0:00:19 0:00:05 23.8M\u000d 81 542M 81 443M 0 0 22.1M 0 0:00:24 0:00:20 0:00:04 23.3M\u000d 86 542M 86 468M 0 0 22.2M 0 0:00:24 0:00:21 0:00:03 23.6M\u000d 90 542M 90 492M 0 0 22.3M 0 0:00:24 0:00:22 0:00:02 23.4M\u000d 95 542M 95 517M 0 0 22.4M 0 0:00:24 0:00:23 0:00:01 24.4M\u000d 99 542M 99 542M 0 0 22.5M 0 0:00:24 0:00:23 0:00:01 24.8M\u000d100 542M 100 542M 0 0 22.6M 0 0:00:23 0:00:23 --:--:-- 25.0M May 13 23:57:11.774938 systemd[1]: Started sshd@3-10.200.8.13:22-10.200.16.10:57082.service - OpenSSH per-connection server daemon (10.200.16.10:57082). May 13 23:57:12.425113 sshd[2181]: Accepted publickey for core from 10.200.16.10 port 57082 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:12.426476 sshd-session[2181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:12.440230 systemd-logind[1694]: New session 6 of user core. May 13 23:57:12.446118 systemd[1]: Started session-6.scope - Session 6 of User core. May 13 23:57:12.880762 sshd[2183]: Connection closed by 10.200.16.10 port 57082 May 13 23:57:12.880032 sshd-session[2181]: pam_unix(sshd:session): session closed for user core May 13 23:57:12.884850 systemd[1]: sshd@3-10.200.8.13:22-10.200.16.10:57082.service: Deactivated successfully. May 13 23:57:12.886510 systemd[1]: session-6.scope: Deactivated successfully. May 13 23:57:12.891449 systemd-logind[1694]: Session 6 logged out. Waiting for processes to exit. May 13 23:57:12.892498 systemd-logind[1694]: Removed session 6. May 13 23:57:12.993559 systemd[1]: Started sshd@4-10.200.8.13:22-10.200.16.10:57094.service - OpenSSH per-connection server daemon (10.200.16.10:57094). May 13 23:57:13.641366 sshd[2189]: Accepted publickey for core from 10.200.16.10 port 57094 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:13.642866 sshd-session[2189]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:13.650626 systemd-logind[1694]: New session 7 of user core. May 13 23:57:13.659491 systemd[1]: Started session-7.scope - Session 7 of User core. May 13 23:57:14.086378 sshd[2191]: Connection closed by 10.200.16.10 port 57094 May 13 23:57:14.086939 sshd-session[2189]: pam_unix(sshd:session): session closed for user core May 13 23:57:14.094650 systemd[1]: sshd@4-10.200.8.13:22-10.200.16.10:57094.service: Deactivated successfully. May 13 23:57:14.096656 systemd[1]: session-7.scope: Deactivated successfully. May 13 23:57:14.099453 systemd-logind[1694]: Session 7 logged out. Waiting for processes to exit. May 13 23:57:14.100437 systemd-logind[1694]: Removed session 7. May 13 23:57:20.906338 setup-nvidia[1688]: Downloading NVIDIA 535.230.02 Driver May 13 23:57:20.910759 setup-nvidia[2197]: % Total % Received % Xferd Average Speed Time Time Time Current May 13 23:57:20.911016 setup-nvidia[2197]: Dload Upload Total Spent Left Speed May 13 23:57:22.862387 setup-nvidia[2197]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 17 326M 17 58.0M 0 0 82.1M 0 0:00:03 --:--:-- 0:00:03 82.0M\u000d 82 326M 82 269M 0 0 157M 0 0:00:02 0:00:01 0:00:01 157M\u000d100 326M 100 326M 0 0 167M 0 0:00:01 0:00:01 --:--:-- 167M May 13 23:57:22.978797 kernel: hv_balloon: Max. dynamic memory size: 114688 MB May 13 23:57:23.013742 setup-nvidia[1688]: Extract the NVIDIA Driver Installer 535.230.02 May 13 23:57:23.014032 setup-nvidia[1688]: /opt/nvidia/workdir/nvidia-workdir / May 13 23:57:23.050792 setup-nvidia[2200]: Creating directory NVIDIA-Linux-x86_64-535.230.02 May 13 23:57:23.371924 setup-nvidia[2200]: Verifying archive integrity... OK May 13 23:57:24.186266 setup-nvidia[2200]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 May 13 23:57:24.202120 systemd[1]: Started sshd@5-10.200.8.13:22-10.200.16.10:48276.service - OpenSSH per-connection server daemon (10.200.16.10:48276). May 13 23:57:24.854438 sshd[2231]: Accepted publickey for core from 10.200.16.10 port 48276 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:24.855819 sshd-session[2231]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:24.860612 systemd-logind[1694]: New session 8 of user core. May 13 23:57:24.875971 systemd[1]: Started session-8.scope - Session 8 of User core. May 13 23:57:25.300338 sshd[2233]: Connection closed by 10.200.16.10 port 48276 May 13 23:57:25.300707 sshd-session[2231]: pam_unix(sshd:session): session closed for user core May 13 23:57:25.304155 systemd[1]: sshd@5-10.200.8.13:22-10.200.16.10:48276.service: Deactivated successfully. May 13 23:57:25.305766 systemd[1]: session-8.scope: Deactivated successfully. May 13 23:57:25.306432 systemd-logind[1694]: Session 8 logged out. Waiting for processes to exit. May 13 23:57:25.307400 systemd-logind[1694]: Removed session 8. May 13 23:57:25.413698 systemd[1]: Started sshd@6-10.200.8.13:22-10.200.16.10:48284.service - OpenSSH per-connection server daemon (10.200.16.10:48284). May 13 23:57:26.056087 sshd[2239]: Accepted publickey for core from 10.200.16.10 port 48284 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:26.057395 sshd-session[2239]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:26.069810 systemd-logind[1694]: New session 9 of user core. May 13 23:57:26.085960 systemd[1]: Started session-9.scope - Session 9 of User core. May 13 23:57:26.103177 setup-nvidia[2226]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ May 13 23:57:26.213917 setup-nvidia[1688]: / May 13 23:57:26.213917 setup-nvidia[1688]: Spawn system-nspawn container to install the NVIDIA drivers May 13 23:57:26.381127 update_engine[1699]: I20250513 23:57:26.381052 1699 update_attempter.cc:509] Updating boot flags... May 13 23:57:26.487740 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2262) May 13 23:57:26.501303 sshd[2241]: Connection closed by 10.200.16.10 port 48284 May 13 23:57:26.501981 sshd-session[2239]: pam_unix(sshd:session): session closed for user core May 13 23:57:26.510151 systemd[1]: sshd@6-10.200.8.13:22-10.200.16.10:48284.service: Deactivated successfully. May 13 23:57:26.512113 systemd[1]: session-9.scope: Deactivated successfully. May 13 23:57:26.513108 systemd-logind[1694]: Session 9 logged out. Waiting for processes to exit. May 13 23:57:26.525988 systemd-logind[1694]: Removed session 9. May 13 23:57:26.542778 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2262) May 13 23:57:26.600924 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2262) May 13 23:57:26.612037 sudo[2245]: 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+nightly-20250513-2100.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 May 13 23:57:26.614793 sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 13 23:57:26.643379 systemd[1]: Created slice user-0.slice - User Slice of UID 0. May 13 23:57:26.646008 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 13 23:57:26.671526 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 13 23:57:26.675549 systemd[1]: Starting user@0.service - User Manager for UID 0... May 13 23:57:26.692895 (systemd)[2417]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) May 13 23:57:26.695147 systemd-logind[1694]: New session c3 of user root. May 13 23:57:26.878010 systemd[2417]: Queued start job for default target default.target. May 13 23:57:26.890122 systemd[2417]: Created slice app.slice - User Application Slice. May 13 23:57:26.890153 systemd[2417]: Reached target paths.target - Paths. May 13 23:57:26.890201 systemd[2417]: Reached target timers.target - Timers. May 13 23:57:26.891450 systemd[2417]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:57:26.902477 systemd[2417]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:57:26.902600 systemd[2417]: Reached target sockets.target - Sockets. May 13 23:57:26.902657 systemd[2417]: Reached target basic.target - Basic System. May 13 23:57:26.902703 systemd[2417]: Reached target default.target - Main User Target. May 13 23:57:26.902748 systemd[2417]: Startup finished in 201ms. May 13 23:57:26.902826 systemd[1]: Started user@0.service - User Manager for UID 0. May 13 23:57:26.906928 systemd[1]: Started session-c2.scope - Session c2 of User root. May 13 23:57:27.017757 kernel: loop6: detected capacity change from 0 to 12644352 May 13 23:57:27.045744 kernel: loop6: p9 May 13 23:57:27.302846 setup-nvidia[2426]: ░ Spawning container flatcardevelopercontainer-4284.0.0nightly-20250513-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4284.0.0+nightly-20250513-2100.bin. May 13 23:57:27.349759 kernel: EXT4-fs (loop6p9): mounted filesystem 63c5bf5a-c683-4802-9fa3-6d3ca7f4a515 ro without journal. Quota mode: none. May 13 23:57:36.615352 systemd[1]: Started sshd@7-10.200.8.13:22-10.200.16.10:34424.service - OpenSSH per-connection server daemon (10.200.16.10:34424). May 13 23:57:37.247574 sshd[3088]: Accepted publickey for core from 10.200.16.10 port 34424 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:37.249055 sshd-session[3088]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:37.253407 systemd-logind[1694]: New session 10 of user core. May 13 23:57:37.262908 systemd[1]: Started session-10.scope - Session 10 of User core. May 13 23:57:37.691821 sshd[3244]: Connection closed by 10.200.16.10 port 34424 May 13 23:57:37.692299 sshd-session[3088]: pam_unix(sshd:session): session closed for user core May 13 23:57:37.695635 systemd[1]: sshd@7-10.200.8.13:22-10.200.16.10:34424.service: Deactivated successfully. May 13 23:57:37.697254 systemd[1]: session-10.scope: Deactivated successfully. May 13 23:57:37.697893 systemd-logind[1694]: Session 10 logged out. Waiting for processes to exit. May 13 23:57:37.698649 systemd-logind[1694]: Removed session 10. May 13 23:57:37.809464 systemd[1]: Started sshd@8-10.200.8.13:22-10.200.16.10:34428.service - OpenSSH per-connection server daemon (10.200.16.10:34428). May 13 23:57:38.442498 sshd[3418]: Accepted publickey for core from 10.200.16.10 port 34428 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:38.444426 sshd-session[3418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:38.448746 systemd-logind[1694]: New session 11 of user core. May 13 23:57:38.460855 systemd[1]: Started session-11.scope - Session 11 of User core. May 13 23:57:38.887212 sshd[3459]: Connection closed by 10.200.16.10 port 34428 May 13 23:57:38.887644 sshd-session[3418]: pam_unix(sshd:session): session closed for user core May 13 23:57:38.890966 systemd[1]: sshd@8-10.200.8.13:22-10.200.16.10:34428.service: Deactivated successfully. May 13 23:57:38.892644 systemd[1]: session-11.scope: Deactivated successfully. May 13 23:57:38.893295 systemd-logind[1694]: Session 11 logged out. Waiting for processes to exit. May 13 23:57:38.894075 systemd-logind[1694]: Removed session 11. May 13 23:57:49.003596 systemd[1]: Started sshd@9-10.200.8.13:22-10.200.16.10:51416.service - OpenSSH per-connection server daemon (10.200.16.10:51416). May 13 23:57:49.650999 sshd[3465]: Accepted publickey for core from 10.200.16.10 port 51416 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:49.652467 sshd-session[3465]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:49.656632 systemd-logind[1694]: New session 12 of user core. May 13 23:57:49.673874 systemd[1]: Started session-12.scope - Session 12 of User core. May 13 23:57:50.094864 sshd[3467]: Connection closed by 10.200.16.10 port 51416 May 13 23:57:50.095134 sshd-session[3465]: pam_unix(sshd:session): session closed for user core May 13 23:57:50.098606 systemd[1]: sshd@9-10.200.8.13:22-10.200.16.10:51416.service: Deactivated successfully. May 13 23:57:50.100219 systemd[1]: session-12.scope: Deactivated successfully. May 13 23:57:50.100951 systemd-logind[1694]: Session 12 logged out. Waiting for processes to exit. May 13 23:57:50.102055 systemd-logind[1694]: Removed session 12. May 13 23:57:50.212231 systemd[1]: Started sshd@10-10.200.8.13:22-10.200.16.10:51420.service - OpenSSH per-connection server daemon (10.200.16.10:51420). May 13 23:57:50.848812 sshd[3473]: Accepted publickey for core from 10.200.16.10 port 51420 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:50.850303 sshd-session[3473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:50.854588 systemd-logind[1694]: New session 13 of user core. May 13 23:57:50.862875 systemd[1]: Started session-13.scope - Session 13 of User core. May 13 23:57:51.295616 sshd[3519]: Connection closed by 10.200.16.10 port 51420 May 13 23:57:51.296085 sshd-session[3473]: pam_unix(sshd:session): session closed for user core May 13 23:57:51.299410 systemd[1]: sshd@10-10.200.8.13:22-10.200.16.10:51420.service: Deactivated successfully. May 13 23:57:51.301002 systemd[1]: session-13.scope: Deactivated successfully. May 13 23:57:51.301617 systemd-logind[1694]: Session 13 logged out. Waiting for processes to exit. May 13 23:57:51.302377 systemd-logind[1694]: Removed session 13. May 13 23:58:01.424991 systemd[1]: Started sshd@11-10.200.8.13:22-10.200.16.10:60438.service - OpenSSH per-connection server daemon (10.200.16.10:60438). May 13 23:58:02.081022 sshd[6410]: Accepted publickey for core from 10.200.16.10 port 60438 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:02.082480 sshd-session[6410]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:02.089676 systemd-logind[1694]: New session 14 of user core. May 13 23:58:02.097987 systemd[1]: Started session-14.scope - Session 14 of User core. May 13 23:58:02.526392 sshd[6660]: Connection closed by 10.200.16.10 port 60438 May 13 23:58:02.528041 sshd-session[6410]: pam_unix(sshd:session): session closed for user core May 13 23:58:02.531437 systemd[1]: sshd@11-10.200.8.13:22-10.200.16.10:60438.service: Deactivated successfully. May 13 23:58:02.533065 systemd[1]: session-14.scope: Deactivated successfully. May 13 23:58:02.534139 systemd-logind[1694]: Session 14 logged out. Waiting for processes to exit. May 13 23:58:02.535029 systemd-logind[1694]: Removed session 14. May 13 23:58:02.644902 systemd[1]: Started sshd@12-10.200.8.13:22-10.200.16.10:60454.service - OpenSSH per-connection server daemon (10.200.16.10:60454). May 13 23:58:03.279944 sshd[6780]: Accepted publickey for core from 10.200.16.10 port 60454 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:03.281944 sshd-session[6780]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:03.287257 systemd-logind[1694]: New session 15 of user core. May 13 23:58:03.299245 systemd[1]: Started session-15.scope - Session 15 of User core. May 13 23:58:03.729732 sshd[6912]: Connection closed by 10.200.16.10 port 60454 May 13 23:58:03.730078 sshd-session[6780]: pam_unix(sshd:session): session closed for user core May 13 23:58:03.733476 systemd[1]: sshd@12-10.200.8.13:22-10.200.16.10:60454.service: Deactivated successfully. May 13 23:58:03.735277 systemd[1]: session-15.scope: Deactivated successfully. May 13 23:58:03.736001 systemd-logind[1694]: Session 15 logged out. Waiting for processes to exit. May 13 23:58:03.737200 systemd-logind[1694]: Removed session 15. May 13 23:58:13.847949 systemd[1]: Started sshd@13-10.200.8.13:22-10.200.16.10:56834.service - OpenSSH per-connection server daemon (10.200.16.10:56834). May 13 23:58:14.496320 sshd[10417]: Accepted publickey for core from 10.200.16.10 port 56834 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:14.497787 sshd-session[10417]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:14.503564 systemd-logind[1694]: New session 16 of user core. May 13 23:58:14.514420 systemd[1]: Started session-16.scope - Session 16 of User core. May 13 23:58:14.947295 sshd[10620]: Connection closed by 10.200.16.10 port 56834 May 13 23:58:14.947654 sshd-session[10417]: pam_unix(sshd:session): session closed for user core May 13 23:58:14.953017 systemd-logind[1694]: Session 16 logged out. Waiting for processes to exit. May 13 23:58:14.954941 systemd[1]: sshd@13-10.200.8.13:22-10.200.16.10:56834.service: Deactivated successfully. May 13 23:58:14.957076 systemd[1]: session-16.scope: Deactivated successfully. May 13 23:58:14.958737 systemd-logind[1694]: Removed session 16. May 13 23:58:15.074954 systemd[1]: Started sshd@14-10.200.8.13:22-10.200.16.10:56838.service - OpenSSH per-connection server daemon (10.200.16.10:56838). May 13 23:58:15.722242 sshd[10781]: Accepted publickey for core from 10.200.16.10 port 56838 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:15.724336 sshd-session[10781]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:15.731088 systemd-logind[1694]: New session 17 of user core. May 13 23:58:15.740740 systemd[1]: Started session-17.scope - Session 17 of User core. May 13 23:58:16.170183 sshd[11032]: Connection closed by 10.200.16.10 port 56838 May 13 23:58:16.170535 sshd-session[10781]: pam_unix(sshd:session): session closed for user core May 13 23:58:16.173942 systemd[1]: sshd@14-10.200.8.13:22-10.200.16.10:56838.service: Deactivated successfully. May 13 23:58:16.175760 systemd[1]: session-17.scope: Deactivated successfully. May 13 23:58:16.176444 systemd-logind[1694]: Session 17 logged out. Waiting for processes to exit. May 13 23:58:16.177266 systemd-logind[1694]: Removed session 17. May 13 23:58:26.291979 systemd[1]: Started sshd@15-10.200.8.13:22-10.200.16.10:53990.service - OpenSSH per-connection server daemon (10.200.16.10:53990). May 13 23:58:26.942462 sshd[11907]: Accepted publickey for core from 10.200.16.10 port 53990 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:26.944493 sshd-session[11907]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:26.950652 systemd-logind[1694]: New session 18 of user core. May 13 23:58:26.964802 systemd[1]: Started session-18.scope - Session 18 of User core. May 13 23:58:27.388775 sshd[11928]: Connection closed by 10.200.16.10 port 53990 May 13 23:58:27.389588 sshd-session[11907]: pam_unix(sshd:session): session closed for user core May 13 23:58:27.395389 systemd-logind[1694]: Session 18 logged out. Waiting for processes to exit. May 13 23:58:27.397082 systemd[1]: sshd@15-10.200.8.13:22-10.200.16.10:53990.service: Deactivated successfully. May 13 23:58:27.399586 systemd[1]: session-18.scope: Deactivated successfully. May 13 23:58:27.400987 systemd-logind[1694]: Removed session 18. May 13 23:58:27.513891 systemd[1]: Started sshd@16-10.200.8.13:22-10.200.16.10:54004.service - OpenSSH per-connection server daemon (10.200.16.10:54004). May 13 23:58:28.162843 sshd[11940]: Accepted publickey for core from 10.200.16.10 port 54004 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:28.167575 sshd-session[11940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:28.174110 systemd-logind[1694]: New session 19 of user core. May 13 23:58:28.183218 systemd[1]: Started session-19.scope - Session 19 of User core. May 13 23:58:28.613411 sshd[11961]: Connection closed by 10.200.16.10 port 54004 May 13 23:58:28.613986 sshd-session[11940]: pam_unix(sshd:session): session closed for user core May 13 23:58:28.617047 systemd-logind[1694]: Session 19 logged out. Waiting for processes to exit. May 13 23:58:28.619005 systemd[1]: sshd@16-10.200.8.13:22-10.200.16.10:54004.service: Deactivated successfully. May 13 23:58:28.620586 systemd[1]: session-19.scope: Deactivated successfully. May 13 23:58:28.622409 systemd-logind[1694]: Removed session 19. May 13 23:58:38.730644 systemd[1]: Started sshd@17-10.200.8.13:22-10.200.16.10:41002.service - OpenSSH per-connection server daemon (10.200.16.10:41002). May 13 23:58:39.381817 sshd[12283]: Accepted publickey for core from 10.200.16.10 port 41002 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:39.383708 sshd-session[12283]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:39.388478 systemd-logind[1694]: New session 20 of user core. May 13 23:58:39.402946 systemd[1]: Started session-20.scope - Session 20 of User core. May 13 23:58:39.827940 sshd[12315]: Connection closed by 10.200.16.10 port 41002 May 13 23:58:39.830149 sshd-session[12283]: pam_unix(sshd:session): session closed for user core May 13 23:58:39.836085 systemd[1]: sshd@17-10.200.8.13:22-10.200.16.10:41002.service: Deactivated successfully. May 13 23:58:39.837832 systemd[1]: session-20.scope: Deactivated successfully. May 13 23:58:39.839351 systemd-logind[1694]: Session 20 logged out. Waiting for processes to exit. May 13 23:58:39.840679 systemd-logind[1694]: Removed session 20. May 13 23:58:39.946971 systemd[1]: Started sshd@18-10.200.8.13:22-10.200.16.10:41006.service - OpenSSH per-connection server daemon (10.200.16.10:41006). May 13 23:58:40.604623 sshd[12327]: Accepted publickey for core from 10.200.16.10 port 41006 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:40.606912 sshd-session[12327]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:40.616791 systemd-logind[1694]: New session 21 of user core. May 13 23:58:40.624992 systemd[1]: Started session-21.scope - Session 21 of User core. May 13 23:58:41.049636 sshd[12344]: Connection closed by 10.200.16.10 port 41006 May 13 23:58:41.051334 sshd-session[12327]: pam_unix(sshd:session): session closed for user core May 13 23:58:41.057103 systemd-logind[1694]: Session 21 logged out. Waiting for processes to exit. May 13 23:58:41.057866 systemd[1]: sshd@18-10.200.8.13:22-10.200.16.10:41006.service: Deactivated successfully. May 13 23:58:41.060357 systemd[1]: session-21.scope: Deactivated successfully. May 13 23:58:41.062414 systemd-logind[1694]: Removed session 21. May 13 23:58:51.170567 systemd[1]: Started sshd@19-10.200.8.13:22-10.200.16.10:53662.service - OpenSSH per-connection server daemon (10.200.16.10:53662). May 13 23:58:51.828393 sshd[12683]: Accepted publickey for core from 10.200.16.10 port 53662 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:51.829798 sshd-session[12683]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:51.834040 systemd-logind[1694]: New session 22 of user core. May 13 23:58:51.846906 systemd[1]: Started session-22.scope - Session 22 of User core. May 13 23:58:52.273615 sshd[12712]: Connection closed by 10.200.16.10 port 53662 May 13 23:58:52.274030 sshd-session[12683]: pam_unix(sshd:session): session closed for user core May 13 23:58:52.277375 systemd[1]: sshd@19-10.200.8.13:22-10.200.16.10:53662.service: Deactivated successfully. May 13 23:58:52.279001 systemd[1]: session-22.scope: Deactivated successfully. May 13 23:58:52.279621 systemd-logind[1694]: Session 22 logged out. Waiting for processes to exit. May 13 23:58:52.280411 systemd-logind[1694]: Removed session 22. May 13 23:58:52.390642 systemd[1]: Started sshd@20-10.200.8.13:22-10.200.16.10:53678.service - OpenSSH per-connection server daemon (10.200.16.10:53678). May 13 23:58:53.039001 sshd[12718]: Accepted publickey for core from 10.200.16.10 port 53678 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:53.040466 sshd-session[12718]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:53.044966 systemd-logind[1694]: New session 23 of user core. May 13 23:58:53.059923 systemd[1]: Started session-23.scope - Session 23 of User core. May 13 23:58:53.485294 sshd[12720]: Connection closed by 10.200.16.10 port 53678 May 13 23:58:53.485681 sshd-session[12718]: pam_unix(sshd:session): session closed for user core May 13 23:58:53.489026 systemd[1]: sshd@20-10.200.8.13:22-10.200.16.10:53678.service: Deactivated successfully. May 13 23:58:53.490618 systemd[1]: session-23.scope: Deactivated successfully. May 13 23:58:53.491355 systemd-logind[1694]: Session 23 logged out. Waiting for processes to exit. May 13 23:58:53.492217 systemd-logind[1694]: Removed session 23. May 13 23:58:59.596118 setup-nvidia[2426]: \u000d May 13 23:58:59.596118 setup-nvidia[2426]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most May 13 23:58:59.596118 setup-nvidia[2426]: frequently when this kernel module was built against the wrong or May 13 23:58:59.596118 setup-nvidia[2426]: improperly configured kernel sources, with a version of gcc that differs May 13 23:58:59.596118 setup-nvidia[2426]: from the one used to build the target kernel, or if another driver, such May 13 23:58:59.596118 setup-nvidia[2426]: as nouveau, is present and prevents the NVIDIA kernel module from May 13 23:58:59.596118 setup-nvidia[2426]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device May 13 23:58:59.596118 setup-nvidia[2426]: installed in this system is supported by this NVIDIA Linux graphics May 13 23:58:59.596118 setup-nvidia[2426]: driver release. May 13 23:58:59.596118 setup-nvidia[2426]: \u000d May 13 23:58:59.596118 setup-nvidia[2426]: Please see the log entries 'Kernel module load error' and 'Kernel May 13 23:58:59.596118 setup-nvidia[2426]: messages' at the end of the file May 13 23:58:59.596118 setup-nvidia[2426]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more May 13 23:58:59.596118 setup-nvidia[2426]: information. May 13 23:58:59.596118 setup-nvidia[2426]: \u000d May 13 23:58:59.611315 setup-nvidia[2426]: dmesg: read kernel buffer failed: Operation not permitted May 13 23:58:59.631667 setup-nvidia[2426]: \u000d May 13 23:58:59.631667 setup-nvidia[2426]: ERROR: Installation has failed. Please see the file May 13 23:58:59.631667 setup-nvidia[2426]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for May 13 23:58:59.631667 setup-nvidia[2426]: details. You may find suggestions on fixing installation problems in May 13 23:58:59.631667 setup-nvidia[2426]: the README available on the Linux driver download page at May 13 23:58:59.631667 setup-nvidia[2426]: www.nvidia.com. May 13 23:58:59.631667 setup-nvidia[2426]: \u000d May 13 23:58:59.632057 setup-nvidia[2426]: Last 50 lines of nvidia-installer.log: May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build May 13 23:58:59.632923 setup-nvidia[2426]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers May 13 23:58:59.632923 setup-nvidia[2426]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o May 13 23:58:59.632923 setup-nvidia[2426]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o May 13 23:58:59.632923 setup-nvidia[2426]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o May 13 23:58:59.632923 setup-nvidia[2426]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o May 13 23:58:59.632923 setup-nvidia[2426]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o May 13 23:58:59.632923 setup-nvidia[2426]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 13 23:58:59.632923 setup-nvidia[2426]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 13 23:58:59.632923 setup-nvidia[2426]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 13 23:58:59.632923 setup-nvidia[2426]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 13 23:58:59.632923 setup-nvidia[2426]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 13 23:58:59.632923 setup-nvidia[2426]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 13 23:58:59.632923 setup-nvidia[2426]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux May 13 23:58:59.632923 setup-nvidia[2426]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 13 23:58:59.632923 setup-nvidia[2426]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux May 13 23:58:59.632923 setup-nvidia[2426]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 13 23:58:59.632923 setup-nvidia[2426]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux May 13 23:58:59.632923 setup-nvidia[2426]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 13 23:58:59.632923 setup-nvidia[2426]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux May 13 23:58:59.632923 setup-nvidia[2426]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 13 23:58:59.632923 setup-nvidia[2426]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux May 13 23:58:59.632923 setup-nvidia[2426]: make[2]: Leaving directory '/usr/lib/modules/6.6.89-flatcar/build' May 13 23:58:59.632923 setup-nvidia[2426]: make[1]: Leaving directory '/usr/lib/modules/6.6.89-flatcar/source' May 13 23:58:59.632923 setup-nvidia[2426]: -> done. May 13 23:58:59.632923 setup-nvidia[2426]: -> Kernel module compilation complete. May 13 23:58:59.632923 setup-nvidia[2426]: -> Unable to determine if Secure Boot is enabled: No such file or directory May 13 23:58:59.632923 setup-nvidia[2426]: 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. May 13 23:58:59.632923 setup-nvidia[2426]: \u000d May 13 23:58:59.634073 setup-nvidia[2426]: 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. May 13 23:58:59.634073 setup-nvidia[2426]: -> Kernel module load error: Operation not permitted May 13 23:58:59.634073 setup-nvidia[2426]: -> Kernel messages: May 13 23:58:59.634073 setup-nvidia[2426]: 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. May 13 23:59:03.599430 systemd[1]: Started sshd@21-10.200.8.13:22-10.200.16.10:53352.service - OpenSSH per-connection server daemon (10.200.16.10:53352). May 13 23:59:04.232907 sshd[12931]: Accepted publickey for core from 10.200.16.10 port 53352 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:04.234354 sshd-session[12931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:04.239224 systemd-logind[1694]: New session 24 of user core. May 13 23:59:04.252879 systemd[1]: Started session-24.scope - Session 24 of User core. May 13 23:59:04.678970 sshd[12933]: Connection closed by 10.200.16.10 port 53352 May 13 23:59:04.679344 sshd-session[12931]: pam_unix(sshd:session): session closed for user core May 13 23:59:04.682103 systemd[1]: sshd@21-10.200.8.13:22-10.200.16.10:53352.service: Deactivated successfully. May 13 23:59:04.683925 systemd[1]: session-24.scope: Deactivated successfully. May 13 23:59:04.685262 systemd-logind[1694]: Session 24 logged out. Waiting for processes to exit. May 13 23:59:04.686067 systemd-logind[1694]: Removed session 24. May 13 23:59:04.791354 systemd[1]: Started sshd@22-10.200.8.13:22-10.200.16.10:53366.service - OpenSSH per-connection server daemon (10.200.16.10:53366). May 13 23:59:05.436147 sshd[12939]: Accepted publickey for core from 10.200.16.10 port 53366 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:05.437467 sshd-session[12939]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:05.441704 systemd-logind[1694]: New session 25 of user core. May 13 23:59:05.454844 systemd[1]: Started session-25.scope - Session 25 of User core. May 13 23:59:05.881471 sshd[12941]: Connection closed by 10.200.16.10 port 53366 May 13 23:59:05.881972 sshd-session[12939]: pam_unix(sshd:session): session closed for user core May 13 23:59:05.885192 systemd[1]: sshd@22-10.200.8.13:22-10.200.16.10:53366.service: Deactivated successfully. May 13 23:59:05.886804 systemd[1]: session-25.scope: Deactivated successfully. May 13 23:59:05.887416 systemd-logind[1694]: Session 25 logged out. Waiting for processes to exit. May 13 23:59:05.888192 systemd-logind[1694]: Removed session 25. May 13 23:59:15.992983 systemd[1]: Started sshd@23-10.200.8.13:22-10.200.16.10:35188.service - OpenSSH per-connection server daemon (10.200.16.10:35188). May 13 23:59:16.638898 sshd[12948]: Accepted publickey for core from 10.200.16.10 port 35188 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:16.642917 sshd-session[12948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:16.647203 systemd-logind[1694]: New session 26 of user core. May 13 23:59:16.661862 systemd[1]: Started session-26.scope - Session 26 of User core. May 13 23:59:17.083960 sshd[12950]: Connection closed by 10.200.16.10 port 35188 May 13 23:59:17.084254 sshd-session[12948]: pam_unix(sshd:session): session closed for user core May 13 23:59:17.087558 systemd[1]: sshd@23-10.200.8.13:22-10.200.16.10:35188.service: Deactivated successfully. May 13 23:59:17.089481 systemd[1]: session-26.scope: Deactivated successfully. May 13 23:59:17.090219 systemd-logind[1694]: Session 26 logged out. Waiting for processes to exit. May 13 23:59:17.091072 systemd-logind[1694]: Removed session 26. May 13 23:59:17.195522 systemd[1]: Started sshd@24-10.200.8.13:22-10.200.16.10:35192.service - OpenSSH per-connection server daemon (10.200.16.10:35192). May 13 23:59:17.219743 kernel: EXT4-fs (loop6p9): unmounting filesystem 63c5bf5a-c683-4802-9fa3-6d3ca7f4a515. May 13 23:59:17.221635 setup-nvidia[2426]: Container flatcardevelopercontainer-4284.0.0nightly-20250513-2100.bin exited successfully. May 13 23:59:17.223852 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4284.0.0nightly\x2d20250513\x2d2100.bin.mount: Deactivated successfully. May 13 23:59:17.282782 sudo[2245]: pam_unix(sudo:session): session closed for user root May 13 23:59:17.284800 systemd[1]: session-c2.scope: Deactivated successfully. May 13 23:59:17.285070 systemd[1]: session-c2.scope: Consumed 5min 20.106s CPU time, 883.1M memory peak. May 13 23:59:17.688515 setup-nvidia[1688]: /opt/nvidia / May 13 23:59:17.689472 setup-nvidia[1688]: / May 13 23:59:17.706066 setup-nvidia[12975]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 23:59:17.732748 kernel: IPMI message handler: version 39.2 May 13 23:59:17.735738 kernel: ipmi device interface May 13 23:59:17.743242 setup-nvidia[1688]: /opt/nvidia/current/usr/lib/modules/6.6.89-flatcar/video / May 13 23:59:17.796882 kernel: nvidia: loading out-of-tree module taints kernel. May 13 23:59:17.797029 kernel: nvidia: module license 'NVIDIA' taints kernel. May 13 23:59:17.797055 kernel: Disabling lock debugging due to kernel taint May 13 23:59:17.804175 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel May 13 23:59:17.804220 kernel: nvidia: module license taints kernel. May 13 23:59:17.842959 sshd[12956]: Accepted publickey for core from 10.200.16.10 port 35192 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:17.844307 sshd-session[12956]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:17.848821 systemd-logind[1694]: New session 27 of user core. May 13 23:59:17.863872 systemd[1]: Started session-27.scope - Session 27 of User core. May 13 23:59:18.197748 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 May 13 23:59:18.287081 sshd[12982]: Connection closed by 10.200.16.10 port 35192 May 13 23:59:18.287472 sshd-session[12956]: pam_unix(sshd:session): session closed for user core May 13 23:59:18.290841 systemd[1]: sshd@24-10.200.8.13:22-10.200.16.10:35192.service: Deactivated successfully. May 13 23:59:18.292466 systemd[1]: session-27.scope: Deactivated successfully. May 13 23:59:18.293123 systemd-logind[1694]: Session 27 logged out. Waiting for processes to exit. May 13 23:59:18.293945 systemd-logind[1694]: Removed session 27. May 13 23:59:18.609132 kernel: May 13 23:59:18.609281 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) May 13 23:59:18.815746 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 May 13 23:59:18.838753 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 May 13 23:59:18.859760 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. May 13 23:59:18.906473 setup-nvidia[1688]: / May 13 23:59:18.906826 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. May 13 23:59:20.774352 setup-nvidia[13013]: Tue May 13 23:59:20 2025 May 13 23:59:20.774352 setup-nvidia[13013]: +---------------------------------------------------------------------------------------+ May 13 23:59:20.774352 setup-nvidia[13013]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | May 13 23:59:20.774352 setup-nvidia[13013]: |-----------------------------------------+----------------------+----------------------+ May 13 23:59:20.774352 setup-nvidia[13013]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | May 13 23:59:20.774352 setup-nvidia[13013]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | May 13 23:59:20.774352 setup-nvidia[13013]: | | | MIG M. | May 13 23:59:20.774352 setup-nvidia[13013]: |=========================================+======================+======================| May 13 23:59:20.779404 setup-nvidia[13013]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | May 13 23:59:20.779404 setup-nvidia[13013]: | N/A 27C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | May 13 23:59:20.779404 setup-nvidia[13013]: | | | N/A | May 13 23:59:20.779404 setup-nvidia[13013]: +-----------------------------------------+----------------------+----------------------+ May 13 23:59:20.779404 setup-nvidia[13013]: May 13 23:59:20.779404 setup-nvidia[13013]: +---------------------------------------------------------------------------------------+ May 13 23:59:20.779404 setup-nvidia[13013]: | Processes: | May 13 23:59:20.779404 setup-nvidia[13013]: | GPU GI CI PID Type Process name GPU Memory | May 13 23:59:20.779404 setup-nvidia[13013]: | ID ID Usage | May 13 23:59:20.779404 setup-nvidia[13013]: |=======================================================================================| May 13 23:59:20.779650 setup-nvidia[13013]: | No running processes found | May 13 23:59:20.779650 setup-nvidia[13013]: +---------------------------------------------------------------------------------------+ May 13 23:59:21.155101 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 13 23:59:21.157621 systemd[1]: Reached target multi-user.target - Multi-User System. May 13 23:59:21.158106 systemd[1]: Startup finished in 1.701s (firmware) + 37.460s (loader) + 1.936s (kernel) + 14.617s (initrd) + 2min 53.223s (userspace) = 3min 48.939s. May 13 23:59:27.367464 systemd[1]: Stopping user@0.service - User Manager for UID 0... May 13 23:59:27.368266 systemd[2417]: Activating special unit exit.target... May 13 23:59:27.368350 systemd[2417]: Stopped target default.target - Main User Target. May 13 23:59:27.368390 systemd[2417]: Stopped target basic.target - Basic System. May 13 23:59:27.368419 systemd[2417]: Stopped target paths.target - Paths. May 13 23:59:27.368444 systemd[2417]: Stopped target sockets.target - Sockets. May 13 23:59:27.368470 systemd[2417]: Stopped target timers.target - Timers. May 13 23:59:27.368830 systemd[2417]: Closed dbus.socket - D-Bus User Message Bus Socket. May 13 23:59:27.369040 systemd[2417]: Removed slice app.slice - User Application Slice. May 13 23:59:27.369072 systemd[2417]: Reached target shutdown.target - Shutdown. May 13 23:59:27.369222 systemd[2417]: Finished systemd-exit.service - Exit the Session. May 13 23:59:27.369260 systemd[2417]: Reached target exit.target - Exit the Session. May 13 23:59:27.393331 (sd-pam)[2418]: pam_unix(systemd-user:session): session closed for user root May 13 23:59:27.394941 systemd[1]: user@0.service: Deactivated successfully. May 13 23:59:27.395353 systemd[1]: Stopped user@0.service - User Manager for UID 0. May 13 23:59:27.397143 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 13 23:59:27.420830 systemd[1]: run-user-0.mount: Deactivated successfully. May 13 23:59:27.421796 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. May 13 23:59:27.422002 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 13 23:59:27.423813 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. May 13 23:59:27.423905 systemd[1]: user-0.slice: Consumed 5min 20.343s CPU time, 892.2M memory peak. May 13 23:59:27.424286 systemd-logind[1694]: Removed session c3. May 13 23:59:28.402387 systemd[1]: Started sshd@25-10.200.8.13:22-10.200.16.10:52576.service - OpenSSH per-connection server daemon (10.200.16.10:52576). May 13 23:59:29.046220 sshd[13024]: Accepted publickey for core from 10.200.16.10 port 52576 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:29.047711 sshd-session[13024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:29.051922 systemd-logind[1694]: New session 28 of user core. May 13 23:59:29.065858 systemd[1]: Started session-28.scope - Session 28 of User core. May 13 23:59:29.493310 sshd[13026]: Connection closed by 10.200.16.10 port 52576 May 13 23:59:29.493761 sshd-session[13024]: pam_unix(sshd:session): session closed for user core May 13 23:59:29.497310 systemd[1]: sshd@25-10.200.8.13:22-10.200.16.10:52576.service: Deactivated successfully. May 13 23:59:29.499020 systemd[1]: session-28.scope: Deactivated successfully. May 13 23:59:29.505136 systemd-logind[1694]: Session 28 logged out. Waiting for processes to exit. May 13 23:59:29.506200 systemd-logind[1694]: Removed session 28. May 13 23:59:29.605348 systemd[1]: Started sshd@26-10.200.8.13:22-10.200.16.10:38520.service - OpenSSH per-connection server daemon (10.200.16.10:38520). May 13 23:59:30.239205 sshd[13032]: Accepted publickey for core from 10.200.16.10 port 38520 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:30.240583 sshd-session[13032]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:30.244759 systemd-logind[1694]: New session 29 of user core. May 13 23:59:30.254843 systemd[1]: Started session-29.scope - Session 29 of User core. May 13 23:59:30.679209 sshd[13035]: Connection closed by 10.200.16.10 port 38520 May 13 23:59:30.679619 sshd-session[13032]: pam_unix(sshd:session): session closed for user core May 13 23:59:30.683038 systemd[1]: sshd@26-10.200.8.13:22-10.200.16.10:38520.service: Deactivated successfully. May 13 23:59:30.684640 systemd[1]: session-29.scope: Deactivated successfully. May 13 23:59:30.685270 systemd-logind[1694]: Session 29 logged out. Waiting for processes to exit. May 13 23:59:30.686036 systemd-logind[1694]: Removed session 29. May 13 23:59:30.795540 systemd[1]: Started sshd@27-10.200.8.13:22-10.200.16.10:38528.service - OpenSSH per-connection server daemon (10.200.16.10:38528). May 13 23:59:31.441493 sshd[13041]: Accepted publickey for core from 10.200.16.10 port 38528 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:31.442961 sshd-session[13041]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:31.447690 systemd-logind[1694]: New session 30 of user core. May 13 23:59:31.462859 systemd[1]: Started session-30.scope - Session 30 of User core. May 13 23:59:31.885562 sshd[13044]: Connection closed by 10.200.16.10 port 38528 May 13 23:59:31.885954 sshd-session[13041]: pam_unix(sshd:session): session closed for user core May 13 23:59:31.889307 systemd[1]: sshd@27-10.200.8.13:22-10.200.16.10:38528.service: Deactivated successfully. May 13 23:59:31.890981 systemd[1]: session-30.scope: Deactivated successfully. May 13 23:59:31.891765 systemd-logind[1694]: Session 30 logged out. Waiting for processes to exit. May 13 23:59:31.892667 systemd-logind[1694]: Removed session 30. May 13 23:59:31.996249 systemd[1]: Started sshd@28-10.200.8.13:22-10.200.16.10:38532.service - OpenSSH per-connection server daemon (10.200.16.10:38532). May 13 23:59:32.644197 sshd[13051]: Accepted publickey for core from 10.200.16.10 port 38532 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:32.645631 sshd-session[13051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:32.650526 systemd-logind[1694]: New session 31 of user core. May 13 23:59:32.656838 systemd[1]: Started session-31.scope - Session 31 of User core. May 13 23:59:33.092251 sshd[13053]: Connection closed by 10.200.16.10 port 38532 May 13 23:59:33.092529 sshd-session[13051]: pam_unix(sshd:session): session closed for user core May 13 23:59:33.095844 systemd[1]: sshd@28-10.200.8.13:22-10.200.16.10:38532.service: Deactivated successfully. May 13 23:59:33.097481 systemd[1]: session-31.scope: Deactivated successfully. May 13 23:59:33.098129 systemd-logind[1694]: Session 31 logged out. Waiting for processes to exit. May 13 23:59:33.098938 systemd-logind[1694]: Removed session 31. May 13 23:59:33.207308 systemd[1]: Started sshd@29-10.200.8.13:22-10.200.16.10:38542.service - OpenSSH per-connection server daemon (10.200.16.10:38542). May 13 23:59:33.848498 sshd[13059]: Accepted publickey for core from 10.200.16.10 port 38542 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:33.849904 sshd-session[13059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:33.854327 systemd-logind[1694]: New session 32 of user core. May 13 23:59:33.862844 systemd[1]: Started session-32.scope - Session 32 of User core. May 13 23:59:35.788147 sshd[13061]: Connection closed by 10.200.16.10 port 38542 May 13 23:59:35.788535 sshd-session[13059]: pam_unix(sshd:session): session closed for user core May 13 23:59:35.791838 systemd[1]: sshd@29-10.200.8.13:22-10.200.16.10:38542.service: Deactivated successfully. May 13 23:59:35.794489 systemd[1]: session-32.scope: Deactivated successfully. May 13 23:59:35.794775 systemd[1]: session-32.scope: Consumed 1.439s CPU time, 14.1M memory peak. May 13 23:59:35.795245 systemd-logind[1694]: Session 32 logged out. Waiting for processes to exit. May 13 23:59:35.796172 systemd-logind[1694]: Removed session 32.