May 13 23:56:16.214969 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:16.214999 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:16.215010 kernel: BIOS-provided physical RAM map: May 13 23:56:16.215028 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 13 23:56:16.215035 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 13 23:56:16.215042 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 13 23:56:16.215050 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved May 13 23:56:16.215060 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data May 13 23:56:16.215067 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 13 23:56:16.215073 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 13 23:56:16.215080 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable May 13 23:56:16.215087 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable May 13 23:56:16.215094 kernel: printk: bootconsole [earlyser0] enabled May 13 23:56:16.215101 kernel: NX (Execute Disable) protection: active May 13 23:56:16.215112 kernel: APIC: Static calls initialized May 13 23:56:16.215119 kernel: efi: EFI v2.7 by Microsoft May 13 23:56:16.215127 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f61e418 RNG=0x3ffd1018 May 13 23:56:16.215135 kernel: random: crng init done May 13 23:56:16.215142 kernel: secureboot: Secure boot disabled May 13 23:56:16.215149 kernel: SMBIOS 3.1.0 present. May 13 23:56:16.215157 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 May 13 23:56:16.215164 kernel: Hypervisor detected: Microsoft Hyper-V May 13 23:56:16.215171 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 May 13 23:56:16.215179 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 May 13 23:56:16.215188 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 13 23:56:16.215196 kernel: Hyper-V: Using hypercall for remote TLB flush May 13 23:56:16.215203 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 13 23:56:16.215211 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 13 23:56:16.215219 kernel: tsc: Marking TSC unstable due to running on Hyper-V May 13 23:56:16.215227 kernel: tsc: Detected 2593.991 MHz processor May 13 23:56:16.215235 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 13 23:56:16.215243 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 13 23:56:16.215250 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 May 13 23:56:16.215261 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs May 13 23:56:16.215268 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 13 23:56:16.215276 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved May 13 23:56:16.215283 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved May 13 23:56:16.215290 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 May 13 23:56:16.215298 kernel: Using GB pages for direct mapping May 13 23:56:16.215306 kernel: ACPI: Early table checksum verification disabled May 13 23:56:16.215317 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 13 23:56:16.215328 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215336 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215344 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) May 13 23:56:16.215352 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 13 23:56:16.215360 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215368 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215378 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215386 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215394 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215402 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215410 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 13 23:56:16.215418 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 13 23:56:16.215426 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] May 13 23:56:16.215434 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 13 23:56:16.215445 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 13 23:56:16.215453 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 13 23:56:16.215461 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 13 23:56:16.215469 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] May 13 23:56:16.215477 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] May 13 23:56:16.215484 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 13 23:56:16.215492 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] May 13 23:56:16.215500 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 May 13 23:56:16.215508 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 May 13 23:56:16.215519 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 May 13 23:56:16.215527 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 May 13 23:56:16.215534 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 May 13 23:56:16.215542 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 May 13 23:56:16.215550 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 13 23:56:16.215558 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug May 13 23:56:16.215566 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug May 13 23:56:16.215574 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug May 13 23:56:16.215582 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug May 13 23:56:16.215592 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug May 13 23:56:16.215601 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug May 13 23:56:16.215608 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug May 13 23:56:16.215616 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] May 13 23:56:16.215624 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] May 13 23:56:16.215657 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] May 13 23:56:16.215671 kernel: Zone ranges: May 13 23:56:16.215681 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 13 23:56:16.215689 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 13 23:56:16.215703 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] May 13 23:56:16.215712 kernel: Movable zone start for each node May 13 23:56:16.215720 kernel: Early memory node ranges May 13 23:56:16.215728 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 13 23:56:16.215736 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 13 23:56:16.215744 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 13 23:56:16.215752 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] May 13 23:56:16.215760 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] May 13 23:56:16.215768 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] May 13 23:56:16.215779 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 13 23:56:16.215788 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 13 23:56:16.215796 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges May 13 23:56:16.215804 kernel: ACPI: PM-Timer IO Port: 0x408 May 13 23:56:16.215812 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) May 13 23:56:16.215820 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 May 13 23:56:16.215828 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 13 23:56:16.215836 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 13 23:56:16.215845 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 13 23:56:16.215855 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs May 13 23:56:16.215864 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 13 23:56:16.215872 kernel: Booting paravirtualized kernel on Hyper-V May 13 23:56:16.215880 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 13 23:56:16.215888 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 May 13 23:56:16.215896 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 May 13 23:56:16.215905 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 May 13 23:56:16.215913 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - May 13 23:56:16.215921 kernel: Hyper-V: PV spinlocks enabled May 13 23:56:16.215939 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 13 23:56:16.215949 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:16.215958 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 23:56:16.215967 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) May 13 23:56:16.215975 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) May 13 23:56:16.215983 kernel: Fallback order for Node 0: 0 May 13 23:56:16.215991 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 May 13 23:56:16.215999 kernel: Policy zone: Normal May 13 23:56:16.216011 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 23:56:16.216039 kernel: software IO TLB: area num 8. May 13 23:56:16.216049 kernel: Memory: 115326388K/117439364K available (14336K kernel code, 2296K rwdata, 25068K rodata, 43604K init, 1468K bss, 2112720K reserved, 0K cma-reserved) May 13 23:56:16.216060 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 May 13 23:56:16.216069 kernel: Kernel/User page tables isolation: enabled May 13 23:56:16.216078 kernel: ftrace: allocating 37993 entries in 149 pages May 13 23:56:16.216086 kernel: ftrace: allocated 149 pages with 4 groups May 13 23:56:16.216095 kernel: Dynamic Preempt: voluntary May 13 23:56:16.216103 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 23:56:16.216112 kernel: rcu: RCU event tracing is enabled. May 13 23:56:16.216121 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. May 13 23:56:16.216130 kernel: Trampoline variant of Tasks RCU enabled. May 13 23:56:16.216141 kernel: Rude variant of Tasks RCU enabled. May 13 23:56:16.216150 kernel: Tracing variant of Tasks RCU enabled. May 13 23:56:16.216158 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 23:56:16.216167 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 May 13 23:56:16.216178 kernel: Using NULL legacy PIC May 13 23:56:16.216187 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 May 13 23:56:16.216195 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 13 23:56:16.216204 kernel: Console: colour dummy device 80x25 May 13 23:56:16.216212 kernel: printk: console [tty1] enabled May 13 23:56:16.216221 kernel: printk: console [ttyS0] enabled May 13 23:56:16.216230 kernel: printk: bootconsole [earlyser0] disabled May 13 23:56:16.216238 kernel: ACPI: Core revision 20230628 May 13 23:56:16.216247 kernel: Failed to register legacy timer interrupt May 13 23:56:16.216255 kernel: APIC: Switch to symmetric I/O mode setup May 13 23:56:16.216266 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 13 23:56:16.216275 kernel: Hyper-V: Using IPI hypercalls May 13 23:56:16.216283 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 13 23:56:16.216292 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 13 23:56:16.216300 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 13 23:56:16.216309 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 13 23:56:16.216318 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 13 23:56:16.216326 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 13 23:56:16.216335 kernel: Hyper-V: Using enlightened APIC (xapic mode) May 13 23:56:16.216345 kernel: APIC: eoi() replaced with hv_apic_eoi_write() May 13 23:56:16.216354 kernel: APIC: read() replaced with hv_apic_read() May 13 23:56:16.216362 kernel: APIC: write() replaced with hv_apic_write() May 13 23:56:16.216371 kernel: APIC: icr_write() replaced with hv_apic_icr_write() May 13 23:56:16.216379 kernel: APIC: icr_read() replaced with hv_apic_icr_read() May 13 23:56:16.216388 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593991) May 13 23:56:16.216397 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 May 13 23:56:16.216405 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 May 13 23:56:16.216442 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 13 23:56:16.216456 kernel: Spectre V2 : Mitigation: Retpolines May 13 23:56:16.216464 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 13 23:56:16.216473 kernel: Speculative Store Bypass: Vulnerable May 13 23:56:16.216481 kernel: MDS: Mitigation: Clear CPU buffers May 13 23:56:16.216489 kernel: TAA: Mitigation: Clear CPU buffers May 13 23:56:16.216498 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 13 23:56:16.216506 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 13 23:56:16.216514 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 13 23:56:16.216523 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 13 23:56:16.216531 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 13 23:56:16.216539 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 13 23:56:16.216550 kernel: Freeing SMP alternatives memory: 32K May 13 23:56:16.216559 kernel: pid_max: default: 32768 minimum: 301 May 13 23:56:16.216567 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 13 23:56:16.216575 kernel: landlock: Up and running. May 13 23:56:16.216584 kernel: SELinux: Initializing. May 13 23:56:16.216592 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 13 23:56:16.216601 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 13 23:56:16.216609 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) May 13 23:56:16.216618 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:16.216627 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:16.216638 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:16.216647 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 13 23:56:16.216655 kernel: signal: max sigframe size: 1776 May 13 23:56:16.216664 kernel: rcu: Hierarchical SRCU implementation. May 13 23:56:16.216673 kernel: rcu: Max phase no-delay instances is 400. May 13 23:56:16.216681 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 13 23:56:16.216690 kernel: smp: Bringing up secondary CPUs ... May 13 23:56:16.216698 kernel: smpboot: x86: Booting SMP configuration: May 13 23:56:16.216707 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 May 13 23:56:16.216718 kernel: smp: Brought up 1 node, 6 CPUs May 13 23:56:16.216727 kernel: smpboot: Max logical packages: 1 May 13 23:56:16.216735 kernel: smpboot: Total of 6 processors activated (31127.89 BogoMIPS) May 13 23:56:16.216744 kernel: devtmpfs: initialized May 13 23:56:16.216752 kernel: x86/mm: Memory block size: 1024MB May 13 23:56:16.216761 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 13 23:56:16.216769 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 23:56:16.216778 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) May 13 23:56:16.216787 kernel: pinctrl core: initialized pinctrl subsystem May 13 23:56:16.216798 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 23:56:16.216814 kernel: audit: initializing netlink subsys (disabled) May 13 23:56:16.216823 kernel: audit: type=2000 audit(1747180574.028:1): state=initialized audit_enabled=0 res=1 May 13 23:56:16.216832 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 23:56:16.216840 kernel: thermal_sys: Registered thermal governor 'user_space' May 13 23:56:16.216849 kernel: cpuidle: using governor menu May 13 23:56:16.216857 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 23:56:16.216866 kernel: dca service started, version 1.12.1 May 13 23:56:16.216874 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 13 23:56:16.216886 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 13 23:56:16.216895 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 13 23:56:16.216904 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 13 23:56:16.216913 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 13 23:56:16.216921 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 13 23:56:16.216930 kernel: ACPI: Added _OSI(Module Device) May 13 23:56:16.216938 kernel: ACPI: Added _OSI(Processor Device) May 13 23:56:16.216947 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 23:56:16.216955 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 23:56:16.216966 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 23:56:16.216975 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 13 23:56:16.216984 kernel: ACPI: Interpreter enabled May 13 23:56:16.216992 kernel: ACPI: PM: (supports S0 S5) May 13 23:56:16.217000 kernel: ACPI: Using IOAPIC for interrupt routing May 13 23:56:16.217009 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 13 23:56:16.217028 kernel: PCI: Ignoring E820 reservations for host bridge windows May 13 23:56:16.217038 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 13 23:56:16.217046 kernel: iommu: Default domain type: Translated May 13 23:56:16.217055 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 13 23:56:16.217067 kernel: efivars: Registered efivars operations May 13 23:56:16.217076 kernel: PCI: Using ACPI for IRQ routing May 13 23:56:16.217084 kernel: PCI: System does not support PCI May 13 23:56:16.217093 kernel: vgaarb: loaded May 13 23:56:16.217110 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page May 13 23:56:16.217124 kernel: VFS: Disk quotas dquot_6.6.0 May 13 23:56:16.217141 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 23:56:16.217150 kernel: pnp: PnP ACPI init May 13 23:56:16.217162 kernel: pnp: PnP ACPI: found 3 devices May 13 23:56:16.217171 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 13 23:56:16.217179 kernel: NET: Registered PF_INET protocol family May 13 23:56:16.217188 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 23:56:16.217197 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) May 13 23:56:16.217205 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 23:56:16.217214 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 13 23:56:16.217222 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:16.217231 kernel: TCP: Hash tables configured (established 524288 bind 65536) May 13 23:56:16.217242 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:16.217251 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 13 23:56:16.217259 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 23:56:16.217268 kernel: NET: Registered PF_XDP protocol family May 13 23:56:16.217276 kernel: PCI: CLS 0 bytes, default 64 May 13 23:56:16.217285 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 13 23:56:16.217294 kernel: software IO TLB: mapped [mem 0x000000003ae5e000-0x000000003ee5e000] (64MB) May 13 23:56:16.217302 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 13 23:56:16.217311 kernel: Initialise system trusted keyrings May 13 23:56:16.217322 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 May 13 23:56:16.217330 kernel: Key type asymmetric registered May 13 23:56:16.217338 kernel: Asymmetric key parser 'x509' registered May 13 23:56:16.217347 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 13 23:56:16.217355 kernel: io scheduler mq-deadline registered May 13 23:56:16.217364 kernel: io scheduler kyber registered May 13 23:56:16.217372 kernel: io scheduler bfq registered May 13 23:56:16.217381 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 13 23:56:16.217389 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 23:56:16.217401 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 13 23:56:16.217409 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 13 23:56:16.217418 kernel: i8042: PNP: No PS/2 controller found. May 13 23:56:16.217654 kernel: rtc_cmos 00:02: registered as rtc0 May 13 23:56:16.217768 kernel: rtc_cmos 00:02: setting system clock to 2025-05-13T23:56:15 UTC (1747180575) May 13 23:56:16.217873 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 13 23:56:16.217887 kernel: intel_pstate: CPU model not supported May 13 23:56:16.217896 kernel: efifb: probing for efifb May 13 23:56:16.217909 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 13 23:56:16.217918 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 13 23:56:16.217927 kernel: efifb: scrolling: redraw May 13 23:56:16.217935 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 13 23:56:16.217944 kernel: Console: switching to colour frame buffer device 128x48 May 13 23:56:16.217952 kernel: fb0: EFI VGA frame buffer device May 13 23:56:16.217961 kernel: pstore: Using crash dump compression: deflate May 13 23:56:16.217970 kernel: pstore: Registered efi_pstore as persistent store backend May 13 23:56:16.217978 kernel: NET: Registered PF_INET6 protocol family May 13 23:56:16.217990 kernel: Segment Routing with IPv6 May 13 23:56:16.217998 kernel: In-situ OAM (IOAM) with IPv6 May 13 23:56:16.218007 kernel: NET: Registered PF_PACKET protocol family May 13 23:56:16.218015 kernel: Key type dns_resolver registered May 13 23:56:16.218036 kernel: IPI shorthand broadcast: enabled May 13 23:56:16.218047 kernel: sched_clock: Marking stable (1663004200, 103000800)->(2103490200, -337485200) May 13 23:56:16.218056 kernel: registered taskstats version 1 May 13 23:56:16.218064 kernel: Loading compiled-in X.509 certificates May 13 23:56:16.218073 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 166efda032ca4d6e9037c569aca9b53585ee6f94' May 13 23:56:16.218085 kernel: Key type .fscrypt registered May 13 23:56:16.218094 kernel: Key type fscrypt-provisioning registered May 13 23:56:16.218102 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 23:56:16.218111 kernel: ima: Allocated hash algorithm: sha1 May 13 23:56:16.218119 kernel: ima: No architecture policies found May 13 23:56:16.218128 kernel: clk: Disabling unused clocks May 13 23:56:16.218136 kernel: Freeing unused kernel image (initmem) memory: 43604K May 13 23:56:16.218145 kernel: Write protecting the kernel read-only data: 40960k May 13 23:56:16.218154 kernel: Freeing unused kernel image (rodata/data gap) memory: 1556K May 13 23:56:16.218165 kernel: Run /init as init process May 13 23:56:16.218174 kernel: with arguments: May 13 23:56:16.218182 kernel: /init May 13 23:56:16.218190 kernel: with environment: May 13 23:56:16.218199 kernel: HOME=/ May 13 23:56:16.218207 kernel: TERM=linux May 13 23:56:16.218215 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 23:56:16.218225 systemd[1]: Successfully made /usr/ read-only. May 13 23:56:16.218237 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:16.218249 systemd[1]: Detected virtualization microsoft. May 13 23:56:16.218258 systemd[1]: Detected architecture x86-64. May 13 23:56:16.218267 systemd[1]: Running in initrd. May 13 23:56:16.218276 systemd[1]: No hostname configured, using default hostname. May 13 23:56:16.218285 systemd[1]: Hostname set to . May 13 23:56:16.218294 systemd[1]: Initializing machine ID from random generator. May 13 23:56:16.218303 systemd[1]: Queued start job for default target initrd.target. May 13 23:56:16.218314 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:16.218323 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:16.218333 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 13 23:56:16.218342 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:56:16.218351 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 13 23:56:16.218361 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 13 23:56:16.218371 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:16.218383 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 13 23:56:16.218392 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:16.218401 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:16.218410 systemd[1]: Reached target paths.target - Path Units. May 13 23:56:16.218419 systemd[1]: Reached target slices.target - Slice Units. May 13 23:56:16.218428 systemd[1]: Reached target swap.target - Swaps. May 13 23:56:16.218437 systemd[1]: Reached target timers.target - Timer Units. May 13 23:56:16.218447 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:56:16.218458 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:56:16.218468 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 13 23:56:16.218477 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 13 23:56:16.218486 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:16.218495 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:56:16.218504 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:16.218513 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:56:16.218522 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 13 23:56:16.218531 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:56:16.218542 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 13 23:56:16.218551 systemd[1]: Starting systemd-fsck-usr.service... May 13 23:56:16.218560 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:56:16.218569 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:56:16.218599 systemd-journald[203]: Collecting audit messages is disabled. May 13 23:56:16.218626 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:16.218636 systemd-journald[203]: Journal started May 13 23:56:16.218656 systemd-journald[203]: Runtime Journal (/run/log/journal/44b5d77618a04d5b9657d16555795c0e) is 8M, max 2.2G, 2.1G free. May 13 23:56:16.221863 systemd-modules-load[205]: Inserted module 'overlay' May 13 23:56:16.228036 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:56:16.228146 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 13 23:56:16.229396 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:16.230275 systemd[1]: Finished systemd-fsck-usr.service. May 13 23:56:16.232990 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:56:16.233714 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:56:16.247598 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:56:16.248937 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:56:16.303755 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:16.309454 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:16.319828 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:16.334446 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:16.354036 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:16.357924 systemd-modules-load[205]: Inserted module 'br_netfilter' May 13 23:56:16.361561 kernel: Bridge firewalling registered May 13 23:56:16.368194 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:56:16.369826 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:56:16.383912 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:16.386352 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 13 23:56:16.407421 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:16.410156 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:56:16.428564 dracut-cmdline[238]: dracut-dracut-053 May 13 23:56:16.431438 dracut-cmdline[238]: 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:16.449336 systemd-resolved[245]: Positive Trust Anchors: May 13 23:56:16.449345 systemd-resolved[245]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:56:16.449375 systemd-resolved[245]: 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:16.451999 systemd-resolved[245]: Defaulting to hostname 'linux'. May 13 23:56:16.453055 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:56:16.456185 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:16.577051 kernel: SCSI subsystem initialized May 13 23:56:16.588041 kernel: Loading iSCSI transport class v2.0-870. May 13 23:56:16.600041 kernel: iscsi: registered transport (tcp) May 13 23:56:16.623372 kernel: iscsi: registered transport (qla4xxx) May 13 23:56:16.623401 kernel: QLogic iSCSI HBA Driver May 13 23:56:16.661734 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 13 23:56:16.664455 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 13 23:56:16.722262 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:16.722302 kernel: device-mapper: uevent: version 1.0.3 May 13 23:56:16.726898 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 13 23:56:16.768051 kernel: raid6: avx2x4 gen() 31000 MB/s May 13 23:56:16.788038 kernel: raid6: avx2x2 gen() 31122 MB/s May 13 23:56:16.809228 kernel: raid6: avx2x1 gen() 27594 MB/s May 13 23:56:16.809252 kernel: raid6: using algorithm avx2x2 gen() 31122 MB/s May 13 23:56:16.831306 kernel: raid6: .... xor() 18149 MB/s, rmw enabled May 13 23:56:16.831333 kernel: raid6: using avx2x2 recovery algorithm May 13 23:56:16.855039 kernel: xor: automatically using best checksumming function avx May 13 23:56:17.007051 kernel: Btrfs loaded, zoned=no, fsverity=no May 13 23:56:17.017537 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 13 23:56:17.020515 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:17.062278 systemd-udevd[424]: Using default interface naming scheme 'v255'. May 13 23:56:17.067707 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:17.070012 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 13 23:56:17.106124 dracut-pre-trigger[432]: rd.md=0: removing MD RAID activation May 13 23:56:17.136988 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:56:17.139671 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:56:17.216145 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:17.232132 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 13 23:56:17.245038 kernel: cryptd: max_cpu_qlen set to 1000 May 13 23:56:17.261250 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 13 23:56:17.290285 kernel: hv_vmbus: Vmbus version:4.0 May 13 23:56:17.290315 kernel: AVX2 version of gcm_enc/dec engaged. May 13 23:56:17.290328 kernel: AES CTR mode by8 optimization enabled May 13 23:56:17.290340 kernel: pps_core: LinuxPPS API ver. 1 registered May 13 23:56:17.290359 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 13 23:56:17.290372 kernel: PTP clock support registered May 13 23:56:17.290384 kernel: hv_vmbus: registering driver hyperv_keyboard May 13 23:56:17.272187 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:56:17.302567 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:17.310045 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:17.310984 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:56:17.324458 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 13 23:56:17.336511 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:56:17.347373 kernel: hv_vmbus: registering driver hv_storvsc May 13 23:56:17.347397 kernel: hv_vmbus: registering driver hv_netvsc May 13 23:56:17.347410 kernel: hid: raw HID events driver (C) Jiri Kosina May 13 23:56:17.336747 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:17.365344 kernel: scsi host1: storvsc_host_t May 13 23:56:17.365540 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 13 23:56:17.365568 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 13 23:56:17.371042 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 13 23:56:17.373066 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:17.381688 kernel: scsi host0: storvsc_host_t May 13 23:56:17.380212 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:17.380439 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:17.409251 kernel: hv_utils: Registering HyperV Utility Driver May 13 23:56:17.409285 kernel: hv_vmbus: registering driver hv_utils May 13 23:56:17.394176 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:17.416297 kernel: hv_vmbus: registering driver hid_hyperv May 13 23:56:17.416318 kernel: hv_vmbus: registering driver hv_pci May 13 23:56:17.403187 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:17.427833 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:17.439461 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 13 23:56:17.448461 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:17.448576 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:17.457866 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:17.478894 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:17.481187 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:56:17.514211 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:17.702066 kernel: hv_utils: Heartbeat IC version 3.0 May 13 23:56:17.702116 kernel: hv_utils: Shutdown IC version 3.2 May 13 23:56:17.706919 kernel: hv_utils: TimeSync IC version 4.0 May 13 23:56:17.708042 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 May 13 23:56:18.446027 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 13 23:56:18.445881 systemd-resolved[245]: Clock change detected. Flushing caches. May 13 23:56:18.491254 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 May 13 23:56:18.491476 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 13 23:56:18.491613 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] May 13 23:56:18.491780 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] May 13 23:56:18.491935 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] May 13 23:56:18.492063 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 May 13 23:56:18.492091 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] May 13 23:56:18.492110 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] May 13 23:56:18.506957 kernel: sr 1:0:0:2: [sr0] scsi-1 drive May 13 23:56:18.507223 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] May 13 23:56:18.507250 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 13 23:56:18.509826 kernel: pci 0001:00:00.0: Enabling HDA controller May 13 23:56:18.514825 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 May 13 23:56:18.531834 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 13 23:56:18.532037 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) May 13 23:56:18.532203 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks May 13 23:56:18.532358 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 May 13 23:56:18.532492 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] May 13 23:56:18.532648 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] May 13 23:56:18.532789 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] May 13 23:56:18.532951 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks May 13 23:56:18.538949 kernel: sd 1:0:0:0: [sda] Write Protect is off May 13 23:56:18.543064 kernel: sd 1:0:0:1: [sdb] Write Protect is off May 13 23:56:18.543254 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 13 23:56:18.543402 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 13 23:56:18.550569 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 May 13 23:56:18.555701 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:18.555730 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA May 13 23:56:18.558844 kernel: sd 1:0:0:0: [sda] Attached SCSI disk May 13 23:56:18.559826 kernel: sdb: sdb1 May 13 23:56:18.580856 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk May 13 23:56:19.196828 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (469) May 13 23:56:19.217914 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 13 23:56:19.237830 kernel: BTRFS: device fsid d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 devid 1 transid 39 /dev/sda3 scanned by (udev-worker) (480) May 13 23:56:19.251518 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 13 23:56:19.266172 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 13 23:56:19.271451 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 13 23:56:19.284150 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 13 23:56:19.382723 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 13 23:56:20.331668 disk-uuid[639]: The operation has completed successfully. May 13 23:56:20.335173 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 13 23:56:20.701715 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 23:56:20.701842 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 13 23:56:20.704226 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 13 23:56:20.726519 sh[729]: Success May 13 23:56:20.756831 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" May 13 23:56:20.959317 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 13 23:56:20.962633 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 13 23:56:20.986690 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 13 23:56:21.003183 kernel: BTRFS info (device dm-0): first mount of filesystem d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 May 13 23:56:21.003219 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:21.008612 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 13 23:56:21.012338 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 13 23:56:21.015398 kernel: BTRFS info (device dm-0): using free space tree May 13 23:56:21.372544 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 13 23:56:21.374642 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.375446 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 13 23:56:21.395324 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 13 23:56:21.413831 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:21.413868 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:21.420673 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:21.438828 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:21.446827 kernel: BTRFS info (device sda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:21.454896 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 13 23:56:21.456916 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 13 23:56:21.503292 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:56:21.506263 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:56:21.552229 systemd-networkd[914]: lo: Link UP May 13 23:56:21.552240 systemd-networkd[914]: lo: Gained carrier May 13 23:56:21.553645 systemd-networkd[914]: Enumeration completed May 13 23:56:21.553855 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:56:21.554029 systemd-networkd[914]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:21.554033 systemd-networkd[914]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:21.554899 systemd-networkd[914]: eth0: Link UP May 13 23:56:21.555065 systemd-networkd[914]: eth0: Gained carrier May 13 23:56:21.555074 systemd-networkd[914]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:21.559950 systemd[1]: Reached target network.target - Network. May 13 23:56:21.617842 systemd-networkd[914]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:56:22.772063 ignition[870]: Ignition 2.20.0 May 13 23:56:22.772076 ignition[870]: Stage: fetch-offline May 13 23:56:22.772127 ignition[870]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:22.772139 ignition[870]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:22.772250 ignition[870]: parsed url from cmdline: "" May 13 23:56:22.772255 ignition[870]: no config URL provided May 13 23:56:22.772261 ignition[870]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:56:22.772272 ignition[870]: no config at "/usr/lib/ignition/user.ign" May 13 23:56:22.772277 ignition[870]: failed to fetch config: resource requires networking May 13 23:56:22.772498 ignition[870]: Ignition finished successfully May 13 23:56:22.796238 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:56:22.798413 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 13 23:56:22.837429 ignition[926]: Ignition 2.20.0 May 13 23:56:22.837440 ignition[926]: Stage: fetch May 13 23:56:22.837582 ignition[926]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:22.837594 ignition[926]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:22.837706 ignition[926]: parsed url from cmdline: "" May 13 23:56:22.837710 ignition[926]: no config URL provided May 13 23:56:22.837716 ignition[926]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:56:22.837726 ignition[926]: no config at "/usr/lib/ignition/user.ign" May 13 23:56:22.837757 ignition[926]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 13 23:56:22.922962 ignition[926]: GET result: OK May 13 23:56:22.923024 ignition[926]: config has been read from IMDS userdata May 13 23:56:22.923036 ignition[926]: parsing config with SHA512: 61cfd5212aa969fd0b1d7558fc3f6c31a0f3ac425af324331bed64b13342a383f9e098cd08dc8bad070926ae8c725a5925c022f6d34f3388717634b6df30376d May 13 23:56:22.929308 unknown[926]: fetched base config from "system" May 13 23:56:22.929320 unknown[926]: fetched base config from "system" May 13 23:56:22.929525 ignition[926]: fetch: fetch complete May 13 23:56:22.929325 unknown[926]: fetched user config from "azure" May 13 23:56:22.929530 ignition[926]: fetch: fetch passed May 13 23:56:22.940050 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 13 23:56:22.929573 ignition[926]: Ignition finished successfully May 13 23:56:22.946539 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 13 23:56:22.982075 ignition[936]: Ignition 2.20.0 May 13 23:56:22.982088 ignition[936]: Stage: kargs May 13 23:56:22.982291 ignition[936]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:22.982304 ignition[936]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:22.982982 ignition[936]: kargs: kargs passed May 13 23:56:22.990621 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 13 23:56:22.983345 ignition[936]: Ignition finished successfully May 13 23:56:22.999556 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 13 23:56:23.029214 ignition[947]: Ignition 2.20.0 May 13 23:56:23.029225 ignition[947]: Stage: disks May 13 23:56:23.029377 ignition[947]: no configs at "/usr/lib/ignition/base.d" May 13 23:56:23.029389 ignition[947]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:23.030378 ignition[947]: disks: disks passed May 13 23:56:23.038253 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 13 23:56:23.030542 ignition[947]: Ignition finished successfully May 13 23:56:23.046017 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 13 23:56:23.047991 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 13 23:56:23.058323 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:56:23.067386 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:56:23.078262 systemd[1]: Reached target basic.target - Basic System. May 13 23:56:23.097534 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 13 23:56:23.206577 systemd-fsck[959]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks May 13 23:56:23.214075 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 13 23:56:23.225217 systemd[1]: Mounting sysroot.mount - /sysroot... May 13 23:56:23.342830 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.343836 systemd[1]: Mounted sysroot.mount - /sysroot. May 13 23:56:23.345875 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 13 23:56:23.380604 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:56:23.382906 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 13 23:56:23.403321 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 13 23:56:23.408096 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.442003 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (970) May 13 23:56:23.442032 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:23.442047 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:23.442059 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:23.408130 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:56:23.423051 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 13 23:56:23.444611 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 13 23:56:23.467825 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:23.469062 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:56:23.594925 systemd-networkd[914]: eth0: Gained IPv6LL May 13 23:56:24.351571 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory May 13 23:56:24.390592 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory May 13 23:56:24.412801 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory May 13 23:56:24.449042 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory May 13 23:56:24.474191 coreos-metadata[972]: May 13 23:56:24.474 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 13 23:56:24.480144 coreos-metadata[972]: May 13 23:56:24.476 INFO Fetch successful May 13 23:56:24.480144 coreos-metadata[972]: May 13 23:56:24.477 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.491919 coreos-metadata[972]: May 13 23:56:24.484 INFO Fetch successful May 13 23:56:24.491919 coreos-metadata[972]: May 13 23:56:24.484 INFO wrote hostname ci-4284.0.0-n-50b71dfdc2 to /sysroot/etc/hostname May 13 23:56:24.486581 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:56:25.775892 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 13 23:56:25.785803 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 13 23:56:25.788011 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 13 23:56:25.812867 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 13 23:56:25.821738 kernel: BTRFS info (device sda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:25.841995 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 13 23:56:25.852883 ignition[1091]: INFO : Ignition 2.20.0 May 13 23:56:25.852883 ignition[1091]: INFO : Stage: mount May 13 23:56:25.858799 ignition[1091]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:25.858799 ignition[1091]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:25.858799 ignition[1091]: INFO : mount: mount passed May 13 23:56:25.858799 ignition[1091]: INFO : Ignition finished successfully May 13 23:56:25.863139 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 13 23:56:25.866272 systemd[1]: Starting ignition-files.service - Ignition (files)... May 13 23:56:25.899426 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:56:25.930827 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1104) May 13 23:56:25.930863 kernel: BTRFS info (device sda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:56:25.939314 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:56:25.942588 kernel: BTRFS info (device sda6): using free space tree May 13 23:56:25.948836 kernel: BTRFS info (device sda6): auto enabling async discard May 13 23:56:25.950667 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:56:25.978674 ignition[1121]: INFO : Ignition 2.20.0 May 13 23:56:25.978674 ignition[1121]: INFO : Stage: files May 13 23:56:25.984685 ignition[1121]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:25.984685 ignition[1121]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:25.984685 ignition[1121]: DEBUG : files: compiled without relabeling support, skipping May 13 23:56:25.998501 ignition[1121]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 13 23:56:25.998501 ignition[1121]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 13 23:56:26.136492 ignition[1121]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 13 23:56:26.142024 ignition[1121]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 13 23:56:26.147297 ignition[1121]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 13 23:56:26.142474 unknown[1121]: wrote ssh authorized keys file for user: core May 13 23:56:26.189235 ignition[1121]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:56:26.196569 ignition[1121]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:56:26.196569 ignition[1121]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 23:56:26.196569 ignition[1121]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 23:56:26.196569 ignition[1121]: INFO : files: files passed May 13 23:56:26.196569 ignition[1121]: INFO : Ignition finished successfully May 13 23:56:26.205007 systemd[1]: Finished ignition-files.service - Ignition (files). May 13 23:56:26.215774 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 13 23:56:26.244043 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 13 23:56:26.255196 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 23:56:26.255300 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 13 23:56:26.274225 initrd-setup-root-after-ignition[1154]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.274225 initrd-setup-root-after-ignition[1154]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.286702 initrd-setup-root-after-ignition[1158]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:56:26.287374 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:56:26.289214 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 13 23:56:26.308466 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 13 23:56:26.359794 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 23:56:26.359914 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 13 23:56:26.368599 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 13 23:56:26.377419 systemd[1]: Reached target initrd.target - Initrd Default Target. May 13 23:56:26.385212 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 13 23:56:26.386356 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 13 23:56:26.422800 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:56:26.424933 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 13 23:56:26.457667 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:26.459352 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:26.468564 systemd[1]: Stopped target timers.target - Timer Units. May 13 23:56:26.477962 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 23:56:26.478093 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:56:26.492141 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 13 23:56:26.501605 systemd[1]: Stopped target basic.target - Basic System. May 13 23:56:26.503676 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 13 23:56:26.513925 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:56:26.521716 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 13 23:56:26.534592 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 13 23:56:26.542974 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:56:26.551898 systemd[1]: Stopped target sysinit.target - System Initialization. May 13 23:56:26.560670 systemd[1]: Stopped target local-fs.target - Local File Systems. May 13 23:56:26.568503 systemd[1]: Stopped target swap.target - Swaps. May 13 23:56:26.575445 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 23:56:26.575577 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 13 23:56:26.586977 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:26.588391 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:26.604479 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 13 23:56:26.609211 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:26.618139 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 23:56:26.618264 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 13 23:56:26.631803 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 23:56:26.631951 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:56:26.640876 systemd[1]: ignition-files.service: Deactivated successfully. May 13 23:56:26.640994 systemd[1]: Stopped ignition-files.service - Ignition (files). May 13 23:56:26.649065 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 13 23:56:26.649190 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:56:26.659789 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 13 23:56:26.666621 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 13 23:56:26.673083 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 23:56:26.673214 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:26.716169 ignition[1179]: INFO : Ignition 2.20.0 May 13 23:56:26.716169 ignition[1179]: INFO : Stage: umount May 13 23:56:26.716169 ignition[1179]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:56:26.716169 ignition[1179]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 13 23:56:26.716169 ignition[1179]: INFO : umount: umount passed May 13 23:56:26.716169 ignition[1179]: INFO : Ignition finished successfully May 13 23:56:26.681926 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 23:56:26.682046 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:56:26.698536 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 23:56:26.698631 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 13 23:56:26.702670 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 23:56:26.702769 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 13 23:56:26.710438 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 23:56:26.710908 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 23:56:26.710957 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 13 23:56:26.717699 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 23:56:26.717755 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 13 23:56:26.724658 systemd[1]: ignition-fetch.service: Deactivated successfully. May 13 23:56:26.724714 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 13 23:56:26.731294 systemd[1]: Stopped target network.target - Network. May 13 23:56:26.739056 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 23:56:26.739110 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:56:26.747226 systemd[1]: Stopped target paths.target - Path Units. May 13 23:56:26.754785 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 23:56:26.764969 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:26.766550 systemd[1]: Stopped target slices.target - Slice Units. May 13 23:56:26.775583 systemd[1]: Stopped target sockets.target - Socket Units. May 13 23:56:26.782955 systemd[1]: iscsid.socket: Deactivated successfully. May 13 23:56:26.783071 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:56:26.790837 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 23:56:26.790952 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:56:26.792292 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 23:56:26.792343 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 13 23:56:26.807121 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 13 23:56:26.807194 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 13 23:56:26.814921 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 13 23:56:26.822793 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 13 23:56:26.830895 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 23:56:26.830995 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 13 23:56:26.847488 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 13 23:56:26.847760 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 23:56:26.848842 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 13 23:56:26.860793 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 13 23:56:26.861061 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 23:56:26.861149 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 13 23:56:26.868887 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 23:56:26.868935 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:26.876621 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 23:56:26.876672 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 13 23:56:26.885132 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 13 23:56:26.891975 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 23:56:26.892030 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:56:26.893491 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 23:56:26.893537 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:26.913859 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 23:56:26.913916 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 13 23:56:26.915882 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 13 23:56:26.915927 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:26.933229 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:26.942646 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 13 23:56:26.942712 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:26.979473 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 23:56:26.979622 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:26.984653 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 23:56:26.984745 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 13 23:56:26.993459 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 23:56:26.993517 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 13 23:56:27.000843 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 23:56:27.000933 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:27.010206 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 23:56:27.010259 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 13 23:56:27.024198 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 23:56:27.024249 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 13 23:56:27.039985 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:56:27.040044 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:56:27.055005 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 13 23:56:27.062629 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 13 23:56:27.062692 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:27.074357 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 13 23:56:27.074411 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:56:27.082002 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 23:56:27.082050 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:27.090936 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:27.090983 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:27.101090 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 13 23:56:27.101151 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:56:27.101515 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 23:56:27.101602 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 13 23:56:27.105089 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 13 23:56:27.114909 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 13 23:56:27.154372 systemd[1]: Switching root. May 13 23:56:27.341530 systemd-journald[203]: Journal stopped May 13 23:56:32.983374 systemd-journald[203]: Received SIGTERM from PID 1 (systemd). May 13 23:56:32.983405 kernel: SELinux: policy capability network_peer_controls=1 May 13 23:56:32.983418 kernel: SELinux: policy capability open_perms=1 May 13 23:56:32.983428 kernel: SELinux: policy capability extended_socket_class=1 May 13 23:56:32.983437 kernel: SELinux: policy capability always_check_network=0 May 13 23:56:32.983449 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 23:56:32.983460 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 23:56:32.983473 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 23:56:32.983482 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 23:56:32.983492 kernel: audit: type=1403 audit(1747180588.050:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 13 23:56:32.983503 systemd[1]: Successfully loaded SELinux policy in 142.125ms. May 13 23:56:32.983514 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.082ms. May 13 23:56:32.983526 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.983536 systemd[1]: Detected virtualization microsoft. May 13 23:56:32.983550 systemd[1]: Detected architecture x86-64. May 13 23:56:32.983562 systemd[1]: Detected first boot. May 13 23:56:32.983573 systemd[1]: Hostname set to . May 13 23:56:32.983583 systemd[1]: Initializing machine ID from random generator. May 13 23:56:32.983594 zram_generator::config[1225]: No configuration found. May 13 23:56:32.983608 kernel: Guest personality initialized and is inactive May 13 23:56:32.983618 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 13 23:56:32.983628 kernel: Initialized host personality May 13 23:56:32.983637 kernel: NET: Registered PF_VSOCK protocol family May 13 23:56:32.983648 systemd[1]: Populated /etc with preset unit settings. May 13 23:56:32.983659 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 13 23:56:32.983669 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 13 23:56:32.983685 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 13 23:56:32.983700 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 13 23:56:32.983711 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 13 23:56:32.983722 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 13 23:56:32.983733 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 13 23:56:32.983744 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 13 23:56:32.983755 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 13 23:56:32.983766 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 13 23:56:32.983780 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 13 23:56:32.983791 systemd[1]: Created slice user.slice - User and Session Slice. May 13 23:56:32.983801 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:56:32.983821 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:56:32.983832 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 13 23:56:32.983843 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 13 23:56:32.983859 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 13 23:56:32.983870 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:56:32.983884 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 13 23:56:32.983895 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:56:32.983906 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 13 23:56:32.983918 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 13 23:56:32.983929 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 13 23:56:32.983943 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 13 23:56:32.983954 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:56:32.983965 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:56:32.983979 systemd[1]: Reached target slices.target - Slice Units. May 13 23:56:32.983990 systemd[1]: Reached target swap.target - Swaps. May 13 23:56:32.984001 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 13 23:56:32.984012 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 13 23:56:32.984023 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 13 23:56:32.984038 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:56:32.984049 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:56:32.984060 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:56:32.984072 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 13 23:56:32.984083 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 13 23:56:32.984094 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 13 23:56:32.984105 systemd[1]: Mounting media.mount - External Media Directory... May 13 23:56:32.984117 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:32.984131 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 13 23:56:32.984142 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 13 23:56:32.984153 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 13 23:56:32.984165 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.984177 systemd[1]: Reached target machines.target - Containers. May 13 23:56:32.984191 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 13 23:56:32.984202 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:32.984214 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:56:32.984228 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 13 23:56:32.984240 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:32.984251 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:56:32.984262 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:32.984273 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 13 23:56:32.984285 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:32.984296 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.984307 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 13 23:56:32.984321 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 13 23:56:32.984333 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 13 23:56:32.984344 systemd[1]: Stopped systemd-fsck-usr.service. May 13 23:56:32.984356 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.984367 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:56:32.984378 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:56:32.984390 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 13 23:56:32.984401 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 13 23:56:32.984415 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 13 23:56:32.984426 kernel: loop: module loaded May 13 23:56:32.984440 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:56:32.984451 systemd[1]: verity-setup.service: Deactivated successfully. May 13 23:56:32.984462 systemd[1]: Stopped verity-setup.service. May 13 23:56:32.984473 kernel: fuse: init (API version 7.39) May 13 23:56:32.984484 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.984518 systemd-journald[1332]: Collecting audit messages is disabled. May 13 23:56:32.984546 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 13 23:56:32.984559 systemd-journald[1332]: Journal started May 13 23:56:32.984584 systemd-journald[1332]: Runtime Journal (/run/log/journal/8b8e0baa461744e3adf2cf34cbb12933) is 8M, max 2.2G, 2.1G free. May 13 23:56:32.209106 systemd[1]: Queued start job for default target multi-user.target. May 13 23:56:32.221252 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 13 23:56:32.221734 systemd[1]: systemd-journald.service: Deactivated successfully. May 13 23:56:32.992099 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:56:32.996313 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 13 23:56:33.001122 systemd[1]: Mounted media.mount - External Media Directory. May 13 23:56:33.005290 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 13 23:56:33.011537 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 13 23:56:33.016084 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 13 23:56:33.020286 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 13 23:56:33.025322 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:56:33.030538 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 23:56:33.030731 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 13 23:56:33.035891 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:33.036087 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:33.041401 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:33.041598 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:33.047822 kernel: ACPI: bus type drm_connector registered May 13 23:56:33.049354 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 23:56:33.049555 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 13 23:56:33.054398 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:56:33.054584 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:56:33.062949 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:33.063128 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:33.067904 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:56:33.072789 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 13 23:56:33.078133 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 13 23:56:33.083592 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 13 23:56:33.097889 systemd[1]: Reached target network-pre.target - Preparation for Network. May 13 23:56:33.103703 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 13 23:56:33.109040 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 13 23:56:33.110591 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 23:56:33.110629 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:56:33.118743 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 13 23:56:33.138985 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 13 23:56:33.145567 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 13 23:56:33.149718 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:33.151034 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 13 23:56:33.158463 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 13 23:56:33.163160 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.164106 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 13 23:56:33.168470 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:56:33.169387 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:56:33.174379 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 13 23:56:33.176762 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:56:33.182478 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:56:33.188933 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 13 23:56:33.194700 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 13 23:56:33.202201 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 13 23:56:33.207192 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 13 23:56:33.214729 systemd-journald[1332]: Time spent on flushing to /var/log/journal/8b8e0baa461744e3adf2cf34cbb12933 is 13.711ms for 942 entries. May 13 23:56:33.214729 systemd-journald[1332]: System Journal (/var/log/journal/8b8e0baa461744e3adf2cf34cbb12933) is 8M, max 2.6G, 2.6G free. May 13 23:56:33.272798 systemd-journald[1332]: Received client request to flush runtime journal. May 13 23:56:33.272868 kernel: loop0: detected capacity change from 0 to 109808 May 13 23:56:33.221475 udevadm[1374]: 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.236887 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 13 23:56:33.241872 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 13 23:56:33.247896 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 13 23:56:33.272329 systemd-tmpfiles[1368]: ACLs are not supported, ignoring. May 13 23:56:33.272345 systemd-tmpfiles[1368]: ACLs are not supported, ignoring. May 13 23:56:33.274253 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 13 23:56:33.279601 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:56:33.286604 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 13 23:56:33.310950 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 23:56:33.311559 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 13 23:56:33.393527 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:56:33.705883 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 13 23:56:33.711712 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:56:33.742286 systemd-tmpfiles[1389]: ACLs are not supported, ignoring. May 13 23:56:33.742308 systemd-tmpfiles[1389]: ACLs are not supported, ignoring. May 13 23:56:33.746719 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:56:33.846835 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 23:56:33.929836 kernel: loop1: detected capacity change from 0 to 28424 May 13 23:56:34.410032 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 13 23:56:34.416676 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:56:34.459042 systemd-udevd[1395]: Using default interface naming scheme 'v255'. May 13 23:56:34.704842 kernel: loop2: detected capacity change from 0 to 151640 May 13 23:56:34.763295 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:56:34.771638 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:56:34.790865 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 13 23:56:34.834835 kernel: hv_vmbus: registering driver hyperv_fb May 13 23:56:34.834917 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 13 23:56:34.843315 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 13 23:56:34.847903 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 13 23:56:34.849095 kernel: Console: switching to colour dummy device 80x25 May 13 23:56:34.855219 kernel: Console: switching to colour frame buffer device 128x48 May 13 23:56:34.860847 kernel: mousedev: PS/2 mouse device common for all mice May 13 23:56:34.871831 kernel: hv_vmbus: registering driver hv_balloon May 13 23:56:34.871885 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 13 23:56:34.884365 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:34.892673 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:56:34.892987 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:34.898598 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:56:34.903081 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 13 23:56:34.947870 kernel: EDAC MC: Ver: 3.0.0 May 13 23:56:34.988999 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1408) May 13 23:56:35.031758 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 13 23:56:35.036724 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.067476 systemd-networkd[1410]: lo: Link UP May 13 23:56:35.067485 systemd-networkd[1410]: lo: Gained carrier May 13 23:56:35.068934 systemd-networkd[1410]: Enumeration completed May 13 23:56:35.069039 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:56:35.069323 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:35.069333 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:35.069945 systemd-networkd[1410]: eth0: Link UP May 13 23:56:35.069954 systemd-networkd[1410]: eth0: Gained carrier May 13 23:56:35.069967 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:35.071942 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 13 23:56:35.072958 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 13 23:56:35.116854 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:56:35.119562 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 13 23:56:35.151689 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 13 23:56:35.157071 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 13 23:56:35.186825 kernel: loop3: detected capacity change from 0 to 109808 May 13 23:56:35.197825 kernel: loop4: detected capacity change from 0 to 28424 May 13 23:56:35.206828 kernel: loop5: detected capacity change from 0 to 151640 May 13 23:56:35.210274 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.216375 (sd-merge)[1527]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 13 23:56:35.216876 (sd-merge)[1527]: Merged extensions into '/usr'. May 13 23:56:35.220116 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 13 23:56:35.225518 systemd[1]: Starting ensure-sysext.service... May 13 23:56:35.227910 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:56:35.263773 systemd[1]: Reload requested from client PID 1531 ('systemctl') (unit ensure-sysext.service)... May 13 23:56:35.263790 systemd[1]: Reloading... May 13 23:56:35.283543 lvm[1526]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:56:35.290235 systemd-tmpfiles[1532]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 23:56:35.290537 systemd-tmpfiles[1532]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 13 23:56:35.291504 systemd-tmpfiles[1532]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 23:56:35.291790 systemd-tmpfiles[1532]: ACLs are not supported, ignoring. May 13 23:56:35.291873 systemd-tmpfiles[1532]: ACLs are not supported, ignoring. May 13 23:56:35.295944 systemd-tmpfiles[1532]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:56:35.295956 systemd-tmpfiles[1532]: Skipping /boot May 13 23:56:35.311040 systemd-tmpfiles[1532]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:56:35.311057 systemd-tmpfiles[1532]: Skipping /boot May 13 23:56:35.316932 zram_generator::config[1568]: No configuration found. May 13 23:56:35.447456 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.579529 systemd[1]: Reloading finished in 315 ms. May 13 23:56:35.600293 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:56:35.605491 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 13 23:56:35.628796 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:56:35.641030 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:56:35.646970 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:56:35.666223 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 13 23:56:35.681452 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 13 23:56:35.687768 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 13 23:56:35.691152 lvm[1633]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:56:35.694295 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:56:35.700123 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 13 23:56:35.715708 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.715989 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:35.717320 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:35.732294 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:35.738094 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:35.742271 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:35.742460 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.742617 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.746439 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 13 23:56:35.752580 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 13 23:56:35.758290 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:35.758463 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:35.764156 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:35.764327 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:35.770140 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:35.770315 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:35.779240 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 13 23:56:35.788164 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.788381 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:35.789601 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:35.804235 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:35.809918 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:35.814892 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:35.815019 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.815153 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.816452 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:35.816631 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:35.821774 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:35.821955 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:35.837722 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:35.837917 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:35.847483 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:56:35.847824 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:56:35.849144 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:56:35.863470 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:56:35.868682 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:56:35.874496 augenrules[1675]: No rules May 13 23:56:35.875411 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:56:35.879766 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:56:35.879908 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.880099 systemd[1]: Reached target time-set.target - System Time Set. May 13 23:56:35.884417 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.886153 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:56:35.886373 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:56:35.891087 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:56:35.891265 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:56:35.896365 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:56:35.896549 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:56:35.904098 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:56:35.904275 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:56:35.909728 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:56:35.909919 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:56:35.911580 systemd-resolved[1640]: Positive Trust Anchors: May 13 23:56:35.911599 systemd-resolved[1640]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:56:35.911631 systemd-resolved[1640]: 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:35.917717 systemd[1]: Finished ensure-sysext.service. May 13 23:56:35.924052 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.924108 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:56:35.943439 systemd-resolved[1640]: Using system hostname 'ci-4284.0.0-n-50b71dfdc2'. May 13 23:56:35.945333 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:56:35.949914 systemd[1]: Reached target network.target - Network. May 13 23:56:35.953489 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:56:36.387711 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 13 23:56:36.393231 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.906930 systemd-networkd[1410]: eth0: Gained IPv6LL May 13 23:56:36.909124 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 13 23:56:36.914594 systemd[1]: Reached target network-online.target - Network is Online. May 13 23:56:40.530327 ldconfig[1362]: /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.550284 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 13 23:56:40.557061 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 13 23:56:40.595241 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 13 23:56:40.600072 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:56:40.604562 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 13 23:56:40.609522 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.614875 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 13 23:56:40.619382 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 13 23:56:40.624428 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 13 23:56:40.629663 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.629702 systemd[1]: Reached target paths.target - Path Units. May 13 23:56:40.633433 systemd[1]: Reached target timers.target - Timer Units. May 13 23:56:40.638041 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 13 23:56:40.643903 systemd[1]: Starting docker.socket - Docker Socket for the API... May 13 23:56:40.650587 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 13 23:56:40.655981 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 13 23:56:40.661218 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 13 23:56:40.667497 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 13 23:56:40.672023 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 13 23:56:40.678504 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 13 23:56:40.683021 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:56:40.686989 systemd[1]: Reached target basic.target - Basic System. May 13 23:56:40.690890 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 13 23:56:40.690921 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 13 23:56:40.693319 systemd[1]: Starting chronyd.service - NTP client/server... May 13 23:56:40.697820 systemd[1]: Starting containerd.service - containerd container runtime... May 13 23:56:40.710677 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 13 23:56:40.715984 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 13 23:56:40.720968 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 13 23:56:40.726178 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 13 23:56:40.730318 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.730356 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.731507 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. May 13 23:56:40.735900 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.736905 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 13 23:56:40.739681 jq[1699]: false May 13 23:56:40.741898 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 13 23:56:40.746696 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 13 23:56:40.752290 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 13 23:56:40.757698 (chronyd)[1695]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 13 23:56:40.759984 systemd[1]: Starting systemd-logind.service - User Login Management... May 13 23:56:40.765031 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 23:56:40.765487 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 13 23:56:40.767950 systemd[1]: Starting update-engine.service - Update Engine... May 13 23:56:40.772402 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 13 23:56:40.777829 extend-filesystems[1703]: Found loop3 May 13 23:56:40.777829 extend-filesystems[1703]: Found loop4 May 13 23:56:40.777829 extend-filesystems[1703]: Found loop5 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda May 13 23:56:40.777829 extend-filesystems[1703]: Found sda1 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda2 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda3 May 13 23:56:40.777829 extend-filesystems[1703]: Found usr May 13 23:56:40.777829 extend-filesystems[1703]: Found sda4 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda6 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda7 May 13 23:56:40.777829 extend-filesystems[1703]: Found sda9 May 13 23:56:40.777829 extend-filesystems[1703]: Checking size of /dev/sda9 May 13 23:56:40.824768 kernel: hv_utils: KVP IC version 4.0 May 13 23:56:40.824937 extend-filesystems[1703]: Old size kept for /dev/sda9 May 13 23:56:40.824937 extend-filesystems[1703]: Found sdb May 13 23:56:40.824937 extend-filesystems[1703]: Found sdb1 May 13 23:56:40.824937 extend-filesystems[1703]: Found sr0 May 13 23:56:40.782219 chronyd[1721]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 13 23:56:40.780030 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 23:56:40.836366 jq[1717]: true May 13 23:56:40.805428 KVP[1704]: KVP starting; pid is:1704 May 13 23:56:40.780243 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 13 23:56:40.836916 jq[1722]: true May 13 23:56:40.808490 KVP[1704]: KVP LIC Version: 3.1 May 13 23:56:40.780559 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 23:56:40.780743 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 13 23:56:40.805460 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 23:56:40.805700 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 13 23:56:40.819274 (ntainerd)[1736]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 13 23:56:40.836124 systemd[1]: motdgen.service: Deactivated successfully. May 13 23:56:40.836340 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 13 23:56:40.847832 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1762) May 13 23:56:40.891053 chronyd[1721]: Timezone right/UTC failed leap second check, ignoring May 13 23:56:40.891218 chronyd[1721]: Loaded seccomp filter (level 2) May 13 23:56:40.894411 systemd[1]: Started chronyd.service - NTP client/server. May 13 23:56:40.903562 dbus-daemon[1698]: [system] SELinux support is enabled May 13 23:56:40.903925 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 13 23:56:40.908611 systemd-logind[1713]: New seat seat0. May 13 23:56:40.910535 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.910574 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 13 23:56:40.913493 update_engine[1716]: I20250513 23:56:40.913420 1716 main.cc:92] Flatcar Update Engine starting May 13 23:56:40.916548 update_engine[1716]: I20250513 23:56:40.915992 1716 update_check_scheduler.cc:74] Next update check in 10m3s May 13 23:56:40.919323 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.919361 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 13 23:56:40.919740 systemd-logind[1713]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 13 23:56:40.924669 systemd[1]: Started systemd-logind.service - User Login Management. May 13 23:56:40.929684 systemd[1]: Started update-engine.service - Update Engine. May 13 23:56:40.935910 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 13 23:56:40.999290 coreos-metadata[1697]: May 13 23:56:40.999 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 13 23:56:41.001886 coreos-metadata[1697]: May 13 23:56:41.001 INFO Fetch successful May 13 23:56:41.002046 coreos-metadata[1697]: May 13 23:56:41.002 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 13 23:56:41.002499 sshd_keygen[1727]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 23:56:41.005610 coreos-metadata[1697]: May 13 23:56:41.005 INFO Fetch successful May 13 23:56:41.005937 coreos-metadata[1697]: May 13 23:56:41.005 INFO Fetching http://168.63.129.16/machine/a3056853-f68d-4539-aded-ce323acf31ab/cd253889%2D6a86%2D47a8%2D9d3f%2Dc48cdc96d2a2.%5Fci%2D4284.0.0%2Dn%2D50b71dfdc2?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 13 23:56:41.007445 coreos-metadata[1697]: May 13 23:56:41.007 INFO Fetch successful May 13 23:56:41.007618 coreos-metadata[1697]: May 13 23:56:41.007 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.014828 coreos-metadata[1697]: May 13 23:56:41.014 INFO Fetch successful May 13 23:56:41.032591 bash[1751]: Updated "/home/core/.ssh/authorized_keys" May 13 23:56:41.037122 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 13 23:56:41.042367 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 13 23:56:41.043547 setup-nvidia[1707]: Downloading Flatcar Container Linux Developer Container for version: 4284.0.0+nightly-20250513-2100 May 13 23:56:41.050268 systemd[1]: Starting issuegen.service - Generate /run/issue... May 13 23:56:41.054274 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 13 23:56:41.065804 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 13 23:56:41.071671 systemd[1]: issuegen.service: Deactivated successfully. May 13 23:56:41.071892 systemd[1]: Finished issuegen.service - Generate /run/issue. May 13 23:56:41.077618 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 13 23:56:41.084273 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 13 23:56:41.085817 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 13 23:56:41.105082 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 13 23:56:41.133882 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 13 23:56:41.140225 systemd[1]: Started getty@tty1.service - Getty on tty1. May 13 23:56:41.145155 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 13 23:56:41.149879 systemd[1]: Reached target getty.target - Login Prompts. May 13 23:56:41.179345 locksmithd[1823]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 23:56:42.038108 containerd[1736]: time="2025-05-13T23:56:42Z" 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:42.039837 containerd[1736]: time="2025-05-13T23:56:42.039789500Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 May 13 23:56:42.048129 containerd[1736]: time="2025-05-13T23:56:42.048056800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.1µs" May 13 23:56:42.048129 containerd[1736]: time="2025-05-13T23:56:42.048091000Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 13 23:56:42.048129 containerd[1736]: time="2025-05-13T23:56:42.048118000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 13 23:56:42.048329 containerd[1736]: time="2025-05-13T23:56:42.048293300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 13 23:56:42.048484 containerd[1736]: time="2025-05-13T23:56:42.048385300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 13 23:56:42.048535 containerd[1736]: time="2025-05-13T23:56:42.048506100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:56:42.048607 containerd[1736]: time="2025-05-13T23:56:42.048583500Z" 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.048637 containerd[1736]: time="2025-05-13T23:56:42.048604100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:56:42.048831 containerd[1736]: time="2025-05-13T23:56:42.048793900Z" 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.048869 containerd[1736]: time="2025-05-13T23:56:42.048830000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:56:42.048869 containerd[1736]: time="2025-05-13T23:56:42.048843900Z" 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.048869 containerd[1736]: time="2025-05-13T23:56:42.048852600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 13 23:56:42.048957 containerd[1736]: time="2025-05-13T23:56:42.048934400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 13 23:56:42.049170 containerd[1736]: time="2025-05-13T23:56:42.049148400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:56:42.049198 containerd[1736]: time="2025-05-13T23:56:42.049184600Z" 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.049231 containerd[1736]: time="2025-05-13T23:56:42.049207800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 13 23:56:42.049255 containerd[1736]: time="2025-05-13T23:56:42.049235200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 13 23:56:42.049495 containerd[1736]: time="2025-05-13T23:56:42.049466500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 13 23:56:42.049572 containerd[1736]: time="2025-05-13T23:56:42.049552700Z" level=info msg="metadata content store policy set" policy=shared May 13 23:56:42.067143 containerd[1736]: time="2025-05-13T23:56:42.067109800Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 13 23:56:42.067197 containerd[1736]: time="2025-05-13T23:56:42.067154500Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 13 23:56:42.067197 containerd[1736]: time="2025-05-13T23:56:42.067171600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 13 23:56:42.067197 containerd[1736]: time="2025-05-13T23:56:42.067184100Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067197500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067208800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067220800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067233500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067245100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067255800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 13 23:56:42.067272 containerd[1736]: time="2025-05-13T23:56:42.067266500Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 13 23:56:42.067426 containerd[1736]: time="2025-05-13T23:56:42.067277700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 13 23:56:42.067426 containerd[1736]: time="2025-05-13T23:56:42.067382300Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 13 23:56:42.067426 containerd[1736]: time="2025-05-13T23:56:42.067403900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 13 23:56:42.067426 containerd[1736]: time="2025-05-13T23:56:42.067417000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067428600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067454400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067467900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067479500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067491700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067503700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 13 23:56:42.067516 containerd[1736]: time="2025-05-13T23:56:42.067515100Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 13 23:56:42.067676 containerd[1736]: time="2025-05-13T23:56:42.067525900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 13 23:56:42.067676 containerd[1736]: time="2025-05-13T23:56:42.067583500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 13 23:56:42.067676 containerd[1736]: time="2025-05-13T23:56:42.067596700Z" level=info msg="Start snapshots syncer" May 13 23:56:42.067676 containerd[1736]: time="2025-05-13T23:56:42.067625600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 13 23:56:42.067931 containerd[1736]: time="2025-05-13T23:56:42.067855300Z" 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.068077 containerd[1736]: time="2025-05-13T23:56:42.067939500Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 13 23:56:42.068077 containerd[1736]: time="2025-05-13T23:56:42.068040100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 13 23:56:42.068164 containerd[1736]: time="2025-05-13T23:56:42.068140100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 13 23:56:42.068199 containerd[1736]: time="2025-05-13T23:56:42.068167200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 13 23:56:42.068199 containerd[1736]: time="2025-05-13T23:56:42.068180700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 13 23:56:42.068199 containerd[1736]: time="2025-05-13T23:56:42.068192200Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 13 23:56:42.068269 containerd[1736]: time="2025-05-13T23:56:42.068205100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 13 23:56:42.068269 containerd[1736]: time="2025-05-13T23:56:42.068216500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 13 23:56:42.068269 containerd[1736]: time="2025-05-13T23:56:42.068228200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 13 23:56:42.068269 containerd[1736]: time="2025-05-13T23:56:42.068250200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 13 23:56:42.068269 containerd[1736]: time="2025-05-13T23:56:42.068263000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 13 23:56:42.068365 containerd[1736]: time="2025-05-13T23:56:42.068273000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 13 23:56:42.068365 containerd[1736]: time="2025-05-13T23:56:42.068305600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:56:42.068365 containerd[1736]: time="2025-05-13T23:56:42.068319600Z" 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.068365 containerd[1736]: time="2025-05-13T23:56:42.068328800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:56:42.068365 containerd[1736]: time="2025-05-13T23:56:42.068337800Z" 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.068365 containerd[1736]: time="2025-05-13T23:56:42.068346200Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 13 23:56:42.068365 containerd[1736]: time="2025-05-13T23:56:42.068356400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068366900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068388800Z" level=info msg="runtime interface created" May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068395100Z" level=info msg="created NRI interface" May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068403200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068413500Z" level=info msg="Connect containerd service" May 13 23:56:42.068506 containerd[1736]: time="2025-05-13T23:56:42.068438500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 13 23:56:42.069093 containerd[1736]: time="2025-05-13T23:56:42.069065100Z" 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:42.411436 setup-nvidia[1877]: % Total % Received % Xferd Average Speed Time Time Time Current May 13 23:56:42.411793 setup-nvidia[1877]: Dload Upload Total Spent Left Speed May 13 23:56:43.542304 containerd[1736]: time="2025-05-13T23:56:43.542253600Z" level=info msg="Start subscribing containerd event" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542319500Z" level=info msg="Start recovering state" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542442800Z" level=info msg="Start event monitor" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542452800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542467700Z" level=info msg="Start cni network conf syncer for default" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542496400Z" level=info msg="Start streaming server" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542512000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542520800Z" level=info msg="runtime interface starting up..." May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542528500Z" level=info msg="starting plugins..." May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542547100Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 13 23:56:43.542664 containerd[1736]: time="2025-05-13T23:56:43.542521400Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 23:56:43.542971 containerd[1736]: time="2025-05-13T23:56:43.542701700Z" level=info msg="containerd successfully booted in 1.505228s" May 13 23:56:43.542821 systemd[1]: Started containerd.service - containerd container runtime. May 13 23:56:43.690550 waagent[1853]: 2025-05-13T23:56:43.690466Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 13 23:56:43.694957 waagent[1853]: 2025-05-13T23:56:43.694913Z INFO Daemon Daemon OS: flatcar 4284.0.0 May 13 23:56:43.698504 waagent[1853]: 2025-05-13T23:56:43.698457Z INFO Daemon Daemon Python: 3.11.11 May 13 23:56:43.701871 waagent[1853]: 2025-05-13T23:56:43.701827Z INFO Daemon Daemon Run daemon May 13 23:56:43.704946 waagent[1853]: 2025-05-13T23:56:43.704902Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4284.0.0' May 13 23:56:43.711794 waagent[1853]: 2025-05-13T23:56:43.711745Z INFO Daemon Daemon Using waagent for provisioning May 13 23:56:43.716559 waagent[1853]: 2025-05-13T23:56:43.716478Z INFO Daemon Daemon Activate resource disk May 13 23:56:43.720227 waagent[1853]: 2025-05-13T23:56:43.720186Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 13 23:56:43.731069 waagent[1853]: 2025-05-13T23:56:43.731022Z INFO Daemon Daemon Found device: sdb May 13 23:56:43.740389 waagent[1853]: 2025-05-13T23:56:43.740346Z INFO Daemon Daemon Examining partition table May 13 23:56:43.858704 waagent[1853]: 2025-05-13T23:56:43.858572Z INFO Daemon Daemon GPT not detected, determining filesystem May 13 23:56:43.984791 waagent[1853]: 2025-05-13T23:56:43.984714Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded May 13 23:56:43.989364 waagent[1853]: 2025-05-13T23:56:43.989322Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 May 13 23:56:44.432831 kernel: sdb: sdb1 May 13 23:56:44.468483 waagent[1853]: 2025-05-13T23:56:44.468414Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded May 13 23:56:44.484832 kernel: sdb: sdb1 May 13 23:56:44.484968 waagent[1853]: 2025-05-13T23:56:44.484921Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] May 13 23:56:48.855461 login[1857]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 13 23:56:48.902035 login[1856]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 13 23:56:48.913132 systemd-logind[1713]: New session 2 of user core. May 13 23:56:48.914169 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 13 23:56:48.915277 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 13 23:56:48.976680 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 13 23:56:48.979081 systemd[1]: Starting user@500.service - User Manager for UID 500... May 13 23:56:49.009023 (systemd)[1954]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 23:56:49.011447 systemd-logind[1713]: New session c1 of user core. May 13 23:56:49.857286 login[1857]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 13 23:56:49.861680 systemd-logind[1713]: New session 1 of user core. May 13 23:56:50.432105 systemd[1954]: Queued start job for default target default.target. May 13 23:56:50.439852 systemd[1954]: Created slice app.slice - User Application Slice. May 13 23:56:50.439884 systemd[1954]: Reached target paths.target - Paths. May 13 23:56:50.439931 systemd[1954]: Reached target timers.target - Timers. May 13 23:56:50.441182 systemd[1954]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:56:50.451856 systemd[1954]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:56:50.452028 systemd[1954]: Reached target sockets.target - Sockets. May 13 23:56:50.452083 systemd[1954]: Reached target basic.target - Basic System. May 13 23:56:50.452126 systemd[1954]: Reached target default.target - Main User Target. May 13 23:56:50.452157 systemd[1954]: Startup finished in 1.433s. May 13 23:56:50.452222 systemd[1]: Started user@500.service - User Manager for UID 500. May 13 23:56:50.453595 systemd[1]: Started session-1.scope - Session 1 of User core. May 13 23:56:50.454346 systemd[1]: Started session-2.scope - Session 2 of User core. May 13 23:56:50.930607 waagent[1853]: 2025-05-13T23:56:50.930525Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] May 13 23:56:50.953836 kernel: EXT4-fs (sdb1): mounted filesystem a4e2f902-4eae-436d-9cfe-fc4eb82c0f57 r/w with ordered data mode. Quota mode: none. May 13 23:56:50.954219 waagent[1853]: 2025-05-13T23:56:50.954168Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 May 13 23:56:50.960995 waagent[1853]: 2025-05-13T23:56:50.960949Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 13 23:56:50.965671 waagent[1853]: 2025-05-13T23:56:50.965624Z INFO Daemon Daemon Running default provisioning handler May 13 23:56:50.973730 waagent[1853]: 2025-05-13T23:56:50.973677Z 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.983689 waagent[1853]: 2025-05-13T23:56:50.983641Z 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.985394 waagent[1853]: 2025-05-13T23:56:50.985353Z INFO Daemon Daemon cloud-init is enabled: False May 13 23:56:50.986851 waagent[1853]: 2025-05-13T23:56:50.986814Z INFO Daemon Daemon Copying ovf-env.xml May 13 23:56:51.092209 waagent[1853]: 2025-05-13T23:56:51.092126Z INFO Daemon Daemon Successfully mounted dvd May 13 23:56:51.105114 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 13 23:56:51.105314 waagent[1853]: 2025-05-13T23:56:51.105255Z INFO Daemon Daemon Detect protocol endpoint May 13 23:56:51.106959 waagent[1853]: 2025-05-13T23:56:51.106914Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 13 23:56:51.108512 waagent[1853]: 2025-05-13T23:56:51.108468Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 13 23:56:51.109913 waagent[1853]: 2025-05-13T23:56:51.109878Z INFO Daemon Daemon Test for route to 168.63.129.16 May 13 23:56:51.110816 waagent[1853]: 2025-05-13T23:56:51.110771Z INFO Daemon Daemon Route to 168.63.129.16 exists May 13 23:56:51.111440 waagent[1853]: 2025-05-13T23:56:51.111405Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 13 23:56:51.131789 waagent[1853]: 2025-05-13T23:56:51.131744Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 13 23:56:51.133587 waagent[1853]: 2025-05-13T23:56:51.133561Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 13 23:56:51.135247 waagent[1853]: 2025-05-13T23:56:51.135199Z INFO Daemon Daemon Server preferred version:2015-04-05 May 13 23:56:51.229480 waagent[1853]: 2025-05-13T23:56:51.229346Z INFO Daemon Daemon Initializing goal state during protocol detection May 13 23:56:51.231158 waagent[1853]: 2025-05-13T23:56:51.231114Z INFO Daemon Daemon Forcing an update of the goal state. May 13 23:56:51.235842 waagent[1853]: 2025-05-13T23:56:51.235786Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 13 23:56:51.275281 waagent[1853]: 2025-05-13T23:56:51.275236Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 13 23:56:51.278154 waagent[1853]: 2025-05-13T23:56:51.278108Z INFO Daemon May 13 23:56:51.278507 waagent[1853]: 2025-05-13T23:56:51.278473Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: e73e204c-72cd-4ee6-aa28-5f9cdcb77ec7 eTag: 2809482409120404844 source: Fabric] May 13 23:56:51.280308 waagent[1853]: 2025-05-13T23:56:51.280263Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 13 23:56:51.280991 waagent[1853]: 2025-05-13T23:56:51.280952Z INFO Daemon May 13 23:56:51.281481 waagent[1853]: 2025-05-13T23:56:51.281449Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 13 23:56:51.286769 waagent[1853]: 2025-05-13T23:56:51.286732Z INFO Daemon Daemon Downloading artifacts profile blob May 13 23:56:51.418032 waagent[1853]: 2025-05-13T23:56:51.417966Z INFO Daemon Downloaded certificate {'thumbprint': 'F56FBEC571DB38DC6C6826F13DE77EF1B4FF434C', 'hasPrivateKey': False} May 13 23:56:51.420423 waagent[1853]: 2025-05-13T23:56:51.420376Z INFO Daemon Downloaded certificate {'thumbprint': '4B99B473DBE4AC406BD982792F1FD26550598690', 'hasPrivateKey': True} May 13 23:56:51.422332 waagent[1853]: 2025-05-13T23:56:51.422288Z INFO Daemon Fetch goal state completed May 13 23:56:51.465382 waagent[1853]: 2025-05-13T23:56:51.465337Z INFO Daemon Daemon Starting provisioning May 13 23:56:51.467053 waagent[1853]: 2025-05-13T23:56:51.466992Z INFO Daemon Daemon Handle ovf-env.xml. May 13 23:56:51.468580 waagent[1853]: 2025-05-13T23:56:51.468534Z INFO Daemon Daemon Set hostname [ci-4284.0.0-n-50b71dfdc2] May 13 23:56:51.546430 waagent[1853]: 2025-05-13T23:56:51.546375Z INFO Daemon Daemon Publish hostname [ci-4284.0.0-n-50b71dfdc2] May 13 23:56:51.548328 waagent[1853]: 2025-05-13T23:56:51.548279Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 13 23:56:51.549849 waagent[1853]: 2025-05-13T23:56:51.549790Z INFO Daemon Daemon Primary interface is [eth0] May 13 23:56:51.567624 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:56:51.567634 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:56:51.567661 systemd-networkd[1410]: eth0: DHCP lease lost May 13 23:56:51.568330 waagent[1853]: 2025-05-13T23:56:51.568274Z INFO Daemon Daemon Create user account if not exists May 13 23:56:51.570086 waagent[1853]: 2025-05-13T23:56:51.570042Z INFO Daemon Daemon User core already exists, skip useradd May 13 23:56:51.570788 waagent[1853]: 2025-05-13T23:56:51.570752Z INFO Daemon Daemon Configure sudoer May 13 23:56:51.571823 waagent[1853]: 2025-05-13T23:56:51.571778Z INFO Daemon Daemon Configure sshd May 13 23:56:51.572507 waagent[1853]: 2025-05-13T23:56:51.572470Z 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:51.573840 waagent[1853]: 2025-05-13T23:56:51.573797Z INFO Daemon Daemon Deploy ssh public key. May 13 23:56:51.651869 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 13 23:57:04.722901 chronyd[1721]: Selected source PHC0 May 13 23:57:10.490003 setup-nvidia[1877]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 542M 0 224k 0 0 153k 0 1:00:23 0:00:01 1:00:22 153k\u000d 2 542M 2 13.2M 0 0 5574k 0 0:01:39 0:00:02 0:01:37 5572k\u000d 6 542M 6 34.6M 0 0 10.1M 0 0:00:53 0:00:03 0:00:50 10.1M\u000d 11 542M 11 59.8M 0 0 13.5M 0 0:00:39 0:00:04 0:00:35 13.5M\u000d 15 542M 15 85.0M 0 0 15.7M 0 0:00:34 0:00:05 0:00:29 17.3M\u000d 20 542M 20 110M 0 0 17.3M 0 0:00:31 0:00:06 0:00:25 22.3M\u000d 25 542M 25 136M 0 0 18.0M 0 0:00:29 0:00:07 0:00:22 24.1M\u000d 29 542M 29 159M 0 0 18.7M 0 0:00:28 0:00:08 0:00:20 24.4M\u000d 32 542M 32 176M 0 0 18.2M 0 0:00:29 0:00:09 0:00:20 22.2M\u000d 32 542M 32 176M 0 0 16.8M 0 0:00:32 0:00:10 0:00:22 18.0M\u000d 32 542M 32 176M 0 0 15.4M 0 0:00:35 0:00:11 0:00:24 13.0M\u000d 32 542M 32 178M 0 0 14.2M 0 0:00:38 0:00:12 0:00:26 8674k\u000d 35 542M 35 192M 0 0 14.2M 0 0:00:38 0:00:13 0:00:25 6854k\u000d 39 542M 39 214M 0 0 14.8M 0 0:00:36 0:00:14 0:00:22 8127k\u000d 44 542M 44 239M 0 0 15.4M 0 0:00:35 0:00:15 0:00:20 12.5M\u000d 48 542M 48 261M 0 0 15.8M 0 0:00:34 0:00:16 0:00:18 16.8M\u000d 52 542M 52 282M 0 0 16.2M 0 0:00:33 0:00:17 0:00:16 21.2M\u000d 56 542M 56 306M 0 0 16.6M 0 0:00:32 0:00:18 0:00:14 23.0M\u000d 60 542M 60 330M 0 0 17.0M 0 0:00:31 0:00:19 0:00:12 23.4M\u000d 65 542M 65 355M 0 0 17.4M 0 0:00:31 0:00:20 0:00:11 23.6M\u000d 70 542M 70 381M 0 0 17.7M 0 0:00:30 0:00:21 0:00:09 24.1M\u000d 74 542M 74 406M 0 0 18.1M 0 0:00:29 0:00:22 0:00:07 24.8M\u000d 79 542M 79 431M 0 0 18.4M 0 0:00:29 0:00:23 0:00:06 25.1M\u000d 84 542M 84 457M 0 0 18.7M 0 0:00:28 0:00:24 0:00:04 25.3M\u000d 88 542M 88 482M 0 0 18.9M 0 0:00:28 0:00:25 0:00:03 25.3M\u000d 93 542M 93 504M 0 0 19.0M 0 0:00:28 0:00:26 0:00:02 24.2M\u000d 97 542M 97 527M 0 0 19.1M 0 0:00:28 0:00:27 0:00:01 23.7M\u000d100 542M 100 542M 0 0 19.3M 0 0:00:28 0:00:28 --:--:-- 23.7M May 13 23:57:21.671287 waagent[1853]: 2025-05-13T23:57:21.671228Z INFO Daemon Daemon Provisioning complete May 13 23:57:21.685125 waagent[1853]: 2025-05-13T23:57:21.684096Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 13 23:57:21.685979 waagent[1853]: 2025-05-13T23:57:21.685925Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 13 23:57:21.686680 waagent[1853]: 2025-05-13T23:57:21.686641Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 13 23:57:21.897085 waagent[2026]: 2025-05-13T23:57:21.896996Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 13 23:57:21.897461 waagent[2026]: 2025-05-13T23:57:21.897172Z INFO ExtHandler ExtHandler OS: flatcar 4284.0.0 May 13 23:57:21.897461 waagent[2026]: 2025-05-13T23:57:21.897249Z INFO ExtHandler ExtHandler Python: 3.11.11 May 13 23:57:21.897461 waagent[2026]: 2025-05-13T23:57:21.897327Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 13 23:57:21.949977 waagent[2026]: 2025-05-13T23:57:21.947125Z 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:57:21.949977 waagent[2026]: 2025-05-13T23:57:21.947390Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:57:21.949977 waagent[2026]: 2025-05-13T23:57:21.947476Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:57:21.956666 waagent[2026]: 2025-05-13T23:57:21.956610Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 13 23:57:21.968578 waagent[2026]: 2025-05-13T23:57:21.968527Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 13 23:57:21.970434 waagent[2026]: 2025-05-13T23:57:21.970395Z INFO ExtHandler May 13 23:57:21.970609 waagent[2026]: 2025-05-13T23:57:21.970576Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: df30e85e-cb12-49bd-8fa3-d3672139d4f5 eTag: 2809482409120404844 source: Fabric] May 13 23:57:21.971037 waagent[2026]: 2025-05-13T23:57:21.971001Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 13 23:57:21.971773 waagent[2026]: 2025-05-13T23:57:21.971737Z INFO ExtHandler May 13 23:57:21.971952 waagent[2026]: 2025-05-13T23:57:21.971919Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 13 23:57:21.976530 waagent[2026]: 2025-05-13T23:57:21.976494Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 13 23:57:22.103896 waagent[2026]: 2025-05-13T23:57:22.103821Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F56FBEC571DB38DC6C6826F13DE77EF1B4FF434C', 'hasPrivateKey': False} May 13 23:57:22.105754 waagent[2026]: 2025-05-13T23:57:22.104450Z INFO ExtHandler Downloaded certificate {'thumbprint': '4B99B473DBE4AC406BD982792F1FD26550598690', 'hasPrivateKey': True} May 13 23:57:22.105754 waagent[2026]: 2025-05-13T23:57:22.104918Z INFO ExtHandler Fetch goal state completed May 13 23:57:22.123632 waagent[2026]: 2025-05-13T23:57:22.123008Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 13 23:57:22.128662 waagent[2026]: 2025-05-13T23:57:22.128610Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2026 May 13 23:57:22.128961 waagent[2026]: 2025-05-13T23:57:22.128927Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 13 23:57:22.129417 waagent[2026]: 2025-05-13T23:57:22.129382Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 13 23:57:22.133521 waagent[2026]: 2025-05-13T23:57:22.133483Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4284.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 13 23:57:22.134101 waagent[2026]: 2025-05-13T23:57:22.134064Z 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:57:22.134330 waagent[2026]: 2025-05-13T23:57:22.134298Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 13 23:57:22.135038 waagent[2026]: 2025-05-13T23:57:22.135002Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 13 23:57:22.225088 waagent[2026]: 2025-05-13T23:57:22.224988Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 13 23:57:22.225440 waagent[2026]: 2025-05-13T23:57:22.225405Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 13 23:57:22.252044 waagent[2026]: 2025-05-13T23:57:22.252006Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 13 23:57:22.261003 systemd[1]: Reload requested from client PID 2043 ('systemctl') (unit waagent.service)... May 13 23:57:22.261334 systemd[1]: Reloading... May 13 23:57:22.360850 zram_generator::config[2086]: No configuration found. May 13 23:57:22.643755 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:57:22.813483 systemd[1]: Reloading finished in 551 ms. May 13 23:57:22.839751 waagent[2026]: 2025-05-13T23:57:22.839677Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 13 23:57:22.840763 waagent[2026]: 2025-05-13T23:57:22.840046Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 13 23:57:22.978877 kernel: hv_balloon: Max. dynamic memory size: 114688 MB May 13 23:57:23.655971 waagent[2026]: 2025-05-13T23:57:23.655889Z 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:57:23.656362 waagent[2026]: 2025-05-13T23:57:23.656252Z 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:57:23.657076 waagent[2026]: 2025-05-13T23:57:23.657026Z INFO ExtHandler ExtHandler Starting env monitor service. May 13 23:57:23.657447 waagent[2026]: 2025-05-13T23:57:23.657403Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:57:23.657637 waagent[2026]: 2025-05-13T23:57:23.657605Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:57:23.658022 waagent[2026]: 2025-05-13T23:57:23.657980Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 13 23:57:23.658306 waagent[2026]: 2025-05-13T23:57:23.658271Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 13 23:57:23.658306 waagent[2026]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 13 23:57:23.658306 waagent[2026]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 13 23:57:23.658306 waagent[2026]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 13 23:57:23.658306 waagent[2026]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 13 23:57:23.658306 waagent[2026]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 13 23:57:23.658306 waagent[2026]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 13 23:57:23.659101 waagent[2026]: 2025-05-13T23:57:23.659028Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 13 23:57:23.659199 waagent[2026]: 2025-05-13T23:57:23.659159Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 13 23:57:23.659586 waagent[2026]: 2025-05-13T23:57:23.659527Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 13 23:57:23.659650 waagent[2026]: 2025-05-13T23:57:23.659603Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 13 23:57:23.660389 waagent[2026]: 2025-05-13T23:57:23.660335Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 13 23:57:23.660481 waagent[2026]: 2025-05-13T23:57:23.660442Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 13 23:57:23.660593 waagent[2026]: 2025-05-13T23:57:23.660545Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 13 23:57:23.663888 waagent[2026]: 2025-05-13T23:57:23.663848Z 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:57:23.667131 waagent[2026]: 2025-05-13T23:57:23.667080Z INFO EnvHandler ExtHandler Configure routes May 13 23:57:23.667198 waagent[2026]: 2025-05-13T23:57:23.667178Z INFO EnvHandler ExtHandler Gateway:None May 13 23:57:23.667282 waagent[2026]: 2025-05-13T23:57:23.667242Z INFO EnvHandler ExtHandler Routes:None May 13 23:57:23.674235 waagent[2026]: 2025-05-13T23:57:23.674186Z INFO ExtHandler ExtHandler May 13 23:57:23.674414 waagent[2026]: 2025-05-13T23:57:23.674374Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 380afb43-a0b0-4c26-9ecb-36afd7b26ac9 correlation 965b6459-b7d1-46ac-839e-f8f4f0d66099 created: 2025-05-13T23:55:10.778320Z] May 13 23:57:23.674822 waagent[2026]: 2025-05-13T23:57:23.674770Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 13 23:57:23.675555 waagent[2026]: 2025-05-13T23:57:23.675512Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] May 13 23:57:23.781661 waagent[2026]: 2025-05-13T23:57:23.781603Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 21978697-F867-43E8-B610-D86049945ACC;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 13 23:57:23.787816 waagent[2026]: 2025-05-13T23:57:23.787753Z INFO MonitorHandler ExtHandler Network interfaces: May 13 23:57:23.787816 waagent[2026]: Executing ['ip', '-a', '-o', 'link']: May 13 23:57:23.787816 waagent[2026]: 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:57:23.787816 waagent[2026]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:37:3d:df brd ff:ff:ff:ff:ff:ff May 13 23:57:23.787816 waagent[2026]: Executing ['ip', '-4', '-a', '-o', 'address']: May 13 23:57:23.787816 waagent[2026]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 13 23:57:23.787816 waagent[2026]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 13 23:57:23.787816 waagent[2026]: Executing ['ip', '-6', '-a', '-o', 'address']: May 13 23:57:23.787816 waagent[2026]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 13 23:57:23.787816 waagent[2026]: 2: eth0 inet6 fe80::7e1e:52ff:fe37:3ddf/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 13 23:57:23.959830 waagent[2026]: 2025-05-13T23:57:23.959688Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 13 23:57:23.959830 waagent[2026]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.959830 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.959830 waagent[2026]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.959830 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.959830 waagent[2026]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.959830 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.959830 waagent[2026]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 13 23:57:23.959830 waagent[2026]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 13 23:57:23.959830 waagent[2026]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 13 23:57:23.963545 waagent[2026]: 2025-05-13T23:57:23.963501Z INFO EnvHandler ExtHandler Current Firewall rules: May 13 23:57:23.963545 waagent[2026]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.963545 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.963545 waagent[2026]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.963545 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.963545 waagent[2026]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 13 23:57:23.963545 waagent[2026]: pkts bytes target prot opt in out source destination May 13 23:57:23.963545 waagent[2026]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 13 23:57:23.963545 waagent[2026]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 13 23:57:23.963545 waagent[2026]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 13 23:57:23.964116 waagent[2026]: 2025-05-13T23:57:23.964084Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 May 13 23:57:23.964357 waagent[2026]: 2025-05-13T23:57:23.964326Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 13 23:57:26.140609 setup-nvidia[1707]: Downloading NVIDIA 535.230.02 Driver May 13 23:57:26.145030 setup-nvidia[2177]: % Total % Received % Xferd Average Speed Time Time Time Current May 13 23:57:26.145228 setup-nvidia[2177]: Dload Upload Total Spent Left Speed May 13 23:57:26.688732 update_engine[1716]: I20250513 23:57:26.688647 1716 update_attempter.cc:509] Updating boot flags... May 13 23:57:26.759947 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2145) May 13 23:57:27.504049 setup-nvidia[2177]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 38 326M 38 125M 0 0 186M 0 0:00:01 --:--:-- 0:00:01 186M\u000d100 326M 100 326M 0 0 239M 0 0:00:01 0:00:01 --:--:-- 239M May 13 23:57:27.555716 setup-nvidia[1707]: Extract the NVIDIA Driver Installer 535.230.02 May 13 23:57:27.555716 setup-nvidia[1707]: /opt/nvidia/workdir/nvidia-workdir / May 13 23:57:27.574354 setup-nvidia[2240]: Creating directory NVIDIA-Linux-x86_64-535.230.02 May 13 23:57:27.840565 setup-nvidia[2240]: Verifying archive integrity... OK May 13 23:57:27.981063 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 13 23:57:27.985804 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:59024.service - OpenSSH per-connection server daemon (10.200.16.10:59024). May 13 23:57:28.620869 setup-nvidia[2240]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 May 13 23:57:28.815679 sshd[2264]: Accepted publickey for core from 10.200.16.10 port 59024 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:28.817194 sshd-session[2264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:28.822895 systemd-logind[1713]: New session 3 of user core. May 13 23:57:28.829950 systemd[1]: Started session-3.scope - Session 3 of User core. May 13 23:57:29.374867 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:47864.service - OpenSSH per-connection server daemon (10.200.16.10:47864). May 13 23:57:29.673032 kernel: hv_netvsc 7c1e5237-3ddf-7c1e-5237-3ddf7c1e5237 eth0: nvsp_rndis_pkt_complete error status: 2 May 13 23:57:29.767432 kernel: hv_netvsc 7c1e5237-3ddf-7c1e-5237-3ddf7c1e5237 eth0: nvsp_rndis_pkt_complete error status: 2 May 13 23:57:29.874217 kernel: hv_netvsc 7c1e5237-3ddf-7c1e-5237-3ddf7c1e5237 eth0: nvsp_rndis_pkt_complete error status: 2 May 13 23:57:29.874603 kernel: hv_netvsc 7c1e5237-3ddf-7c1e-5237-3ddf7c1e5237 eth0: nvsp_rndis_pkt_complete error status: 2 May 13 23:57:30.006847 sshd[2276]: Accepted publickey for core from 10.200.16.10 port 47864 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:30.008259 sshd-session[2276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:30.013083 systemd-logind[1713]: New session 4 of user core. May 13 23:57:30.025033 systemd[1]: Started session-4.scope - Session 4 of User core. May 13 23:57:30.453244 sshd[2278]: Connection closed by 10.200.16.10 port 47864 May 13 23:57:30.453614 sshd-session[2276]: pam_unix(sshd:session): session closed for user core May 13 23:57:30.457059 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:47864.service: Deactivated successfully. May 13 23:57:30.458624 systemd[1]: session-4.scope: Deactivated successfully. May 13 23:57:30.459323 systemd-logind[1713]: Session 4 logged out. Waiting for processes to exit. May 13 23:57:30.460281 systemd-logind[1713]: Removed session 4. May 13 23:57:30.562235 setup-nvidia[2269]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ May 13 23:57:30.568494 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:47866.service - OpenSSH per-connection server daemon (10.200.16.10:47866). May 13 23:57:30.650361 setup-nvidia[1707]: / May 13 23:57:30.650361 setup-nvidia[1707]: Spawn system-nspawn container to install the NVIDIA drivers May 13 23:57:30.835294 sudo[2289]: 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:30.837068 sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 13 23:57:30.844458 systemd[1]: Created slice user-0.slice - User Slice of UID 0. May 13 23:57:30.847088 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 13 23:57:30.886697 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 13 23:57:30.891103 systemd[1]: Starting user@0.service - User Manager for UID 0... May 13 23:57:30.907151 (systemd)[2292]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) May 13 23:57:30.909487 systemd-logind[1713]: New session c3 of user root. May 13 23:57:31.103659 systemd[2292]: Queued start job for default target default.target. May 13 23:57:31.118157 systemd[2292]: Created slice app.slice - User Application Slice. May 13 23:57:31.118194 systemd[2292]: Reached target paths.target - Paths. May 13 23:57:31.118249 systemd[2292]: Reached target timers.target - Timers. May 13 23:57:31.119557 systemd[2292]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:57:31.130761 systemd[2292]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:57:31.131893 systemd[2292]: Reached target sockets.target - Sockets. May 13 23:57:31.131958 systemd[2292]: Reached target basic.target - Basic System. May 13 23:57:31.132014 systemd[2292]: Reached target default.target - Main User Target. May 13 23:57:31.132050 systemd[2292]: Startup finished in 216ms. May 13 23:57:31.132057 systemd[1]: Started user@0.service - User Manager for UID 0. May 13 23:57:31.135291 systemd[1]: Started session-c2.scope - Session c2 of User root. May 13 23:57:31.205901 sshd[2285]: Accepted publickey for core from 10.200.16.10 port 47866 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:31.207357 sshd-session[2285]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:31.211678 systemd-logind[1713]: New session 5 of user core. May 13 23:57:31.225827 kernel: loop6: detected capacity change from 0 to 12644352 May 13 23:57:31.226942 systemd[1]: Started session-5.scope - Session 5 of User core. May 13 23:57:31.236831 kernel: loop6: p9 May 13 23:57:31.368029 setup-nvidia[2301]: ░ 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:31.379829 kernel: EXT4-fs (loop6p9): mounted filesystem 63c5bf5a-c683-4802-9fa3-6d3ca7f4a515 ro without journal. Quota mode: none. May 13 23:57:31.653351 sshd[2303]: Connection closed by 10.200.16.10 port 47866 May 13 23:57:31.653660 sshd-session[2285]: pam_unix(sshd:session): session closed for user core May 13 23:57:31.656934 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:47866.service: Deactivated successfully. May 13 23:57:31.658516 systemd[1]: session-5.scope: Deactivated successfully. May 13 23:57:31.659172 systemd-logind[1713]: Session 5 logged out. Waiting for processes to exit. May 13 23:57:31.659937 systemd-logind[1713]: Removed session 5. May 13 23:57:41.765451 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:49804.service - OpenSSH per-connection server daemon (10.200.16.10:49804). May 13 23:57:42.422666 sshd[3330]: Accepted publickey for core from 10.200.16.10 port 49804 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:42.424072 sshd-session[3330]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:42.428232 systemd-logind[1713]: New session 6 of user core. May 13 23:57:42.441943 systemd[1]: Started session-6.scope - Session 6 of User core. May 13 23:57:42.867622 sshd[3332]: Connection closed by 10.200.16.10 port 49804 May 13 23:57:42.868006 sshd-session[3330]: pam_unix(sshd:session): session closed for user core May 13 23:57:42.871260 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:49804.service: Deactivated successfully. May 13 23:57:42.872850 systemd[1]: session-6.scope: Deactivated successfully. May 13 23:57:42.873490 systemd-logind[1713]: Session 6 logged out. Waiting for processes to exit. May 13 23:57:42.874342 systemd-logind[1713]: Removed session 6. May 13 23:57:42.985634 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:49820.service - OpenSSH per-connection server daemon (10.200.16.10:49820). May 13 23:57:43.618881 sshd[3338]: Accepted publickey for core from 10.200.16.10 port 49820 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:43.620235 sshd-session[3338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:43.624513 systemd-logind[1713]: New session 7 of user core. May 13 23:57:43.640986 systemd[1]: Started session-7.scope - Session 7 of User core. May 13 23:57:44.063689 sshd[3340]: Connection closed by 10.200.16.10 port 49820 May 13 23:57:44.064095 sshd-session[3338]: pam_unix(sshd:session): session closed for user core May 13 23:57:44.067368 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:49820.service: Deactivated successfully. May 13 23:57:44.069000 systemd[1]: session-7.scope: Deactivated successfully. May 13 23:57:44.069628 systemd-logind[1713]: Session 7 logged out. Waiting for processes to exit. May 13 23:57:44.070408 systemd-logind[1713]: Removed session 7. May 13 23:57:54.183342 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:36968.service - OpenSSH per-connection server daemon (10.200.16.10:36968). May 13 23:57:54.813322 sshd[3487]: Accepted publickey for core from 10.200.16.10 port 36968 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:54.814687 sshd-session[3487]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:54.818905 systemd-logind[1713]: New session 8 of user core. May 13 23:57:54.828937 systemd[1]: Started session-8.scope - Session 8 of User core. May 13 23:57:55.258189 sshd[3489]: Connection closed by 10.200.16.10 port 36968 May 13 23:57:55.258522 sshd-session[3487]: pam_unix(sshd:session): session closed for user core May 13 23:57:55.261950 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:36968.service: Deactivated successfully. May 13 23:57:55.263522 systemd[1]: session-8.scope: Deactivated successfully. May 13 23:57:55.264164 systemd-logind[1713]: Session 8 logged out. Waiting for processes to exit. May 13 23:57:55.265029 systemd-logind[1713]: Removed session 8. May 13 23:57:55.373398 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:36974.service - OpenSSH per-connection server daemon (10.200.16.10:36974). May 13 23:57:56.013514 sshd[3495]: Accepted publickey for core from 10.200.16.10 port 36974 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:57:56.014872 sshd-session[3495]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:56.019309 systemd-logind[1713]: New session 9 of user core. May 13 23:57:56.033170 systemd[1]: Started session-9.scope - Session 9 of User core. May 13 23:57:56.460947 sshd[3522]: Connection closed by 10.200.16.10 port 36974 May 13 23:57:56.459890 sshd-session[3495]: pam_unix(sshd:session): session closed for user core May 13 23:57:56.462765 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:36974.service: Deactivated successfully. May 13 23:57:56.464537 systemd[1]: session-9.scope: Deactivated successfully. May 13 23:57:56.466296 systemd-logind[1713]: Session 9 logged out. Waiting for processes to exit. May 13 23:57:56.467271 systemd-logind[1713]: Removed session 9. May 13 23:58:06.580033 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:34808.service - OpenSSH per-connection server daemon (10.200.16.10:34808). May 13 23:58:07.227960 sshd[7307]: Accepted publickey for core from 10.200.16.10 port 34808 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:07.229992 sshd-session[7307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:07.235529 systemd-logind[1713]: New session 10 of user core. May 13 23:58:07.246979 systemd[1]: Started session-10.scope - Session 10 of User core. May 13 23:58:07.673346 sshd[7546]: Connection closed by 10.200.16.10 port 34808 May 13 23:58:07.673737 sshd-session[7307]: pam_unix(sshd:session): session closed for user core May 13 23:58:07.676658 systemd-logind[1713]: Session 10 logged out. Waiting for processes to exit. May 13 23:58:07.678152 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:34808.service: Deactivated successfully. May 13 23:58:07.680059 systemd[1]: session-10.scope: Deactivated successfully. May 13 23:58:07.681395 systemd-logind[1713]: Removed session 10. May 13 23:58:07.789761 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:34824.service - OpenSSH per-connection server daemon (10.200.16.10:34824). May 13 23:58:08.438835 sshd[7768]: Accepted publickey for core from 10.200.16.10 port 34824 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:08.437517 sshd-session[7768]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:08.450297 systemd-logind[1713]: New session 11 of user core. May 13 23:58:08.467492 systemd[1]: Started session-11.scope - Session 11 of User core. May 13 23:58:08.889854 sshd[7861]: Connection closed by 10.200.16.10 port 34824 May 13 23:58:08.890223 sshd-session[7768]: pam_unix(sshd:session): session closed for user core May 13 23:58:08.894269 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:34824.service: Deactivated successfully. May 13 23:58:08.896679 systemd[1]: session-11.scope: Deactivated successfully. May 13 23:58:08.898544 systemd-logind[1713]: Session 11 logged out. Waiting for processes to exit. May 13 23:58:08.899773 systemd-logind[1713]: Removed session 11. May 13 23:58:19.020724 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:44892.service - OpenSSH per-connection server daemon (10.200.16.10:44892). May 13 23:58:19.672174 sshd[11418]: Accepted publickey for core from 10.200.16.10 port 44892 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:19.674067 sshd-session[11418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:19.680527 systemd-logind[1713]: New session 12 of user core. May 13 23:58:19.687612 systemd[1]: Started session-12.scope - Session 12 of User core. May 13 23:58:20.126092 sshd[11425]: Connection closed by 10.200.16.10 port 44892 May 13 23:58:20.126470 sshd-session[11418]: pam_unix(sshd:session): session closed for user core May 13 23:58:20.131592 systemd-logind[1713]: Session 12 logged out. Waiting for processes to exit. May 13 23:58:20.133487 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:44892.service: Deactivated successfully. May 13 23:58:20.135784 systemd[1]: session-12.scope: Deactivated successfully. May 13 23:58:20.139305 systemd-logind[1713]: Removed session 12. May 13 23:58:20.245067 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:44906.service - OpenSSH per-connection server daemon (10.200.16.10:44906). May 13 23:58:20.897384 sshd[11456]: Accepted publickey for core from 10.200.16.10 port 44906 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:20.899164 sshd-session[11456]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:20.906040 systemd-logind[1713]: New session 13 of user core. May 13 23:58:20.912010 systemd[1]: Started session-13.scope - Session 13 of User core. May 13 23:58:21.343192 sshd[11477]: Connection closed by 10.200.16.10 port 44906 May 13 23:58:21.345215 sshd-session[11456]: pam_unix(sshd:session): session closed for user core May 13 23:58:21.349140 systemd-logind[1713]: Session 13 logged out. Waiting for processes to exit. May 13 23:58:21.352199 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:44906.service: Deactivated successfully. May 13 23:58:21.354000 systemd[1]: session-13.scope: Deactivated successfully. May 13 23:58:21.355897 systemd-logind[1713]: Removed session 13. May 13 23:58:31.462084 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:53926.service - OpenSSH per-connection server daemon (10.200.16.10:53926). May 13 23:58:32.113374 sshd[11885]: Accepted publickey for core from 10.200.16.10 port 53926 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:32.114439 sshd-session[11885]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:32.119370 systemd-logind[1713]: New session 14 of user core. May 13 23:58:32.129971 systemd[1]: Started session-14.scope - Session 14 of User core. May 13 23:58:32.559111 sshd[11904]: Connection closed by 10.200.16.10 port 53926 May 13 23:58:32.559666 sshd-session[11885]: pam_unix(sshd:session): session closed for user core May 13 23:58:32.562609 systemd-logind[1713]: Session 14 logged out. Waiting for processes to exit. May 13 23:58:32.564279 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:53926.service: Deactivated successfully. May 13 23:58:32.566532 systemd[1]: session-14.scope: Deactivated successfully. May 13 23:58:32.568238 systemd-logind[1713]: Removed session 14. May 13 23:58:32.677656 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:53934.service - OpenSSH per-connection server daemon (10.200.16.10:53934). May 13 23:58:33.336738 sshd[11922]: Accepted publickey for core from 10.200.16.10 port 53934 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:33.339458 sshd-session[11922]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:33.346436 systemd-logind[1713]: New session 15 of user core. May 13 23:58:33.362831 systemd[1]: Started session-15.scope - Session 15 of User core. May 13 23:58:33.780975 sshd[11942]: Connection closed by 10.200.16.10 port 53934 May 13 23:58:33.783056 sshd-session[11922]: pam_unix(sshd:session): session closed for user core May 13 23:58:33.786581 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:53934.service: Deactivated successfully. May 13 23:58:33.790559 systemd[1]: session-15.scope: Deactivated successfully. May 13 23:58:33.791931 systemd-logind[1713]: Session 15 logged out. Waiting for processes to exit. May 13 23:58:33.793096 systemd-logind[1713]: Removed session 15. May 13 23:58:43.900037 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:32858.service - OpenSSH per-connection server daemon (10.200.16.10:32858). May 13 23:58:44.547881 sshd[12273]: Accepted publickey for core from 10.200.16.10 port 32858 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:44.551506 sshd-session[12273]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:44.560489 systemd-logind[1713]: New session 16 of user core. May 13 23:58:44.568023 systemd[1]: Started session-16.scope - Session 16 of User core. May 13 23:58:44.995912 sshd[12292]: Connection closed by 10.200.16.10 port 32858 May 13 23:58:44.996281 sshd-session[12273]: pam_unix(sshd:session): session closed for user core May 13 23:58:45.000012 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:32858.service: Deactivated successfully. May 13 23:58:45.002700 systemd[1]: session-16.scope: Deactivated successfully. May 13 23:58:45.004456 systemd-logind[1713]: Session 16 logged out. Waiting for processes to exit. May 13 23:58:45.005639 systemd-logind[1713]: Removed session 16. May 13 23:58:45.112063 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:32868.service - OpenSSH per-connection server daemon (10.200.16.10:32868). May 13 23:58:45.757707 sshd[12313]: Accepted publickey for core from 10.200.16.10 port 32868 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:45.759564 sshd-session[12313]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:45.766185 systemd-logind[1713]: New session 17 of user core. May 13 23:58:45.775056 systemd[1]: Started session-17.scope - Session 17 of User core. May 13 23:58:46.206379 sshd[12330]: Connection closed by 10.200.16.10 port 32868 May 13 23:58:46.207058 sshd-session[12313]: pam_unix(sshd:session): session closed for user core May 13 23:58:46.212248 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:32868.service: Deactivated successfully. May 13 23:58:46.214730 systemd[1]: session-17.scope: Deactivated successfully. May 13 23:58:46.218799 systemd-logind[1713]: Session 17 logged out. Waiting for processes to exit. May 13 23:58:46.220155 systemd-logind[1713]: Removed session 17. May 13 23:58:56.324392 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:43654.service - OpenSSH per-connection server daemon (10.200.16.10:43654). May 13 23:58:56.956163 sshd[12591]: Accepted publickey for core from 10.200.16.10 port 43654 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:56.957573 sshd-session[12591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:56.962097 systemd-logind[1713]: New session 18 of user core. May 13 23:58:56.970943 systemd[1]: Started session-18.scope - Session 18 of User core. May 13 23:58:57.400463 sshd[12593]: Connection closed by 10.200.16.10 port 43654 May 13 23:58:57.400868 sshd-session[12591]: pam_unix(sshd:session): session closed for user core May 13 23:58:57.404137 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:43654.service: Deactivated successfully. May 13 23:58:57.405765 systemd[1]: session-18.scope: Deactivated successfully. May 13 23:58:57.406447 systemd-logind[1713]: Session 18 logged out. Waiting for processes to exit. May 13 23:58:57.407211 systemd-logind[1713]: Removed session 18. May 13 23:58:57.535997 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:43660.service - OpenSSH per-connection server daemon (10.200.16.10:43660). May 13 23:58:58.179377 sshd[12599]: Accepted publickey for core from 10.200.16.10 port 43660 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:58:58.180730 sshd-session[12599]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:58:58.184964 systemd-logind[1713]: New session 19 of user core. May 13 23:58:58.201014 systemd[1]: Started session-19.scope - Session 19 of User core. May 13 23:58:58.625534 sshd[12601]: Connection closed by 10.200.16.10 port 43660 May 13 23:58:58.625932 sshd-session[12599]: pam_unix(sshd:session): session closed for user core May 13 23:58:58.629153 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:43660.service: Deactivated successfully. May 13 23:58:58.630704 systemd[1]: session-19.scope: Deactivated successfully. May 13 23:58:58.631341 systemd-logind[1713]: Session 19 logged out. Waiting for processes to exit. May 13 23:58:58.632124 systemd-logind[1713]: Removed session 19. May 13 23:59:01.273650 setup-nvidia[2301]: \u000d May 13 23:59:01.273650 setup-nvidia[2301]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most May 13 23:59:01.273650 setup-nvidia[2301]: frequently when this kernel module was built against the wrong or May 13 23:59:01.273650 setup-nvidia[2301]: improperly configured kernel sources, with a version of gcc that differs May 13 23:59:01.273650 setup-nvidia[2301]: from the one used to build the target kernel, or if another driver, such May 13 23:59:01.273650 setup-nvidia[2301]: as nouveau, is present and prevents the NVIDIA kernel module from May 13 23:59:01.273650 setup-nvidia[2301]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device May 13 23:59:01.273650 setup-nvidia[2301]: installed in this system is supported by this NVIDIA Linux graphics May 13 23:59:01.273650 setup-nvidia[2301]: driver release. May 13 23:59:01.273650 setup-nvidia[2301]: \u000d May 13 23:59:01.273650 setup-nvidia[2301]: Please see the log entries 'Kernel module load error' and 'Kernel May 13 23:59:01.273650 setup-nvidia[2301]: messages' at the end of the file May 13 23:59:01.273650 setup-nvidia[2301]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more May 13 23:59:01.273650 setup-nvidia[2301]: information. May 13 23:59:01.273650 setup-nvidia[2301]: \u000d May 13 23:59:01.288087 setup-nvidia[2301]: dmesg: read kernel buffer failed: Operation not permitted May 13 23:59:01.307322 setup-nvidia[2301]: \u000d May 13 23:59:01.307322 setup-nvidia[2301]: ERROR: Installation has failed. Please see the file May 13 23:59:01.307322 setup-nvidia[2301]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for May 13 23:59:01.307322 setup-nvidia[2301]: details. You may find suggestions on fixing installation problems in May 13 23:59:01.307322 setup-nvidia[2301]: the README available on the Linux driver download page at May 13 23:59:01.307322 setup-nvidia[2301]: www.nvidia.com. May 13 23:59:01.307322 setup-nvidia[2301]: \u000d May 13 23:59:01.307322 setup-nvidia[2301]: Last 50 lines of nvidia-installer.log: May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build May 13 23:59:01.307918 setup-nvidia[2301]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers May 13 23:59:01.307918 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o May 13 23:59:01.307918 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o May 13 23:59:01.307918 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o May 13 23:59:01.307918 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o May 13 23:59:01.307918 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o May 13 23:59:01.307918 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 13 23:59:01.307918 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 13 23:59:01.307918 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 13 23:59:01.307918 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 13 23:59:01.307918 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 13 23:59:01.307918 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 13 23:59:01.307918 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux May 13 23:59:01.307918 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 13 23:59:01.307918 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux May 13 23:59:01.307918 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 13 23:59:01.307918 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux May 13 23:59:01.307918 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 13 23:59:01.307918 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux May 13 23:59:01.307918 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 13 23:59:01.307918 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux May 13 23:59:01.307918 setup-nvidia[2301]: make[2]: Leaving directory '/usr/lib/modules/6.6.89-flatcar/build' May 13 23:59:01.307918 setup-nvidia[2301]: make[1]: Leaving directory '/usr/lib/modules/6.6.89-flatcar/source' May 13 23:59:01.307918 setup-nvidia[2301]: -> done. May 13 23:59:01.307918 setup-nvidia[2301]: -> Kernel module compilation complete. May 13 23:59:01.307918 setup-nvidia[2301]: -> Unable to determine if Secure Boot is enabled: No such file or directory May 13 23:59:01.309062 setup-nvidia[2301]: 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:59:01.309062 setup-nvidia[2301]: \u000d May 13 23:59:01.309062 setup-nvidia[2301]: 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:59:01.309062 setup-nvidia[2301]: -> Kernel module load error: Operation not permitted May 13 23:59:01.309062 setup-nvidia[2301]: -> Kernel messages: May 13 23:59:01.309062 setup-nvidia[2301]: 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:08.743346 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:48324.service - OpenSSH per-connection server daemon (10.200.16.10:48324). May 13 23:59:09.373973 sshd[12812]: Accepted publickey for core from 10.200.16.10 port 48324 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:09.375376 sshd-session[12812]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:09.379486 systemd-logind[1713]: New session 20 of user core. May 13 23:59:09.390930 systemd[1]: Started session-20.scope - Session 20 of User core. May 13 23:59:09.818248 sshd[12814]: Connection closed by 10.200.16.10 port 48324 May 13 23:59:09.818594 sshd-session[12812]: pam_unix(sshd:session): session closed for user core May 13 23:59:09.821652 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:48324.service: Deactivated successfully. May 13 23:59:09.823216 systemd[1]: session-20.scope: Deactivated successfully. May 13 23:59:09.823846 systemd-logind[1713]: Session 20 logged out. Waiting for processes to exit. May 13 23:59:09.824613 systemd-logind[1713]: Removed session 20. May 13 23:59:09.929050 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:48334.service - OpenSSH per-connection server daemon (10.200.16.10:48334). May 13 23:59:10.559930 sshd[12820]: Accepted publickey for core from 10.200.16.10 port 48334 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:10.561431 sshd-session[12820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:10.565528 systemd-logind[1713]: New session 21 of user core. May 13 23:59:10.574932 systemd[1]: Started session-21.scope - Session 21 of User core. May 13 23:59:11.006466 sshd[12822]: Connection closed by 10.200.16.10 port 48334 May 13 23:59:11.006751 sshd-session[12820]: pam_unix(sshd:session): session closed for user core May 13 23:59:11.009909 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:48334.service: Deactivated successfully. May 13 23:59:11.011529 systemd[1]: session-21.scope: Deactivated successfully. May 13 23:59:11.012169 systemd-logind[1713]: Session 21 logged out. Waiting for processes to exit. May 13 23:59:11.012964 systemd-logind[1713]: Removed session 21. May 13 23:59:16.857833 kernel: EXT4-fs (loop6p9): unmounting filesystem 63c5bf5a-c683-4802-9fa3-6d3ca7f4a515. May 13 23:59:16.858399 setup-nvidia[2301]: Container flatcardevelopercontainer-4284.0.0nightly-20250513-2100.bin exited successfully. May 13 23:59:16.860740 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4284.0.0nightly\x2d20250513\x2d2100.bin.mount: Deactivated successfully. May 13 23:59:16.915616 sudo[2289]: pam_unix(sudo:session): session closed for user root May 13 23:59:16.917543 systemd[1]: session-c2.scope: Deactivated successfully. May 13 23:59:16.918609 systemd[1]: session-c2.scope: Consumed 5min 20.616s CPU time, 882.6M memory peak. May 13 23:59:17.335252 setup-nvidia[1707]: /opt/nvidia / May 13 23:59:17.336257 setup-nvidia[1707]: / May 13 23:59:17.351825 setup-nvidia[12845]: 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.376831 kernel: IPMI message handler: version 39.2 May 13 23:59:17.380827 kernel: ipmi device interface May 13 23:59:17.387326 setup-nvidia[1707]: /opt/nvidia/current/usr/lib/modules/6.6.89-flatcar/video / May 13 23:59:17.441384 kernel: nvidia: loading out-of-tree module taints kernel. May 13 23:59:17.441522 kernel: nvidia: module license 'NVIDIA' taints kernel. May 13 23:59:17.441546 kernel: Disabling lock debugging due to kernel taint May 13 23:59:17.448885 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel May 13 23:59:17.448925 kernel: nvidia: module license taints kernel. May 13 23:59:17.837830 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 May 13 23:59:18.240951 kernel: May 13 23:59:18.241071 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) May 13 23:59:18.447829 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.469007 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.488840 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. May 13 23:59:18.533735 setup-nvidia[1707]: / May 13 23:59:18.534082 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. May 13 23:59:20.295359 setup-nvidia[12877]: Tue May 13 23:59:20 2025 May 13 23:59:20.295359 setup-nvidia[12877]: +---------------------------------------------------------------------------------------+ May 13 23:59:20.295359 setup-nvidia[12877]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | May 13 23:59:20.295359 setup-nvidia[12877]: |-----------------------------------------+----------------------+----------------------+ May 13 23:59:20.295359 setup-nvidia[12877]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | May 13 23:59:20.295359 setup-nvidia[12877]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | May 13 23:59:20.295359 setup-nvidia[12877]: | | | MIG M. | May 13 23:59:20.295359 setup-nvidia[12877]: |=========================================+======================+======================| May 13 23:59:20.298810 setup-nvidia[12877]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | May 13 23:59:20.298810 setup-nvidia[12877]: | N/A 27C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | May 13 23:59:20.298810 setup-nvidia[12877]: | | | N/A | May 13 23:59:20.298810 setup-nvidia[12877]: +-----------------------------------------+----------------------+----------------------+ May 13 23:59:20.298916 setup-nvidia[12877]: May 13 23:59:20.298916 setup-nvidia[12877]: +---------------------------------------------------------------------------------------+ May 13 23:59:20.298916 setup-nvidia[12877]: | Processes: | May 13 23:59:20.298916 setup-nvidia[12877]: | GPU GI CI PID Type Process name GPU Memory | May 13 23:59:20.298916 setup-nvidia[12877]: | ID ID Usage | May 13 23:59:20.298916 setup-nvidia[12877]: |=======================================================================================| May 13 23:59:20.299050 setup-nvidia[12877]: | No running processes found | May 13 23:59:20.299050 setup-nvidia[12877]: +---------------------------------------------------------------------------------------+ May 13 23:59:20.673863 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 13 23:59:20.676017 systemd[1]: Reached target multi-user.target - Multi-User System. May 13 23:59:20.683899 systemd[1]: Startup finished in 1.082s (firmware) + 30.097s (loader) + 1.845s (kernel) + 11.400s (initrd) + 2min 52.773s (userspace) = 3min 37.198s. May 13 23:59:21.118282 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:56376.service - OpenSSH per-connection server daemon (10.200.16.10:56376). May 13 23:59:21.755701 sshd[12884]: Accepted publickey for core from 10.200.16.10 port 56376 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:21.756987 sshd-session[12884]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:21.761100 systemd-logind[1713]: New session 22 of user core. May 13 23:59:21.780941 systemd[1]: Started session-22.scope - Session 22 of User core. May 13 23:59:22.200024 sshd[12886]: Connection closed by 10.200.16.10 port 56376 May 13 23:59:22.200403 sshd-session[12884]: pam_unix(sshd:session): session closed for user core May 13 23:59:22.203735 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:56376.service: Deactivated successfully. May 13 23:59:22.205341 systemd[1]: session-22.scope: Deactivated successfully. May 13 23:59:22.205979 systemd-logind[1713]: Session 22 logged out. Waiting for processes to exit. May 13 23:59:22.206763 systemd-logind[1713]: Removed session 22. May 13 23:59:22.311157 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:56386.service - OpenSSH per-connection server daemon (10.200.16.10:56386). May 13 23:59:22.949957 sshd[12892]: Accepted publickey for core from 10.200.16.10 port 56386 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:22.951211 sshd-session[12892]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:22.955282 systemd-logind[1713]: New session 23 of user core. May 13 23:59:22.964928 systemd[1]: Started session-23.scope - Session 23 of User core. May 13 23:59:23.390493 sshd[12894]: Connection closed by 10.200.16.10 port 56386 May 13 23:59:23.390885 sshd-session[12892]: pam_unix(sshd:session): session closed for user core May 13 23:59:23.394122 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:56386.service: Deactivated successfully. May 13 23:59:23.395702 systemd[1]: session-23.scope: Deactivated successfully. May 13 23:59:23.396355 systemd-logind[1713]: Session 23 logged out. Waiting for processes to exit. May 13 23:59:23.397138 systemd-logind[1713]: Removed session 23. May 13 23:59:23.502646 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:56394.service - OpenSSH per-connection server daemon (10.200.16.10:56394). May 13 23:59:24.139988 sshd[12900]: Accepted publickey for core from 10.200.16.10 port 56394 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:24.141323 sshd-session[12900]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:24.145491 systemd-logind[1713]: New session 24 of user core. May 13 23:59:24.155955 systemd[1]: Started session-24.scope - Session 24 of User core. May 13 23:59:24.585829 sshd[12902]: Connection closed by 10.200.16.10 port 56394 May 13 23:59:24.586228 sshd-session[12900]: pam_unix(sshd:session): session closed for user core May 13 23:59:24.589500 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:56394.service: Deactivated successfully. May 13 23:59:24.591087 systemd[1]: session-24.scope: Deactivated successfully. May 13 23:59:24.591698 systemd-logind[1713]: Session 24 logged out. Waiting for processes to exit. May 13 23:59:24.592532 systemd-logind[1713]: Removed session 24. May 13 23:59:24.697056 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:56406.service - OpenSSH per-connection server daemon (10.200.16.10:56406). May 13 23:59:25.338316 sshd[12908]: Accepted publickey for core from 10.200.16.10 port 56406 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:25.339675 sshd-session[12908]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:25.343852 systemd-logind[1713]: New session 25 of user core. May 13 23:59:25.353933 systemd[1]: Started session-25.scope - Session 25 of User core. May 13 23:59:25.683979 sudo[12911]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 13 23:59:25.684291 sudo[12911]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:59:25.746068 sudo[12911]: pam_unix(sudo:session): session closed for user root May 13 23:59:25.846851 sshd[12910]: Connection closed by 10.200.16.10 port 56406 May 13 23:59:25.847316 sshd-session[12908]: pam_unix(sshd:session): session closed for user core May 13 23:59:25.850868 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:56406.service: Deactivated successfully. May 13 23:59:25.852431 systemd[1]: session-25.scope: Deactivated successfully. May 13 23:59:25.853120 systemd-logind[1713]: Session 25 logged out. Waiting for processes to exit. May 13 23:59:25.854236 systemd-logind[1713]: Removed session 25. May 13 23:59:25.965470 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:56420.service - OpenSSH per-connection server daemon (10.200.16.10:56420). May 13 23:59:26.596185 sshd[12917]: Accepted publickey for core from 10.200.16.10 port 56420 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:26.597567 sshd-session[12917]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:26.601710 systemd-logind[1713]: New session 26 of user core. May 13 23:59:26.617926 systemd[1]: Started session-26.scope - Session 26 of User core. May 13 23:59:26.942148 sudo[12921]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 13 23:59:26.942456 sudo[12921]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:59:26.945110 systemd[1]: Stopping user@0.service - User Manager for UID 0... May 13 23:59:26.945696 sudo[12921]: pam_unix(sudo:session): session closed for user root May 13 23:59:26.945898 systemd[2292]: Activating special unit exit.target... May 13 23:59:26.945974 systemd[2292]: Stopped target default.target - Main User Target. May 13 23:59:26.946014 systemd[2292]: Stopped target basic.target - Basic System. May 13 23:59:26.946045 systemd[2292]: Stopped target paths.target - Paths. May 13 23:59:26.946072 systemd[2292]: Stopped target sockets.target - Sockets. May 13 23:59:26.946100 systemd[2292]: Stopped target timers.target - Timers. May 13 23:59:26.946462 systemd[2292]: Closed dbus.socket - D-Bus User Message Bus Socket. May 13 23:59:26.946673 systemd[2292]: Removed slice app.slice - User Application Slice. May 13 23:59:26.946705 systemd[2292]: Reached target shutdown.target - Shutdown. May 13 23:59:26.946877 systemd[2292]: Finished systemd-exit.service - Exit the Session. May 13 23:59:26.946918 systemd[2292]: Reached target exit.target - Exit the Session. May 13 23:59:26.951160 sudo[12920]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 13 23:59:26.951446 sudo[12920]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:59:26.959881 (sd-pam)[2293]: pam_unix(systemd-user:session): session closed for user root May 13 23:59:26.960475 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:59:26.961194 (sd-pam)[2293]: pam_systemd(systemd-user:session): Failed to release session: Caller does not belong to any known session. May 13 23:59:26.961236 (sd-pam)[2293]: pam_systemd(systemd-user:session): Failed to release session: Transport endpoint is not connected May 13 23:59:26.962561 systemd[1]: user@0.service: Deactivated successfully. May 13 23:59:26.962899 systemd[1]: Stopped user@0.service - User Manager for UID 0. May 13 23:59:26.972031 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 13 23:59:26.994055 systemd[1]: run-user-0.mount: Deactivated successfully. May 13 23:59:26.995202 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. May 13 23:59:26.995401 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 13 23:59:26.997052 augenrules[12945]: No rules May 13 23:59:26.997286 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. May 13 23:59:26.997410 systemd[1]: user-0.slice: Consumed 5min 20.864s CPU time, 891.7M memory peak. May 13 23:59:26.997901 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:59:26.998106 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:59:26.998850 sudo[12920]: pam_unix(sudo:session): session closed for user root May 13 23:59:26.999277 systemd-logind[1713]: Removed session c3. May 13 23:59:27.102918 sshd[12919]: Connection closed by 10.200.16.10 port 56420 May 13 23:59:27.103312 sshd-session[12917]: pam_unix(sshd:session): session closed for user core May 13 23:59:27.106540 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:56420.service: Deactivated successfully. May 13 23:59:27.108159 systemd[1]: session-26.scope: Deactivated successfully. May 13 23:59:27.108786 systemd-logind[1713]: Session 26 logged out. Waiting for processes to exit. May 13 23:59:27.109565 systemd-logind[1713]: Removed session 26. May 13 23:59:27.213525 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:56434.service - OpenSSH per-connection server daemon (10.200.16.10:56434). May 13 23:59:27.843333 sshd[12956]: Accepted publickey for core from 10.200.16.10 port 56434 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:27.844717 sshd-session[12956]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:27.850175 systemd-logind[1713]: New session 27 of user core. May 13 23:59:27.864924 systemd[1]: Started session-27.scope - Session 27 of User core. May 13 23:59:28.188781 sudo[12959]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . May 13 23:59:29.509534 sshd[12958]: Connection closed by 10.200.16.10 port 56434 May 13 23:59:28.402283 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:56436.service - OpenSSH per-connection server daemon (10.200.16.10:56436). May 13 23:59:28.189106 sudo[12959]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:59:29.510098 sshd[12962]: Accepted publickey for core from 10.200.16.10 port 56436 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:28.192324 sudo[12959]: pam_unix(sudo:session): session closed for user root May 13 23:59:29.509389 sshd-session[12956]: pam_unix(sshd:session): session closed for user core May 13 23:59:29.509593 sshd-session[12962]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:29.513013 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:56434.service: Deactivated successfully. May 13 23:59:29.514621 systemd[1]: session-27.scope: Deactivated successfully. May 13 23:59:29.516728 systemd-logind[1713]: New session 28 of user core. May 13 23:59:29.536000 systemd[1]: Started session-28.scope - Session 28 of User core. May 13 23:59:29.536595 systemd-logind[1713]: Session 27 logged out. Waiting for processes to exit. May 13 23:59:29.537357 systemd-logind[1713]: Removed session 27. May 13 23:59:29.787324 sudo[12970]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet May 13 23:59:29.787631 sudo[12970]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:59:33.459124 sudo[12970]: pam_unix(sudo:session): session closed for user root May 13 23:59:33.559704 sshd[12967]: Connection closed by 10.200.16.10 port 56436 May 13 23:59:33.560034 sshd-session[12962]: pam_unix(sshd:session): session closed for user core May 13 23:59:33.563212 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:56436.service: Deactivated successfully. May 13 23:59:33.564800 systemd[1]: session-28.scope: Deactivated successfully. May 13 23:59:33.565450 systemd-logind[1713]: Session 28 logged out. Waiting for processes to exit. May 13 23:59:33.566220 systemd-logind[1713]: Removed session 28. May 13 23:59:33.670964 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:47002.service - OpenSSH per-connection server daemon (10.200.16.10:47002). May 13 23:59:34.310954 sshd[12976]: Accepted publickey for core from 10.200.16.10 port 47002 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:34.312291 sshd-session[12976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:34.316595 systemd-logind[1713]: New session 29 of user core. May 13 23:59:34.325922 systemd[1]: Started session-29.scope - Session 29 of User core. May 13 23:59:34.825788 sshd[12979]: Connection closed by 10.200.16.10 port 47002 May 13 23:59:34.826182 sshd-session[12976]: pam_unix(sshd:session): session closed for user core May 13 23:59:34.829458 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:47002.service: Deactivated successfully. May 13 23:59:34.831071 systemd[1]: session-29.scope: Deactivated successfully. May 13 23:59:34.831741 systemd-logind[1713]: Session 29 logged out. Waiting for processes to exit. May 13 23:59:34.832580 systemd-logind[1713]: Removed session 29. May 13 23:59:34.936100 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:47012.service - OpenSSH per-connection server daemon (10.200.16.10:47012). May 13 23:59:35.564198 sshd[12995]: Accepted publickey for core from 10.200.16.10 port 47012 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:35.565567 sshd-session[12995]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:35.569947 systemd-logind[1713]: New session 30 of user core. May 13 23:59:35.581927 systemd[1]: Started session-30.scope - Session 30 of User core. May 13 23:59:37.188508 systemd[1]: Starting docker.service - Docker Application Container Engine... May 13 23:59:37.201156 (dockerd)[13019]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 13 23:59:39.165410 dockerd[13019]: time="2025-05-13T23:59:39.165334790Z" level=info msg="Starting up" May 13 23:59:39.166494 dockerd[13019]: time="2025-05-13T23:59:39.166470649Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 13 23:59:39.239863 dockerd[13019]: time="2025-05-13T23:59:39.239822722Z" level=info msg="Loading containers: start." May 13 23:59:39.467834 kernel: Initializing XFRM netlink socket May 13 23:59:39.550271 systemd-networkd[1410]: docker0: Link UP May 13 23:59:39.640004 dockerd[13019]: time="2025-05-13T23:59:39.639969991Z" level=info msg="Loading containers: done." May 13 23:59:39.653776 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck332590686-merged.mount: Deactivated successfully. May 13 23:59:39.665362 dockerd[13019]: time="2025-05-13T23:59:39.665324383Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 13 23:59:39.665438 dockerd[13019]: time="2025-05-13T23:59:39.665396880Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 May 13 23:59:39.665518 dockerd[13019]: time="2025-05-13T23:59:39.665496476Z" level=info msg="Daemon has completed initialization" May 13 23:59:39.734264 dockerd[13019]: time="2025-05-13T23:59:39.734213615Z" level=info msg="API listen on /run/docker.sock" May 13 23:59:39.734346 systemd[1]: Started docker.service - Docker Application Container Engine. May 13 23:59:40.749626 systemd[1]: var-lib-docker-overlay2-6511d9a8a7da613e128280faef23186f643c7c6946baa6aad674b36439194d31\x2dinit-merged.mount: Deactivated successfully. May 13 23:59:40.833946 kernel: docker0: port 1(vetheb266b7) entered blocking state May 13 23:59:40.834045 kernel: docker0: port 1(vetheb266b7) entered disabled state May 13 23:59:40.834068 kernel: vetheb266b7: entered allmulticast mode May 13 23:59:40.839112 kernel: vetheb266b7: entered promiscuous mode May 13 23:59:40.839723 systemd-networkd[1410]: vetheb266b7: Link UP May 13 23:59:40.947943 containerd[1736]: time="2025-05-13T23:59:40.947899346Z" level=info msg="connecting to shim 82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea" address="unix:///run/containerd/s/8e56fd57a9e64bacf6626cf2c69683997ac2f94d3ce54877acb62e0b9a3a3a8a" namespace=moby protocol=ttrpc version=3 May 13 23:59:40.975962 systemd[1]: Started docker-82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea.scope - libcontainer container 82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea. May 13 23:59:41.034830 kernel: eth0: renamed from veth7ea7405 May 13 23:59:41.048501 kernel: docker0: port 1(vetheb266b7) entered blocking state May 13 23:59:41.048567 kernel: docker0: port 1(vetheb266b7) entered forwarding state May 13 23:59:41.048607 systemd-networkd[1410]: vetheb266b7: Gained carrier May 13 23:59:41.048903 systemd-networkd[1410]: docker0: Gained carrier May 13 23:59:42.378948 systemd-networkd[1410]: vetheb266b7: Gained IPv6LL May 13 23:59:42.442880 systemd-networkd[1410]: docker0: Gained IPv6LL May 13 23:59:44.341390 systemd[1]: docker-82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea.scope: Deactivated successfully. May 13 23:59:44.353773 containerd[1736]: time="2025-05-13T23:59:44.353694265Z" level=info msg="shim disconnected" id=82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea namespace=moby May 13 23:59:44.353773 containerd[1736]: time="2025-05-13T23:59:44.353727164Z" level=warning msg="cleaning up after shim disconnected" id=82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea namespace=moby May 13 23:59:44.353773 containerd[1736]: time="2025-05-13T23:59:44.353736063Z" level=info msg="cleaning up dead shim" namespace=moby May 13 23:59:44.354152 dockerd[13019]: time="2025-05-13T23:59:44.353755462Z" level=info msg="ignoring event" container=82f639055a50f21d4341eaee85a8cbfa7773d9e4656e4abe35956d02df3a2eea module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 13 23:59:44.384352 systemd-networkd[1410]: vetheb266b7: Lost carrier May 13 23:59:44.385031 kernel: docker0: port 1(vetheb266b7) entered disabled state May 13 23:59:44.385092 kernel: veth7ea7405: renamed from eth0 May 13 23:59:44.415943 systemd-networkd[1410]: vetheb266b7: Link DOWN May 13 23:59:44.419829 kernel: docker0: port 1(vetheb266b7) entered disabled state May 13 23:59:44.426384 kernel: vetheb266b7 (unregistering): left allmulticast mode May 13 23:59:44.426434 kernel: vetheb266b7 (unregistering): left promiscuous mode May 13 23:59:44.429747 kernel: docker0: port 1(vetheb266b7) entered disabled state May 13 23:59:44.463849 systemd[1]: run-docker-netns-aefc324f2f78.mount: Deactivated successfully. May 13 23:59:44.466886 systemd[1]: var-lib-docker-overlay2-6511d9a8a7da613e128280faef23186f643c7c6946baa6aad674b36439194d31-merged.mount: Deactivated successfully. May 13 23:59:44.599954 sshd[12997]: Connection closed by 10.200.16.10 port 47012 May 13 23:59:44.600251 sshd-session[12995]: pam_unix(sshd:session): session closed for user core May 13 23:59:44.603384 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:47012.service: Deactivated successfully. May 13 23:59:44.604916 systemd[1]: session-30.scope: Deactivated successfully. May 13 23:59:44.605617 systemd-logind[1713]: Session 30 logged out. Waiting for processes to exit. May 13 23:59:44.606426 systemd-logind[1713]: Removed session 30. May 13 23:59:44.714046 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:47016.service - OpenSSH per-connection server daemon (10.200.16.10:47016). May 13 23:59:45.356675 sshd[13308]: Accepted publickey for core from 10.200.16.10 port 47016 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:45.358034 sshd-session[13308]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:45.362294 systemd-logind[1713]: New session 31 of user core. May 13 23:59:45.372933 systemd[1]: Started session-31.scope - Session 31 of User core. May 13 23:59:45.387005 systemd-networkd[1410]: docker0: Lost carrier May 13 23:59:45.758065 systemd[1]: var-lib-docker-overlay2-8d3f5815a1cfc208c37657a259c55b77c935a59fee20cf9af14ca9909084c617\x2dinit-merged.mount: Deactivated successfully. May 13 23:59:45.847358 kernel: docker0: port 1(veth84c5296) entered blocking state May 13 23:59:45.847471 kernel: docker0: port 1(veth84c5296) entered disabled state May 13 23:59:45.850205 kernel: veth84c5296: entered allmulticast mode May 13 23:59:45.853082 kernel: veth84c5296: entered promiscuous mode May 13 23:59:45.854412 systemd-networkd[1410]: veth84c5296: Link UP May 13 23:59:45.856551 kernel: docker0: port 1(veth84c5296) entered blocking state May 13 23:59:45.860066 kernel: docker0: port 1(veth84c5296) entered forwarding state May 13 23:59:45.864854 kernel: docker0: port 1(veth84c5296) entered disabled state May 13 23:59:45.948427 containerd[1736]: time="2025-05-13T23:59:45.948377782Z" level=info msg="connecting to shim 0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856" address="unix:///run/containerd/s/aef1e3201cf40d0c7d2bc092e3b8a5e5b07bd72df4d36080f2cf4b72c78fc52e" namespace=moby protocol=ttrpc version=3 May 13 23:59:45.976955 systemd[1]: Started docker-0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856.scope - libcontainer container 0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856. May 13 23:59:46.032836 kernel: eth0: renamed from veth5bf2f4c May 13 23:59:46.046761 kernel: docker0: port 1(veth84c5296) entered blocking state May 13 23:59:46.046843 kernel: docker0: port 1(veth84c5296) entered forwarding state May 13 23:59:46.047143 systemd-networkd[1410]: veth84c5296: Gained carrier May 13 23:59:46.047407 systemd-networkd[1410]: docker0: Gained carrier May 13 23:59:46.090155 systemd[1]: docker-0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856.scope: Deactivated successfully. May 13 23:59:46.109696 containerd[1736]: time="2025-05-13T23:59:46.109648570Z" level=info msg="shim disconnected" id=0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856 namespace=moby May 13 23:59:46.109696 containerd[1736]: time="2025-05-13T23:59:46.109683668Z" level=warning msg="cleaning up after shim disconnected" id=0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856 namespace=moby May 13 23:59:46.109828 containerd[1736]: time="2025-05-13T23:59:46.109692668Z" level=info msg="cleaning up dead shim" namespace=moby May 13 23:59:46.109867 dockerd[13019]: time="2025-05-13T23:59:46.109678068Z" level=info msg="ignoring event" container=0a62fd0fb2e40b139ce26e10c6b209e84bba776fd4f07d3ad19a8bd8f1884856 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 13 23:59:46.136599 systemd-networkd[1410]: veth84c5296: Lost carrier May 13 23:59:46.136824 kernel: docker0: port 1(veth84c5296) entered disabled state May 13 23:59:46.136865 kernel: veth5bf2f4c: renamed from eth0 May 13 23:59:46.166997 systemd-networkd[1410]: veth84c5296: Link DOWN May 13 23:59:46.170826 kernel: docker0: port 1(veth84c5296) entered disabled state May 13 23:59:46.177415 kernel: veth84c5296 (unregistering): left allmulticast mode May 13 23:59:46.177470 kernel: veth84c5296 (unregistering): left promiscuous mode May 13 23:59:46.180852 kernel: docker0: port 1(veth84c5296) entered disabled state May 13 23:59:46.337870 sshd[13310]: Connection closed by 10.200.16.10 port 47016 May 13 23:59:46.338248 sshd-session[13308]: pam_unix(sshd:session): session closed for user core May 13 23:59:46.341540 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:47016.service: Deactivated successfully. May 13 23:59:46.343168 systemd[1]: session-31.scope: Deactivated successfully. May 13 23:59:46.343800 systemd-logind[1713]: Session 31 logged out. Waiting for processes to exit. May 13 23:59:46.344569 systemd-logind[1713]: Removed session 31. May 13 23:59:46.395017 systemd-networkd[1410]: docker0: Lost carrier May 13 23:59:46.451086 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:47024.service - OpenSSH per-connection server daemon (10.200.16.10:47024). May 13 23:59:46.755374 systemd[1]: var-lib-docker-overlay2-8d3f5815a1cfc208c37657a259c55b77c935a59fee20cf9af14ca9909084c617-merged.mount: Deactivated successfully. May 13 23:59:47.089678 sshd[13422]: Accepted publickey for core from 10.200.16.10 port 47024 ssh2: RSA SHA256:XYeyNE2tr1+zuVqm6/HQV3FMct5euDXGdJUVi8AjOP4 May 13 23:59:47.091143 sshd-session[13422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:59:47.095203 systemd-logind[1713]: New session 32 of user core. May 13 23:59:47.106936 systemd[1]: Started session-32.scope - Session 32 of User core. May 13 23:59:47.580765 ntpdate[13435]: ntpdate 4.2.8p17@1.4004-o Tue May 13 21:33:09 UTC 2025 (1) May 13 23:59:54.586168 ntpdate[13435]: adjust time server 193.1.8.98 offset -0.001819 sec May 13 23:59:54.687991 sshd[13424]: Connection closed by 10.200.16.10 port 47024 May 13 23:59:54.688388 sshd-session[13422]: pam_unix(sshd:session): session closed for user core May 13 23:59:54.691645 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:47024.service: Deactivated successfully. May 13 23:59:54.693272 systemd[1]: session-32.scope: Deactivated successfully. May 13 23:59:54.693917 systemd-logind[1713]: Session 32 logged out. Waiting for processes to exit. May 13 23:59:54.694715 systemd-logind[1713]: Removed session 32.