May 14 18:08:16.197624 kernel: Linux version 6.12.20-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 Wed May 14 16:37:27 -00 2025 May 14 18:08:16.197651 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=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0 May 14 18:08:16.197662 kernel: BIOS-provided physical RAM map: May 14 18:08:16.197669 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 14 18:08:16.197675 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 14 18:08:16.197681 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 14 18:08:16.197689 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved May 14 18:08:16.197699 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data May 14 18:08:16.197706 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 14 18:08:16.197713 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 14 18:08:16.197719 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable May 14 18:08:16.197726 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable May 14 18:08:16.197733 kernel: printk: legacy bootconsole [earlyser0] enabled May 14 18:08:16.197739 kernel: NX (Execute Disable) protection: active May 14 18:08:16.197750 kernel: APIC: Static calls initialized May 14 18:08:16.197758 kernel: efi: EFI v2.7 by Microsoft May 14 18:08:16.197766 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 May 14 18:08:16.197773 kernel: random: crng init done May 14 18:08:16.197781 kernel: secureboot: Secure boot disabled May 14 18:08:16.197788 kernel: SMBIOS 3.1.0 present. May 14 18:08:16.197796 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 May 14 18:08:16.197803 kernel: DMI: Memory slots populated: 3/3 May 14 18:08:16.197811 kernel: Hypervisor detected: Microsoft Hyper-V May 14 18:08:16.197818 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 May 14 18:08:16.197828 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 14 18:08:16.197835 kernel: Hyper-V: Using hypercall for remote TLB flush May 14 18:08:16.197843 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 14 18:08:16.197851 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 14 18:08:16.197859 kernel: tsc: Marking TSC unstable due to running on Hyper-V May 14 18:08:16.197866 kernel: tsc: Detected 2593.993 MHz processor May 14 18:08:16.197874 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 14 18:08:16.197882 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 14 18:08:16.197890 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 May 14 18:08:16.197900 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs May 14 18:08:16.197908 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 14 18:08:16.197915 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved May 14 18:08:16.197923 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved May 14 18:08:16.197930 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 May 14 18:08:16.197938 kernel: Using GB pages for direct mapping May 14 18:08:16.197945 kernel: ACPI: Early table checksum verification disabled May 14 18:08:16.197957 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 14 18:08:16.197967 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.197975 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.197983 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) May 14 18:08:16.197991 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 14 18:08:16.197999 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198008 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198018 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198026 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198034 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198042 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198050 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 14 18:08:16.198058 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 14 18:08:16.198066 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] May 14 18:08:16.198074 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 14 18:08:16.198085 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 14 18:08:16.198093 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 14 18:08:16.198101 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 14 18:08:16.198109 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] May 14 18:08:16.198117 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] May 14 18:08:16.198125 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 14 18:08:16.198133 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] May 14 18:08:16.198141 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 14 18:08:16.198149 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug May 14 18:08:16.198159 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug May 14 18:08:16.198167 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug May 14 18:08:16.198175 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug May 14 18:08:16.198184 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug May 14 18:08:16.198192 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug May 14 18:08:16.198200 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug May 14 18:08:16.198208 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] May 14 18:08:16.198216 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] May 14 18:08:16.198224 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] May 14 18:08:16.198235 kernel: Zone ranges: May 14 18:08:16.198243 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 14 18:08:16.198251 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 14 18:08:16.198259 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] May 14 18:08:16.198267 kernel: Device empty May 14 18:08:16.198275 kernel: Movable zone start for each node May 14 18:08:16.198283 kernel: Early memory node ranges May 14 18:08:16.198291 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 14 18:08:16.198299 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 14 18:08:16.198309 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 14 18:08:16.198317 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] May 14 18:08:16.198325 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] May 14 18:08:16.198333 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] May 14 18:08:16.198341 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 14 18:08:16.198349 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 14 18:08:16.198382 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges May 14 18:08:16.198391 kernel: ACPI: PM-Timer IO Port: 0x408 May 14 18:08:16.198399 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) May 14 18:08:16.198410 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 May 14 18:08:16.198418 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 14 18:08:16.198426 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 14 18:08:16.198434 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 14 18:08:16.198443 kernel: CPU topo: Max. logical packages: 1 May 14 18:08:16.198451 kernel: CPU topo: Max. logical dies: 1 May 14 18:08:16.198459 kernel: CPU topo: Max. dies per package: 1 May 14 18:08:16.198467 kernel: CPU topo: Max. threads per core: 1 May 14 18:08:16.198474 kernel: CPU topo: Num. cores per package: 6 May 14 18:08:16.198485 kernel: CPU topo: Num. threads per package: 6 May 14 18:08:16.198493 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs May 14 18:08:16.198501 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 14 18:08:16.198509 kernel: Booting paravirtualized kernel on Hyper-V May 14 18:08:16.198517 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 14 18:08:16.198525 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 May 14 18:08:16.198533 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 May 14 18:08:16.198541 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 May 14 18:08:16.198549 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - May 14 18:08:16.198559 kernel: Hyper-V: PV spinlocks enabled May 14 18:08:16.198568 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 14 18:08:16.198577 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=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0 May 14 18:08:16.198585 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 14 18:08:16.198594 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) May 14 18:08:16.198602 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) May 14 18:08:16.198610 kernel: Fallback order for Node 0: 0 May 14 18:08:16.198618 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 May 14 18:08:16.198629 kernel: Policy zone: Normal May 14 18:08:16.198645 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 14 18:08:16.198653 kernel: software IO TLB: area num 8. May 14 18:08:16.198664 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 May 14 18:08:16.198673 kernel: Kernel/User page tables isolation: enabled May 14 18:08:16.198681 kernel: ftrace: allocating 40065 entries in 157 pages May 14 18:08:16.198690 kernel: ftrace: allocated 157 pages with 5 groups May 14 18:08:16.198698 kernel: Dynamic Preempt: voluntary May 14 18:08:16.198707 kernel: rcu: Preemptible hierarchical RCU implementation. May 14 18:08:16.198716 kernel: rcu: RCU event tracing is enabled. May 14 18:08:16.198724 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. May 14 18:08:16.198733 kernel: Trampoline variant of Tasks RCU enabled. May 14 18:08:16.198744 kernel: Rude variant of Tasks RCU enabled. May 14 18:08:16.198753 kernel: Tracing variant of Tasks RCU enabled. May 14 18:08:16.198761 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 14 18:08:16.198770 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 May 14 18:08:16.198779 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 14 18:08:16.198790 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 14 18:08:16.198798 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 14 18:08:16.198807 kernel: Using NULL legacy PIC May 14 18:08:16.198816 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 May 14 18:08:16.198824 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 14 18:08:16.198833 kernel: Console: colour dummy device 80x25 May 14 18:08:16.198841 kernel: printk: legacy console [tty1] enabled May 14 18:08:16.198850 kernel: printk: legacy console [ttyS0] enabled May 14 18:08:16.198858 kernel: printk: legacy bootconsole [earlyser0] disabled May 14 18:08:16.198869 kernel: ACPI: Core revision 20240827 May 14 18:08:16.198878 kernel: Failed to register legacy timer interrupt May 14 18:08:16.198886 kernel: APIC: Switch to symmetric I/O mode setup May 14 18:08:16.198895 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 May 14 18:08:16.198903 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 14 18:08:16.198912 kernel: Hyper-V: Using IPI hypercalls May 14 18:08:16.198920 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 14 18:08:16.198928 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 14 18:08:16.198937 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 14 18:08:16.198948 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 14 18:08:16.198957 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 14 18:08:16.198965 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 14 18:08:16.198974 kernel: Hyper-V: Using enlightened APIC (xapic mode) May 14 18:08:16.198982 kernel: APIC: eoi() replaced with hv_apic_eoi_write() May 14 18:08:16.198991 kernel: APIC: read() replaced with hv_apic_read() May 14 18:08:16.198999 kernel: APIC: write() replaced with hv_apic_write() May 14 18:08:16.199008 kernel: APIC: icr_write() replaced with hv_apic_icr_write() May 14 18:08:16.199016 kernel: APIC: icr_read() replaced with hv_apic_icr_read() May 14 18:08:16.199027 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) May 14 18:08:16.199036 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 May 14 18:08:16.199044 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 May 14 18:08:16.199053 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 14 18:08:16.199061 kernel: Spectre V2 : Mitigation: Retpolines May 14 18:08:16.199070 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 14 18:08:16.199078 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT May 14 18:08:16.199086 kernel: Speculative Store Bypass: Vulnerable May 14 18:08:16.199095 kernel: MDS: Mitigation: Clear CPU buffers May 14 18:08:16.199103 kernel: TAA: Mitigation: Clear CPU buffers May 14 18:08:16.199114 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 14 18:08:16.199122 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 14 18:08:16.199130 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 14 18:08:16.199139 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 14 18:08:16.199147 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 14 18:08:16.199156 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 14 18:08:16.199164 kernel: Freeing SMP alternatives memory: 32K May 14 18:08:16.199172 kernel: pid_max: default: 32768 minimum: 301 May 14 18:08:16.199181 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 14 18:08:16.199189 kernel: landlock: Up and running. May 14 18:08:16.199197 kernel: SELinux: Initializing. May 14 18:08:16.199208 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 14 18:08:16.199217 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 14 18:08:16.199225 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) May 14 18:08:16.199233 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 14 18:08:16.199242 kernel: signal: max sigframe size: 1776 May 14 18:08:16.199250 kernel: rcu: Hierarchical SRCU implementation. May 14 18:08:16.199259 kernel: rcu: Max phase no-delay instances is 400. May 14 18:08:16.199268 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 14 18:08:16.199276 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 14 18:08:16.199285 kernel: smp: Bringing up secondary CPUs ... May 14 18:08:16.199295 kernel: smpboot: x86: Booting SMP configuration: May 14 18:08:16.199304 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 May 14 18:08:16.199312 kernel: smp: Brought up 1 node, 6 CPUs May 14 18:08:16.199321 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) May 14 18:08:16.199330 kernel: Memory: 115339080K/117439364K available (14336K kernel code, 2438K rwdata, 9944K rodata, 54424K init, 2536K bss, 2090008K reserved, 0K cma-reserved) May 14 18:08:16.199338 kernel: devtmpfs: initialized May 14 18:08:16.199347 kernel: x86/mm: Memory block size: 1024MB May 14 18:08:16.199363 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 14 18:08:16.199373 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 14 18:08:16.199384 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) May 14 18:08:16.199393 kernel: pinctrl core: initialized pinctrl subsystem May 14 18:08:16.199401 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 14 18:08:16.199410 kernel: audit: initializing netlink subsys (disabled) May 14 18:08:16.199418 kernel: thermal_sys: Registered thermal governor 'step_wise' May 14 18:08:16.199427 kernel: thermal_sys: Registered thermal governor 'user_space' May 14 18:08:16.199436 kernel: audit: type=2000 audit(1747246093.029:1): state=initialized audit_enabled=0 res=1 May 14 18:08:16.199444 kernel: cpuidle: using governor menu May 14 18:08:16.199452 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 14 18:08:16.199464 kernel: dca service started, version 1.12.1 May 14 18:08:16.199472 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 14 18:08:16.199480 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 14 18:08:16.199489 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 14 18:08:16.199498 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 14 18:08:16.199509 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 14 18:08:16.199518 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 14 18:08:16.199526 kernel: ACPI: Added _OSI(Module Device) May 14 18:08:16.199537 kernel: ACPI: Added _OSI(Processor Device) May 14 18:08:16.199552 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 14 18:08:16.199561 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 14 18:08:16.199570 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 14 18:08:16.199578 kernel: ACPI: Interpreter enabled May 14 18:08:16.199587 kernel: ACPI: PM: (supports S0 S5) May 14 18:08:16.199595 kernel: ACPI: Using IOAPIC for interrupt routing May 14 18:08:16.199604 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 14 18:08:16.199612 kernel: PCI: Ignoring E820 reservations for host bridge windows May 14 18:08:16.199621 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 14 18:08:16.199633 kernel: iommu: Default domain type: Translated May 14 18:08:16.199641 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 14 18:08:16.199650 kernel: efivars: Registered efivars operations May 14 18:08:16.199658 kernel: PCI: Using ACPI for IRQ routing May 14 18:08:16.199667 kernel: PCI: System does not support PCI May 14 18:08:16.199675 kernel: vgaarb: loaded May 14 18:08:16.199684 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page May 14 18:08:16.199692 kernel: VFS: Disk quotas dquot_6.6.0 May 14 18:08:16.199701 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 14 18:08:16.199712 kernel: pnp: PnP ACPI init May 14 18:08:16.199721 kernel: pnp: PnP ACPI: found 3 devices May 14 18:08:16.199729 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 14 18:08:16.199738 kernel: NET: Registered PF_INET protocol family May 14 18:08:16.199746 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 14 18:08:16.199755 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) May 14 18:08:16.199763 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 14 18:08:16.199772 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 14 18:08:16.199783 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 14 18:08:16.199792 kernel: TCP: Hash tables configured (established 524288 bind 65536) May 14 18:08:16.199800 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 14 18:08:16.199809 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 14 18:08:16.199818 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 14 18:08:16.199826 kernel: NET: Registered PF_XDP protocol family May 14 18:08:16.199835 kernel: PCI: CLS 0 bytes, default 64 May 14 18:08:16.199843 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 14 18:08:16.199852 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) May 14 18:08:16.199863 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 14 18:08:16.199871 kernel: Initialise system trusted keyrings May 14 18:08:16.199880 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 May 14 18:08:16.199888 kernel: Key type asymmetric registered May 14 18:08:16.199897 kernel: Asymmetric key parser 'x509' registered May 14 18:08:16.199905 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 14 18:08:16.199913 kernel: io scheduler mq-deadline registered May 14 18:08:16.199922 kernel: io scheduler kyber registered May 14 18:08:16.199930 kernel: io scheduler bfq registered May 14 18:08:16.199941 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 14 18:08:16.199950 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 14 18:08:16.199958 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 14 18:08:16.199967 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 14 18:08:16.199976 kernel: i8042: PNP: No PS/2 controller found. May 14 18:08:16.200124 kernel: rtc_cmos 00:02: registered as rtc0 May 14 18:08:16.200223 kernel: rtc_cmos 00:02: setting system clock to 2025-05-14T18:08:15 UTC (1747246095) May 14 18:08:16.200313 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 14 18:08:16.200329 kernel: intel_pstate: CPU model not supported May 14 18:08:16.200338 kernel: efifb: probing for efifb May 14 18:08:16.200346 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 14 18:08:16.200367 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 14 18:08:16.200377 kernel: efifb: scrolling: redraw May 14 18:08:16.200385 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 14 18:08:16.200394 kernel: Console: switching to colour frame buffer device 128x48 May 14 18:08:16.200403 kernel: fb0: EFI VGA frame buffer device May 14 18:08:16.200411 kernel: pstore: Using crash dump compression: deflate May 14 18:08:16.200423 kernel: pstore: Registered efi_pstore as persistent store backend May 14 18:08:16.200432 kernel: NET: Registered PF_INET6 protocol family May 14 18:08:16.200440 kernel: Segment Routing with IPv6 May 14 18:08:16.200449 kernel: In-situ OAM (IOAM) with IPv6 May 14 18:08:16.200458 kernel: NET: Registered PF_PACKET protocol family May 14 18:08:16.200466 kernel: Key type dns_resolver registered May 14 18:08:16.200475 kernel: IPI shorthand broadcast: enabled May 14 18:08:16.200483 kernel: sched_clock: Marking stable (3730005600, 107969800)->(4119508200, -281532800) May 14 18:08:16.200492 kernel: registered taskstats version 1 May 14 18:08:16.200503 kernel: Loading compiled-in X.509 certificates May 14 18:08:16.200512 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: 41e2a150aa08ec2528be2394819b3db677e5f4ef' May 14 18:08:16.200520 kernel: Demotion targets for Node 0: null May 14 18:08:16.200529 kernel: Key type .fscrypt registered May 14 18:08:16.200537 kernel: Key type fscrypt-provisioning registered May 14 18:08:16.200546 kernel: ima: No TPM chip found, activating TPM-bypass! May 14 18:08:16.200554 kernel: ima: Allocated hash algorithm: sha1 May 14 18:08:16.200563 kernel: ima: No architecture policies found May 14 18:08:16.200571 kernel: clk: Disabling unused clocks May 14 18:08:16.200582 kernel: Warning: unable to open an initial console. May 14 18:08:16.200591 kernel: Freeing unused kernel image (initmem) memory: 54424K May 14 18:08:16.200600 kernel: Write protecting the kernel read-only data: 24576k May 14 18:08:16.200608 kernel: Freeing unused kernel image (rodata/data gap) memory: 296K May 14 18:08:16.200617 kernel: Run /init as init process May 14 18:08:16.200625 kernel: with arguments: May 14 18:08:16.200634 kernel: /init May 14 18:08:16.200642 kernel: with environment: May 14 18:08:16.200650 kernel: HOME=/ May 14 18:08:16.200661 kernel: TERM=linux May 14 18:08:16.200669 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 14 18:08:16.200679 systemd[1]: Successfully made /usr/ read-only. May 14 18:08:16.200691 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 14 18:08:16.200700 systemd[1]: Detected virtualization microsoft. May 14 18:08:16.200709 systemd[1]: Detected architecture x86-64. May 14 18:08:16.200717 systemd[1]: Running in initrd. May 14 18:08:16.200726 systemd[1]: No hostname configured, using default hostname. May 14 18:08:16.200738 systemd[1]: Hostname set to . May 14 18:08:16.200747 systemd[1]: Initializing machine ID from random generator. May 14 18:08:16.200756 systemd[1]: Queued start job for default target initrd.target. May 14 18:08:16.200765 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:08:16.200774 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:08:16.200784 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 14 18:08:16.200793 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 18:08:16.200804 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 14 18:08:16.200814 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 14 18:08:16.200824 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 14 18:08:16.200833 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 14 18:08:16.200842 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:08:16.200852 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 18:08:16.200861 systemd[1]: Reached target paths.target - Path Units. May 14 18:08:16.200872 systemd[1]: Reached target slices.target - Slice Units. May 14 18:08:16.200881 systemd[1]: Reached target swap.target - Swaps. May 14 18:08:16.200890 systemd[1]: Reached target timers.target - Timer Units. May 14 18:08:16.200899 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 14 18:08:16.200908 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 18:08:16.200917 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 14 18:08:16.200926 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 14 18:08:16.200935 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 18:08:16.200944 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 18:08:16.200956 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:08:16.200965 systemd[1]: Reached target sockets.target - Socket Units. May 14 18:08:16.200974 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 14 18:08:16.200983 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 18:08:16.200992 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 14 18:08:16.201001 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 14 18:08:16.201011 systemd[1]: Starting systemd-fsck-usr.service... May 14 18:08:16.201020 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 18:08:16.201031 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 18:08:16.201059 systemd-journald[232]: Collecting audit messages is disabled. May 14 18:08:16.201081 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:08:16.201091 systemd-journald[232]: Journal started May 14 18:08:16.201114 systemd-journald[232]: Runtime Journal (/run/log/journal/b7640c06afbe4b019f6d9ebece62a593) is 8M, max 2.2G, 2.1G free. May 14 18:08:16.203898 systemd-modules-load[234]: Inserted module 'overlay' May 14 18:08:16.210125 systemd[1]: Started systemd-journald.service - Journal Service. May 14 18:08:16.210446 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 14 18:08:16.210935 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:08:16.211642 systemd[1]: Finished systemd-fsck-usr.service. May 14 18:08:16.213913 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 18:08:16.214947 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 18:08:16.258593 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:08:16.260563 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 18:08:16.261870 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 14 18:08:16.268988 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 18:08:16.278303 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:08:16.280146 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 18:08:16.318844 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:08:16.324750 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 14 18:08:16.337498 systemd-modules-load[234]: Inserted module 'br_netfilter' May 14 18:08:16.338961 kernel: Bridge firewalling registered May 14 18:08:16.338119 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 18:08:16.339952 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 18:08:16.342030 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 18:08:16.344292 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 14 18:08:16.373175 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 18:08:16.382726 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 18:08:16.393942 dracut-cmdline[269]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=adf4ab3cd3fc72d424aa1ba920dfa0e67212fa35eadab2c698966b09b9e294b0 May 14 18:08:16.442312 systemd-resolved[281]: Positive Trust Anchors: May 14 18:08:16.442330 systemd-resolved[281]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 18:08:16.442379 systemd-resolved[281]: 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 14 18:08:16.444991 systemd-resolved[281]: Defaulting to hostname 'linux'. May 14 18:08:16.445978 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 18:08:16.455256 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 18:08:16.552379 kernel: SCSI subsystem initialized May 14 18:08:16.562374 kernel: Loading iSCSI transport class v2.0-870. May 14 18:08:16.575376 kernel: iscsi: registered transport (tcp) May 14 18:08:16.597747 kernel: iscsi: registered transport (qla4xxx) May 14 18:08:16.597774 kernel: QLogic iSCSI HBA Driver May 14 18:08:16.616053 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 18:08:16.651016 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:08:16.653395 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 18:08:16.706532 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 14 18:08:16.708479 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 14 18:08:16.775382 kernel: raid6: avx2x4 gen() 30650 MB/s May 14 18:08:16.794377 kernel: raid6: avx2x2 gen() 30728 MB/s May 14 18:08:16.815424 kernel: raid6: avx2x1 gen() 27515 MB/s May 14 18:08:16.815450 kernel: raid6: using algorithm avx2x2 gen() 30728 MB/s May 14 18:08:16.838555 kernel: raid6: .... xor() 18186 MB/s, rmw enabled May 14 18:08:16.838585 kernel: raid6: using avx2x2 recovery algorithm May 14 18:08:16.861378 kernel: xor: automatically using best checksumming function avx May 14 18:08:17.030385 kernel: Btrfs loaded, zoned=no, fsverity=no May 14 18:08:17.037313 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 14 18:08:17.047021 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:08:17.090840 systemd-udevd[486]: Using default interface naming scheme 'v255'. May 14 18:08:17.097174 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:08:17.107436 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 14 18:08:17.139545 dracut-pre-trigger[497]: rd.md=0: removing MD RAID activation May 14 18:08:17.167281 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 14 18:08:17.175948 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 18:08:17.267914 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:08:17.270472 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 14 18:08:17.300380 kernel: cryptd: max_cpu_qlen set to 1000 May 14 18:08:17.308376 kernel: AES CTR mode by8 optimization enabled May 14 18:08:17.327261 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 18:08:17.341159 kernel: hv_vmbus: Vmbus version:4.0 May 14 18:08:17.327534 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:08:17.372524 kernel: hv_vmbus: registering driver hyperv_keyboard May 14 18:08:17.372553 kernel: pps_core: LinuxPPS API ver. 1 registered May 14 18:08:17.372566 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 14 18:08:17.372584 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 14 18:08:17.372596 kernel: hid: raw HID events driver (C) Jiri Kosina May 14 18:08:17.336415 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:08:17.349490 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:08:17.372897 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 18:08:17.389375 kernel: PTP clock support registered May 14 18:08:17.389407 kernel: hv_vmbus: registering driver hv_netvsc May 14 18:08:17.396349 kernel: hv_vmbus: registering driver hv_pci May 14 18:08:17.396394 kernel: hv_vmbus: registering driver hid_hyperv May 14 18:08:17.400376 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 May 14 18:08:17.922203 kernel: hv_vmbus: registering driver hv_storvsc May 14 18:08:17.922225 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 14 18:08:17.922238 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 14 18:08:17.922379 kernel: hv_utils: Registering HyperV Utility Driver May 14 18:08:17.922393 kernel: hv_vmbus: registering driver hv_utils May 14 18:08:17.922413 kernel: hv_utils: TimeSync IC version 4.0 May 14 18:08:17.922425 kernel: hv_utils: Heartbeat IC version 3.0 May 14 18:08:17.922436 kernel: hv_utils: Shutdown IC version 3.2 May 14 18:08:17.922447 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 May 14 18:08:17.922573 kernel: scsi host1: storvsc_host_t May 14 18:08:17.922698 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] May 14 18:08:17.922829 kernel: scsi host0: storvsc_host_t May 14 18:08:17.922950 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 14 18:08:18.007190 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] May 14 18:08:18.007349 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] May 14 18:08:18.007482 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint May 14 18:08:18.007639 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 14 18:08:18.011594 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] May 14 18:08:18.011769 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] May 14 18:08:18.011913 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 14 18:08:18.018560 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] May 14 18:08:18.018783 kernel: pci 0001:00:00.0: Enabling HDA controller May 14 18:08:18.018965 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 14 18:08:18.019148 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) May 14 18:08:18.019297 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks May 14 18:08:18.019450 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 May 14 18:08:18.019596 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned May 14 18:08:18.019785 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned May 14 18:08:18.019965 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned May 14 18:08:18.020149 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks May 14 18:08:18.020315 kernel: sd 0:0:0:0: [sda] Write Protect is off May 14 18:08:18.020454 kernel: sd 0:0:0:1: [sdb] Write Protect is off May 14 18:08:18.020599 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 14 18:08:18.020723 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 14 18:08:18.020868 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 May 14 18:08:18.021008 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#453 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:18.021163 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA May 14 18:08:18.021312 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#460 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:18.021429 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#387 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:18.021562 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#394 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:18.021682 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 18:08:18.021696 kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 14 18:08:18.021834 kernel: sdb: sdb1 May 14 18:08:18.021848 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk May 14 18:08:18.021983 kernel: sr 0:0:0:2: [sr0] scsi-1 drive May 14 18:08:18.022137 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 14 18:08:18.022152 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 May 14 18:08:17.843878 systemd-resolved[281]: Clock change detected. Flushing caches. May 14 18:08:17.873246 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:08:18.686628 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 14 18:08:18.699035 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 14 18:08:18.700407 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 14 18:08:18.710528 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 14 18:08:18.759677 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 14 18:08:18.772805 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 14 18:08:19.755149 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#426 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:19.767586 disk-uuid[718]: The operation has completed successfully. May 14 18:08:19.771487 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 18:08:21.762498 systemd[1]: disk-uuid.service: Deactivated successfully. May 14 18:08:21.762612 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 14 18:08:21.772252 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 14 18:08:21.787310 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 14 18:08:21.792334 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 14 18:08:21.799341 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:08:21.816384 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 18:08:21.818644 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 14 18:08:21.846056 sh[886]: Success May 14 18:08:21.855814 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 14 18:08:21.888882 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 14 18:08:21.888958 kernel: device-mapper: uevent: version 1.0.3 May 14 18:08:21.895062 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 14 18:08:21.911232 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" May 14 18:08:22.158729 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 14 18:08:22.162096 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 14 18:08:22.180598 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 14 18:08:22.200000 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 14 18:08:22.200032 kernel: BTRFS: device fsid dedcf745-d4ff-44ac-b61c-5ec1bad114c7 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (911) May 14 18:08:22.201115 kernel: BTRFS info (device dm-0): first mount of filesystem dedcf745-d4ff-44ac-b61c-5ec1bad114c7 May 14 18:08:22.209491 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 14 18:08:22.212590 kernel: BTRFS info (device dm-0): using free-space-tree May 14 18:08:22.688864 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 14 18:08:22.690766 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 14 18:08:22.696744 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 14 18:08:22.697547 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 14 18:08:22.733606 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 14 18:08:22.758212 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (934) May 14 18:08:22.758254 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998 May 14 18:08:22.762409 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 18:08:22.765404 kernel: BTRFS info (device sda6): using free-space-tree May 14 18:08:22.789100 kernel: BTRFS info (device sda6): last unmount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998 May 14 18:08:22.789432 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 14 18:08:22.791761 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 14 18:08:22.854100 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 18:08:22.865623 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 18:08:22.914365 systemd-networkd[1080]: lo: Link UP May 14 18:08:22.914375 systemd-networkd[1080]: lo: Gained carrier May 14 18:08:22.915640 systemd-networkd[1080]: Enumeration completed May 14 18:08:22.915744 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 18:08:22.915987 systemd-networkd[1080]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:08:22.915992 systemd-networkd[1080]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 18:08:22.916736 systemd-networkd[1080]: eth0: Link UP May 14 18:08:22.916920 systemd-networkd[1080]: eth0: Gained carrier May 14 18:08:22.916930 systemd-networkd[1080]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:08:22.921902 systemd[1]: Reached target network.target - Network. May 14 18:08:22.970118 systemd-networkd[1080]: eth0: DHCPv4 address 10.200.8.37/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 14 18:08:24.633153 ignition[1016]: Ignition 2.21.0 May 14 18:08:24.633167 ignition[1016]: Stage: fetch-offline May 14 18:08:24.633292 ignition[1016]: no configs at "/usr/lib/ignition/base.d" May 14 18:08:24.633303 ignition[1016]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:24.633400 ignition[1016]: parsed url from cmdline: "" May 14 18:08:24.633404 ignition[1016]: no config URL provided May 14 18:08:24.633410 ignition[1016]: reading system config file "/usr/lib/ignition/user.ign" May 14 18:08:24.633418 ignition[1016]: no config at "/usr/lib/ignition/user.ign" May 14 18:08:24.633424 ignition[1016]: failed to fetch config: resource requires networking May 14 18:08:24.633592 ignition[1016]: Ignition finished successfully May 14 18:08:24.663712 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 14 18:08:24.666042 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 14 18:08:24.708047 ignition[1092]: Ignition 2.21.0 May 14 18:08:24.708061 ignition[1092]: Stage: fetch May 14 18:08:24.708285 ignition[1092]: no configs at "/usr/lib/ignition/base.d" May 14 18:08:24.708295 ignition[1092]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:24.708368 ignition[1092]: parsed url from cmdline: "" May 14 18:08:24.708372 ignition[1092]: no config URL provided May 14 18:08:24.708377 ignition[1092]: reading system config file "/usr/lib/ignition/user.ign" May 14 18:08:24.708385 ignition[1092]: no config at "/usr/lib/ignition/user.ign" May 14 18:08:24.708419 ignition[1092]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 14 18:08:24.780206 systemd-networkd[1080]: eth0: Gained IPv6LL May 14 18:08:24.797748 ignition[1092]: GET result: OK May 14 18:08:24.797947 ignition[1092]: config has been read from IMDS userdata May 14 18:08:24.797970 ignition[1092]: parsing config with SHA512: c4511d68bb9300a166a52b758400239b0fdd32f6b331e61cb16dd98fe7628f8066e97e9d72d9cb6a1592ef5d46370dd43fec531f5f5d7062a7e6de5814a4dd29 May 14 18:08:24.802530 unknown[1092]: fetched base config from "system" May 14 18:08:24.802694 ignition[1092]: fetch: fetch complete May 14 18:08:24.802537 unknown[1092]: fetched base config from "system" May 14 18:08:24.802699 ignition[1092]: fetch: fetch passed May 14 18:08:24.802542 unknown[1092]: fetched user config from "azure" May 14 18:08:24.802739 ignition[1092]: Ignition finished successfully May 14 18:08:24.806177 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 14 18:08:24.812014 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 14 18:08:24.852245 ignition[1103]: Ignition 2.21.0 May 14 18:08:24.852260 ignition[1103]: Stage: kargs May 14 18:08:24.852502 ignition[1103]: no configs at "/usr/lib/ignition/base.d" May 14 18:08:24.852513 ignition[1103]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:24.854149 ignition[1103]: kargs: kargs passed May 14 18:08:24.861297 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 14 18:08:24.854215 ignition[1103]: Ignition finished successfully May 14 18:08:24.863815 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 14 18:08:24.904925 ignition[1112]: Ignition 2.21.0 May 14 18:08:24.904939 ignition[1112]: Stage: disks May 14 18:08:24.905480 ignition[1112]: no configs at "/usr/lib/ignition/base.d" May 14 18:08:24.905497 ignition[1112]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:24.911400 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 14 18:08:24.906510 ignition[1112]: disks: disks passed May 14 18:08:24.915142 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 14 18:08:24.906570 ignition[1112]: Ignition finished successfully May 14 18:08:24.922906 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 14 18:08:24.931061 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 18:08:24.938304 systemd[1]: Reached target sysinit.target - System Initialization. May 14 18:08:24.946416 systemd[1]: Reached target basic.target - Basic System. May 14 18:08:24.953998 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 14 18:08:25.057740 systemd-fsck[1123]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks May 14 18:08:25.064016 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 14 18:08:25.074979 systemd[1]: Mounting sysroot.mount - /sysroot... May 14 18:08:25.325100 kernel: EXT4-fs (sda9): mounted filesystem d6072e19-4548-4806-a012-87bb17c59f4c r/w with ordered data mode. Quota mode: none. May 14 18:08:25.325678 systemd[1]: Mounted sysroot.mount - /sysroot. May 14 18:08:25.327675 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 14 18:08:25.391531 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 18:08:25.393812 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 14 18:08:25.417713 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 14 18:08:25.431074 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1137) May 14 18:08:25.419253 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 14 18:08:25.455483 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998 May 14 18:08:25.455510 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 18:08:25.455523 kernel: BTRFS info (device sda6): using free-space-tree May 14 18:08:25.419287 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 14 18:08:25.433806 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 14 18:08:25.458039 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 14 18:08:25.476342 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 18:08:26.382460 coreos-metadata[1139]: May 14 18:08:26.382 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 14 18:08:26.388222 coreos-metadata[1139]: May 14 18:08:26.388 INFO Fetch successful May 14 18:08:26.392018 coreos-metadata[1139]: May 14 18:08:26.388 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 14 18:08:26.399945 coreos-metadata[1139]: May 14 18:08:26.399 INFO Fetch successful May 14 18:08:26.414339 coreos-metadata[1139]: May 14 18:08:26.414 INFO wrote hostname ci-4334.0.0-a-f26f88f26d to /sysroot/etc/hostname May 14 18:08:26.420945 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 14 18:08:26.616886 initrd-setup-root[1167]: cut: /sysroot/etc/passwd: No such file or directory May 14 18:08:26.665466 initrd-setup-root[1174]: cut: /sysroot/etc/group: No such file or directory May 14 18:08:26.671220 initrd-setup-root[1181]: cut: /sysroot/etc/shadow: No such file or directory May 14 18:08:26.675866 initrd-setup-root[1188]: cut: /sysroot/etc/gshadow: No such file or directory May 14 18:08:28.049151 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 14 18:08:28.054675 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 14 18:08:28.079699 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 14 18:08:28.085180 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 14 18:08:28.094557 kernel: BTRFS info (device sda6): last unmount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998 May 14 18:08:28.111639 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 14 18:08:28.123740 ignition[1257]: INFO : Ignition 2.21.0 May 14 18:08:28.123740 ignition[1257]: INFO : Stage: mount May 14 18:08:28.129241 ignition[1257]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:08:28.129241 ignition[1257]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:28.129241 ignition[1257]: INFO : mount: mount passed May 14 18:08:28.129241 ignition[1257]: INFO : Ignition finished successfully May 14 18:08:28.130437 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 14 18:08:28.137922 systemd[1]: Starting ignition-files.service - Ignition (files)... May 14 18:08:28.171478 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 18:08:28.190114 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1271) May 14 18:08:28.190150 kernel: BTRFS info (device sda6): first mount of filesystem 9b1e3c61-417b-43c0-b064-c7db19a42998 May 14 18:08:28.198783 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 18:08:28.201883 kernel: BTRFS info (device sda6): using free-space-tree May 14 18:08:28.205649 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 18:08:28.228039 ignition[1288]: INFO : Ignition 2.21.0 May 14 18:08:28.228039 ignition[1288]: INFO : Stage: files May 14 18:08:28.233662 ignition[1288]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:08:28.233662 ignition[1288]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:28.233662 ignition[1288]: DEBUG : files: compiled without relabeling support, skipping May 14 18:08:28.246492 ignition[1288]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 14 18:08:28.246492 ignition[1288]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 14 18:08:28.294277 ignition[1288]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 14 18:08:28.299626 ignition[1288]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 14 18:08:28.304616 ignition[1288]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 14 18:08:28.300068 unknown[1288]: wrote ssh authorized keys file for user: core May 14 18:08:28.334471 ignition[1288]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 14 18:08:28.341488 ignition[1288]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 14 18:08:28.341488 ignition[1288]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 14 18:08:28.341488 ignition[1288]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 14 18:08:28.341488 ignition[1288]: INFO : files: files passed May 14 18:08:28.341488 ignition[1288]: INFO : Ignition finished successfully May 14 18:08:28.363548 systemd[1]: Finished ignition-files.service - Ignition (files). May 14 18:08:28.369918 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 14 18:08:28.406355 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 14 18:08:28.409574 systemd[1]: ignition-quench.service: Deactivated successfully. May 14 18:08:28.409668 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 14 18:08:28.448404 initrd-setup-root-after-ignition[1321]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 18:08:28.448404 initrd-setup-root-after-ignition[1321]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 14 18:08:28.460736 initrd-setup-root-after-ignition[1325]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 18:08:28.467078 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 18:08:28.468389 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 14 18:08:28.483617 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 14 18:08:28.518423 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 14 18:08:28.518523 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 14 18:08:28.527164 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 14 18:08:28.535759 systemd[1]: Reached target initrd.target - Initrd Default Target. May 14 18:08:28.543395 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 14 18:08:28.544184 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 14 18:08:28.587223 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 18:08:28.589822 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 14 18:08:28.625796 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 14 18:08:28.634747 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:08:28.636305 systemd[1]: Stopped target timers.target - Timer Units. May 14 18:08:28.645224 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 14 18:08:28.645359 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 18:08:28.658916 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 14 18:08:28.667709 systemd[1]: Stopped target basic.target - Basic System. May 14 18:08:28.675914 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 14 18:08:28.683914 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 14 18:08:28.692263 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 14 18:08:28.694374 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 14 18:08:28.713268 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 14 18:08:28.721479 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 14 18:08:28.726829 systemd[1]: Stopped target sysinit.target - System Initialization. May 14 18:08:28.735574 systemd[1]: Stopped target local-fs.target - Local File Systems. May 14 18:08:28.743201 systemd[1]: Stopped target swap.target - Swaps. May 14 18:08:28.750000 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 14 18:08:28.750168 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 14 18:08:28.765253 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 14 18:08:28.766174 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:08:28.778155 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 14 18:08:28.782448 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:08:28.787416 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 14 18:08:28.787544 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 14 18:08:28.800477 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 14 18:08:28.800607 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 18:08:28.808905 systemd[1]: ignition-files.service: Deactivated successfully. May 14 18:08:28.809027 systemd[1]: Stopped ignition-files.service - Ignition (files). May 14 18:08:28.816549 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 14 18:08:28.816665 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 14 18:08:28.826542 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 14 18:08:28.833740 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 14 18:08:28.833871 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:08:28.853648 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 14 18:08:28.855927 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 14 18:08:28.887782 ignition[1345]: INFO : Ignition 2.21.0 May 14 18:08:28.887782 ignition[1345]: INFO : Stage: umount May 14 18:08:28.887782 ignition[1345]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:08:28.887782 ignition[1345]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 14 18:08:28.887782 ignition[1345]: INFO : umount: umount passed May 14 18:08:28.887782 ignition[1345]: INFO : Ignition finished successfully May 14 18:08:28.856059 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:08:28.863078 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 14 18:08:28.863439 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 14 18:08:28.882535 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 14 18:08:28.885622 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 14 18:08:28.885715 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 14 18:08:28.889420 systemd[1]: ignition-mount.service: Deactivated successfully. May 14 18:08:28.889509 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 14 18:08:28.897674 systemd[1]: ignition-disks.service: Deactivated successfully. May 14 18:08:28.897727 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 14 18:08:28.904062 systemd[1]: ignition-kargs.service: Deactivated successfully. May 14 18:08:28.904124 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 14 18:08:28.910960 systemd[1]: ignition-fetch.service: Deactivated successfully. May 14 18:08:28.911004 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 14 18:08:28.920102 systemd[1]: Stopped target network.target - Network. May 14 18:08:28.926767 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 14 18:08:28.926823 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 14 18:08:28.935047 systemd[1]: Stopped target paths.target - Path Units. May 14 18:08:28.946398 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 14 18:08:28.956152 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:08:28.957842 systemd[1]: Stopped target slices.target - Slice Units. May 14 18:08:28.969822 systemd[1]: Stopped target sockets.target - Socket Units. May 14 18:08:28.976887 systemd[1]: iscsid.socket: Deactivated successfully. May 14 18:08:28.976933 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 14 18:08:28.984508 systemd[1]: iscsiuio.socket: Deactivated successfully. May 14 18:08:28.984587 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 18:08:29.055142 systemd[1]: ignition-setup.service: Deactivated successfully. May 14 18:08:29.055222 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 14 18:08:29.057121 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 14 18:08:29.057167 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 14 18:08:29.065544 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 14 18:08:29.072417 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 14 18:08:29.086612 systemd[1]: systemd-resolved.service: Deactivated successfully. May 14 18:08:29.086723 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 14 18:08:29.100885 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 14 18:08:29.101168 systemd[1]: systemd-networkd.service: Deactivated successfully. May 14 18:08:29.101269 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 14 18:08:29.112141 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 14 18:08:29.112378 systemd[1]: sysroot-boot.service: Deactivated successfully. May 14 18:08:29.112474 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 14 18:08:29.121244 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 14 18:08:29.124109 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 14 18:08:29.124153 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 14 18:08:29.135869 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 14 18:08:29.135923 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 14 18:08:29.145473 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 14 18:08:29.151540 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 14 18:08:29.151595 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 18:08:29.159661 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 14 18:08:29.159705 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 14 18:08:29.168383 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 14 18:08:29.168426 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 14 18:08:29.175345 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 14 18:08:29.175394 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:08:29.185223 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:08:29.187950 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 14 18:08:29.188016 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 14 18:08:29.210773 systemd[1]: network-cleanup.service: Deactivated successfully. May 14 18:08:29.210917 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 14 18:08:29.223705 systemd[1]: systemd-udevd.service: Deactivated successfully. May 14 18:08:29.223848 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:08:29.227395 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 14 18:08:29.227494 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 14 18:08:29.239028 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 14 18:08:29.239093 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:08:29.247915 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 14 18:08:29.247967 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 14 18:08:29.264299 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 14 18:08:29.264351 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 14 18:08:29.280400 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 18:08:29.280454 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 18:08:29.297895 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 14 18:08:29.305864 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 14 18:08:29.305921 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:08:29.321942 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 14 18:08:29.321996 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:08:29.331043 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 18:08:29.331109 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:08:29.340932 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 14 18:08:29.340988 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 14 18:08:29.341030 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 18:08:29.346210 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 14 18:08:29.346316 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 14 18:08:29.354508 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 14 18:08:29.356850 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 14 18:08:29.407613 systemd[1]: Switching root. May 14 18:08:29.510204 systemd-journald[232]: Journal stopped May 14 18:08:34.672680 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). May 14 18:08:34.672711 kernel: SELinux: policy capability network_peer_controls=1 May 14 18:08:34.672724 kernel: SELinux: policy capability open_perms=1 May 14 18:08:34.672733 kernel: SELinux: policy capability extended_socket_class=1 May 14 18:08:34.672742 kernel: SELinux: policy capability always_check_network=0 May 14 18:08:34.672751 kernel: SELinux: policy capability cgroup_seclabel=1 May 14 18:08:34.672762 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 14 18:08:34.672774 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 14 18:08:34.672784 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 14 18:08:34.672794 kernel: SELinux: policy capability userspace_initial_context=0 May 14 18:08:34.672803 kernel: audit: type=1403 audit(1747246110.470:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 14 18:08:34.672814 systemd[1]: Successfully loaded SELinux policy in 225.038ms. May 14 18:08:34.672830 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.714ms. May 14 18:08:34.672842 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 14 18:08:34.672857 systemd[1]: Detected virtualization microsoft. May 14 18:08:34.672868 systemd[1]: Detected architecture x86-64. May 14 18:08:34.672879 systemd[1]: Detected first boot. May 14 18:08:34.672890 systemd[1]: Hostname set to . May 14 18:08:34.672901 systemd[1]: Initializing machine ID from random generator. May 14 18:08:34.672915 zram_generator::config[1391]: No configuration found. May 14 18:08:34.672926 kernel: Guest personality initialized and is inactive May 14 18:08:34.672936 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 14 18:08:34.672946 kernel: Initialized host personality May 14 18:08:34.672956 kernel: NET: Registered PF_VSOCK protocol family May 14 18:08:34.672966 systemd[1]: Populated /etc with preset unit settings. May 14 18:08:34.672978 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 14 18:08:34.672992 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 14 18:08:34.673002 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 14 18:08:34.673013 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 14 18:08:34.673023 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 14 18:08:34.673035 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 14 18:08:34.673046 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 14 18:08:34.673057 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 14 18:08:34.673074 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 14 18:08:34.673096 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 14 18:08:34.673108 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 14 18:08:34.673119 systemd[1]: Created slice user.slice - User and Session Slice. May 14 18:08:34.673130 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:08:34.673141 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:08:34.673152 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 14 18:08:34.673168 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 14 18:08:34.673179 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 14 18:08:34.673194 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 18:08:34.673206 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 14 18:08:34.673217 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:08:34.673228 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 18:08:34.673239 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 14 18:08:34.673251 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 14 18:08:34.673262 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 14 18:08:34.673276 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 14 18:08:34.673288 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:08:34.673299 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 18:08:34.673310 systemd[1]: Reached target slices.target - Slice Units. May 14 18:08:34.673321 systemd[1]: Reached target swap.target - Swaps. May 14 18:08:34.673337 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 14 18:08:34.673348 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 14 18:08:34.673363 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 14 18:08:34.673375 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 18:08:34.673386 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 18:08:34.673398 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:08:34.673409 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 14 18:08:34.673421 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 14 18:08:34.673435 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 14 18:08:34.673447 systemd[1]: Mounting media.mount - External Media Directory... May 14 18:08:34.673459 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 18:08:34.673470 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 14 18:08:34.673481 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 14 18:08:34.673493 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 14 18:08:34.673505 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 14 18:08:34.673516 systemd[1]: Reached target machines.target - Containers. May 14 18:08:34.673531 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 14 18:08:34.673543 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:08:34.673554 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 18:08:34.673566 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 14 18:08:34.673577 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:08:34.673591 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 18:08:34.673603 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:08:34.673614 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 14 18:08:34.673628 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:08:34.673641 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 14 18:08:34.673652 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 14 18:08:34.673663 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 14 18:08:34.673675 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 14 18:08:34.673686 systemd[1]: Stopped systemd-fsck-usr.service. May 14 18:08:34.673698 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 14 18:08:34.673709 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 18:08:34.673721 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 18:08:34.673735 kernel: fuse: init (API version 7.41) May 14 18:08:34.673746 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 18:08:34.673758 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 14 18:08:34.673769 kernel: loop: module loaded May 14 18:08:34.673779 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 14 18:08:34.673791 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 18:08:34.673802 systemd[1]: verity-setup.service: Deactivated successfully. May 14 18:08:34.673813 systemd[1]: Stopped verity-setup.service. May 14 18:08:34.673828 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 18:08:34.673843 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 14 18:08:34.673876 systemd-journald[1499]: Collecting audit messages is disabled. May 14 18:08:34.673905 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 14 18:08:34.673921 systemd-journald[1499]: Journal started May 14 18:08:34.673945 systemd-journald[1499]: Runtime Journal (/run/log/journal/d437226e09094bcc82830d1f3e7fb77e) is 8M, max 2.2G, 2.1G free. May 14 18:08:33.922369 systemd[1]: Queued start job for default target multi-user.target. May 14 18:08:33.948430 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 14 18:08:33.948908 systemd[1]: systemd-journald.service: Deactivated successfully. May 14 18:08:34.687484 systemd[1]: Started systemd-journald.service - Journal Service. May 14 18:08:34.690808 systemd[1]: Mounted media.mount - External Media Directory. May 14 18:08:34.694905 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 14 18:08:34.701326 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 14 18:08:34.705816 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 14 18:08:34.709910 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 14 18:08:34.714860 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:08:34.720066 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 14 18:08:34.720274 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 14 18:08:34.725198 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:08:34.725394 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:08:34.730225 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:08:34.730400 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:08:34.735602 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 14 18:08:34.735789 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 14 18:08:34.741099 kernel: ACPI: bus type drm_connector registered May 14 18:08:34.742523 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:08:34.742704 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:08:34.749429 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 18:08:34.749607 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 18:08:34.754152 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 18:08:34.759021 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:08:34.764504 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 14 18:08:34.769919 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 14 18:08:34.776516 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:08:34.789824 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 18:08:34.795416 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 14 18:08:34.815214 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 14 18:08:34.819883 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 14 18:08:34.819925 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 18:08:34.825212 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 14 18:08:34.831183 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 14 18:08:34.835276 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:08:34.836317 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 14 18:08:34.841503 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 14 18:08:34.846111 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 18:08:34.847026 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 14 18:08:34.851865 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 18:08:34.852826 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 18:08:34.857683 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 14 18:08:34.862909 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 14 18:08:34.868654 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 14 18:08:34.870092 systemd-journald[1499]: Time spent on flushing to /var/log/journal/d437226e09094bcc82830d1f3e7fb77e is 13.925ms for 944 entries. May 14 18:08:34.870092 systemd-journald[1499]: System Journal (/var/log/journal/d437226e09094bcc82830d1f3e7fb77e) is 8M, max 2.6G, 2.6G free. May 14 18:08:34.926069 systemd-journald[1499]: Received client request to flush runtime journal. May 14 18:08:34.877225 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 14 18:08:34.910551 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 14 18:08:34.915458 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 14 18:08:34.921487 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 14 18:08:34.935261 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 14 18:08:34.978746 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 18:08:34.985109 kernel: loop0: detected capacity change from 0 to 113872 May 14 18:08:35.014121 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 14 18:08:35.015323 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 14 18:08:35.115734 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 14 18:08:35.121384 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 18:08:35.194375 systemd-tmpfiles[1548]: ACLs are not supported, ignoring. May 14 18:08:35.194397 systemd-tmpfiles[1548]: ACLs are not supported, ignoring. May 14 18:08:35.199506 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:08:35.607110 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 14 18:08:35.711112 kernel: loop1: detected capacity change from 0 to 146240 May 14 18:08:36.394650 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 14 18:08:36.401413 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:08:36.405109 kernel: loop2: detected capacity change from 0 to 28536 May 14 18:08:36.457419 systemd-udevd[1555]: Using default interface naming scheme 'v255'. May 14 18:08:36.805854 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:08:36.815520 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 18:08:36.834012 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 14 18:08:36.874124 kernel: loop3: detected capacity change from 0 to 113872 May 14 18:08:36.888139 kernel: loop4: detected capacity change from 0 to 146240 May 14 18:08:36.894191 kernel: hv_vmbus: registering driver hyperv_fb May 14 18:08:36.894249 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 14 18:08:36.899192 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 14 18:08:36.911287 kernel: Console: switching to colour dummy device 80x25 May 14 18:08:36.911340 kernel: loop5: detected capacity change from 0 to 28536 May 14 18:08:36.918102 kernel: Console: switching to colour frame buffer device 128x48 May 14 18:08:36.924106 (sd-merge)[1605]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 14 18:08:36.925352 (sd-merge)[1605]: Merged extensions into '/usr'. May 14 18:08:36.928201 kernel: mousedev: PS/2 mouse device common for all mice May 14 18:08:36.931557 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 14 18:08:36.938638 systemd[1]: Starting ensure-sysext.service... May 14 18:08:36.950858 kernel: hv_vmbus: registering driver hv_balloon May 14 18:08:36.953597 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 18:08:36.961099 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 14 18:08:36.994697 systemd[1]: Reload requested from client PID 1624 ('systemctl') (unit ensure-sysext.service)... May 14 18:08:36.994713 systemd[1]: Reloading... May 14 18:08:37.039260 systemd-tmpfiles[1627]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 14 18:08:37.039303 systemd-tmpfiles[1627]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 14 18:08:37.039579 systemd-tmpfiles[1627]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 14 18:08:37.039858 systemd-tmpfiles[1627]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 14 18:08:37.041680 systemd-tmpfiles[1627]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 14 18:08:37.041974 systemd-tmpfiles[1627]: ACLs are not supported, ignoring. May 14 18:08:37.042035 systemd-tmpfiles[1627]: ACLs are not supported, ignoring. May 14 18:08:37.050131 zram_generator::config[1657]: No configuration found. May 14 18:08:37.050868 systemd-tmpfiles[1627]: Detected autofs mount point /boot during canonicalization of boot. May 14 18:08:37.050884 systemd-tmpfiles[1627]: Skipping /boot May 14 18:08:37.053293 kernel: EDAC MC: Ver: 3.0.0 May 14 18:08:37.063294 systemd-tmpfiles[1627]: Detected autofs mount point /boot during canonicalization of boot. May 14 18:08:37.063310 systemd-tmpfiles[1627]: Skipping /boot May 14 18:08:37.165641 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 14 18:08:37.305651 systemd[1]: Reloading finished in 310 ms. May 14 18:08:37.360382 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:08:37.424142 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 14 18:08:37.431749 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 18:08:37.433352 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 18:08:37.453534 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 14 18:08:37.458262 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:08:37.459445 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:08:37.464598 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 18:08:37.469706 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:08:37.475284 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:08:37.479287 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:08:37.480388 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 14 18:08:37.484798 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 14 18:08:37.485918 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 14 18:08:37.492232 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 18:08:37.497005 systemd[1]: Reached target time-set.target - System Time Set. May 14 18:08:37.502143 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 14 18:08:37.508257 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 14 18:08:37.513234 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:08:37.517449 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 18:08:37.520240 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:08:37.520421 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:08:37.525405 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 18:08:37.525573 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 18:08:37.530321 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:08:37.530490 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:08:37.537760 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:08:37.537951 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:08:37.545063 systemd[1]: Finished ensure-sysext.service. May 14 18:08:37.553614 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 18:08:37.553766 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 18:08:37.555241 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 14 18:08:37.593905 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 14 18:08:37.656339 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 14 18:08:37.671649 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 14 18:08:37.706930 systemd-resolved[1791]: Positive Trust Anchors: May 14 18:08:37.706957 systemd-resolved[1791]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 18:08:37.706990 systemd-resolved[1791]: 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 14 18:08:37.712901 systemd-resolved[1791]: Using system hostname 'ci-4334.0.0-a-f26f88f26d'. May 14 18:08:37.714607 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 18:08:37.716059 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 18:08:37.724539 augenrules[1832]: No rules May 14 18:08:37.726030 systemd[1]: audit-rules.service: Deactivated successfully. May 14 18:08:37.726394 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 18:08:37.824862 systemd-networkd[1582]: lo: Link UP May 14 18:08:37.824872 systemd-networkd[1582]: lo: Gained carrier May 14 18:08:37.826283 systemd-networkd[1582]: Enumeration completed May 14 18:08:37.826409 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 18:08:37.826673 systemd-networkd[1582]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:08:37.826683 systemd-networkd[1582]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 18:08:37.827448 systemd-networkd[1582]: eth0: Link UP May 14 18:08:37.827457 systemd-networkd[1582]: eth0: Gained carrier May 14 18:08:37.827472 systemd-networkd[1582]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:08:37.828120 systemd[1]: Reached target network.target - Network. May 14 18:08:37.830149 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 14 18:08:37.831341 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 14 18:08:37.888128 systemd-networkd[1582]: eth0: DHCPv4 address 10.200.8.37/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 14 18:08:37.890562 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 14 18:08:38.093657 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:08:38.112801 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 14 18:08:38.117996 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 14 18:08:39.820241 systemd-networkd[1582]: eth0: Gained IPv6LL May 14 18:08:39.822751 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 14 18:08:39.834225 systemd[1]: Reached target network-online.target - Network is Online. May 14 18:08:42.234414 ldconfig[1529]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 18:08:42.247793 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 14 18:08:42.254323 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 14 18:08:42.289553 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 14 18:08:42.294041 systemd[1]: Reached target sysinit.target - System Initialization. May 14 18:08:42.298441 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 14 18:08:42.303253 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 14 18:08:42.308299 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 14 18:08:42.313149 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 14 18:08:42.317418 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 14 18:08:42.322467 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 14 18:08:42.327503 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 14 18:08:42.327545 systemd[1]: Reached target paths.target - Path Units. May 14 18:08:42.331078 systemd[1]: Reached target timers.target - Timer Units. May 14 18:08:42.335636 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 14 18:08:42.341161 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 18:08:42.347763 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 14 18:08:42.352906 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 14 18:08:42.357862 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 14 18:08:42.366780 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 14 18:08:42.371624 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 14 18:08:42.377156 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 18:08:42.382490 systemd[1]: Reached target sockets.target - Socket Units. May 14 18:08:42.386313 systemd[1]: Reached target basic.target - Basic System. May 14 18:08:42.389950 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 14 18:08:42.389984 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 14 18:08:42.392403 systemd[1]: Starting chronyd.service - NTP client/server... May 14 18:08:42.396768 systemd[1]: Starting containerd.service - containerd container runtime... May 14 18:08:42.402121 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 14 18:08:42.407360 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 14 18:08:42.412286 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 14 18:08:42.418849 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 14 18:08:42.427287 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 14 18:08:42.431361 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 14 18:08:42.431479 jq[1860]: false May 14 18:08:42.432467 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 14 18:08:42.437586 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 14 18:08:42.444210 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 14 18:08:42.448569 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 14 18:08:42.454250 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 14 18:08:42.455196 (chronyd)[1852]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 14 18:08:42.461849 systemd[1]: Starting systemd-logind.service - User Login Management... May 14 18:08:42.466703 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 14 18:08:42.467144 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 14 18:08:42.467938 systemd[1]: Starting update-engine.service - Update Engine... May 14 18:08:42.472432 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 14 18:08:42.479275 jq[1875]: true May 14 18:08:42.480385 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 14 18:08:42.482964 extend-filesystems[1861]: Found loop3 May 14 18:08:42.482964 extend-filesystems[1861]: Found loop4 May 14 18:08:42.482964 extend-filesystems[1861]: Found loop5 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda May 14 18:08:42.482964 extend-filesystems[1861]: Found sda1 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda2 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda3 May 14 18:08:42.482964 extend-filesystems[1861]: Found usr May 14 18:08:42.482964 extend-filesystems[1861]: Found sda4 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda6 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda7 May 14 18:08:42.482964 extend-filesystems[1861]: Found sda9 May 14 18:08:42.482964 extend-filesystems[1861]: Checking size of /dev/sda9 May 14 18:08:42.515748 oslogin_cache_refresh[1862]: Refreshing passwd entry cache May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Refreshing passwd entry cache May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Failure getting users, quitting May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Refreshing group entry cache May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Failure getting groups, quitting May 14 18:08:42.532412 google_oslogin_nss_cache[1862]: oslogin_cache_refresh[1862]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 14 18:08:42.485611 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 14 18:08:42.521027 chronyd[1899]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 14 18:08:42.485810 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 14 18:08:42.524145 oslogin_cache_refresh[1862]: Failure getting users, quitting May 14 18:08:42.486178 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 14 18:08:42.524161 oslogin_cache_refresh[1862]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 14 18:08:42.533488 jq[1881]: true May 14 18:08:42.486364 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 14 18:08:42.524247 oslogin_cache_refresh[1862]: Refreshing group entry cache May 14 18:08:42.500458 (ntainerd)[1882]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 14 18:08:42.529703 oslogin_cache_refresh[1862]: Failure getting groups, quitting May 14 18:08:42.515330 systemd[1]: motdgen.service: Deactivated successfully. May 14 18:08:42.529714 oslogin_cache_refresh[1862]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 14 18:08:42.515551 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 14 18:08:42.531261 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 14 18:08:42.531487 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 14 18:08:42.582291 chronyd[1899]: Timezone right/UTC failed leap second check, ignoring May 14 18:08:42.582474 chronyd[1899]: Loaded seccomp filter (level 2) May 14 18:08:42.583347 systemd[1]: Started chronyd.service - NTP client/server. May 14 18:08:42.593989 extend-filesystems[1861]: Old size kept for /dev/sda9 May 14 18:08:42.595583 extend-filesystems[1861]: Found sdb May 14 18:08:42.595583 extend-filesystems[1861]: Found sdb1 May 14 18:08:42.595583 extend-filesystems[1861]: Found sr0 May 14 18:08:42.595197 systemd[1]: extend-filesystems.service: Deactivated successfully. May 14 18:08:42.612421 update_engine[1872]: I20250514 18:08:42.606432 1872 main.cc:92] Flatcar Update Engine starting May 14 18:08:42.595455 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 14 18:08:42.662568 dbus-daemon[1855]: [system] SELinux support is enabled May 14 18:08:42.665531 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 14 18:08:42.666377 update_engine[1872]: I20250514 18:08:42.666317 1872 update_check_scheduler.cc:74] Next update check in 3m59s May 14 18:08:42.684791 systemd[1]: Started update-engine.service - Update Engine. May 14 18:08:42.688643 bash[1913]: Updated "/home/core/.ssh/authorized_keys" May 14 18:08:42.689000 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 14 18:08:42.689034 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 14 18:08:42.694045 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 14 18:08:42.694071 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 14 18:08:42.699807 systemd-logind[1871]: New seat seat0. May 14 18:08:42.700291 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 14 18:08:42.707195 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 14 18:08:42.707347 systemd-logind[1871]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 14 18:08:42.712312 systemd[1]: Started systemd-logind.service - User Login Management. May 14 18:08:42.717689 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 14 18:08:42.723794 setup-nvidia[1865]: Downloading Flatcar Container Linux Developer Container for version: 4334.0.0+afterburn-5.8.0 May 14 18:08:42.776294 coreos-metadata[1854]: May 14 18:08:42.776 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 14 18:08:42.778777 coreos-metadata[1854]: May 14 18:08:42.778 INFO Fetch successful May 14 18:08:42.778935 coreos-metadata[1854]: May 14 18:08:42.778 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 14 18:08:42.782884 coreos-metadata[1854]: May 14 18:08:42.782 INFO Fetch successful May 14 18:08:42.783132 coreos-metadata[1854]: May 14 18:08:42.783 INFO Fetching http://168.63.129.16/machine/a31bcc3f-8699-426d-8573-95b31aebd735/2e07e0c3%2D89c7%2D4997%2Da4ca%2D95ef8e70770e.%5Fci%2D4334.0.0%2Da%2Df26f88f26d?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 14 18:08:42.817935 coreos-metadata[1854]: May 14 18:08:42.817 INFO Fetch successful May 14 18:08:42.818071 coreos-metadata[1854]: May 14 18:08:42.818 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 14 18:08:42.825767 coreos-metadata[1854]: May 14 18:08:42.825 INFO Fetch successful May 14 18:08:42.857109 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 14 18:08:42.862078 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 14 18:08:42.884633 sshd_keygen[1876]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 14 18:08:42.905817 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 14 18:08:42.911786 systemd[1]: Starting issuegen.service - Generate /run/issue... May 14 18:08:42.916512 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 14 18:08:42.947969 systemd[1]: issuegen.service: Deactivated successfully. May 14 18:08:42.948220 systemd[1]: Finished issuegen.service - Generate /run/issue. May 14 18:08:42.954878 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 14 18:08:42.965000 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 14 18:08:42.971203 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 14 18:08:42.977311 systemd[1]: Started getty@tty1.service - Getty on tty1. May 14 18:08:42.982102 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 14 18:08:42.986537 systemd[1]: Reached target getty.target - Login Prompts. May 14 18:08:43.012079 locksmithd[1980]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 14 18:08:44.368749 containerd[1882]: time="2025-05-14T18:08:44Z" 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 14 18:08:44.369383 containerd[1882]: time="2025-05-14T18:08:44.369353900Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 14 18:08:44.378299 containerd[1882]: time="2025-05-14T18:08:44.378262800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.6µs" May 14 18:08:44.378299 containerd[1882]: time="2025-05-14T18:08:44.378289600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 14 18:08:44.378380 containerd[1882]: time="2025-05-14T18:08:44.378306900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 14 18:08:44.378460 containerd[1882]: time="2025-05-14T18:08:44.378436100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 14 18:08:44.378490 containerd[1882]: time="2025-05-14T18:08:44.378457400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 14 18:08:44.378490 containerd[1882]: time="2025-05-14T18:08:44.378482000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 14 18:08:44.378580 containerd[1882]: time="2025-05-14T18:08:44.378554000Z" 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 14 18:08:44.378580 containerd[1882]: time="2025-05-14T18:08:44.378576300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 14 18:08:44.378771 containerd[1882]: time="2025-05-14T18:08:44.378748500Z" 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 14 18:08:44.378771 containerd[1882]: time="2025-05-14T18:08:44.378767500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 18:08:44.378820 containerd[1882]: time="2025-05-14T18:08:44.378778000Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 18:08:44.378820 containerd[1882]: time="2025-05-14T18:08:44.378785500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 14 18:08:44.378872 containerd[1882]: time="2025-05-14T18:08:44.378856900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 14 18:08:44.379097 containerd[1882]: time="2025-05-14T18:08:44.379069600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 14 18:08:44.379144 containerd[1882]: time="2025-05-14T18:08:44.379124200Z" 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 14 18:08:44.379144 containerd[1882]: time="2025-05-14T18:08:44.379138800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 14 18:08:44.379204 containerd[1882]: time="2025-05-14T18:08:44.379163600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 14 18:08:44.379390 containerd[1882]: time="2025-05-14T18:08:44.379357400Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 14 18:08:44.379475 containerd[1882]: time="2025-05-14T18:08:44.379452600Z" level=info msg="metadata content store policy set" policy=shared May 14 18:08:44.426050 containerd[1882]: time="2025-05-14T18:08:44.426007100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 14 18:08:44.426116 containerd[1882]: time="2025-05-14T18:08:44.426068200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 14 18:08:44.426116 containerd[1882]: time="2025-05-14T18:08:44.426102300Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426119200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426131900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426142300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426154100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426165900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 14 18:08:44.426185 containerd[1882]: time="2025-05-14T18:08:44.426177400Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 14 18:08:44.426310 containerd[1882]: time="2025-05-14T18:08:44.426187600Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 14 18:08:44.426310 containerd[1882]: time="2025-05-14T18:08:44.426198600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 14 18:08:44.426310 containerd[1882]: time="2025-05-14T18:08:44.426211200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 14 18:08:44.426380 containerd[1882]: time="2025-05-14T18:08:44.426322700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 14 18:08:44.426380 containerd[1882]: time="2025-05-14T18:08:44.426343300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 14 18:08:44.426380 containerd[1882]: time="2025-05-14T18:08:44.426355800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 14 18:08:44.426380 containerd[1882]: time="2025-05-14T18:08:44.426365000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 14 18:08:44.426380 containerd[1882]: time="2025-05-14T18:08:44.426374000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426383700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426394000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426403700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426414200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426424200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 14 18:08:44.426485 containerd[1882]: time="2025-05-14T18:08:44.426449000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 14 18:08:44.426676 containerd[1882]: time="2025-05-14T18:08:44.426506900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 14 18:08:44.426676 containerd[1882]: time="2025-05-14T18:08:44.426521300Z" level=info msg="Start snapshots syncer" May 14 18:08:44.426676 containerd[1882]: time="2025-05-14T18:08:44.426547800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 14 18:08:44.426833 containerd[1882]: time="2025-05-14T18:08:44.426780700Z" 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 14 18:08:44.426942 containerd[1882]: time="2025-05-14T18:08:44.426848300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 14 18:08:44.426942 containerd[1882]: time="2025-05-14T18:08:44.426934300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 14 18:08:44.427057 containerd[1882]: time="2025-05-14T18:08:44.427033400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 14 18:08:44.427105 containerd[1882]: time="2025-05-14T18:08:44.427060800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 14 18:08:44.427105 containerd[1882]: time="2025-05-14T18:08:44.427072600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 14 18:08:44.427105 containerd[1882]: time="2025-05-14T18:08:44.427098500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 14 18:08:44.427180 containerd[1882]: time="2025-05-14T18:08:44.427112200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 14 18:08:44.427180 containerd[1882]: time="2025-05-14T18:08:44.427122600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 14 18:08:44.427180 containerd[1882]: time="2025-05-14T18:08:44.427131800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 14 18:08:44.427180 containerd[1882]: time="2025-05-14T18:08:44.427171600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427185200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427194900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427225100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427239500Z" 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 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427248300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427256300Z" 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 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427263600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 14 18:08:44.427272 containerd[1882]: time="2025-05-14T18:08:44.427271600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427281400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427297700Z" level=info msg="runtime interface created" May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427303100Z" level=info msg="created NRI interface" May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427319800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427334400Z" level=info msg="Connect containerd service" May 14 18:08:44.427441 containerd[1882]: time="2025-05-14T18:08:44.427356200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 14 18:08:44.428032 containerd[1882]: time="2025-05-14T18:08:44.428006600Z" 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 14 18:08:46.388348 containerd[1882]: time="2025-05-14T18:08:46.388299100Z" level=info msg="Start subscribing containerd event" May 14 18:08:46.388348 containerd[1882]: time="2025-05-14T18:08:46.388358000Z" level=info msg="Start recovering state" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388463000Z" level=info msg="Start event monitor" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388485900Z" level=info msg="Start cni network conf syncer for default" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388495200Z" level=info msg="Start streaming server" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388505700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388515100Z" level=info msg="runtime interface starting up..." May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388521500Z" level=info msg="starting plugins..." May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388536300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388504200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 14 18:08:46.388742 containerd[1882]: time="2025-05-14T18:08:46.388725200Z" level=info msg=serving... address=/run/containerd/containerd.sock May 14 18:08:46.388937 containerd[1882]: time="2025-05-14T18:08:46.388804000Z" level=info msg="containerd successfully booted in 2.020506s" May 14 18:08:46.388908 systemd[1]: Started containerd.service - containerd container runtime. May 14 18:08:46.792839 waagent[2012]: 2025-05-14T18:08:46.792699Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 14 18:08:46.797063 waagent[2012]: 2025-05-14T18:08:46.797010Z INFO Daemon Daemon OS: flatcar 4334.0.0 May 14 18:08:46.800423 waagent[2012]: 2025-05-14T18:08:46.800375Z INFO Daemon Daemon Python: 3.11.12 May 14 18:08:46.803708 waagent[2012]: 2025-05-14T18:08:46.803661Z INFO Daemon Daemon Run daemon May 14 18:08:46.806670 waagent[2012]: 2025-05-14T18:08:46.806626Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4334.0.0' May 14 18:08:46.813088 waagent[2012]: 2025-05-14T18:08:46.813035Z INFO Daemon Daemon Using waagent for provisioning May 14 18:08:46.816930 waagent[2012]: 2025-05-14T18:08:46.816881Z INFO Daemon Daemon Activate resource disk May 14 18:08:46.820342 waagent[2012]: 2025-05-14T18:08:46.820295Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 14 18:08:46.827669 waagent[2012]: 2025-05-14T18:08:46.827484Z INFO Daemon Daemon Found device: sdb May 14 18:08:46.836786 waagent[2012]: 2025-05-14T18:08:46.836737Z INFO Daemon Daemon Examining partition table May 14 18:08:47.035564 waagent[2012]: 2025-05-14T18:08:47.035490Z INFO Daemon Daemon GPT not detected, determining filesystem May 14 18:08:47.081196 waagent[2012]: 2025-05-14T18:08:47.081067Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded May 14 18:08:47.085579 waagent[2012]: 2025-05-14T18:08:47.085527Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 May 14 18:08:50.454834 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#358 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:50.466114 kernel: sdb: sdb1 May 14 18:08:50.476080 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#373 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:50.492105 kernel: sdb: sdb1 May 14 18:08:50.973536 login[2015]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 14 18:08:50.980335 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 14 18:08:50.973986 login[2014]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 14 18:08:50.981459 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 14 18:08:50.987541 systemd-logind[1871]: New session 2 of user core. May 14 18:08:51.007493 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 14 18:08:51.010153 systemd[1]: Starting user@500.service - User Manager for UID 500... May 14 18:08:51.030490 (systemd)[2111]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 14 18:08:51.033047 systemd-logind[1871]: New session c1 of user core. May 14 18:08:51.060758 waagent[2012]: 2025-05-14T18:08:51.060679Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded May 14 18:08:51.077104 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#418 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 14 18:08:51.100188 kernel: sdb: sdb1 May 14 18:08:51.100242 waagent[2012]: 2025-05-14T18:08:51.100199Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] May 14 18:08:51.501011 systemd[2111]: Queued start job for default target default.target. May 14 18:08:51.519053 systemd[2111]: Created slice app.slice - User Application Slice. May 14 18:08:51.519101 systemd[2111]: Reached target paths.target - Paths. May 14 18:08:51.519150 systemd[2111]: Reached target timers.target - Timers. May 14 18:08:51.520406 systemd[2111]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 18:08:51.530712 systemd[2111]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 18:08:51.530769 systemd[2111]: Reached target sockets.target - Sockets. May 14 18:08:51.530813 systemd[2111]: Reached target basic.target - Basic System. May 14 18:08:51.530853 systemd[2111]: Reached target default.target - Main User Target. May 14 18:08:51.530883 systemd[2111]: Startup finished in 491ms. May 14 18:08:51.531131 systemd[1]: Started user@500.service - User Manager for UID 500. May 14 18:08:51.532596 systemd[1]: Started session-2.scope - Session 2 of User core. May 14 18:08:51.975414 login[2015]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 14 18:08:51.979736 systemd-logind[1871]: New session 1 of user core. May 14 18:08:51.988202 systemd[1]: Started session-1.scope - Session 1 of User core. May 14 18:08:56.731933 waagent[2012]: 2025-05-14T18:08:56.731855Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] May 14 18:08:56.840117 kernel: EXT4-fs (sdb1): mounted filesystem 088e9d91-eb1c-458c-bffd-ceb27dbe17e4 r/w with ordered data mode. Quota mode: none. May 14 18:08:56.840214 waagent[2012]: 2025-05-14T18:08:56.840152Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 May 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.842347Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.842617Z INFO Daemon Daemon Running default provisioning handler May 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.851166Z 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 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.851701Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.852069Z INFO Daemon Daemon cloud-init is enabled: False May 14 18:08:56.876909 waagent[2012]: 2025-05-14T18:08:56.852816Z INFO Daemon Daemon Copying ovf-env.xml May 14 18:08:56.917796 waagent[2012]: 2025-05-14T18:08:56.917719Z INFO Daemon Daemon Successfully mounted dvd May 14 18:08:56.943733 waagent[2012]: 2025-05-14T18:08:56.943679Z INFO Daemon Daemon Detect protocol endpoint May 14 18:08:56.943864 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 14 18:08:56.966770 waagent[2012]: 2025-05-14T18:08:56.945280Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 14 18:08:56.966770 waagent[2012]: 2025-05-14T18:08:56.946028Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 14 18:08:56.966770 waagent[2012]: 2025-05-14T18:08:56.946716Z INFO Daemon Daemon Test for route to 168.63.129.16 May 14 18:08:56.966770 waagent[2012]: 2025-05-14T18:08:56.948256Z INFO Daemon Daemon Route to 168.63.129.16 exists May 14 18:08:56.966770 waagent[2012]: 2025-05-14T18:08:56.948872Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 14 18:08:56.974067 waagent[2012]: 2025-05-14T18:08:56.974018Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 14 18:08:56.976516 waagent[2012]: 2025-05-14T18:08:56.976488Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 14 18:08:56.977819 waagent[2012]: 2025-05-14T18:08:56.977780Z INFO Daemon Daemon Server preferred version:2015-04-05 May 14 18:08:57.345716 waagent[2012]: 2025-05-14T18:08:57.345635Z INFO Daemon Daemon Initializing goal state during protocol detection May 14 18:08:57.347371 waagent[2012]: 2025-05-14T18:08:57.347319Z INFO Daemon Daemon Forcing an update of the goal state. May 14 18:08:57.352196 waagent[2012]: 2025-05-14T18:08:57.352146Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 14 18:08:57.382338 waagent[2012]: 2025-05-14T18:08:57.382295Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 14 18:08:57.384395 waagent[2012]: 2025-05-14T18:08:57.384343Z INFO Daemon May 14 18:08:57.384735 waagent[2012]: 2025-05-14T18:08:57.384701Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: acee80f3-3e9d-4096-b5ba-69b5d3416ca6 eTag: 6081392274948133862 source: Fabric] May 14 18:08:57.385712 waagent[2012]: 2025-05-14T18:08:57.385672Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 14 18:08:57.386377 waagent[2012]: 2025-05-14T18:08:57.386340Z INFO Daemon May 14 18:08:57.386907 waagent[2012]: 2025-05-14T18:08:57.386876Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 14 18:08:57.396044 waagent[2012]: 2025-05-14T18:08:57.396003Z INFO Daemon Daemon Downloading artifacts profile blob May 14 18:08:57.542897 waagent[2012]: 2025-05-14T18:08:57.542824Z INFO Daemon Downloaded certificate {'thumbprint': 'C2E7CC3877C07897B2079E18105025241A2B4E19', 'hasPrivateKey': True} May 14 18:08:57.545353 waagent[2012]: 2025-05-14T18:08:57.545275Z INFO Daemon Downloaded certificate {'thumbprint': 'B43BFA75EA6EFFA498A86616A9584DC7B38F81D1', 'hasPrivateKey': False} May 14 18:08:57.547465 waagent[2012]: 2025-05-14T18:08:57.547415Z INFO Daemon Fetch goal state completed May 14 18:08:57.586646 waagent[2012]: 2025-05-14T18:08:57.586597Z INFO Daemon Daemon Starting provisioning May 14 18:08:57.588294 waagent[2012]: 2025-05-14T18:08:57.588248Z INFO Daemon Daemon Handle ovf-env.xml. May 14 18:08:57.589012 waagent[2012]: 2025-05-14T18:08:57.588972Z INFO Daemon Daemon Set hostname [ci-4334.0.0-a-f26f88f26d] May 14 18:08:57.711844 waagent[2012]: 2025-05-14T18:08:57.711764Z INFO Daemon Daemon Publish hostname [ci-4334.0.0-a-f26f88f26d] May 14 18:08:57.713592 waagent[2012]: 2025-05-14T18:08:57.713487Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 14 18:08:57.714321 waagent[2012]: 2025-05-14T18:08:57.714192Z INFO Daemon Daemon Primary interface is [eth0] May 14 18:08:57.731961 systemd-networkd[1582]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:08:57.731972 systemd-networkd[1582]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 18:08:57.732003 systemd-networkd[1582]: eth0: DHCP lease lost May 14 18:08:57.732502 waagent[2012]: 2025-05-14T18:08:57.732455Z INFO Daemon Daemon Create user account if not exists May 14 18:08:57.734034 waagent[2012]: 2025-05-14T18:08:57.733991Z INFO Daemon Daemon User core already exists, skip useradd May 14 18:08:57.735407 waagent[2012]: 2025-05-14T18:08:57.735368Z INFO Daemon Daemon Configure sudoer May 14 18:08:57.740903 waagent[2012]: 2025-05-14T18:08:57.740862Z INFO Daemon Daemon Configure sshd May 14 18:08:57.746259 waagent[2012]: 2025-05-14T18:08:57.746216Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 14 18:08:57.747864 waagent[2012]: 2025-05-14T18:08:57.747822Z INFO Daemon Daemon Deploy ssh public key. May 14 18:08:57.804144 systemd-networkd[1582]: eth0: DHCPv4 address 10.200.8.37/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 14 18:08:57.844944 waagent[2012]: 2025-05-14T18:08:57.844895Z INFO Daemon Daemon Provisioning complete May 14 18:08:57.855195 waagent[2012]: 2025-05-14T18:08:57.855152Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 14 18:08:57.856770 waagent[2012]: 2025-05-14T18:08:57.856724Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 14 18:08:57.858204 waagent[2012]: 2025-05-14T18:08:57.858157Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 14 18:08:58.022992 waagent[2190]: 2025-05-14T18:08:58.022856Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 14 18:08:58.023348 waagent[2190]: 2025-05-14T18:08:58.022995Z INFO ExtHandler ExtHandler OS: flatcar 4334.0.0 May 14 18:08:58.023348 waagent[2190]: 2025-05-14T18:08:58.023064Z INFO ExtHandler ExtHandler Python: 3.11.12 May 14 18:08:58.023348 waagent[2190]: 2025-05-14T18:08:58.023165Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 14 18:08:58.219787 waagent[2190]: 2025-05-14T18:08:58.219711Z INFO ExtHandler ExtHandler Distro: flatcar-4334.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; May 14 18:08:58.219999 waagent[2190]: 2025-05-14T18:08:58.219961Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 14 18:08:58.220094 waagent[2190]: 2025-05-14T18:08:58.220049Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 14 18:08:58.226735 waagent[2190]: 2025-05-14T18:08:58.226678Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 14 18:08:58.232063 waagent[2190]: 2025-05-14T18:08:58.232020Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 14 18:08:58.232516 waagent[2190]: 2025-05-14T18:08:58.232476Z INFO ExtHandler May 14 18:08:58.232595 waagent[2190]: 2025-05-14T18:08:58.232563Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: d3a6e23b-a3e6-4e72-a7c4-5c0bfdc496a6 eTag: 6081392274948133862 source: Fabric] May 14 18:08:58.232908 waagent[2190]: 2025-05-14T18:08:58.232872Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 14 18:08:58.233549 waagent[2190]: 2025-05-14T18:08:58.233503Z INFO ExtHandler May 14 18:08:58.233606 waagent[2190]: 2025-05-14T18:08:58.233583Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 14 18:08:58.237062 waagent[2190]: 2025-05-14T18:08:58.237025Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 14 18:08:58.313570 waagent[2190]: 2025-05-14T18:08:58.313473Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C2E7CC3877C07897B2079E18105025241A2B4E19', 'hasPrivateKey': True} May 14 18:08:58.313945 waagent[2190]: 2025-05-14T18:08:58.313878Z INFO ExtHandler Downloaded certificate {'thumbprint': 'B43BFA75EA6EFFA498A86616A9584DC7B38F81D1', 'hasPrivateKey': False} May 14 18:08:58.314380 waagent[2190]: 2025-05-14T18:08:58.314333Z INFO ExtHandler Fetch goal state completed May 14 18:08:58.328381 waagent[2190]: 2025-05-14T18:08:58.328330Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 14 18:08:58.345382 waagent[2190]: 2025-05-14T18:08:58.345328Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2190 May 14 18:08:58.345515 waagent[2190]: 2025-05-14T18:08:58.345478Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 14 18:08:58.345851 waagent[2190]: 2025-05-14T18:08:58.345814Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 14 18:08:58.347649 waagent[2190]: 2025-05-14T18:08:58.347592Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 14 18:08:58.348121 waagent[2190]: 2025-05-14T18:08:58.348056Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported May 14 18:08:58.348277 waagent[2190]: 2025-05-14T18:08:58.348240Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 14 18:08:58.348903 waagent[2190]: 2025-05-14T18:08:58.348860Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 14 18:08:58.617892 waagent[2190]: 2025-05-14T18:08:58.617789Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 14 18:08:58.618035 waagent[2190]: 2025-05-14T18:08:58.617996Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 14 18:08:58.624410 waagent[2190]: 2025-05-14T18:08:58.624377Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 14 18:08:58.631226 systemd[1]: Reload requested from client PID 2207 ('systemctl') (unit waagent.service)... May 14 18:08:58.631242 systemd[1]: Reloading... May 14 18:08:58.689119 zram_generator::config[2245]: No configuration found. May 14 18:08:58.801220 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 14 18:08:58.964547 systemd[1]: Reloading finished in 332 ms. May 14 18:08:58.986517 waagent[2190]: 2025-05-14T18:08:58.986437Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 14 18:08:58.986618 waagent[2190]: 2025-05-14T18:08:58.986586Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 14 18:08:59.563752 waagent[2190]: 2025-05-14T18:08:59.563674Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 14 18:08:59.564078 waagent[2190]: 2025-05-14T18:08:59.564020Z 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 14 18:08:59.564845 waagent[2190]: 2025-05-14T18:08:59.564798Z INFO ExtHandler ExtHandler Starting env monitor service. May 14 18:08:59.565110 waagent[2190]: 2025-05-14T18:08:59.565054Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 14 18:08:59.565456 waagent[2190]: 2025-05-14T18:08:59.565415Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 14 18:08:59.565501 waagent[2190]: 2025-05-14T18:08:59.565464Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 14 18:08:59.565834 waagent[2190]: 2025-05-14T18:08:59.565792Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 14 18:08:59.566349 waagent[2190]: 2025-05-14T18:08:59.566306Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 14 18:08:59.566448 waagent[2190]: 2025-05-14T18:08:59.566392Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 14 18:08:59.566585 waagent[2190]: 2025-05-14T18:08:59.566544Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 14 18:08:59.566658 waagent[2190]: 2025-05-14T18:08:59.566615Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 14 18:08:59.566658 waagent[2190]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 14 18:08:59.566658 waagent[2190]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 14 18:08:59.566658 waagent[2190]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 14 18:08:59.566658 waagent[2190]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 14 18:08:59.566658 waagent[2190]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 14 18:08:59.566658 waagent[2190]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 14 18:08:59.567110 waagent[2190]: 2025-05-14T18:08:59.567018Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 14 18:08:59.567285 waagent[2190]: 2025-05-14T18:08:59.567235Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 14 18:08:59.567418 waagent[2190]: 2025-05-14T18:08:59.567375Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 14 18:08:59.567821 waagent[2190]: 2025-05-14T18:08:59.567775Z INFO EnvHandler ExtHandler Configure routes May 14 18:08:59.568191 waagent[2190]: 2025-05-14T18:08:59.568145Z INFO EnvHandler ExtHandler Gateway:None May 14 18:08:59.568370 waagent[2190]: 2025-05-14T18:08:59.568303Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 14 18:08:59.568774 waagent[2190]: 2025-05-14T18:08:59.568726Z INFO EnvHandler ExtHandler Routes:None May 14 18:08:59.574349 waagent[2190]: 2025-05-14T18:08:59.574307Z INFO ExtHandler ExtHandler May 14 18:08:59.574626 waagent[2190]: 2025-05-14T18:08:59.574588Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 33267011-b31b-4f08-a33a-6d23ee3827d4 correlation 9742d577-6cd3-4c8d-b071-ecc205348576 created: 2025-05-14T18:07:11.549339Z] May 14 18:08:59.575710 waagent[2190]: 2025-05-14T18:08:59.575669Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 14 18:08:59.577879 waagent[2190]: 2025-05-14T18:08:59.577836Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] May 14 18:08:59.602232 waagent[2190]: 2025-05-14T18:08:59.602186Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command May 14 18:08:59.602232 waagent[2190]: Try `iptables -h' or 'iptables --help' for more information.) May 14 18:08:59.602596 waagent[2190]: 2025-05-14T18:08:59.602559Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 2A6595BE-B923-4834-B30E-FAFFB0C67DC8;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 14 18:08:59.682615 waagent[2190]: 2025-05-14T18:08:59.682461Z INFO MonitorHandler ExtHandler Network interfaces: May 14 18:08:59.682615 waagent[2190]: Executing ['ip', '-a', '-o', 'link']: May 14 18:08:59.682615 waagent[2190]: 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 14 18:08:59.682615 waagent[2190]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:36:05:ad brd ff:ff:ff:ff:ff:ff May 14 18:08:59.682615 waagent[2190]: Executing ['ip', '-4', '-a', '-o', 'address']: May 14 18:08:59.682615 waagent[2190]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 14 18:08:59.682615 waagent[2190]: 2: eth0 inet 10.200.8.37/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 14 18:08:59.682615 waagent[2190]: Executing ['ip', '-6', '-a', '-o', 'address']: May 14 18:08:59.682615 waagent[2190]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 14 18:08:59.682615 waagent[2190]: 2: eth0 inet6 fe80::7e1e:52ff:fe36:5ad/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 14 18:08:59.933445 waagent[2190]: 2025-05-14T18:08:59.933339Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 14 18:08:59.933445 waagent[2190]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.933445 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.933445 waagent[2190]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.933445 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.933445 waagent[2190]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.933445 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.933445 waagent[2190]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 14 18:08:59.933445 waagent[2190]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 14 18:08:59.933445 waagent[2190]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 14 18:08:59.936687 waagent[2190]: 2025-05-14T18:08:59.936643Z INFO EnvHandler ExtHandler Current Firewall rules: May 14 18:08:59.936687 waagent[2190]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.936687 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.936687 waagent[2190]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.936687 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.936687 waagent[2190]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 14 18:08:59.936687 waagent[2190]: pkts bytes target prot opt in out source destination May 14 18:08:59.936687 waagent[2190]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 14 18:08:59.936687 waagent[2190]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 14 18:08:59.936687 waagent[2190]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 14 18:08:59.936913 waagent[2190]: 2025-05-14T18:08:59.936892Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 May 14 18:08:59.937063 waagent[2190]: 2025-05-14T18:08:59.937031Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 14 18:09:06.362456 chronyd[1899]: Selected source PHC0 May 14 18:09:25.061631 kernel: hv_balloon: Max. dynamic memory size: 114688 MB May 14 18:09:28.276230 update_engine[1872]: I20250514 18:09:28.276134 1872 update_attempter.cc:509] Updating boot flags... May 14 18:09:28.615804 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 14 18:09:28.621390 systemd[1]: Started sshd@0-10.200.8.37:22-10.200.16.10:45904.service - OpenSSH per-connection server daemon (10.200.16.10:45904). May 14 18:09:29.519550 sshd[2405]: Accepted publickey for core from 10.200.16.10 port 45904 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:29.520935 sshd-session[2405]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:29.525377 systemd-logind[1871]: New session 3 of user core. May 14 18:09:29.537207 systemd[1]: Started session-3.scope - Session 3 of User core. May 14 18:09:30.083247 systemd[1]: Started sshd@1-10.200.8.37:22-10.200.16.10:45916.service - OpenSSH per-connection server daemon (10.200.16.10:45916). May 14 18:09:30.740067 sshd[2410]: Accepted publickey for core from 10.200.16.10 port 45916 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:30.741443 sshd-session[2410]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:30.745690 systemd-logind[1871]: New session 4 of user core. May 14 18:09:30.756200 systemd[1]: Started session-4.scope - Session 4 of User core. May 14 18:09:31.198401 sshd[2412]: Connection closed by 10.200.16.10 port 45916 May 14 18:09:31.198796 sshd-session[2410]: pam_unix(sshd:session): session closed for user core May 14 18:09:31.202300 systemd[1]: sshd@1-10.200.8.37:22-10.200.16.10:45916.service: Deactivated successfully. May 14 18:09:31.203996 systemd[1]: session-4.scope: Deactivated successfully. May 14 18:09:31.204742 systemd-logind[1871]: Session 4 logged out. Waiting for processes to exit. May 14 18:09:31.205700 systemd-logind[1871]: Removed session 4. May 14 18:09:31.315496 systemd[1]: Started sshd@2-10.200.8.37:22-10.200.16.10:45918.service - OpenSSH per-connection server daemon (10.200.16.10:45918). May 14 18:09:31.972772 sshd[2418]: Accepted publickey for core from 10.200.16.10 port 45918 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:31.974726 sshd-session[2418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:31.980097 systemd-logind[1871]: New session 5 of user core. May 14 18:09:31.995206 systemd[1]: Started session-5.scope - Session 5 of User core. May 14 18:09:32.427548 sshd[2420]: Connection closed by 10.200.16.10 port 45918 May 14 18:09:32.427804 sshd-session[2418]: pam_unix(sshd:session): session closed for user core May 14 18:09:32.431122 systemd[1]: sshd@2-10.200.8.37:22-10.200.16.10:45918.service: Deactivated successfully. May 14 18:09:32.432619 systemd[1]: session-5.scope: Deactivated successfully. May 14 18:09:32.433286 systemd-logind[1871]: Session 5 logged out. Waiting for processes to exit. May 14 18:09:32.434204 systemd-logind[1871]: Removed session 5. May 14 18:09:42.544480 systemd[1]: Started sshd@3-10.200.8.37:22-10.200.16.10:59506.service - OpenSSH per-connection server daemon (10.200.16.10:59506). May 14 18:09:43.009542 setup-nvidia[2428]: % Total % Received % Xferd Average Speed Time Time Time Current May 14 18:09:43.009900 setup-nvidia[2428]: Dload Upload Total Spent Left Speed May 14 18:09:43.180197 sshd[2426]: Accepted publickey for core from 10.200.16.10 port 59506 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:43.181520 sshd-session[2426]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:43.185879 systemd-logind[1871]: New session 6 of user core. May 14 18:09:43.197218 systemd[1]: Started session-6.scope - Session 6 of User core. May 14 18:09:43.629370 sshd[2429]: Connection closed by 10.200.16.10 port 59506 May 14 18:09:43.629751 sshd-session[2426]: pam_unix(sshd:session): session closed for user core May 14 18:09:43.633427 systemd[1]: sshd@3-10.200.8.37:22-10.200.16.10:59506.service: Deactivated successfully. May 14 18:09:43.635219 systemd[1]: session-6.scope: Deactivated successfully. May 14 18:09:43.635869 systemd-logind[1871]: Session 6 logged out. Waiting for processes to exit. May 14 18:09:43.636796 systemd-logind[1871]: Removed session 6. May 14 18:09:43.741684 systemd[1]: Started sshd@4-10.200.8.37:22-10.200.16.10:59520.service - OpenSSH per-connection server daemon (10.200.16.10:59520). May 14 18:09:44.377313 sshd[2435]: Accepted publickey for core from 10.200.16.10 port 59520 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:44.378680 sshd-session[2435]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:44.383145 systemd-logind[1871]: New session 7 of user core. May 14 18:09:44.395221 systemd[1]: Started session-7.scope - Session 7 of User core. May 14 18:09:44.826729 sshd[2437]: Connection closed by 10.200.16.10 port 59520 May 14 18:09:44.827138 sshd-session[2435]: pam_unix(sshd:session): session closed for user core May 14 18:09:44.830674 systemd[1]: sshd@4-10.200.8.37:22-10.200.16.10:59520.service: Deactivated successfully. May 14 18:09:44.832328 systemd[1]: session-7.scope: Deactivated successfully. May 14 18:09:44.832988 systemd-logind[1871]: Session 7 logged out. Waiting for processes to exit. May 14 18:09:44.833971 systemd-logind[1871]: Removed session 7. May 14 18:09:54.943268 systemd[1]: Started sshd@5-10.200.8.37:22-10.200.16.10:60394.service - OpenSSH per-connection server daemon (10.200.16.10:60394). May 14 18:09:55.578056 sshd[2443]: Accepted publickey for core from 10.200.16.10 port 60394 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:55.579395 sshd-session[2443]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:55.583624 systemd-logind[1871]: New session 8 of user core. May 14 18:09:55.594203 systemd[1]: Started session-8.scope - Session 8 of User core. May 14 18:09:56.030953 sshd[2445]: Connection closed by 10.200.16.10 port 60394 May 14 18:09:56.031332 sshd-session[2443]: pam_unix(sshd:session): session closed for user core May 14 18:09:56.034705 systemd[1]: sshd@5-10.200.8.37:22-10.200.16.10:60394.service: Deactivated successfully. May 14 18:09:56.036454 systemd[1]: session-8.scope: Deactivated successfully. May 14 18:09:56.037178 systemd-logind[1871]: Session 8 logged out. Waiting for processes to exit. May 14 18:09:56.038175 systemd-logind[1871]: Removed session 8. May 14 18:09:56.155860 systemd[1]: Started sshd@6-10.200.8.37:22-10.200.16.10:60398.service - OpenSSH per-connection server daemon (10.200.16.10:60398). May 14 18:09:56.793014 sshd[2452]: Accepted publickey for core from 10.200.16.10 port 60398 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:09:56.795478 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:09:56.799753 systemd-logind[1871]: New session 9 of user core. May 14 18:09:56.812214 systemd[1]: Started session-9.scope - Session 9 of User core. May 14 18:09:57.241898 sshd[2454]: Connection closed by 10.200.16.10 port 60398 May 14 18:09:57.242276 sshd-session[2452]: pam_unix(sshd:session): session closed for user core May 14 18:09:57.245694 systemd[1]: sshd@6-10.200.8.37:22-10.200.16.10:60398.service: Deactivated successfully. May 14 18:09:57.247653 systemd[1]: session-9.scope: Deactivated successfully. May 14 18:09:57.250076 systemd-logind[1871]: Session 9 logged out. Waiting for processes to exit. May 14 18:09:57.252064 systemd-logind[1871]: Removed session 9. May 14 18:10:06.107647 setup-nvidia[2428]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 543M 0 1743k 0 0 1238k 0 0:07:29 0:00:01 0:07:28 1237k\u000d 5 543M 5 28.2M 0 0 11.1M 0 0:00:48 0:00:02 0:00:46 11.1M\u000d 9 543M 9 52.7M 0 0 14.9M 0 0:00:36 0:00:03 0:00:33 14.9M\u000d 14 543M 14 78.3M 0 0 17.3M 0 0:00:31 0:00:04 0:00:27 17.3M\u000d 19 543M 19 103M 0 0 18.8M 0 0:00:28 0:00:05 0:00:23 20.4M\u000d 23 543M 23 127M 0 0 19.6M 0 0:00:27 0:00:06 0:00:21 24.7M\u000d 28 543M 28 153M 0 0 20.4M 0 0:00:26 0:00:07 0:00:19 25.1M\u000d 32 543M 32 178M 0 0 21.0M 0 0:00:25 0:00:08 0:00:17 25.3M\u000d 37 543M 37 202M 0 0 21.3M 0 0:00:25 0:00:09 0:00:16 24.9M\u000d 41 543M 41 227M 0 0 21.7M 0 0:00:25 0:00:10 0:00:15 24.9M\u000d 46 543M 46 251M 0 0 21.9M 0 0:00:24 0:00:11 0:00:13 24.9M\u000d 50 543M 50 276M 0 0 22.1M 0 0:00:24 0:00:12 0:00:12 24.8M\u000d 55 543M 55 301M 0 0 22.3M 0 0:00:24 0:00:13 0:00:11 24.7M\u000d 60 543M 60 326M 0 0 22.5M 0 0:00:24 0:00:14 0:00:10 24.9M\u000d 64 543M 64 351M 0 0 22.7M 0 0:00:23 0:00:15 0:00:08 24.9M\u000d 69 543M 69 376M 0 0 22.8M 0 0:00:23 0:00:16 0:00:07 25.0M\u000d 73 543M 73 400M 0 0 23.0M 0 0:00:23 0:00:17 0:00:06 25.0M\u000d 78 543M 78 424M 0 0 23.0M 0 0:00:23 0:00:18 0:00:05 24.8M\u000d 82 543M 82 449M 0 0 23.1M 0 0:00:23 0:00:19 0:00:04 24.8M\u000d 87 543M 87 476M 0 0 23.3M 0 0:00:23 0:00:20 0:00:03 25.2M\u000d 92 543M 92 502M 0 0 23.3M 0 0:00:23 0:00:21 0:00:02 24.7M\u000d 96 543M 96 526M 0 0 23.3M 0 0:00:23 0:00:22 0:00:01 24.7M\u000d100 543M 100 543M 0 0 23.5M 0 0:00:23 0:00:23 --:--:-- 25.4M May 14 18:10:07.362975 systemd[1]: Started sshd@7-10.200.8.37:22-10.200.16.10:45022.service - OpenSSH per-connection server daemon (10.200.16.10:45022). May 14 18:10:08.018845 sshd[2470]: Accepted publickey for core from 10.200.16.10 port 45022 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:08.020237 sshd-session[2470]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:08.027586 systemd-logind[1871]: New session 10 of user core. May 14 18:10:08.034265 systemd[1]: Started session-10.scope - Session 10 of User core. May 14 18:10:08.472692 sshd[2472]: Connection closed by 10.200.16.10 port 45022 May 14 18:10:08.474294 sshd-session[2470]: pam_unix(sshd:session): session closed for user core May 14 18:10:08.478442 systemd[1]: sshd@7-10.200.8.37:22-10.200.16.10:45022.service: Deactivated successfully. May 14 18:10:08.483478 systemd[1]: session-10.scope: Deactivated successfully. May 14 18:10:08.484797 systemd-logind[1871]: Session 10 logged out. Waiting for processes to exit. May 14 18:10:08.487281 systemd-logind[1871]: Removed session 10. May 14 18:10:08.587925 systemd[1]: Started sshd@8-10.200.8.37:22-10.200.16.10:45938.service - OpenSSH per-connection server daemon (10.200.16.10:45938). May 14 18:10:09.237730 sshd[2478]: Accepted publickey for core from 10.200.16.10 port 45938 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:09.239160 sshd-session[2478]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:09.244828 systemd-logind[1871]: New session 11 of user core. May 14 18:10:09.254288 systemd[1]: Started session-11.scope - Session 11 of User core. May 14 18:10:09.691248 sshd[2480]: Connection closed by 10.200.16.10 port 45938 May 14 18:10:09.693305 sshd-session[2478]: pam_unix(sshd:session): session closed for user core May 14 18:10:09.697438 systemd-logind[1871]: Session 11 logged out. Waiting for processes to exit. May 14 18:10:09.697923 systemd[1]: sshd@8-10.200.8.37:22-10.200.16.10:45938.service: Deactivated successfully. May 14 18:10:09.700817 systemd[1]: session-11.scope: Deactivated successfully. May 14 18:10:09.703640 systemd-logind[1871]: Removed session 11. May 14 18:10:17.009540 setup-nvidia[1865]: Downloading NVIDIA 535.230.02 Driver May 14 18:10:17.014046 setup-nvidia[2486]: % Total % Received % Xferd Average Speed Time Time Time Current May 14 18:10:17.014297 setup-nvidia[2486]: Dload Upload Total Spent Left Speed May 14 18:10:18.866851 setup-nvidia[2486]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 16384 0 0 35719 0 2:39:32 --:--:-- 2:39:32 35694\u000d 65 326M 65 212M 0 0 151M 0 0:00:02 0:00:01 0:00:01 151M\u000d100 326M 100 326M 0 0 175M 0 0:00:01 0:00:01 --:--:-- 175M May 14 18:10:18.951961 setup-nvidia[1865]: Extract the NVIDIA Driver Installer 535.230.02 May 14 18:10:18.951961 setup-nvidia[1865]: /opt/nvidia/workdir/nvidia-workdir / May 14 18:10:18.969642 setup-nvidia[2489]: Creating directory NVIDIA-Linux-x86_64-535.230.02 May 14 18:10:19.258270 setup-nvidia[2489]: Verifying archive integrity... OK May 14 18:10:19.803827 systemd[1]: Started sshd@9-10.200.8.37:22-10.200.16.10:57328.service - OpenSSH per-connection server daemon (10.200.16.10:57328). May 14 18:10:20.008276 setup-nvidia[2489]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 May 14 18:10:20.449662 sshd[2513]: Accepted publickey for core from 10.200.16.10 port 57328 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:20.451095 sshd-session[2513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:20.456608 systemd-logind[1871]: New session 12 of user core. May 14 18:10:20.467265 systemd[1]: Started session-12.scope - Session 12 of User core. May 14 18:10:20.899734 sshd[2522]: Connection closed by 10.200.16.10 port 57328 May 14 18:10:20.900108 sshd-session[2513]: pam_unix(sshd:session): session closed for user core May 14 18:10:20.903581 systemd[1]: sshd@9-10.200.8.37:22-10.200.16.10:57328.service: Deactivated successfully. May 14 18:10:20.905445 systemd[1]: session-12.scope: Deactivated successfully. May 14 18:10:20.906510 systemd-logind[1871]: Session 12 logged out. Waiting for processes to exit. May 14 18:10:20.907606 systemd-logind[1871]: Removed session 12. May 14 18:10:21.013727 systemd[1]: Started sshd@10-10.200.8.37:22-10.200.16.10:57334.service - OpenSSH per-connection server daemon (10.200.16.10:57334). May 14 18:10:21.651207 sshd[2528]: Accepted publickey for core from 10.200.16.10 port 57334 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:21.652633 sshd-session[2528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:21.657493 systemd-logind[1871]: New session 13 of user core. May 14 18:10:21.665265 systemd[1]: Started session-13.scope - Session 13 of User core. May 14 18:10:21.831434 setup-nvidia[2518]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ May 14 18:10:21.896626 setup-nvidia[1865]: / May 14 18:10:21.896889 setup-nvidia[1865]: Spawn system-nspawn container to install the NVIDIA drivers May 14 18:10:22.010534 sudo[2534]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 May 14 18:10:22.012227 sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:10:22.020693 systemd[1]: Created slice user-0.slice - User Slice of UID 0. May 14 18:10:22.023194 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 14 18:10:22.052173 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 14 18:10:22.056226 systemd[1]: Starting user@0.service - User Manager for UID 0... May 14 18:10:22.083056 (systemd)[2538]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) May 14 18:10:22.086518 systemd-logind[1871]: New session c3 of user root. May 14 18:10:22.101149 sshd[2530]: Connection closed by 10.200.16.10 port 57334 May 14 18:10:22.101472 sshd-session[2528]: pam_unix(sshd:session): session closed for user core May 14 18:10:22.104492 systemd[1]: sshd@10-10.200.8.37:22-10.200.16.10:57334.service: Deactivated successfully. May 14 18:10:22.115359 systemd[1]: session-13.scope: Deactivated successfully. May 14 18:10:22.116044 systemd-logind[1871]: Session 13 logged out. Waiting for processes to exit. May 14 18:10:22.116963 systemd-logind[1871]: Removed session 13. May 14 18:10:22.266273 systemd[2538]: Queued start job for default target default.target. May 14 18:10:22.286964 systemd[2538]: Created slice app.slice - User Application Slice. May 14 18:10:22.286999 systemd[2538]: Reached target paths.target - Paths. May 14 18:10:22.287051 systemd[2538]: Reached target timers.target - Timers. May 14 18:10:22.288367 systemd[2538]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 18:10:22.300591 systemd[2538]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 18:10:22.300714 systemd[2538]: Reached target sockets.target - Sockets. May 14 18:10:22.300772 systemd[2538]: Reached target basic.target - Basic System. May 14 18:10:22.300817 systemd[2538]: Reached target default.target - Main User Target. May 14 18:10:22.300857 systemd[2538]: Startup finished in 208ms. May 14 18:10:22.300955 systemd[1]: Started user@0.service - User Manager for UID 0. May 14 18:10:22.306661 systemd[1]: Started session-c2.scope - Session c2 of User root. May 14 18:10:22.403108 kernel: loop6: detected capacity change from 0 to 12644352 May 14 18:10:22.410102 kernel: loop6: p9 May 14 18:10:22.483103 setup-nvidia[2550]: ░ Spawning container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.0.bin. May 14 18:10:23.126243 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup May 14 18:10:23.126376 kernel: EXT4-fs (loop6p9): mounted filesystem 4c03bb24-ac25-4d8b-af70-04c72efb9bcb ro without journal. Quota mode: none. May 14 18:10:32.221559 systemd[1]: Started sshd@11-10.200.8.37:22-10.200.16.10:59406.service - OpenSSH per-connection server daemon (10.200.16.10:59406). May 14 18:10:32.857417 sshd[3813]: Accepted publickey for core from 10.200.16.10 port 59406 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:32.858694 sshd-session[3813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:32.863146 systemd-logind[1871]: New session 14 of user core. May 14 18:10:32.876250 systemd[1]: Started session-14.scope - Session 14 of User core. May 14 18:10:33.306839 sshd[4290]: Connection closed by 10.200.16.10 port 59406 May 14 18:10:33.307234 sshd-session[3813]: pam_unix(sshd:session): session closed for user core May 14 18:10:33.310683 systemd[1]: sshd@11-10.200.8.37:22-10.200.16.10:59406.service: Deactivated successfully. May 14 18:10:33.312532 systemd[1]: session-14.scope: Deactivated successfully. May 14 18:10:33.313242 systemd-logind[1871]: Session 14 logged out. Waiting for processes to exit. May 14 18:10:33.314516 systemd-logind[1871]: Removed session 14. May 14 18:10:33.423313 systemd[1]: Started sshd@12-10.200.8.37:22-10.200.16.10:59414.service - OpenSSH per-connection server daemon (10.200.16.10:59414). May 14 18:10:34.077173 sshd[4684]: Accepted publickey for core from 10.200.16.10 port 59414 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:34.077914 sshd-session[4684]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:34.083376 systemd-logind[1871]: New session 15 of user core. May 14 18:10:34.094254 systemd[1]: Started session-15.scope - Session 15 of User core. May 14 18:10:34.526263 sshd[5144]: Connection closed by 10.200.16.10 port 59414 May 14 18:10:34.526643 sshd-session[4684]: pam_unix(sshd:session): session closed for user core May 14 18:10:34.529993 systemd[1]: sshd@12-10.200.8.37:22-10.200.16.10:59414.service: Deactivated successfully. May 14 18:10:34.531530 systemd[1]: session-15.scope: Deactivated successfully. May 14 18:10:34.532175 systemd-logind[1871]: Session 15 logged out. Waiting for processes to exit. May 14 18:10:34.533095 systemd-logind[1871]: Removed session 15. May 14 18:10:44.643442 systemd[1]: Started sshd@13-10.200.8.37:22-10.200.16.10:43810.service - OpenSSH per-connection server daemon (10.200.16.10:43810). May 14 18:10:45.278998 sshd[8207]: Accepted publickey for core from 10.200.16.10 port 43810 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:45.280331 sshd-session[8207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:45.286244 systemd-logind[1871]: New session 16 of user core. May 14 18:10:45.295268 systemd[1]: Started session-16.scope - Session 16 of User core. May 14 18:10:45.727788 sshd[8600]: Connection closed by 10.200.16.10 port 43810 May 14 18:10:45.728152 sshd-session[8207]: pam_unix(sshd:session): session closed for user core May 14 18:10:45.731562 systemd[1]: sshd@13-10.200.8.37:22-10.200.16.10:43810.service: Deactivated successfully. May 14 18:10:45.733071 systemd[1]: session-16.scope: Deactivated successfully. May 14 18:10:45.733946 systemd-logind[1871]: Session 16 logged out. Waiting for processes to exit. May 14 18:10:45.735210 systemd-logind[1871]: Removed session 16. May 14 18:10:45.842017 systemd[1]: Started sshd@14-10.200.8.37:22-10.200.16.10:43818.service - OpenSSH per-connection server daemon (10.200.16.10:43818). May 14 18:10:46.490193 sshd[8934]: Accepted publickey for core from 10.200.16.10 port 43818 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:46.491671 sshd-session[8934]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:46.496895 systemd-logind[1871]: New session 17 of user core. May 14 18:10:46.516334 systemd[1]: Started session-17.scope - Session 17 of User core. May 14 18:10:46.939174 sshd[9055]: Connection closed by 10.200.16.10 port 43818 May 14 18:10:46.941355 sshd-session[8934]: pam_unix(sshd:session): session closed for user core May 14 18:10:46.946847 systemd[1]: sshd@14-10.200.8.37:22-10.200.16.10:43818.service: Deactivated successfully. May 14 18:10:46.947256 systemd-logind[1871]: Session 17 logged out. Waiting for processes to exit. May 14 18:10:46.950448 systemd[1]: session-17.scope: Deactivated successfully. May 14 18:10:46.952768 systemd-logind[1871]: Removed session 17. May 14 18:10:57.065696 systemd[1]: Started sshd@15-10.200.8.37:22-10.200.16.10:40148.service - OpenSSH per-connection server daemon (10.200.16.10:40148). May 14 18:10:57.706570 sshd[11346]: Accepted publickey for core from 10.200.16.10 port 40148 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:57.707918 sshd-session[11346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:57.712217 systemd-logind[1871]: New session 18 of user core. May 14 18:10:57.727226 systemd[1]: Started session-18.scope - Session 18 of User core. May 14 18:10:58.162889 sshd[11348]: Connection closed by 10.200.16.10 port 40148 May 14 18:10:58.163389 sshd-session[11346]: pam_unix(sshd:session): session closed for user core May 14 18:10:58.167967 systemd[1]: sshd@15-10.200.8.37:22-10.200.16.10:40148.service: Deactivated successfully. May 14 18:10:58.169546 systemd[1]: session-18.scope: Deactivated successfully. May 14 18:10:58.170230 systemd-logind[1871]: Session 18 logged out. Waiting for processes to exit. May 14 18:10:58.171453 systemd-logind[1871]: Removed session 18. May 14 18:10:58.277070 systemd[1]: Started sshd@16-10.200.8.37:22-10.200.16.10:40164.service - OpenSSH per-connection server daemon (10.200.16.10:40164). May 14 18:10:58.919392 sshd[11368]: Accepted publickey for core from 10.200.16.10 port 40164 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:10:58.919932 sshd-session[11368]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:10:58.930040 systemd-logind[1871]: New session 19 of user core. May 14 18:10:58.934256 systemd[1]: Started session-19.scope - Session 19 of User core. May 14 18:10:59.396585 sshd[11386]: Connection closed by 10.200.16.10 port 40164 May 14 18:10:59.398478 sshd-session[11368]: pam_unix(sshd:session): session closed for user core May 14 18:10:59.401726 systemd-logind[1871]: Session 19 logged out. Waiting for processes to exit. May 14 18:10:59.402350 systemd[1]: sshd@16-10.200.8.37:22-10.200.16.10:40164.service: Deactivated successfully. May 14 18:10:59.404054 systemd[1]: session-19.scope: Deactivated successfully. May 14 18:10:59.410120 systemd-logind[1871]: Removed session 19. May 14 18:11:09.511735 systemd[1]: Started sshd@17-10.200.8.37:22-10.200.16.10:39514.service - OpenSSH per-connection server daemon (10.200.16.10:39514). May 14 18:11:10.170180 sshd[11818]: Accepted publickey for core from 10.200.16.10 port 39514 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:10.171496 sshd-session[11818]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:10.180471 systemd-logind[1871]: New session 20 of user core. May 14 18:11:10.187245 systemd[1]: Started session-20.scope - Session 20 of User core. May 14 18:11:10.622079 sshd[11833]: Connection closed by 10.200.16.10 port 39514 May 14 18:11:10.622734 sshd-session[11818]: pam_unix(sshd:session): session closed for user core May 14 18:11:10.628742 systemd[1]: sshd@17-10.200.8.37:22-10.200.16.10:39514.service: Deactivated successfully. May 14 18:11:10.630154 systemd-logind[1871]: Session 20 logged out. Waiting for processes to exit. May 14 18:11:10.632539 systemd[1]: session-20.scope: Deactivated successfully. May 14 18:11:10.635433 systemd-logind[1871]: Removed session 20. May 14 18:11:10.741349 systemd[1]: Started sshd@18-10.200.8.37:22-10.200.16.10:39528.service - OpenSSH per-connection server daemon (10.200.16.10:39528). May 14 18:11:11.410186 sshd[11850]: Accepted publickey for core from 10.200.16.10 port 39528 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:11.411788 sshd-session[11850]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:11.419509 systemd-logind[1871]: New session 21 of user core. May 14 18:11:11.427376 systemd[1]: Started session-21.scope - Session 21 of User core. May 14 18:11:11.864063 sshd[11870]: Connection closed by 10.200.16.10 port 39528 May 14 18:11:11.865079 sshd-session[11850]: pam_unix(sshd:session): session closed for user core May 14 18:11:11.868816 systemd[1]: sshd@18-10.200.8.37:22-10.200.16.10:39528.service: Deactivated successfully. May 14 18:11:11.871441 systemd[1]: session-21.scope: Deactivated successfully. May 14 18:11:11.875454 systemd-logind[1871]: Session 21 logged out. Waiting for processes to exit. May 14 18:11:11.876853 systemd-logind[1871]: Removed session 21. May 14 18:11:21.983464 systemd[1]: Started sshd@19-10.200.8.37:22-10.200.16.10:36460.service - OpenSSH per-connection server daemon (10.200.16.10:36460). May 14 18:11:22.637715 sshd[12222]: Accepted publickey for core from 10.200.16.10 port 36460 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:22.642161 sshd-session[12222]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:22.649393 systemd-logind[1871]: New session 22 of user core. May 14 18:11:22.658327 systemd[1]: Started session-22.scope - Session 22 of User core. May 14 18:11:23.094782 sshd[12244]: Connection closed by 10.200.16.10 port 36460 May 14 18:11:23.097273 sshd-session[12222]: pam_unix(sshd:session): session closed for user core May 14 18:11:23.100715 systemd-logind[1871]: Session 22 logged out. Waiting for processes to exit. May 14 18:11:23.102746 systemd[1]: sshd@19-10.200.8.37:22-10.200.16.10:36460.service: Deactivated successfully. May 14 18:11:23.105789 systemd[1]: session-22.scope: Deactivated successfully. May 14 18:11:23.108187 systemd-logind[1871]: Removed session 22. May 14 18:11:23.211760 systemd[1]: Started sshd@20-10.200.8.37:22-10.200.16.10:36474.service - OpenSSH per-connection server daemon (10.200.16.10:36474). May 14 18:11:23.870415 sshd[12264]: Accepted publickey for core from 10.200.16.10 port 36474 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:23.871634 sshd-session[12264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:23.877696 systemd-logind[1871]: New session 23 of user core. May 14 18:11:23.885168 systemd[1]: Started session-23.scope - Session 23 of User core. May 14 18:11:24.321460 sshd[12276]: Connection closed by 10.200.16.10 port 36474 May 14 18:11:24.322259 sshd-session[12264]: pam_unix(sshd:session): session closed for user core May 14 18:11:24.326792 systemd-logind[1871]: Session 23 logged out. Waiting for processes to exit. May 14 18:11:24.328276 systemd[1]: sshd@20-10.200.8.37:22-10.200.16.10:36474.service: Deactivated successfully. May 14 18:11:24.329960 systemd[1]: session-23.scope: Deactivated successfully. May 14 18:11:24.332263 systemd-logind[1871]: Removed session 23. May 14 18:11:34.441537 systemd[1]: Started sshd@21-10.200.8.37:22-10.200.16.10:48082.service - OpenSSH per-connection server daemon (10.200.16.10:48082). May 14 18:11:35.078596 sshd[12519]: Accepted publickey for core from 10.200.16.10 port 48082 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:35.079901 sshd-session[12519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:35.084377 systemd-logind[1871]: New session 24 of user core. May 14 18:11:35.102240 systemd[1]: Started session-24.scope - Session 24 of User core. May 14 18:11:35.533473 sshd[12521]: Connection closed by 10.200.16.10 port 48082 May 14 18:11:35.533847 sshd-session[12519]: pam_unix(sshd:session): session closed for user core May 14 18:11:35.537386 systemd[1]: sshd@21-10.200.8.37:22-10.200.16.10:48082.service: Deactivated successfully. May 14 18:11:35.538946 systemd[1]: session-24.scope: Deactivated successfully. May 14 18:11:35.539611 systemd-logind[1871]: Session 24 logged out. Waiting for processes to exit. May 14 18:11:35.540543 systemd-logind[1871]: Removed session 24. May 14 18:11:35.652783 systemd[1]: Started sshd@22-10.200.8.37:22-10.200.16.10:48098.service - OpenSSH per-connection server daemon (10.200.16.10:48098). May 14 18:11:36.290099 sshd[12527]: Accepted publickey for core from 10.200.16.10 port 48098 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:36.291475 sshd-session[12527]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:36.296691 systemd-logind[1871]: New session 25 of user core. May 14 18:11:36.316373 systemd[1]: Started session-25.scope - Session 25 of User core. May 14 18:11:36.743513 sshd[12529]: Connection closed by 10.200.16.10 port 48098 May 14 18:11:36.743861 sshd-session[12527]: pam_unix(sshd:session): session closed for user core May 14 18:11:36.748004 systemd[1]: sshd@22-10.200.8.37:22-10.200.16.10:48098.service: Deactivated successfully. May 14 18:11:36.749558 systemd[1]: session-25.scope: Deactivated successfully. May 14 18:11:36.750230 systemd-logind[1871]: Session 25 logged out. Waiting for processes to exit. May 14 18:11:36.751169 systemd-logind[1871]: Removed session 25. May 14 18:11:39.083880 setup-nvidia[2550]: \u000d May 14 18:11:39.083880 setup-nvidia[2550]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most May 14 18:11:39.083880 setup-nvidia[2550]: frequently when this kernel module was built against the wrong or May 14 18:11:39.083880 setup-nvidia[2550]: improperly configured kernel sources, with a version of gcc that differs May 14 18:11:39.083880 setup-nvidia[2550]: from the one used to build the target kernel, or if another driver, such May 14 18:11:39.083880 setup-nvidia[2550]: as nouveau, is present and prevents the NVIDIA kernel module from May 14 18:11:39.083880 setup-nvidia[2550]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device May 14 18:11:39.083880 setup-nvidia[2550]: installed in this system is supported by this NVIDIA Linux graphics May 14 18:11:39.083880 setup-nvidia[2550]: driver release. May 14 18:11:39.083880 setup-nvidia[2550]: \u000d May 14 18:11:39.083880 setup-nvidia[2550]: Please see the log entries 'Kernel module load error' and 'Kernel May 14 18:11:39.083880 setup-nvidia[2550]: messages' at the end of the file May 14 18:11:39.083880 setup-nvidia[2550]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more May 14 18:11:39.083880 setup-nvidia[2550]: information. May 14 18:11:39.083880 setup-nvidia[2550]: \u000d May 14 18:11:39.099640 setup-nvidia[2550]: dmesg: read kernel buffer failed: Operation not permitted May 14 18:11:39.119532 setup-nvidia[2550]: \u000d May 14 18:11:39.119532 setup-nvidia[2550]: ERROR: Installation has failed. Please see the file May 14 18:11:39.119532 setup-nvidia[2550]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for May 14 18:11:39.119532 setup-nvidia[2550]: details. You may find suggestions on fixing installation problems in May 14 18:11:39.119532 setup-nvidia[2550]: the README available on the Linux driver download page at May 14 18:11:39.119532 setup-nvidia[2550]: www.nvidia.com. May 14 18:11:39.119532 setup-nvidia[2550]: \u000d May 14 18:11:39.119997 setup-nvidia[2550]: Last 50 lines of nvidia-installer.log: May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build May 14 18:11:39.120792 setup-nvidia[2550]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o May 14 18:11:39.120792 setup-nvidia[2550]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o May 14 18:11:39.120792 setup-nvidia[2550]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 14 18:11:39.120792 setup-nvidia[2550]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 14 18:11:39.120792 setup-nvidia[2550]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 14 18:11:39.120792 setup-nvidia[2550]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 14 18:11:39.120792 setup-nvidia[2550]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 14 18:11:39.120792 setup-nvidia[2550]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 14 18:11:39.120792 setup-nvidia[2550]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux May 14 18:11:39.120792 setup-nvidia[2550]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 14 18:11:39.120792 setup-nvidia[2550]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux May 14 18:11:39.120792 setup-nvidia[2550]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 14 18:11:39.120792 setup-nvidia[2550]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux May 14 18:11:39.120792 setup-nvidia[2550]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 14 18:11:39.120792 setup-nvidia[2550]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux May 14 18:11:39.120792 setup-nvidia[2550]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 14 18:11:39.120792 setup-nvidia[2550]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux May 14 18:11:39.120792 setup-nvidia[2550]: make[2]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/build' May 14 18:11:39.120792 setup-nvidia[2550]: make[1]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/source' May 14 18:11:39.120792 setup-nvidia[2550]: -> done. May 14 18:11:39.120792 setup-nvidia[2550]: -> Kernel module compilation complete. May 14 18:11:39.120792 setup-nvidia[2550]: -> Unable to determine if Secure Boot is enabled: No such file or directory May 14 18:11:39.120792 setup-nvidia[2550]: 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 14 18:11:39.120792 setup-nvidia[2550]: \u000d May 14 18:11:39.122056 setup-nvidia[2550]: 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 14 18:11:39.122056 setup-nvidia[2550]: -> Kernel module load error: Operation not permitted May 14 18:11:39.122056 setup-nvidia[2550]: -> Kernel messages: May 14 18:11:39.122056 setup-nvidia[2550]: 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 14 18:11:46.860537 systemd[1]: Started sshd@23-10.200.8.37:22-10.200.16.10:39644.service - OpenSSH per-connection server daemon (10.200.16.10:39644). May 14 18:11:47.497646 sshd[12715]: Accepted publickey for core from 10.200.16.10 port 39644 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:47.499276 sshd-session[12715]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:47.503670 systemd-logind[1871]: New session 26 of user core. May 14 18:11:47.514208 systemd[1]: Started session-26.scope - Session 26 of User core. May 14 18:11:47.949566 sshd[12717]: Connection closed by 10.200.16.10 port 39644 May 14 18:11:47.949921 sshd-session[12715]: pam_unix(sshd:session): session closed for user core May 14 18:11:47.953374 systemd[1]: sshd@23-10.200.8.37:22-10.200.16.10:39644.service: Deactivated successfully. May 14 18:11:47.954888 systemd[1]: session-26.scope: Deactivated successfully. May 14 18:11:47.955557 systemd-logind[1871]: Session 26 logged out. Waiting for processes to exit. May 14 18:11:47.956526 systemd-logind[1871]: Removed session 26. May 14 18:11:48.060706 systemd[1]: Started sshd@24-10.200.8.37:22-10.200.16.10:39652.service - OpenSSH per-connection server daemon (10.200.16.10:39652). May 14 18:11:48.709818 sshd[12723]: Accepted publickey for core from 10.200.16.10 port 39652 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:48.711847 sshd-session[12723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:48.716501 systemd-logind[1871]: New session 27 of user core. May 14 18:11:48.732275 systemd[1]: Started session-27.scope - Session 27 of User core. May 14 18:11:49.160711 sshd[12725]: Connection closed by 10.200.16.10 port 39652 May 14 18:11:49.160978 sshd-session[12723]: pam_unix(sshd:session): session closed for user core May 14 18:11:49.164475 systemd[1]: sshd@24-10.200.8.37:22-10.200.16.10:39652.service: Deactivated successfully. May 14 18:11:49.166007 systemd[1]: session-27.scope: Deactivated successfully. May 14 18:11:49.166672 systemd-logind[1871]: Session 27 logged out. Waiting for processes to exit. May 14 18:11:49.167943 systemd-logind[1871]: Removed session 27. May 14 18:11:54.287119 kernel: EXT4-fs (loop6p9): unmounting filesystem 4c03bb24-ac25-4d8b-af70-04c72efb9bcb. May 14 18:11:54.287857 setup-nvidia[2550]: Container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin exited successfully. May 14 18:11:54.290262 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4334.0.0afterburn\x2d5.8.0.bin.mount: Deactivated successfully. May 14 18:11:54.339977 sudo[2534]: pam_unix(sudo:session): session closed for user root May 14 18:11:54.341905 systemd[1]: session-c2.scope: Deactivated successfully. May 14 18:11:54.342185 systemd[1]: session-c2.scope: Consumed 5min 14.165s CPU time, 892.4M memory peak. May 14 18:11:54.753318 setup-nvidia[1865]: /opt/nvidia / May 14 18:11:54.754364 setup-nvidia[1865]: / May 14 18:11:54.777591 setup-nvidia[12748]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 18:11:54.803111 kernel: IPMI message handler: version 39.2 May 14 18:11:54.806105 kernel: ipmi device interface May 14 18:11:54.813691 setup-nvidia[1865]: /opt/nvidia/current/usr/lib/modules/6.12.20-flatcar/video / May 14 18:11:54.869685 kernel: nvidia: loading out-of-tree module taints kernel. May 14 18:11:54.869815 kernel: nvidia: module license 'NVIDIA' taints kernel. May 14 18:11:54.869839 kernel: Disabling lock debugging due to kernel taint May 14 18:11:54.876821 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel May 14 18:11:54.876876 kernel: nvidia: module license taints kernel. May 14 18:11:55.264107 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 May 14 18:11:55.669411 kernel: May 14 18:11:55.669559 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) May 14 18:11:55.871637 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 May 14 18:11:55.897103 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 May 14 18:11:55.917121 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. May 14 18:11:55.961106 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. May 14 18:11:55.961185 setup-nvidia[1865]: / May 14 18:11:57.861593 setup-nvidia[12780]: Wed May 14 18:11:57 2025 May 14 18:11:57.861593 setup-nvidia[12780]: +---------------------------------------------------------------------------------------+ May 14 18:11:57.861593 setup-nvidia[12780]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | May 14 18:11:57.861593 setup-nvidia[12780]: |-----------------------------------------+----------------------+----------------------+ May 14 18:11:57.861593 setup-nvidia[12780]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | May 14 18:11:57.861593 setup-nvidia[12780]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | May 14 18:11:57.861593 setup-nvidia[12780]: | | | MIG M. | May 14 18:11:57.861593 setup-nvidia[12780]: |=========================================+======================+======================| May 14 18:11:57.865010 setup-nvidia[12780]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | May 14 18:11:57.865010 setup-nvidia[12780]: | N/A 27C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | May 14 18:11:57.865010 setup-nvidia[12780]: | | | N/A | May 14 18:11:57.865010 setup-nvidia[12780]: +-----------------------------------------+----------------------+----------------------+ May 14 18:11:57.865144 setup-nvidia[12780]: May 14 18:11:57.865144 setup-nvidia[12780]: +---------------------------------------------------------------------------------------+ May 14 18:11:57.865144 setup-nvidia[12780]: | Processes: | May 14 18:11:57.865144 setup-nvidia[12780]: | GPU GI CI PID Type Process name GPU Memory | May 14 18:11:57.865144 setup-nvidia[12780]: | ID ID Usage | May 14 18:11:57.865144 setup-nvidia[12780]: |=======================================================================================| May 14 18:11:57.865279 setup-nvidia[12780]: | No running processes found | May 14 18:11:57.865279 setup-nvidia[12780]: +---------------------------------------------------------------------------------------+ May 14 18:11:58.236606 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 14 18:11:58.241426 systemd[1]: Reached target multi-user.target - Multi-User System. May 14 18:11:58.245908 systemd[1]: Startup finished in 1.430s (firmware) + 39.245s (loader) + 3.861s (kernel) + 14.123s (initrd) + 3min 27.998s (userspace) = 4min 26.659s. May 14 18:11:59.279880 systemd[1]: Started sshd@25-10.200.8.37:22-10.200.16.10:37272.service - OpenSSH per-connection server daemon (10.200.16.10:37272). May 14 18:11:59.916855 sshd[12787]: Accepted publickey for core from 10.200.16.10 port 37272 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:11:59.918301 sshd-session[12787]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:11:59.923289 systemd-logind[1871]: New session 28 of user core. May 14 18:11:59.936213 systemd[1]: Started session-28.scope - Session 28 of User core. May 14 18:12:00.365752 sshd[12789]: Connection closed by 10.200.16.10 port 37272 May 14 18:12:00.366097 sshd-session[12787]: pam_unix(sshd:session): session closed for user core May 14 18:12:00.369487 systemd[1]: sshd@25-10.200.8.37:22-10.200.16.10:37272.service: Deactivated successfully. May 14 18:12:00.371003 systemd[1]: session-28.scope: Deactivated successfully. May 14 18:12:00.371694 systemd-logind[1871]: Session 28 logged out. Waiting for processes to exit. May 14 18:12:00.372654 systemd-logind[1871]: Removed session 28. May 14 18:12:00.478280 systemd[1]: Started sshd@26-10.200.8.37:22-10.200.16.10:37278.service - OpenSSH per-connection server daemon (10.200.16.10:37278). May 14 18:12:01.135404 sshd[12795]: Accepted publickey for core from 10.200.16.10 port 37278 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:01.136764 sshd-session[12795]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:01.141162 systemd-logind[1871]: New session 29 of user core. May 14 18:12:01.158235 systemd[1]: Started session-29.scope - Session 29 of User core. May 14 18:12:01.588861 sshd[12797]: Connection closed by 10.200.16.10 port 37278 May 14 18:12:01.589241 sshd-session[12795]: pam_unix(sshd:session): session closed for user core May 14 18:12:01.592780 systemd[1]: sshd@26-10.200.8.37:22-10.200.16.10:37278.service: Deactivated successfully. May 14 18:12:01.594365 systemd[1]: session-29.scope: Deactivated successfully. May 14 18:12:01.595050 systemd-logind[1871]: Session 29 logged out. Waiting for processes to exit. May 14 18:12:01.596006 systemd-logind[1871]: Removed session 29. May 14 18:12:01.705452 systemd[1]: Started sshd@27-10.200.8.37:22-10.200.16.10:37284.service - OpenSSH per-connection server daemon (10.200.16.10:37284). May 14 18:12:02.369852 sshd[12803]: Accepted publickey for core from 10.200.16.10 port 37284 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:02.371250 sshd-session[12803]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:02.375709 systemd-logind[1871]: New session 30 of user core. May 14 18:12:02.397273 systemd[1]: Started session-30.scope - Session 30 of User core. May 14 18:12:02.821447 sshd[12805]: Connection closed by 10.200.16.10 port 37284 May 14 18:12:02.821809 sshd-session[12803]: pam_unix(sshd:session): session closed for user core May 14 18:12:02.824568 systemd[1]: sshd@27-10.200.8.37:22-10.200.16.10:37284.service: Deactivated successfully. May 14 18:12:02.826196 systemd[1]: session-30.scope: Deactivated successfully. May 14 18:12:02.827477 systemd-logind[1871]: Session 30 logged out. Waiting for processes to exit. May 14 18:12:02.828471 systemd-logind[1871]: Removed session 30. May 14 18:12:02.937401 systemd[1]: Started sshd@28-10.200.8.37:22-10.200.16.10:37286.service - OpenSSH per-connection server daemon (10.200.16.10:37286). May 14 18:12:03.590876 sshd[12811]: Accepted publickey for core from 10.200.16.10 port 37286 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:03.592276 sshd-session[12811]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:03.596597 systemd-logind[1871]: New session 31 of user core. May 14 18:12:03.612220 systemd[1]: Started session-31.scope - Session 31 of User core. May 14 18:12:03.940670 sudo[12814]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 14 18:12:03.940973 sudo[12814]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:12:04.020351 sudo[12814]: pam_unix(sudo:session): session closed for user root May 14 18:12:04.123347 sshd[12813]: Connection closed by 10.200.16.10 port 37286 May 14 18:12:04.123775 sshd-session[12811]: pam_unix(sshd:session): session closed for user core May 14 18:12:04.127553 systemd[1]: sshd@28-10.200.8.37:22-10.200.16.10:37286.service: Deactivated successfully. May 14 18:12:04.129100 systemd[1]: session-31.scope: Deactivated successfully. May 14 18:12:04.129812 systemd-logind[1871]: Session 31 logged out. Waiting for processes to exit. May 14 18:12:04.130785 systemd-logind[1871]: Removed session 31. May 14 18:12:04.239430 systemd[1]: Started sshd@29-10.200.8.37:22-10.200.16.10:37294.service - OpenSSH per-connection server daemon (10.200.16.10:37294). May 14 18:12:04.448711 systemd[1]: Stopping user@0.service - User Manager for UID 0... May 14 18:12:04.449526 systemd[2538]: Activating special unit exit.target... May 14 18:12:04.449603 systemd[2538]: Stopped target default.target - Main User Target. May 14 18:12:04.449641 systemd[2538]: Stopped target basic.target - Basic System. May 14 18:12:04.449669 systemd[2538]: Stopped target paths.target - Paths. May 14 18:12:04.449693 systemd[2538]: Stopped target sockets.target - Sockets. May 14 18:12:04.449717 systemd[2538]: Stopped target timers.target - Timers. May 14 18:12:04.450074 systemd[2538]: Closed dbus.socket - D-Bus User Message Bus Socket. May 14 18:12:04.450336 systemd[2538]: Removed slice app.slice - User Application Slice. May 14 18:12:04.450370 systemd[2538]: Reached target shutdown.target - Shutdown. May 14 18:12:04.450503 systemd[2538]: Finished systemd-exit.service - Exit the Session. May 14 18:12:04.450540 systemd[2538]: Reached target exit.target - Exit the Session. May 14 18:12:04.464097 (sd-pam)[2539]: pam_unix(systemd-user:session): session closed for user root May 14 18:12:04.465455 systemd[1]: user@0.service: Deactivated successfully. May 14 18:12:04.465849 systemd[1]: Stopped user@0.service - User Manager for UID 0. May 14 18:12:04.468062 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 14 18:12:04.487967 systemd[1]: run-user-0.mount: Deactivated successfully. May 14 18:12:04.488872 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. May 14 18:12:04.489060 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 14 18:12:04.490573 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. May 14 18:12:04.490679 systemd[1]: user-0.slice: Consumed 5min 14.398s CPU time, 901.5M memory peak. May 14 18:12:04.491070 systemd-logind[1871]: Removed session c3. May 14 18:12:04.899101 sshd[12820]: Accepted publickey for core from 10.200.16.10 port 37294 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:04.900479 sshd-session[12820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:04.904956 systemd-logind[1871]: New session 32 of user core. May 14 18:12:04.915202 systemd[1]: Started session-32.scope - Session 32 of User core. May 14 18:12:05.247858 sudo[12828]: 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 14 18:12:05.248164 sudo[12828]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:12:05.454844 sudo[12828]: pam_unix(sudo:session): session closed for user root May 14 18:12:05.461805 sudo[12827]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 14 18:12:05.462111 sudo[12827]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:12:05.471795 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 18:12:05.680139 augenrules[12850]: No rules May 14 18:12:05.681362 systemd[1]: audit-rules.service: Deactivated successfully. May 14 18:12:05.681599 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 18:12:05.682517 sudo[12827]: pam_unix(sudo:session): session closed for user root May 14 18:12:05.786274 sshd[12826]: Connection closed by 10.200.16.10 port 37294 May 14 18:12:05.786632 sshd-session[12820]: pam_unix(sshd:session): session closed for user core May 14 18:12:05.790448 systemd[1]: sshd@29-10.200.8.37:22-10.200.16.10:37294.service: Deactivated successfully. May 14 18:12:05.792016 systemd[1]: session-32.scope: Deactivated successfully. May 14 18:12:05.792695 systemd-logind[1871]: Session 32 logged out. Waiting for processes to exit. May 14 18:12:05.793658 systemd-logind[1871]: Removed session 32. May 14 18:12:05.903436 systemd[1]: Started sshd@30-10.200.8.37:22-10.200.16.10:37306.service - OpenSSH per-connection server daemon (10.200.16.10:37306). May 14 18:12:06.542726 sshd[12859]: Accepted publickey for core from 10.200.16.10 port 37306 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:06.544109 sshd-session[12859]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:06.549034 systemd-logind[1871]: New session 33 of user core. May 14 18:12:06.564203 systemd[1]: Started session-33.scope - Session 33 of User core. May 14 18:12:06.891954 sudo[12862]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . May 14 18:12:06.892263 sudo[12862]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:12:06.895966 sudo[12862]: pam_unix(sudo:session): session closed for user root May 14 18:12:07.003547 sshd[12861]: Connection closed by 10.200.16.10 port 37306 May 14 18:12:07.003912 sshd-session[12859]: pam_unix(sshd:session): session closed for user core May 14 18:12:07.007431 systemd[1]: sshd@30-10.200.8.37:22-10.200.16.10:37306.service: Deactivated successfully. May 14 18:12:07.008997 systemd[1]: session-33.scope: Deactivated successfully. May 14 18:12:07.009654 systemd-logind[1871]: Session 33 logged out. Waiting for processes to exit. May 14 18:12:07.010590 systemd-logind[1871]: Removed session 33. May 14 18:12:07.119535 systemd[1]: Started sshd@31-10.200.8.37:22-10.200.16.10:37316.service - OpenSSH per-connection server daemon (10.200.16.10:37316). May 14 18:12:07.756390 sshd[12868]: Accepted publickey for core from 10.200.16.10 port 37316 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:07.757739 sshd-session[12868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:07.762037 systemd-logind[1871]: New session 34 of user core. May 14 18:12:07.772209 systemd[1]: Started session-34.scope - Session 34 of User core. May 14 18:12:08.105959 sudo[12871]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet May 14 18:12:08.106267 sudo[12871]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:12:11.441360 sudo[12871]: pam_unix(sudo:session): session closed for user root May 14 18:12:11.548712 sshd[12870]: Connection closed by 10.200.16.10 port 37316 May 14 18:12:11.549042 sshd-session[12868]: pam_unix(sshd:session): session closed for user core May 14 18:12:11.552822 systemd[1]: sshd@31-10.200.8.37:22-10.200.16.10:37316.service: Deactivated successfully. May 14 18:12:11.554388 systemd[1]: session-34.scope: Deactivated successfully. May 14 18:12:11.555044 systemd-logind[1871]: Session 34 logged out. Waiting for processes to exit. May 14 18:12:11.556019 systemd-logind[1871]: Removed session 34. May 14 18:12:11.672531 systemd[1]: Started sshd@32-10.200.8.37:22-10.200.16.10:50038.service - OpenSSH per-connection server daemon (10.200.16.10:50038). May 14 18:12:12.320044 sshd[12877]: Accepted publickey for core from 10.200.16.10 port 50038 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:12.321394 sshd-session[12877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:12.325804 systemd-logind[1871]: New session 35 of user core. May 14 18:12:12.342224 systemd[1]: Started session-35.scope - Session 35 of User core. May 14 18:12:12.852453 sshd[12879]: Connection closed by 10.200.16.10 port 50038 May 14 18:12:12.852824 sshd-session[12877]: pam_unix(sshd:session): session closed for user core May 14 18:12:12.856345 systemd[1]: sshd@32-10.200.8.37:22-10.200.16.10:50038.service: Deactivated successfully. May 14 18:12:12.857885 systemd[1]: session-35.scope: Deactivated successfully. May 14 18:12:12.858559 systemd-logind[1871]: Session 35 logged out. Waiting for processes to exit. May 14 18:12:12.859561 systemd-logind[1871]: Removed session 35. May 14 18:12:12.976600 systemd[1]: Started sshd@33-10.200.8.37:22-10.200.16.10:50054.service - OpenSSH per-connection server daemon (10.200.16.10:50054). May 14 18:12:13.610974 sshd[12894]: Accepted publickey for core from 10.200.16.10 port 50054 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:13.612315 sshd-session[12894]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:13.619613 systemd-logind[1871]: New session 36 of user core. May 14 18:12:13.638231 systemd[1]: Started session-36.scope - Session 36 of User core. May 14 18:12:14.798117 systemd[1]: Starting docker.service - Docker Application Container Engine... May 14 18:12:14.811438 (dockerd)[12919]: 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 14 18:12:16.074166 dockerd[12919]: time="2025-05-14T18:12:16.074106210Z" level=info msg="Starting up" May 14 18:12:16.077015 dockerd[12919]: time="2025-05-14T18:12:16.074796681Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 14 18:12:16.543938 dockerd[12919]: time="2025-05-14T18:12:16.543887542Z" level=info msg="Loading containers: start." May 14 18:12:16.557107 kernel: Initializing XFRM netlink socket May 14 18:12:16.847989 systemd-networkd[1582]: docker0: Link UP May 14 18:12:16.868898 dockerd[12919]: time="2025-05-14T18:12:16.868861536Z" level=info msg="Loading containers: done." May 14 18:12:16.890301 dockerd[12919]: time="2025-05-14T18:12:16.890255841Z" 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 14 18:12:16.890427 dockerd[12919]: time="2025-05-14T18:12:16.890326938Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 14 18:12:16.890463 dockerd[12919]: time="2025-05-14T18:12:16.890428633Z" level=info msg="Initializing buildkit" May 14 18:12:16.937328 dockerd[12919]: time="2025-05-14T18:12:16.937294771Z" level=info msg="Completed buildkit initialization" May 14 18:12:16.942192 dockerd[12919]: time="2025-05-14T18:12:16.942166867Z" level=info msg="Daemon has completed initialization" May 14 18:12:16.942335 dockerd[12919]: time="2025-05-14T18:12:16.942210965Z" level=info msg="API listen on /run/docker.sock" May 14 18:12:16.942362 systemd[1]: Started docker.service - Docker Application Container Engine. May 14 18:12:18.056777 systemd[1]: var-lib-docker-overlay2-7f25cc25ddec23bc33eb6c590de45f48680db9d3abda0714a7ea6399cb096de9\x2dinit-merged.mount: Deactivated successfully. May 14 18:12:18.190635 containerd[1882]: time="2025-05-14T18:12:18.190588600Z" level=info msg="connecting to shim 6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5" address="unix:///run/containerd/s/2ac9da69283620a7955e220f28604ea1bcc34c63bfc3cafe1d1a7de420d31bb5" namespace=moby protocol=ttrpc version=3 May 14 18:12:18.216211 systemd[1]: Started docker-6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5.scope - libcontainer container 6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5. May 14 18:12:18.262077 kernel: docker0: port 1(veth8f5d96e) entered blocking state May 14 18:12:18.262173 kernel: docker0: port 1(veth8f5d96e) entered disabled state May 14 18:12:18.262197 kernel: veth8f5d96e: entered allmulticast mode May 14 18:12:18.267074 kernel: veth8f5d96e: entered promiscuous mode May 14 18:12:18.267555 systemd-networkd[1582]: veth8f5d96e: Link UP May 14 18:12:18.291103 kernel: eth0: renamed from veth3757fdb May 14 18:12:18.297918 kernel: docker0: port 1(veth8f5d96e) entered blocking state May 14 18:12:18.297966 kernel: docker0: port 1(veth8f5d96e) entered forwarding state May 14 18:12:18.298360 systemd-networkd[1582]: veth8f5d96e: Gained carrier May 14 18:12:18.298660 systemd-networkd[1582]: docker0: Gained carrier May 14 18:12:19.852232 systemd-networkd[1582]: veth8f5d96e: Gained IPv6LL May 14 18:12:20.236226 systemd-networkd[1582]: docker0: Gained IPv6LL May 14 18:12:21.582739 systemd[1]: docker-6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5.scope: Deactivated successfully. May 14 18:12:21.595307 containerd[1882]: time="2025-05-14T18:12:21.595222639Z" level=info msg="shim disconnected" id=6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5 namespace=moby May 14 18:12:21.595307 containerd[1882]: time="2025-05-14T18:12:21.595256637Z" level=warning msg="cleaning up after shim disconnected" id=6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5 namespace=moby May 14 18:12:21.595307 containerd[1882]: time="2025-05-14T18:12:21.595264737Z" level=info msg="cleaning up dead shim" namespace=moby May 14 18:12:21.595661 dockerd[12919]: time="2025-05-14T18:12:21.595489928Z" level=info msg="ignoring event" container=6223b5f04007239cfd27474bf2e58ee95cb453477d300c9320212f52aa4da9f5 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 18:12:21.625109 kernel: docker0: port 1(veth8f5d96e) entered disabled state May 14 18:12:21.625195 kernel: veth3757fdb: renamed from eth0 May 14 18:12:21.625104 systemd-networkd[1582]: veth8f5d96e: Lost carrier May 14 18:12:21.650326 systemd-networkd[1582]: veth8f5d96e: Link DOWN May 14 18:12:21.657726 kernel: docker0: port 1(veth8f5d96e) entered disabled state May 14 18:12:21.657783 kernel: veth8f5d96e (unregistering): left allmulticast mode May 14 18:12:21.658157 kernel: veth8f5d96e (unregistering): left promiscuous mode May 14 18:12:21.664290 kernel: docker0: port 1(veth8f5d96e) entered disabled state May 14 18:12:21.689528 systemd[1]: run-docker-netns-7452c4ab5ddd.mount: Deactivated successfully. May 14 18:12:21.693321 systemd[1]: var-lib-docker-overlay2-7f25cc25ddec23bc33eb6c590de45f48680db9d3abda0714a7ea6399cb096de9-merged.mount: Deactivated successfully. May 14 18:12:21.836977 sshd[12896]: Connection closed by 10.200.16.10 port 50054 May 14 18:12:21.837279 sshd-session[12894]: pam_unix(sshd:session): session closed for user core May 14 18:12:21.840836 systemd[1]: sshd@33-10.200.8.37:22-10.200.16.10:50054.service: Deactivated successfully. May 14 18:12:21.842531 systemd[1]: session-36.scope: Deactivated successfully. May 14 18:12:21.843197 systemd-logind[1871]: Session 36 logged out. Waiting for processes to exit. May 14 18:12:21.844354 systemd-logind[1871]: Removed session 36. May 14 18:12:21.948368 systemd[1]: Started sshd@34-10.200.8.37:22-10.200.16.10:53280.service - OpenSSH per-connection server daemon (10.200.16.10:53280). May 14 18:12:22.583272 sshd[13202]: Accepted publickey for core from 10.200.16.10 port 53280 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:22.584615 sshd-session[13202]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:22.589027 systemd-logind[1871]: New session 37 of user core. May 14 18:12:22.600214 systemd[1]: Started session-37.scope - Session 37 of User core. May 14 18:12:22.668349 systemd-networkd[1582]: docker0: Lost carrier May 14 18:12:22.990489 systemd[1]: var-lib-docker-overlay2-1fa34dc68d02bf7f3a2c83f3a6e1c80e1c6e8d44f31648453015ce1176edf44f\x2dinit-merged.mount: Deactivated successfully. May 14 18:12:23.084183 containerd[1882]: time="2025-05-14T18:12:23.084135042Z" level=info msg="connecting to shim a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7" address="unix:///run/containerd/s/e079cbf21a14a6de3578d917fa3857fde1102625e3bba8c8aee4db5f0aa64f21" namespace=moby protocol=ttrpc version=3 May 14 18:12:23.113226 systemd[1]: Started docker-a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7.scope - libcontainer container a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7. May 14 18:12:23.156341 kernel: docker0: port 1(veth69c9c75) entered blocking state May 14 18:12:23.156429 kernel: docker0: port 1(veth69c9c75) entered disabled state May 14 18:12:23.159203 kernel: veth69c9c75: entered allmulticast mode May 14 18:12:23.161998 kernel: veth69c9c75: entered promiscuous mode May 14 18:12:23.162545 systemd-networkd[1582]: veth69c9c75: Link UP May 14 18:12:23.184102 kernel: eth0: renamed from veth69a87e5 May 14 18:12:23.191679 kernel: docker0: port 1(veth69c9c75) entered blocking state May 14 18:12:23.191746 kernel: docker0: port 1(veth69c9c75) entered forwarding state May 14 18:12:23.192074 systemd-networkd[1582]: veth69c9c75: Gained carrier May 14 18:12:23.192344 systemd-networkd[1582]: docker0: Gained carrier May 14 18:12:23.238663 systemd[1]: docker-a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7.scope: Deactivated successfully. May 14 18:12:23.257425 dockerd[12919]: time="2025-05-14T18:12:23.257342330Z" level=info msg="ignoring event" container=a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 18:12:23.257695 containerd[1882]: time="2025-05-14T18:12:23.257536722Z" level=info msg="shim disconnected" id=a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7 namespace=moby May 14 18:12:23.257695 containerd[1882]: time="2025-05-14T18:12:23.257565720Z" level=warning msg="cleaning up after shim disconnected" id=a87db02f0a555dae2a6925c3017cc5a346db3f4a6b7a566a97619dd8882a0cb7 namespace=moby May 14 18:12:23.257695 containerd[1882]: time="2025-05-14T18:12:23.257574420Z" level=info msg="cleaning up dead shim" namespace=moby May 14 18:12:23.289548 systemd-networkd[1582]: veth69c9c75: Lost carrier May 14 18:12:23.290128 kernel: docker0: port 1(veth69c9c75) entered disabled state May 14 18:12:23.290216 kernel: veth69a87e5: renamed from eth0 May 14 18:12:23.315747 systemd-networkd[1582]: veth69c9c75: Link DOWN May 14 18:12:23.322512 kernel: docker0: port 1(veth69c9c75) entered disabled state May 14 18:12:23.322557 kernel: veth69c9c75 (unregistering): left allmulticast mode May 14 18:12:23.325764 kernel: veth69c9c75 (unregistering): left promiscuous mode May 14 18:12:23.329075 kernel: docker0: port 1(veth69c9c75) entered disabled state May 14 18:12:23.477826 sshd[13204]: Connection closed by 10.200.16.10 port 53280 May 14 18:12:23.478203 sshd-session[13202]: pam_unix(sshd:session): session closed for user core May 14 18:12:23.481611 systemd[1]: sshd@34-10.200.8.37:22-10.200.16.10:53280.service: Deactivated successfully. May 14 18:12:23.483207 systemd[1]: session-37.scope: Deactivated successfully. May 14 18:12:23.483835 systemd-logind[1871]: Session 37 logged out. Waiting for processes to exit. May 14 18:12:23.484751 systemd-logind[1871]: Removed session 37. May 14 18:12:23.601380 systemd[1]: Started sshd@35-10.200.8.37:22-10.200.16.10:53286.service - OpenSSH per-connection server daemon (10.200.16.10:53286). May 14 18:12:23.676397 systemd-networkd[1582]: docker0: Lost carrier May 14 18:12:23.987356 systemd[1]: var-lib-docker-overlay2-1fa34dc68d02bf7f3a2c83f3a6e1c80e1c6e8d44f31648453015ce1176edf44f-merged.mount: Deactivated successfully. May 14 18:12:24.259489 sshd[13304]: Accepted publickey for core from 10.200.16.10 port 53286 ssh2: RSA SHA256:78WAJNOTTv1Xdtha3n7EbOR0MUdYruMVBYaYjO61xRs May 14 18:12:24.260692 sshd-session[13304]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:12:24.264822 systemd-logind[1871]: New session 38 of user core. May 14 18:12:24.271204 systemd[1]: Started session-38.scope - Session 38 of User core. May 14 18:12:24.671703 ntpdate[13317]: ntpdate 4.2.8p17@1.4004-o Wed May 14 16:07:00 UTC 2025 (1) May 14 18:12:31.403500 ntpdate[13317]: adjust time server 193.1.8.98 offset -0.000222 sec May 14 18:12:31.508665 sshd[13306]: Connection closed by 10.200.16.10 port 53286 May 14 18:12:31.509044 sshd-session[13304]: pam_unix(sshd:session): session closed for user core May 14 18:12:31.512567 systemd[1]: sshd@35-10.200.8.37:22-10.200.16.10:53286.service: Deactivated successfully. May 14 18:12:31.514346 systemd[1]: session-38.scope: Deactivated successfully. May 14 18:12:31.515105 systemd-logind[1871]: Session 38 logged out. Waiting for processes to exit. May 14 18:12:31.516064 systemd-logind[1871]: Removed session 38.