Jun 20 18:49:02.222118 kernel: Linux version 6.6.94-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Fri Jun 20 17:12:40 -00 2025 Jun 20 18:49:02.222148 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:02.222158 kernel: BIOS-provided physical RAM map: Jun 20 18:49:02.222165 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 18:49:02.222172 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jun 20 18:49:02.222179 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jun 20 18:49:02.222186 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jun 20 18:49:02.222196 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jun 20 18:49:02.222203 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jun 20 18:49:02.222210 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jun 20 18:49:02.222217 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jun 20 18:49:02.222224 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jun 20 18:49:02.222231 kernel: printk: bootconsole [earlyser0] enabled Jun 20 18:49:02.222238 kernel: NX (Execute Disable) protection: active Jun 20 18:49:02.222249 kernel: APIC: Static calls initialized Jun 20 18:49:02.222257 kernel: efi: EFI v2.7 by Microsoft Jun 20 18:49:02.222265 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bea98 RNG=0x3ffd1018 Jun 20 18:49:02.222272 kernel: random: crng init done Jun 20 18:49:02.222280 kernel: secureboot: Secure boot disabled Jun 20 18:49:02.222287 kernel: SMBIOS 3.1.0 present. Jun 20 18:49:02.222295 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jun 20 18:49:02.222303 kernel: Hypervisor detected: Microsoft Hyper-V Jun 20 18:49:02.222310 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jun 20 18:49:02.222318 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jun 20 18:49:02.222328 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jun 20 18:49:02.222335 kernel: Hyper-V: Using hypercall for remote TLB flush Jun 20 18:49:02.222343 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 18:49:02.222351 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 18:49:02.222359 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jun 20 18:49:02.222367 kernel: tsc: Detected 2593.991 MHz processor Jun 20 18:49:02.222375 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 20 18:49:02.222383 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 20 18:49:02.222391 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jun 20 18:49:02.222401 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jun 20 18:49:02.222421 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 20 18:49:02.222429 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jun 20 18:49:02.222437 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jun 20 18:49:02.222445 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jun 20 18:49:02.222452 kernel: Using GB pages for direct mapping Jun 20 18:49:02.222460 kernel: ACPI: Early table checksum verification disabled Jun 20 18:49:02.222472 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jun 20 18:49:02.222483 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222491 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222500 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jun 20 18:49:02.222508 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jun 20 18:49:02.222516 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222525 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222535 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222544 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222552 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222560 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222568 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:02.222577 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jun 20 18:49:02.222585 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jun 20 18:49:02.222593 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jun 20 18:49:02.222604 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jun 20 18:49:02.222612 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jun 20 18:49:02.222620 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jun 20 18:49:02.222628 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jun 20 18:49:02.222636 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jun 20 18:49:02.222645 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jun 20 18:49:02.222653 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jun 20 18:49:02.222661 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jun 20 18:49:02.222669 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jun 20 18:49:02.222680 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Jun 20 18:49:02.222688 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Jun 20 18:49:02.222696 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Jun 20 18:49:02.222704 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Jun 20 18:49:02.222712 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jun 20 18:49:02.222721 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jun 20 18:49:02.222729 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jun 20 18:49:02.222737 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jun 20 18:49:02.222745 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jun 20 18:49:02.222756 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jun 20 18:49:02.222764 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jun 20 18:49:02.222773 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jun 20 18:49:02.222781 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Jun 20 18:49:02.222789 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Jun 20 18:49:02.222798 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Jun 20 18:49:02.222806 kernel: Zone ranges: Jun 20 18:49:02.222814 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 20 18:49:02.222823 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 20 18:49:02.222833 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jun 20 18:49:02.222842 kernel: Movable zone start for each node Jun 20 18:49:02.222850 kernel: Early memory node ranges Jun 20 18:49:02.222858 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 20 18:49:02.222866 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jun 20 18:49:02.222874 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jun 20 18:49:02.222882 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jun 20 18:49:02.222890 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jun 20 18:49:02.222898 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jun 20 18:49:02.222909 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 18:49:02.222917 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 20 18:49:02.222926 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jun 20 18:49:02.222934 kernel: ACPI: PM-Timer IO Port: 0x408 Jun 20 18:49:02.222942 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jun 20 18:49:02.222950 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jun 20 18:49:02.222958 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 20 18:49:02.222967 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 20 18:49:02.222975 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jun 20 18:49:02.222986 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Jun 20 18:49:02.222994 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jun 20 18:49:02.223002 kernel: Booting paravirtualized kernel on Hyper-V Jun 20 18:49:02.223011 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 20 18:49:02.223019 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jun 20 18:49:02.223027 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Jun 20 18:49:02.223035 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Jun 20 18:49:02.223043 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jun 20 18:49:02.223052 kernel: Hyper-V: PV spinlocks enabled Jun 20 18:49:02.223063 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 20 18:49:02.223072 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:02.223081 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 20 18:49:02.223089 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jun 20 18:49:02.223098 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jun 20 18:49:02.223106 kernel: Fallback order for Node 0: 0 Jun 20 18:49:02.223114 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Jun 20 18:49:02.223122 kernel: Policy zone: Normal Jun 20 18:49:02.223133 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 20 18:49:02.223150 kernel: software IO TLB: area num 8. Jun 20 18:49:02.223159 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43488K init, 1588K bss, 2103120K reserved, 0K cma-reserved) Jun 20 18:49:02.223170 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jun 20 18:49:02.223179 kernel: Kernel/User page tables isolation: enabled Jun 20 18:49:02.223187 kernel: ftrace: allocating 37938 entries in 149 pages Jun 20 18:49:02.223196 kernel: ftrace: allocated 149 pages with 4 groups Jun 20 18:49:02.223204 kernel: Dynamic Preempt: voluntary Jun 20 18:49:02.223213 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 20 18:49:02.223222 kernel: rcu: RCU event tracing is enabled. Jun 20 18:49:02.223231 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jun 20 18:49:02.223240 kernel: Trampoline variant of Tasks RCU enabled. Jun 20 18:49:02.223251 kernel: Rude variant of Tasks RCU enabled. Jun 20 18:49:02.223260 kernel: Tracing variant of Tasks RCU enabled. Jun 20 18:49:02.223269 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 20 18:49:02.223277 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jun 20 18:49:02.223289 kernel: Using NULL legacy PIC Jun 20 18:49:02.223297 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jun 20 18:49:02.223306 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 20 18:49:02.223315 kernel: Console: colour dummy device 80x25 Jun 20 18:49:02.223323 kernel: printk: console [tty1] enabled Jun 20 18:49:02.223332 kernel: printk: console [ttyS0] enabled Jun 20 18:49:02.223341 kernel: printk: bootconsole [earlyser0] disabled Jun 20 18:49:02.223349 kernel: ACPI: Core revision 20230628 Jun 20 18:49:02.223358 kernel: Failed to register legacy timer interrupt Jun 20 18:49:02.223367 kernel: APIC: Switch to symmetric I/O mode setup Jun 20 18:49:02.223378 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jun 20 18:49:02.223387 kernel: Hyper-V: Using IPI hypercalls Jun 20 18:49:02.223395 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jun 20 18:49:02.223404 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jun 20 18:49:02.223420 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jun 20 18:49:02.223429 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jun 20 18:49:02.223438 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jun 20 18:49:02.223446 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jun 20 18:49:02.223455 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jun 20 18:49:02.223466 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jun 20 18:49:02.223475 kernel: APIC: read() replaced with hv_apic_read() Jun 20 18:49:02.223484 kernel: APIC: write() replaced with hv_apic_write() Jun 20 18:49:02.223492 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jun 20 18:49:02.223501 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jun 20 18:49:02.223510 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593991) Jun 20 18:49:02.223518 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jun 20 18:49:02.223527 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jun 20 18:49:02.223536 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 20 18:49:02.223546 kernel: Spectre V2 : Mitigation: Retpolines Jun 20 18:49:02.223555 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 20 18:49:02.223563 kernel: Speculative Store Bypass: Vulnerable Jun 20 18:49:02.223572 kernel: MDS: Mitigation: Clear CPU buffers Jun 20 18:49:02.223580 kernel: TAA: Mitigation: Clear CPU buffers Jun 20 18:49:02.223588 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jun 20 18:49:02.223597 kernel: ITS: Mitigation: Aligned branch/return thunks Jun 20 18:49:02.223605 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 20 18:49:02.223614 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 20 18:49:02.223622 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 20 18:49:02.223630 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 20 18:49:02.223641 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 20 18:49:02.223650 kernel: Freeing SMP alternatives memory: 32K Jun 20 18:49:02.223658 kernel: pid_max: default: 32768 minimum: 301 Jun 20 18:49:02.223667 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jun 20 18:49:02.223675 kernel: landlock: Up and running. Jun 20 18:49:02.223684 kernel: SELinux: Initializing. Jun 20 18:49:02.223692 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 18:49:02.223701 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 18:49:02.223709 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jun 20 18:49:02.223718 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:02.223727 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:02.223738 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:02.223747 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jun 20 18:49:02.223756 kernel: signal: max sigframe size: 1776 Jun 20 18:49:02.223764 kernel: rcu: Hierarchical SRCU implementation. Jun 20 18:49:02.223773 kernel: rcu: Max phase no-delay instances is 400. Jun 20 18:49:02.223781 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 20 18:49:02.223790 kernel: smp: Bringing up secondary CPUs ... Jun 20 18:49:02.223799 kernel: smpboot: x86: Booting SMP configuration: Jun 20 18:49:02.223807 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jun 20 18:49:02.223818 kernel: smp: Brought up 1 node, 6 CPUs Jun 20 18:49:02.223827 kernel: smpboot: Max logical packages: 1 Jun 20 18:49:02.223835 kernel: smpboot: Total of 6 processors activated (31127.89 BogoMIPS) Jun 20 18:49:02.223844 kernel: devtmpfs: initialized Jun 20 18:49:02.223853 kernel: x86/mm: Memory block size: 1024MB Jun 20 18:49:02.223861 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jun 20 18:49:02.223870 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 20 18:49:02.223879 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jun 20 18:49:02.223887 kernel: pinctrl core: initialized pinctrl subsystem Jun 20 18:49:02.223898 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 20 18:49:02.223907 kernel: audit: initializing netlink subsys (disabled) Jun 20 18:49:02.223916 kernel: audit: type=2000 audit(1750445341.029:1): state=initialized audit_enabled=0 res=1 Jun 20 18:49:02.223924 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 20 18:49:02.223933 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 20 18:49:02.223941 kernel: cpuidle: using governor menu Jun 20 18:49:02.223950 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 20 18:49:02.223958 kernel: dca service started, version 1.12.1 Jun 20 18:49:02.223967 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jun 20 18:49:02.223978 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 20 18:49:02.223987 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 20 18:49:02.223996 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 20 18:49:02.224004 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 20 18:49:02.224013 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 20 18:49:02.224021 kernel: ACPI: Added _OSI(Module Device) Jun 20 18:49:02.224030 kernel: ACPI: Added _OSI(Processor Device) Jun 20 18:49:02.224039 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 20 18:49:02.224047 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 20 18:49:02.224058 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jun 20 18:49:02.224067 kernel: ACPI: Interpreter enabled Jun 20 18:49:02.224081 kernel: ACPI: PM: (supports S0 S5) Jun 20 18:49:02.224092 kernel: ACPI: Using IOAPIC for interrupt routing Jun 20 18:49:02.224100 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 20 18:49:02.224109 kernel: PCI: Ignoring E820 reservations for host bridge windows Jun 20 18:49:02.224118 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jun 20 18:49:02.224127 kernel: iommu: Default domain type: Translated Jun 20 18:49:02.224135 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 20 18:49:02.224147 kernel: efivars: Registered efivars operations Jun 20 18:49:02.224156 kernel: PCI: Using ACPI for IRQ routing Jun 20 18:49:02.224165 kernel: PCI: System does not support PCI Jun 20 18:49:02.224174 kernel: vgaarb: loaded Jun 20 18:49:02.224182 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jun 20 18:49:02.224191 kernel: VFS: Disk quotas dquot_6.6.0 Jun 20 18:49:02.224200 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 20 18:49:02.224208 kernel: pnp: PnP ACPI init Jun 20 18:49:02.224217 kernel: pnp: PnP ACPI: found 3 devices Jun 20 18:49:02.224228 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 20 18:49:02.224237 kernel: NET: Registered PF_INET protocol family Jun 20 18:49:02.224246 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 20 18:49:02.224255 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jun 20 18:49:02.224263 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 20 18:49:02.224272 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 20 18:49:02.224281 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:02.224290 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jun 20 18:49:02.224301 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:02.224310 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:02.224318 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 20 18:49:02.224327 kernel: NET: Registered PF_XDP protocol family Jun 20 18:49:02.224336 kernel: PCI: CLS 0 bytes, default 64 Jun 20 18:49:02.224345 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 20 18:49:02.224353 kernel: software IO TLB: mapped [mem 0x000000003b5be000-0x000000003f5be000] (64MB) Jun 20 18:49:02.224362 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jun 20 18:49:02.224371 kernel: Initialise system trusted keyrings Jun 20 18:49:02.224382 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jun 20 18:49:02.224390 kernel: Key type asymmetric registered Jun 20 18:49:02.224399 kernel: Asymmetric key parser 'x509' registered Jun 20 18:49:02.224415 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jun 20 18:49:02.224424 kernel: io scheduler mq-deadline registered Jun 20 18:49:02.224432 kernel: io scheduler kyber registered Jun 20 18:49:02.224441 kernel: io scheduler bfq registered Jun 20 18:49:02.224450 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 20 18:49:02.224458 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 20 18:49:02.224467 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 20 18:49:02.224479 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jun 20 18:49:02.224488 kernel: i8042: PNP: No PS/2 controller found. Jun 20 18:49:02.224648 kernel: rtc_cmos 00:02: registered as rtc0 Jun 20 18:49:02.224760 kernel: rtc_cmos 00:02: setting system clock to 2025-06-20T18:49:01 UTC (1750445341) Jun 20 18:49:02.224864 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jun 20 18:49:02.224877 kernel: intel_pstate: CPU model not supported Jun 20 18:49:02.224886 kernel: efifb: probing for efifb Jun 20 18:49:02.224899 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jun 20 18:49:02.224908 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jun 20 18:49:02.224917 kernel: efifb: scrolling: redraw Jun 20 18:49:02.224926 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 20 18:49:02.224935 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 18:49:02.224943 kernel: fb0: EFI VGA frame buffer device Jun 20 18:49:02.224952 kernel: pstore: Using crash dump compression: deflate Jun 20 18:49:02.224961 kernel: pstore: Registered efi_pstore as persistent store backend Jun 20 18:49:02.224969 kernel: NET: Registered PF_INET6 protocol family Jun 20 18:49:02.224981 kernel: Segment Routing with IPv6 Jun 20 18:49:02.224989 kernel: In-situ OAM (IOAM) with IPv6 Jun 20 18:49:02.224998 kernel: NET: Registered PF_PACKET protocol family Jun 20 18:49:02.225006 kernel: Key type dns_resolver registered Jun 20 18:49:02.225015 kernel: IPI shorthand broadcast: enabled Jun 20 18:49:02.225024 kernel: sched_clock: Marking stable (1634005000, 104243000)->(2075938100, -337690100) Jun 20 18:49:02.225032 kernel: registered taskstats version 1 Jun 20 18:49:02.225041 kernel: Loading compiled-in X.509 certificates Jun 20 18:49:02.225050 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.94-flatcar: 583832681762bbd3c2cbcca308896cbba88c4497' Jun 20 18:49:02.225061 kernel: Key type .fscrypt registered Jun 20 18:49:02.225069 kernel: Key type fscrypt-provisioning registered Jun 20 18:49:02.225078 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 20 18:49:02.225087 kernel: ima: Allocated hash algorithm: sha1 Jun 20 18:49:02.225095 kernel: ima: No architecture policies found Jun 20 18:49:02.225104 kernel: clk: Disabling unused clocks Jun 20 18:49:02.225112 kernel: Freeing unused kernel image (initmem) memory: 43488K Jun 20 18:49:02.225121 kernel: Write protecting the kernel read-only data: 38912k Jun 20 18:49:02.225130 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jun 20 18:49:02.225141 kernel: Run /init as init process Jun 20 18:49:02.225150 kernel: with arguments: Jun 20 18:49:02.225158 kernel: /init Jun 20 18:49:02.225167 kernel: with environment: Jun 20 18:49:02.225175 kernel: HOME=/ Jun 20 18:49:02.225183 kernel: TERM=linux Jun 20 18:49:02.225192 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 20 18:49:02.225202 systemd[1]: Successfully made /usr/ read-only. Jun 20 18:49:02.225213 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) Jun 20 18:49:02.225225 systemd[1]: Detected virtualization microsoft. Jun 20 18:49:02.225234 systemd[1]: Detected architecture x86-64. Jun 20 18:49:02.225243 systemd[1]: Running in initrd. Jun 20 18:49:02.225252 systemd[1]: No hostname configured, using default hostname. Jun 20 18:49:02.225261 systemd[1]: Hostname set to . Jun 20 18:49:02.225270 systemd[1]: Initializing machine ID from random generator. Jun 20 18:49:02.225279 systemd[1]: Queued start job for default target initrd.target. Jun 20 18:49:02.225291 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:02.225300 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:02.225310 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 20 18:49:02.225319 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 18:49:02.225329 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 20 18:49:02.225339 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 20 18:49:02.225349 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 20 18:49:02.225361 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 20 18:49:02.225370 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:02.225379 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:02.225388 systemd[1]: Reached target paths.target - Path Units. Jun 20 18:49:02.225398 systemd[1]: Reached target slices.target - Slice Units. Jun 20 18:49:02.225418 systemd[1]: Reached target swap.target - Swaps. Jun 20 18:49:02.225428 systemd[1]: Reached target timers.target - Timer Units. Jun 20 18:49:02.225438 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 18:49:02.225450 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 18:49:02.225459 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 20 18:49:02.225468 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 20 18:49:02.225478 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:02.225487 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:02.225496 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:02.225505 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 18:49:02.225514 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 20 18:49:02.225523 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 18:49:02.225535 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 20 18:49:02.225544 systemd[1]: Starting systemd-fsck-usr.service... Jun 20 18:49:02.225554 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 18:49:02.225563 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 18:49:02.225592 systemd-journald[205]: Collecting audit messages is disabled. Jun 20 18:49:02.225618 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:02.225628 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 20 18:49:02.225638 systemd-journald[205]: Journal started Jun 20 18:49:02.225658 systemd-journald[205]: Runtime Journal (/run/log/journal/99b69ad8fcae4ae5a77ed22f81b5a1dd) is 8M, max 2.2G, 2.1G free. Jun 20 18:49:02.233562 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 18:49:02.234650 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:02.235220 systemd[1]: Finished systemd-fsck-usr.service. Jun 20 18:49:02.237561 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 18:49:02.238709 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 18:49:02.245716 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 18:49:02.247107 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 18:49:02.300005 systemd-modules-load[207]: Inserted module 'overlay' Jun 20 18:49:02.303965 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:02.305715 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:02.329254 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:02.352427 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 20 18:49:02.353586 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:02.366910 systemd-modules-load[207]: Inserted module 'br_netfilter' Jun 20 18:49:02.367424 kernel: Bridge firewalling registered Jun 20 18:49:02.367820 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:02.369371 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 18:49:02.392230 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:02.393620 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 18:49:02.395482 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:02.405841 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 20 18:49:02.424521 dracut-cmdline[242]: dracut-dracut-053 Jun 20 18:49:02.427343 dracut-cmdline[242]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:02.440005 systemd-resolved[240]: Positive Trust Anchors: Jun 20 18:49:02.440013 systemd-resolved[240]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 18:49:02.440042 systemd-resolved[240]: 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 Jun 20 18:49:02.442551 systemd-resolved[240]: Defaulting to hostname 'linux'. Jun 20 18:49:02.443641 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 18:49:02.455299 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:02.552434 kernel: SCSI subsystem initialized Jun 20 18:49:02.563430 kernel: Loading iSCSI transport class v2.0-870. Jun 20 18:49:02.575429 kernel: iscsi: registered transport (tcp) Jun 20 18:49:02.599331 kernel: iscsi: registered transport (qla4xxx) Jun 20 18:49:02.599360 kernel: QLogic iSCSI HBA Driver Jun 20 18:49:02.640315 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 20 18:49:02.657555 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 20 18:49:02.688359 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 20 18:49:02.688392 kernel: device-mapper: uevent: version 1.0.3 Jun 20 18:49:02.693254 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jun 20 18:49:02.735424 kernel: raid6: avx2x4 gen() 30736 MB/s Jun 20 18:49:02.755423 kernel: raid6: avx2x2 gen() 30996 MB/s Jun 20 18:49:02.776555 kernel: raid6: avx2x1 gen() 28038 MB/s Jun 20 18:49:02.776584 kernel: raid6: using algorithm avx2x2 gen() 30996 MB/s Jun 20 18:49:02.799613 kernel: raid6: .... xor() 17836 MB/s, rmw enabled Jun 20 18:49:02.799640 kernel: raid6: using avx2x2 recovery algorithm Jun 20 18:49:02.823430 kernel: xor: automatically using best checksumming function avx Jun 20 18:49:02.977441 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 20 18:49:02.988080 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 20 18:49:03.003593 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:03.025173 systemd-udevd[427]: Using default interface naming scheme 'v255'. Jun 20 18:49:03.031116 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:03.032001 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 20 18:49:03.063091 dracut-pre-trigger[431]: rd.md=0: removing MD RAID activation Jun 20 18:49:03.094747 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 18:49:03.113558 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 18:49:03.174623 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:03.198027 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 20 18:49:03.209867 kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 18:49:03.213273 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 20 18:49:03.229510 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 18:49:03.244232 kernel: hv_vmbus: Vmbus version:4.0 Jun 20 18:49:03.244257 kernel: AVX2 version of gcm_enc/dec engaged. Jun 20 18:49:03.239493 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:03.271177 kernel: AES CTR mode by8 optimization enabled Jun 20 18:49:03.271201 kernel: hv_vmbus: registering driver hyperv_keyboard Jun 20 18:49:03.271215 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 Jun 20 18:49:03.271236 kernel: pps_core: LinuxPPS API ver. 1 registered Jun 20 18:49:03.271249 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 20 18:49:03.247884 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 18:49:03.283186 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 20 18:49:03.291428 kernel: hv_vmbus: registering driver hid_hyperv Jun 20 18:49:03.292225 kernel: hv_vmbus: registering driver hv_netvsc Jun 20 18:49:03.292247 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jun 20 18:49:03.300147 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jun 20 18:49:03.300351 kernel: hv_vmbus: registering driver hv_storvsc Jun 20 18:49:03.304399 kernel: scsi host1: storvsc_host_t Jun 20 18:49:03.307429 kernel: scsi host0: storvsc_host_t Jun 20 18:49:03.311423 kernel: PTP clock support registered Jun 20 18:49:03.312429 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 18:49:03.314483 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 20 18:49:03.367928 kernel: hv_vmbus: registering driver hv_pci Jun 20 18:49:03.367950 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 18:49:03.368141 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jun 20 18:49:03.368291 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jun 20 18:49:03.368457 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jun 20 18:49:03.368596 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jun 20 18:49:03.368741 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jun 20 18:49:03.368869 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jun 20 18:49:03.368993 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Jun 20 18:49:03.369021 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Jun 20 18:49:03.369040 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 18:49:03.369066 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 18:49:03.363007 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 18:49:03.388176 kernel: pci 0001:00:00.0: Enabling HDA controller Jun 20 18:49:03.363170 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:03.388286 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:03.413923 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jun 20 18:49:03.414784 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 18:49:03.414945 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 18:49:03.415090 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Jun 20 18:49:03.408978 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:03.409185 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:03.441925 kernel: hv_utils: Registering HyperV Utility Driver Jun 20 18:49:03.441951 kernel: hv_vmbus: registering driver hv_utils Jun 20 18:49:03.423953 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:03.433123 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:03.446833 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 20 18:49:03.464316 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:03.492577 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:03.512229 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:03.814629 kernel: hv_utils: Heartbeat IC version 3.0 Jun 20 18:49:03.814685 kernel: hv_utils: Shutdown IC version 3.2 Jun 20 18:49:03.817158 kernel: hv_utils: TimeSync IC version 4.0 Jun 20 18:49:04.911679 systemd-resolved[240]: Clock change detected. Flushing caches. Jun 20 18:49:04.920797 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jun 20 18:49:04.921003 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 20 18:49:04.921027 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jun 20 18:49:04.935263 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jun 20 18:49:04.935459 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jun 20 18:49:04.935623 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jun 20 18:49:04.935792 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jun 20 18:49:04.938008 kernel: sd 0:0:0:1: [sda] Write Protect is off Jun 20 18:49:04.940698 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jun 20 18:49:04.945481 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jun 20 18:49:04.945671 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jun 20 18:49:04.950273 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 18:49:04.950533 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 18:49:04.954682 kernel: sda: sda1 Jun 20 18:49:04.959613 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jun 20 18:49:04.959642 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jun 20 18:49:04.962680 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jun 20 18:49:05.432529 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jun 20 18:49:05.456675 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sdb6 scanned by (udev-worker) (479) Jun 20 18:49:05.478963 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 18:49:05.540567 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jun 20 18:49:05.635689 kernel: BTRFS: device fsid 5ff786f3-14e2-4689-ad32-ff903cf13f91 devid 1 transid 38 /dev/sdb3 scanned by (udev-worker) (475) Jun 20 18:49:05.656160 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jun 20 18:49:05.661295 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jun 20 18:49:05.684786 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 20 18:49:05.697675 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jun 20 18:49:06.710572 disk-uuid[640]: The operation has completed successfully. Jun 20 18:49:06.714444 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jun 20 18:49:08.313643 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 20 18:49:08.313767 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 20 18:49:08.327810 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 20 18:49:08.338910 sh[727]: Success Jun 20 18:49:08.370675 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jun 20 18:49:08.613941 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 20 18:49:08.625069 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 20 18:49:08.637431 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 20 18:49:08.668451 kernel: BTRFS info (device dm-0): first mount of filesystem 5ff786f3-14e2-4689-ad32-ff903cf13f91 Jun 20 18:49:08.668492 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:08.673285 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jun 20 18:49:08.676963 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jun 20 18:49:08.680093 kernel: BTRFS info (device dm-0): using free space tree Jun 20 18:49:09.264340 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 20 18:49:09.265131 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 20 18:49:09.284801 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 20 18:49:09.289241 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 20 18:49:09.324943 kernel: BTRFS info (device sdb6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:09.324993 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:09.328305 kernel: BTRFS info (device sdb6): using free space tree Jun 20 18:49:09.345675 kernel: BTRFS info (device sdb6): auto enabling async discard Jun 20 18:49:09.354695 kernel: BTRFS info (device sdb6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:09.360789 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 20 18:49:09.371828 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 20 18:49:09.405956 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 18:49:09.428831 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 18:49:09.458706 systemd-networkd[908]: lo: Link UP Jun 20 18:49:09.458715 systemd-networkd[908]: lo: Gained carrier Jun 20 18:49:09.461088 systemd-networkd[908]: Enumeration completed Jun 20 18:49:09.461219 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 18:49:09.461637 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:09.461641 systemd-networkd[908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:09.461941 systemd[1]: Reached target network.target - Network. Jun 20 18:49:09.463233 systemd-networkd[908]: eth0: Link UP Jun 20 18:49:09.463791 systemd-networkd[908]: eth0: Gained carrier Jun 20 18:49:09.463825 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:09.521793 systemd-networkd[908]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:10.728773 systemd-networkd[908]: eth0: Gained IPv6LL Jun 20 18:49:10.940888 ignition[872]: Ignition 2.20.0 Jun 20 18:49:10.940903 ignition[872]: Stage: fetch-offline Jun 20 18:49:10.940961 ignition[872]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:10.940975 ignition[872]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:10.941079 ignition[872]: parsed url from cmdline: "" Jun 20 18:49:10.941083 ignition[872]: no config URL provided Jun 20 18:49:10.941089 ignition[872]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 18:49:10.941098 ignition[872]: no config at "/usr/lib/ignition/user.ign" Jun 20 18:49:10.941104 ignition[872]: failed to fetch config: resource requires networking Jun 20 18:49:10.941277 ignition[872]: Ignition finished successfully Jun 20 18:49:10.973806 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 18:49:10.991791 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jun 20 18:49:11.006459 ignition[922]: Ignition 2.20.0 Jun 20 18:49:11.006470 ignition[922]: Stage: fetch Jun 20 18:49:11.006635 ignition[922]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.006648 ignition[922]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.006760 ignition[922]: parsed url from cmdline: "" Jun 20 18:49:11.006765 ignition[922]: no config URL provided Jun 20 18:49:11.006771 ignition[922]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 18:49:11.006780 ignition[922]: no config at "/usr/lib/ignition/user.ign" Jun 20 18:49:11.006804 ignition[922]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jun 20 18:49:11.104923 ignition[922]: GET result: OK Jun 20 18:49:11.104970 ignition[922]: config has been read from IMDS userdata Jun 20 18:49:11.109118 unknown[922]: fetched base config from "system" Jun 20 18:49:11.104983 ignition[922]: parsing config with SHA512: 2eee22f894bda9caa94e2dc2df04137da7db597723333c90568bae17eb1f002d42a5b4b3bce11dbd729af035d9a781e844fa09284e1e007814a27a7bcde4ef54 Jun 20 18:49:11.109126 unknown[922]: fetched base config from "system" Jun 20 18:49:11.109338 ignition[922]: fetch: fetch complete Jun 20 18:49:11.109131 unknown[922]: fetched user config from "azure" Jun 20 18:49:11.109344 ignition[922]: fetch: fetch passed Jun 20 18:49:11.125345 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jun 20 18:49:11.109416 ignition[922]: Ignition finished successfully Jun 20 18:49:11.148800 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 20 18:49:11.169936 ignition[933]: Ignition 2.20.0 Jun 20 18:49:11.169948 ignition[933]: Stage: kargs Jun 20 18:49:11.170113 ignition[933]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.170127 ignition[933]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.170648 ignition[933]: kargs: kargs passed Jun 20 18:49:11.170708 ignition[933]: Ignition finished successfully Jun 20 18:49:11.185721 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 20 18:49:11.203827 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 20 18:49:11.214916 ignition[943]: Ignition 2.20.0 Jun 20 18:49:11.214927 ignition[943]: Stage: disks Jun 20 18:49:11.216917 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 20 18:49:11.215133 ignition[943]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.221611 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 20 18:49:11.215146 ignition[943]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.224375 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 20 18:49:11.215791 ignition[943]: disks: disks passed Jun 20 18:49:11.236761 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 18:49:11.215837 ignition[943]: Ignition finished successfully Jun 20 18:49:11.243893 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 18:49:11.252177 systemd[1]: Reached target basic.target - Basic System. Jun 20 18:49:11.271803 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 20 18:49:11.380096 systemd-fsck[954]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Jun 20 18:49:11.385651 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 20 18:49:11.403736 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 20 18:49:11.500676 kernel: EXT4-fs (sdb9): mounted filesystem 943f8432-3dc9-4e22-b9bd-c29bf6a1f5e1 r/w with ordered data mode. Quota mode: none. Jun 20 18:49:11.500803 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 20 18:49:11.501447 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 20 18:49:11.544727 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 18:49:11.559123 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sdb6 scanned by mount (965) Jun 20 18:49:11.545594 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 20 18:49:11.574774 kernel: BTRFS info (device sdb6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:11.574801 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:11.574815 kernel: BTRFS info (device sdb6): using free space tree Jun 20 18:49:11.560044 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jun 20 18:49:11.579511 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 20 18:49:11.579544 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 18:49:11.609075 kernel: BTRFS info (device sdb6): auto enabling async discard Jun 20 18:49:11.590532 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 20 18:49:11.605828 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 18:49:11.623789 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 20 18:49:12.737910 coreos-metadata[967]: Jun 20 18:49:12.737 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 18:49:12.743995 coreos-metadata[967]: Jun 20 18:49:12.740 INFO Fetch successful Jun 20 18:49:12.743995 coreos-metadata[967]: Jun 20 18:49:12.740 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jun 20 18:49:12.755892 coreos-metadata[967]: Jun 20 18:49:12.747 INFO Fetch successful Jun 20 18:49:12.759781 coreos-metadata[967]: Jun 20 18:49:12.759 INFO wrote hostname ci-4230.2.0-a-ac90cfa922 to /sysroot/etc/hostname Jun 20 18:49:12.766933 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 18:49:12.909949 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory Jun 20 18:49:12.939872 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory Jun 20 18:49:12.944627 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory Jun 20 18:49:12.978255 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory Jun 20 18:49:14.363072 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 20 18:49:14.385731 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 20 18:49:14.386489 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 20 18:49:14.406930 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 20 18:49:14.416009 kernel: BTRFS info (device sdb6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:14.433428 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 20 18:49:14.444006 ignition[1087]: INFO : Ignition 2.20.0 Jun 20 18:49:14.444006 ignition[1087]: INFO : Stage: mount Jun 20 18:49:14.450034 ignition[1087]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:14.450034 ignition[1087]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:14.450034 ignition[1087]: INFO : mount: mount passed Jun 20 18:49:14.450034 ignition[1087]: INFO : Ignition finished successfully Jun 20 18:49:14.467070 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 20 18:49:14.486745 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 20 18:49:14.497491 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 18:49:14.515674 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sdb6 scanned by mount (1099) Jun 20 18:49:14.524616 kernel: BTRFS info (device sdb6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:14.524650 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:14.527814 kernel: BTRFS info (device sdb6): using free space tree Jun 20 18:49:14.533689 kernel: BTRFS info (device sdb6): auto enabling async discard Jun 20 18:49:14.534994 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 18:49:14.563841 ignition[1116]: INFO : Ignition 2.20.0 Jun 20 18:49:14.563841 ignition[1116]: INFO : Stage: files Jun 20 18:49:14.569673 ignition[1116]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:14.569673 ignition[1116]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:14.569673 ignition[1116]: DEBUG : files: compiled without relabeling support, skipping Jun 20 18:49:14.640310 ignition[1116]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 20 18:49:14.640310 ignition[1116]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 20 18:49:14.762587 ignition[1116]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 20 18:49:14.768288 ignition[1116]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 20 18:49:14.773745 ignition[1116]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 20 18:49:14.768436 unknown[1116]: wrote ssh authorized keys file for user: core Jun 20 18:49:14.783140 ignition[1116]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 18:49:14.783140 ignition[1116]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 18:49:14.783140 ignition[1116]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 20 18:49:14.783140 ignition[1116]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 20 18:49:14.783140 ignition[1116]: INFO : files: files passed Jun 20 18:49:14.783140 ignition[1116]: INFO : Ignition finished successfully Jun 20 18:49:14.798977 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 20 18:49:14.828775 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 20 18:49:14.829515 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 20 18:49:14.847084 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.847084 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.859671 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.859823 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 20 18:49:14.859948 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 20 18:49:14.866111 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 18:49:14.875181 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 20 18:49:14.904818 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 20 18:49:14.931124 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 20 18:49:14.931244 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 20 18:49:14.935263 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 20 18:49:14.944874 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 20 18:49:14.952700 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 20 18:49:14.953585 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 20 18:49:14.980893 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 18:49:14.982251 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 20 18:49:15.001672 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:15.001891 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:15.011122 systemd[1]: Stopped target timers.target - Timer Units. Jun 20 18:49:15.020494 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 20 18:49:15.020625 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 18:49:15.036338 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 20 18:49:15.041091 systemd[1]: Stopped target basic.target - Basic System. Jun 20 18:49:15.049070 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 20 18:49:15.056101 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 18:49:15.065453 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 20 18:49:15.074615 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 20 18:49:15.083434 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 18:49:15.091487 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 20 18:49:15.101372 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 20 18:49:15.109388 systemd[1]: Stopped target swap.target - Swaps. Jun 20 18:49:15.117101 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 20 18:49:15.117247 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 20 18:49:15.131370 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:15.135582 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:15.144034 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 20 18:49:15.144156 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:15.153288 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 20 18:49:15.153422 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 20 18:49:15.175505 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 20 18:49:15.175651 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 18:49:15.179705 systemd[1]: ignition-files.service: Deactivated successfully. Jun 20 18:49:15.179836 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 20 18:49:15.189538 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jun 20 18:49:15.189688 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 18:49:15.227538 ignition[1171]: INFO : Ignition 2.20.0 Jun 20 18:49:15.227538 ignition[1171]: INFO : Stage: umount Jun 20 18:49:15.227538 ignition[1171]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:15.227538 ignition[1171]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:15.227538 ignition[1171]: INFO : umount: umount passed Jun 20 18:49:15.227538 ignition[1171]: INFO : Ignition finished successfully Jun 20 18:49:15.209816 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 20 18:49:15.211872 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 20 18:49:15.219385 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 20 18:49:15.219546 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:15.232523 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 20 18:49:15.232650 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 18:49:15.244189 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 20 18:49:15.244288 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 20 18:49:15.247745 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 20 18:49:15.247857 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 20 18:49:15.255357 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 20 18:49:15.255408 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 20 18:49:15.262365 systemd[1]: ignition-fetch.service: Deactivated successfully. Jun 20 18:49:15.262411 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jun 20 18:49:15.269850 systemd[1]: Stopped target network.target - Network. Jun 20 18:49:15.281197 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 20 18:49:15.281249 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 18:49:15.289913 systemd[1]: Stopped target paths.target - Path Units. Jun 20 18:49:15.297449 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 20 18:49:15.301714 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:15.301983 systemd[1]: Stopped target slices.target - Slice Units. Jun 20 18:49:15.310805 systemd[1]: Stopped target sockets.target - Socket Units. Jun 20 18:49:15.318739 systemd[1]: iscsid.socket: Deactivated successfully. Jun 20 18:49:15.318788 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 18:49:15.326144 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 20 18:49:15.326192 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 18:49:15.337720 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 20 18:49:15.337774 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 20 18:49:15.345440 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 20 18:49:15.345490 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 20 18:49:15.353241 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 20 18:49:15.361150 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 20 18:49:15.373837 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 20 18:49:15.374601 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 20 18:49:15.374716 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 20 18:49:15.385209 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 20 18:49:15.385542 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 20 18:49:15.385650 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 20 18:49:15.398378 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 20 18:49:15.398650 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 20 18:49:15.398757 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 20 18:49:15.401147 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 20 18:49:15.401242 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 20 18:49:15.413138 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 20 18:49:15.413183 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:15.419511 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 20 18:49:15.419564 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 20 18:49:15.447764 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 20 18:49:15.451429 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 20 18:49:15.451488 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 18:49:15.463789 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 20 18:49:15.463839 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:15.471916 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 20 18:49:15.471967 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:15.480226 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 20 18:49:15.480277 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:15.493788 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:15.503582 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jun 20 18:49:15.503651 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 20 18:49:15.533318 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 20 18:49:15.533431 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 20 18:49:15.540396 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 20 18:49:15.540554 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:15.550599 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 20 18:49:15.550654 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:15.554505 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 20 18:49:15.554542 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:15.561529 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 20 18:49:15.561576 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 20 18:49:15.577842 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 20 18:49:15.577890 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 20 18:49:15.589935 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 18:49:15.589983 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:15.622783 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 20 18:49:15.624458 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 20 18:49:15.624512 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:15.642754 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jun 20 18:49:15.642801 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 18:49:15.642944 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 20 18:49:15.642990 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:15.657956 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:15.658003 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:15.673532 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jun 20 18:49:15.673591 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 18:49:15.673972 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 20 18:49:15.804882 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Jun 20 18:49:15.674061 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 20 18:49:15.676912 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 20 18:49:15.697801 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 20 18:49:15.705525 systemd[1]: Switching root. Jun 20 18:49:15.820326 systemd-journald[205]: Journal stopped Jun 20 18:49:22.675514 kernel: SELinux: policy capability network_peer_controls=1 Jun 20 18:49:22.675547 kernel: SELinux: policy capability open_perms=1 Jun 20 18:49:22.675560 kernel: SELinux: policy capability extended_socket_class=1 Jun 20 18:49:22.675569 kernel: SELinux: policy capability always_check_network=0 Jun 20 18:49:22.675579 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 20 18:49:22.675588 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 20 18:49:22.675599 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 20 18:49:22.675612 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 20 18:49:22.675621 kernel: audit: type=1403 audit(1750445357.121:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 20 18:49:22.675633 systemd[1]: Successfully loaded SELinux policy in 221.266ms. Jun 20 18:49:22.675645 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.150ms. Jun 20 18:49:22.675668 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) Jun 20 18:49:22.675680 systemd[1]: Detected virtualization microsoft. Jun 20 18:49:22.675691 systemd[1]: Detected architecture x86-64. Jun 20 18:49:22.675705 systemd[1]: Detected first boot. Jun 20 18:49:22.675717 systemd[1]: Hostname set to . Jun 20 18:49:22.675728 systemd[1]: Initializing machine ID from random generator. Jun 20 18:49:22.675739 zram_generator::config[1219]: No configuration found. Jun 20 18:49:22.675750 kernel: Guest personality initialized and is inactive Jun 20 18:49:22.675763 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jun 20 18:49:22.675778 kernel: Initialized host personality Jun 20 18:49:22.675788 kernel: NET: Registered PF_VSOCK protocol family Jun 20 18:49:22.675798 systemd[1]: Populated /etc with preset unit settings. Jun 20 18:49:22.675810 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 20 18:49:22.675821 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 20 18:49:22.675832 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 20 18:49:22.675843 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 20 18:49:22.675857 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 20 18:49:22.675868 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 20 18:49:22.675880 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 20 18:49:22.675891 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 20 18:49:22.675902 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 20 18:49:22.675913 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 20 18:49:22.675924 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 20 18:49:22.675938 systemd[1]: Created slice user.slice - User and Session Slice. Jun 20 18:49:22.675949 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:22.675960 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:22.675971 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 20 18:49:22.675982 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 20 18:49:22.675994 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 20 18:49:22.676009 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 18:49:22.676024 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 20 18:49:22.676035 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:22.676049 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 20 18:49:22.676061 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 20 18:49:22.676072 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 20 18:49:22.676083 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 20 18:49:22.676095 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:22.676106 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 18:49:22.676117 systemd[1]: Reached target slices.target - Slice Units. Jun 20 18:49:22.676131 systemd[1]: Reached target swap.target - Swaps. Jun 20 18:49:22.676142 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 20 18:49:22.676154 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 20 18:49:22.676165 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 20 18:49:22.676177 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:22.676191 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:22.676202 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:22.676213 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 20 18:49:22.676225 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 20 18:49:22.676236 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 20 18:49:22.676248 systemd[1]: Mounting media.mount - External Media Directory... Jun 20 18:49:22.676259 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:22.676274 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 20 18:49:22.676289 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 20 18:49:22.676300 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 20 18:49:22.676312 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). Jun 20 18:49:22.676324 systemd[1]: Reached target machines.target - Containers. Jun 20 18:49:22.676336 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 20 18:49:22.676348 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:22.676359 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 18:49:22.676371 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 20 18:49:22.676385 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:22.676397 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 18:49:22.676408 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:22.676420 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 20 18:49:22.676431 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:22.676443 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 20 18:49:22.676455 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 20 18:49:22.676466 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 20 18:49:22.676478 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 20 18:49:22.676492 systemd[1]: Stopped systemd-fsck-usr.service. Jun 20 18:49:22.676504 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). Jun 20 18:49:22.676516 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 18:49:22.676530 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 18:49:22.676542 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 18:49:22.676554 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 20 18:49:22.676565 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 20 18:49:22.676577 kernel: loop: module loaded Jun 20 18:49:22.676591 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 18:49:22.676602 systemd[1]: verity-setup.service: Deactivated successfully. Jun 20 18:49:22.676613 systemd[1]: Stopped verity-setup.service. Jun 20 18:49:22.676625 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:22.676667 systemd-journald[1326]: Collecting audit messages is disabled. Jun 20 18:49:22.676698 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 20 18:49:22.676711 systemd-journald[1326]: Journal started Jun 20 18:49:22.676733 systemd-journald[1326]: Runtime Journal (/run/log/journal/7cf0197458ff4cb992427daf87ed73f2) is 8M, max 2.2G, 2.1G free. Jun 20 18:49:21.893478 systemd[1]: Queued start job for default target multi-user.target. Jun 20 18:49:21.906108 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jun 20 18:49:21.906567 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 20 18:49:22.683676 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 18:49:22.688002 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 20 18:49:22.691683 kernel: fuse: init (API version 7.39) Jun 20 18:49:22.695519 systemd[1]: Mounted media.mount - External Media Directory. Jun 20 18:49:22.700054 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 20 18:49:22.705168 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 20 18:49:22.709943 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 20 18:49:22.717951 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 20 18:49:22.723235 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:22.727675 kernel: ACPI: bus type drm_connector registered Jun 20 18:49:22.730894 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 20 18:49:22.731089 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 20 18:49:22.736403 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:22.736602 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:22.741594 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 18:49:22.741804 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 18:49:22.746467 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:22.746674 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:22.752338 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 20 18:49:22.752646 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 20 18:49:22.757412 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:22.757611 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:22.762218 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:22.767105 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 18:49:22.773991 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 20 18:49:22.779388 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 20 18:49:22.794488 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 18:49:22.811761 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 20 18:49:22.817186 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 20 18:49:22.821557 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 20 18:49:22.821593 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 18:49:22.826811 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 20 18:49:22.833336 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 20 18:49:22.839075 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 20 18:49:22.843216 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:22.844667 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 20 18:49:22.850292 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 20 18:49:22.854953 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 18:49:22.855918 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 20 18:49:22.860284 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 18:49:22.861235 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 18:49:22.873538 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 20 18:49:22.880562 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 18:49:22.887545 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:22.892522 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 20 18:49:22.895929 systemd-journald[1326]: Time spent on flushing to /var/log/journal/7cf0197458ff4cb992427daf87ed73f2 is 13.706ms for 936 entries. Jun 20 18:49:22.895929 systemd-journald[1326]: System Journal (/var/log/journal/7cf0197458ff4cb992427daf87ed73f2) is 8M, max 2.6G, 2.6G free. Jun 20 18:49:22.944003 systemd-journald[1326]: Received client request to flush runtime journal. Jun 20 18:49:22.944058 kernel: loop0: detected capacity change from 0 to 28272 Jun 20 18:49:22.902153 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 20 18:49:22.907325 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 20 18:49:22.912565 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 20 18:49:22.920133 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 20 18:49:22.926234 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 20 18:49:22.931849 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jun 20 18:49:22.944914 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 20 18:49:22.955339 udevadm[1372]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jun 20 18:49:22.981251 systemd-tmpfiles[1363]: ACLs are not supported, ignoring. Jun 20 18:49:22.981276 systemd-tmpfiles[1363]: ACLs are not supported, ignoring. Jun 20 18:49:22.986642 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 18:49:23.001848 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 20 18:49:23.033081 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 20 18:49:23.033762 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 20 18:49:23.052807 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:23.153318 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 20 18:49:23.172823 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 18:49:23.190503 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Jun 20 18:49:23.190526 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Jun 20 18:49:23.195417 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:23.704688 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 20 18:49:23.863692 kernel: loop1: detected capacity change from 0 to 138176 Jun 20 18:49:24.290893 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 20 18:49:24.308858 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:24.333516 systemd-udevd[1388]: Using default interface naming scheme 'v255'. Jun 20 18:49:24.637224 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:24.655812 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 18:49:24.682652 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 20 18:49:24.725684 kernel: hv_vmbus: registering driver hv_balloon Jun 20 18:49:24.725768 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jun 20 18:49:24.734675 kernel: mousedev: PS/2 mouse device common for all mice Jun 20 18:49:24.734740 kernel: hv_vmbus: registering driver hyperv_fb Jun 20 18:49:24.737673 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jun 20 18:49:24.746795 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jun 20 18:49:24.750192 kernel: Console: switching to colour dummy device 80x25 Jun 20 18:49:24.754402 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 18:49:24.756815 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 20 18:49:24.766361 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:24.775154 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:24.775351 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:24.781215 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:24.784676 kernel: loop2: detected capacity change from 0 to 147912 Jun 20 18:49:24.794698 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:24.794909 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:24.804963 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:24.809382 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 20 18:49:24.910678 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1390) Jun 20 18:49:24.976780 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 18:49:24.991813 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 20 18:49:25.005680 kernel: EDAC MC: Ver: 3.0.0 Jun 20 18:49:25.028080 systemd-networkd[1400]: lo: Link UP Jun 20 18:49:25.028089 systemd-networkd[1400]: lo: Gained carrier Jun 20 18:49:25.029511 systemd-networkd[1400]: Enumeration completed Jun 20 18:49:25.029627 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 18:49:25.029907 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:25.029912 systemd-networkd[1400]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:25.030489 systemd-networkd[1400]: eth0: Link UP Jun 20 18:49:25.030494 systemd-networkd[1400]: eth0: Gained carrier Jun 20 18:49:25.030507 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:25.041798 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 20 18:49:25.042910 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 20 18:49:25.060759 systemd-networkd[1400]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:25.108494 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 20 18:49:25.141718 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 20 18:49:25.436076 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:25.475727 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jun 20 18:49:25.489806 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jun 20 18:49:25.529679 kernel: loop3: detected capacity change from 0 to 28272 Jun 20 18:49:25.538675 kernel: loop4: detected capacity change from 0 to 138176 Jun 20 18:49:25.552676 kernel: loop5: detected capacity change from 0 to 147912 Jun 20 18:49:25.563721 lvm[1527]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 18:49:25.568068 (sd-merge)[1528]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jun 20 18:49:25.568762 (sd-merge)[1528]: Merged extensions into '/usr'. Jun 20 18:49:25.572384 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 20 18:49:25.591809 systemd[1]: Starting ensure-sysext.service... Jun 20 18:49:25.595850 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 18:49:25.602840 systemd[1]: Reload requested from client PID 1530 ('systemctl') (unit ensure-sysext.service)... Jun 20 18:49:25.602856 systemd[1]: Reloading... Jun 20 18:49:25.631636 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 20 18:49:25.631998 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 20 18:49:25.632951 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 20 18:49:25.633258 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Jun 20 18:49:25.633463 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Jun 20 18:49:25.654681 zram_generator::config[1563]: No configuration found. Jun 20 18:49:25.658500 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 18:49:25.658533 systemd-tmpfiles[1531]: Skipping /boot Jun 20 18:49:25.670112 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 18:49:25.670127 systemd-tmpfiles[1531]: Skipping /boot Jun 20 18:49:25.793591 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. Jun 20 18:49:25.929632 systemd[1]: Reloading finished in 326 ms. Jun 20 18:49:25.954040 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jun 20 18:49:25.976878 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:25.994325 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:25.998961 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.008912 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 18:49:26.027822 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 20 18:49:26.033213 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:26.034500 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jun 20 18:49:26.040243 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:26.044007 lvm[1627]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 18:49:26.045508 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:26.051127 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:26.055351 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:26.055484 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). Jun 20 18:49:26.058568 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 20 18:49:26.066301 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 18:49:26.071525 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 20 18:49:26.076236 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.078756 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:26.078955 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:26.084127 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:26.084310 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:26.089610 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:26.089812 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:26.094673 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jun 20 18:49:26.105428 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.105639 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:26.113682 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:26.118874 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:26.124407 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:26.128581 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:26.128813 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). Jun 20 18:49:26.128930 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.132299 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:26.132494 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:26.137503 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:26.137729 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:26.143040 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:26.143235 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:26.155787 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.156077 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:26.166863 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:26.172035 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 18:49:26.177156 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:26.182740 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:26.186810 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:26.186861 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). Jun 20 18:49:26.186924 systemd[1]: Reached target time-set.target - System Time Set. Jun 20 18:49:26.193721 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:26.194696 systemd[1]: Finished ensure-sysext.service. Jun 20 18:49:26.198393 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 20 18:49:26.203737 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 20 18:49:26.209111 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:26.209304 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:26.214255 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 18:49:26.214343 augenrules[1667]: No rules Jun 20 18:49:26.214440 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 18:49:26.219036 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 18:49:26.219252 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 18:49:26.223544 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:26.223742 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:26.228872 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:26.229066 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:26.240250 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 18:49:26.240316 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 18:49:26.282250 systemd-resolved[1637]: Positive Trust Anchors: Jun 20 18:49:26.282271 systemd-resolved[1637]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 18:49:26.282301 systemd-resolved[1637]: 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 Jun 20 18:49:26.338752 systemd-resolved[1637]: Using system hostname 'ci-4230.2.0-a-ac90cfa922'. Jun 20 18:49:26.340856 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 18:49:26.345601 systemd[1]: Reached target network.target - Network. Jun 20 18:49:26.349289 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:26.655981 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 20 18:49:26.661724 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). Jun 20 18:49:26.664758 systemd-networkd[1400]: eth0: Gained IPv6LL Jun 20 18:49:26.666901 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 20 18:49:26.672354 systemd[1]: Reached target network-online.target - Network is Online. Jun 20 18:49:30.395593 ldconfig[1356]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 18:49:30.416697 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 20 18:49:30.440808 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 20 18:49:30.453057 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 20 18:49:30.457845 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 18:49:30.462184 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 20 18:49:30.467227 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 20 18:49:30.472480 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 20 18:49:30.476838 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 20 18:49:30.481952 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 20 18:49:30.487111 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 20 18:49:30.487145 systemd[1]: Reached target paths.target - Path Units. Jun 20 18:49:30.490830 systemd[1]: Reached target timers.target - Timer Units. Jun 20 18:49:30.495362 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 20 18:49:30.501138 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 20 18:49:30.507833 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 20 18:49:30.513117 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 20 18:49:30.518183 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 20 18:49:30.524306 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 20 18:49:30.528768 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 20 18:49:30.534105 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 20 18:49:30.538438 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 18:49:30.542328 systemd[1]: Reached target basic.target - Basic System. Jun 20 18:49:30.546070 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 20 18:49:30.546103 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 20 18:49:30.548216 systemd[1]: Starting chronyd.service - NTP client/server... Jun 20 18:49:30.553306 systemd[1]: Starting containerd.service - containerd container runtime... Jun 20 18:49:30.558751 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jun 20 18:49:30.563802 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 20 18:49:30.568365 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 20 18:49:30.574455 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 20 18:49:30.578544 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). Jun 20 18:49:30.578584 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Jun 20 18:49:30.579780 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jun 20 18:49:30.582163 jq[1694]: false Jun 20 18:49:30.583991 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jun 20 18:49:30.584981 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 20 18:49:30.589942 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 20 18:49:30.594803 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 20 18:49:30.595331 KVP[1696]: KVP starting; pid is:1696 Jun 20 18:49:30.599978 KVP[1696]: KVP LIC Version: 3.1 Jun 20 18:49:30.600677 kernel: hv_utils: KVP IC version 4.0 Jun 20 18:49:30.602719 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 20 18:49:30.604029 (chronyd)[1687]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jun 20 18:49:30.609347 chronyd[1705]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jun 20 18:49:30.609492 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 20 18:49:30.614671 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 20 18:49:30.615098 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 20 18:49:30.615719 systemd[1]: Starting update-engine.service - Update Engine... Jun 20 18:49:30.620244 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 20 18:49:30.626797 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 20 18:49:30.627014 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 20 18:49:30.627531 systemd[1]: motdgen.service: Deactivated successfully. Jun 20 18:49:30.627770 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 20 18:49:30.633217 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 20 18:49:30.633427 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 20 18:49:30.636118 jq[1715]: true Jun 20 18:49:30.646524 (ntainerd)[1718]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 20 18:49:30.650254 jq[1717]: true Jun 20 18:49:30.714836 chronyd[1705]: Timezone right/UTC failed leap second check, ignoring Jun 20 18:49:30.715012 chronyd[1705]: Loaded seccomp filter (level 2) Jun 20 18:49:30.716424 systemd[1]: Started chronyd.service - NTP client/server. Jun 20 18:49:30.739181 update_engine[1713]: I20250620 18:49:30.739100 1713 main.cc:92] Flatcar Update Engine starting Jun 20 18:49:30.767497 bash[1741]: Updated "/home/core/.ssh/authorized_keys" Jun 20 18:49:30.768925 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 20 18:49:30.774640 extend-filesystems[1695]: Found loop3 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found loop4 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found loop5 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sda Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sda1 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb1 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb2 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb3 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found usr Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb4 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb6 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb7 Jun 20 18:49:30.774640 extend-filesystems[1695]: Found sdb9 Jun 20 18:49:30.774640 extend-filesystems[1695]: Checking size of /dev/sdb9 Jun 20 18:49:30.772469 systemd-logind[1706]: New seat seat0. Jun 20 18:49:30.832274 update_engine[1713]: I20250620 18:49:30.826555 1713 update_check_scheduler.cc:74] Next update check in 6m6s Jun 20 18:49:30.824125 dbus-daemon[1690]: [system] SELinux support is enabled Jun 20 18:49:30.773808 systemd-logind[1706]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 20 18:49:30.802164 systemd[1]: Started systemd-logind.service - User Login Management. Jun 20 18:49:30.807642 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 20 18:49:30.824289 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 20 18:49:30.835046 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). Jun 20 18:49:30.835083 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 20 18:49:30.840240 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). Jun 20 18:49:30.840341 extend-filesystems[1695]: Old size kept for /dev/sdb9 Jun 20 18:49:30.840341 extend-filesystems[1695]: Found sr0 Jun 20 18:49:30.835546 dbus-daemon[1690]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 20 18:49:30.840260 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 20 18:49:30.840783 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 20 18:49:30.841008 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 20 18:49:30.856833 systemd[1]: Started update-engine.service - Update Engine. Jun 20 18:49:30.875684 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1751) Jun 20 18:49:30.879043 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 20 18:49:30.885919 sshd_keygen[1714]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 20 18:49:30.890074 setup-nvidia[1698]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.0 Jun 20 18:49:30.910257 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 20 18:49:30.943893 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 20 18:49:30.950147 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jun 20 18:49:30.958984 systemd[1]: issuegen.service: Deactivated successfully. Jun 20 18:49:30.959211 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 20 18:49:30.966908 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 20 18:49:30.976254 coreos-metadata[1689]: Jun 20 18:49:30.976 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 18:49:30.976788 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jun 20 18:49:30.981214 coreos-metadata[1689]: Jun 20 18:49:30.981 INFO Fetch successful Jun 20 18:49:30.981387 coreos-metadata[1689]: Jun 20 18:49:30.981 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jun 20 18:49:30.984105 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 20 18:49:30.989180 coreos-metadata[1689]: Jun 20 18:49:30.989 INFO Fetch successful Jun 20 18:49:30.989457 coreos-metadata[1689]: Jun 20 18:49:30.989 INFO Fetching http://168.63.129.16/machine/e1e49b21-7150-4543-81ea-c1494802c5df/cee96643%2D62b8%2D491a%2D92ac%2D52e89f2fdacf.%5Fci%2D4230.2.0%2Da%2Dac90cfa922?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jun 20 18:49:30.990712 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 20 18:49:30.996079 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 20 18:49:31.000817 systemd[1]: Reached target getty.target - Login Prompts. Jun 20 18:49:31.015483 coreos-metadata[1689]: Jun 20 18:49:31.015 INFO Fetch successful Jun 20 18:49:31.015650 coreos-metadata[1689]: Jun 20 18:49:31.015 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jun 20 18:49:31.022892 coreos-metadata[1689]: Jun 20 18:49:31.022 INFO Fetch successful Jun 20 18:49:31.046875 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jun 20 18:49:31.051930 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 20 18:49:31.054077 locksmithd[1768]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 20 18:49:31.936964 containerd[1718]: time="2025-06-20T18:49:31.936878300Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jun 20 18:49:31.955983 containerd[1718]: time="2025-06-20T18:49:31.955943300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.957498 containerd[1718]: time="2025-06-20T18:49:31.957458500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.94-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.957498 containerd[1718]: time="2025-06-20T18:49:31.957487800Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jun 20 18:49:31.957580 containerd[1718]: time="2025-06-20T18:49:31.957503800Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.957680 containerd[1718]: time="2025-06-20T18:49:31.957648500Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jun 20 18:49:31.957724 containerd[1718]: time="2025-06-20T18:49:31.957684000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.957787 containerd[1718]: time="2025-06-20T18:49:31.957763900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.957787 containerd[1718]: time="2025-06-20T18:49:31.957784100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958001 containerd[1718]: time="2025-06-20T18:49:31.957978600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958033 containerd[1718]: time="2025-06-20T18:49:31.958000300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958033 containerd[1718]: time="2025-06-20T18:49:31.958013600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958033 containerd[1718]: time="2025-06-20T18:49:31.958023000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958129 containerd[1718]: time="2025-06-20T18:49:31.958110400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958351 containerd[1718]: time="2025-06-20T18:49:31.958331800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958481 containerd[1718]: time="2025-06-20T18:49:31.958461700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.958518 containerd[1718]: time="2025-06-20T18:49:31.958479800Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jun 20 18:49:31.958577 containerd[1718]: time="2025-06-20T18:49:31.958558800Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jun 20 18:49:31.958633 containerd[1718]: time="2025-06-20T18:49:31.958616400Z" level=info msg="metadata content store policy set" policy=shared Jun 20 18:49:31.974013 containerd[1718]: time="2025-06-20T18:49:31.973974800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jun 20 18:49:31.974058 containerd[1718]: time="2025-06-20T18:49:31.974025100Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jun 20 18:49:31.974058 containerd[1718]: time="2025-06-20T18:49:31.974042900Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jun 20 18:49:31.974115 containerd[1718]: time="2025-06-20T18:49:31.974058500Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jun 20 18:49:31.974115 containerd[1718]: time="2025-06-20T18:49:31.974073200Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jun 20 18:49:31.974217 containerd[1718]: time="2025-06-20T18:49:31.974194300Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jun 20 18:49:31.974415 containerd[1718]: time="2025-06-20T18:49:31.974394700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jun 20 18:49:31.974520 containerd[1718]: time="2025-06-20T18:49:31.974500400Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jun 20 18:49:31.974553 containerd[1718]: time="2025-06-20T18:49:31.974523400Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jun 20 18:49:31.974553 containerd[1718]: time="2025-06-20T18:49:31.974537900Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jun 20 18:49:31.974553 containerd[1718]: time="2025-06-20T18:49:31.974550500Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974623 containerd[1718]: time="2025-06-20T18:49:31.974562400Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974623 containerd[1718]: time="2025-06-20T18:49:31.974573900Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974623 containerd[1718]: time="2025-06-20T18:49:31.974585800Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974623 containerd[1718]: time="2025-06-20T18:49:31.974604200Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974623 containerd[1718]: time="2025-06-20T18:49:31.974619300Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974630600Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974641500Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974675600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974691500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974703500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974714700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974726000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974737500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974752 containerd[1718]: time="2025-06-20T18:49:31.974747900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974759400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974771200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974784000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974794500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974805000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974815800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974828200Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974846700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974859100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974868600Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974912000Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974929300Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Jun 20 18:49:31.974948 containerd[1718]: time="2025-06-20T18:49:31.974939600Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.975205 containerd[1718]: time="2025-06-20T18:49:31.974951100Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jun 20 18:49:31.975205 containerd[1718]: time="2025-06-20T18:49:31.974960100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.975205 containerd[1718]: time="2025-06-20T18:49:31.974971100Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jun 20 18:49:31.975205 containerd[1718]: time="2025-06-20T18:49:31.974981400Z" level=info msg="NRI interface is disabled by configuration." Jun 20 18:49:31.975205 containerd[1718]: time="2025-06-20T18:49:31.974990600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.975315 containerd[1718]: time="2025-06-20T18:49:31.975253500Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jun 20 18:49:31.975315 containerd[1718]: time="2025-06-20T18:49:31.975298800Z" level=info msg="Connect containerd service" Jun 20 18:49:31.975470 containerd[1718]: time="2025-06-20T18:49:31.975322900Z" level=info msg="using legacy CRI server" Jun 20 18:49:31.975470 containerd[1718]: time="2025-06-20T18:49:31.975330600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 20 18:49:31.975514 containerd[1718]: time="2025-06-20T18:49:31.975427300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jun 20 18:49:31.976573 containerd[1718]: time="2025-06-20T18:49:31.976537800Z" 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" Jun 20 18:49:31.976713 containerd[1718]: time="2025-06-20T18:49:31.976678900Z" level=info msg="Start subscribing containerd event" Jun 20 18:49:31.976751 containerd[1718]: time="2025-06-20T18:49:31.976723400Z" level=info msg="Start recovering state" Jun 20 18:49:31.976799 containerd[1718]: time="2025-06-20T18:49:31.976781100Z" level=info msg="Start event monitor" Jun 20 18:49:31.976841 containerd[1718]: time="2025-06-20T18:49:31.976797600Z" level=info msg="Start snapshots syncer" Jun 20 18:49:31.976841 containerd[1718]: time="2025-06-20T18:49:31.976806500Z" level=info msg="Start cni network conf syncer for default" Jun 20 18:49:31.976841 containerd[1718]: time="2025-06-20T18:49:31.976814000Z" level=info msg="Start streaming server" Jun 20 18:49:31.976904 containerd[1718]: time="2025-06-20T18:49:31.976860500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 20 18:49:31.976946 containerd[1718]: time="2025-06-20T18:49:31.976924700Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 20 18:49:31.977014 containerd[1718]: time="2025-06-20T18:49:31.976996600Z" level=info msg="containerd successfully booted in 0.041133s" Jun 20 18:49:31.977080 systemd[1]: Started containerd.service - containerd container runtime. Jun 20 18:49:32.026904 setup-nvidia[1861]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 18:49:32.027214 setup-nvidia[1861]: Dload Upload Total Spent Left Speed Jun 20 18:49:33.471392 waagent[1836]: 2025-06-20T18:49:33.471296Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Jun 20 18:49:33.475850 waagent[1836]: 2025-06-20T18:49:33.475770Z INFO Daemon Daemon OS: flatcar 4230.2.0 Jun 20 18:49:33.479481 waagent[1836]: 2025-06-20T18:49:33.479423Z INFO Daemon Daemon Python: 3.11.11 Jun 20 18:49:33.482990 waagent[1836]: 2025-06-20T18:49:33.482935Z INFO Daemon Daemon Run daemon Jun 20 18:49:33.485986 waagent[1836]: 2025-06-20T18:49:33.485936Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.0' Jun 20 18:49:33.492762 waagent[1836]: 2025-06-20T18:49:33.492693Z INFO Daemon Daemon Using waagent for provisioning Jun 20 18:49:33.497498 waagent[1836]: 2025-06-20T18:49:33.497438Z INFO Daemon Daemon Activate resource disk Jun 20 18:49:33.501148 waagent[1836]: 2025-06-20T18:49:33.501090Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jun 20 18:49:33.508728 waagent[1836]: 2025-06-20T18:49:33.508676Z INFO Daemon Daemon Found device: sda Jun 20 18:49:33.517943 waagent[1836]: 2025-06-20T18:49:33.517886Z INFO Daemon Daemon Examining partition table Jun 20 18:49:33.546402 waagent[1836]: 2025-06-20T18:49:33.546339Z INFO Daemon Daemon GPT not detected, determining filesystem Jun 20 18:49:33.641429 waagent[1836]: 2025-06-20T18:49:33.641331Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jun 20 18:49:33.646424 waagent[1836]: 2025-06-20T18:49:33.646368Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jun 20 18:49:34.072682 kernel: sda: sda1 Jun 20 18:49:34.092077 waagent[1836]: 2025-06-20T18:49:34.091956Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jun 20 18:49:34.108448 waagent[1836]: 2025-06-20T18:49:34.108390Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jun 20 18:49:34.113110 kernel: sda: sda1 Jun 20 18:49:38.183319 login[1840]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jun 20 18:49:38.231720 login[1839]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 18:49:38.243178 systemd-logind[1706]: New session 1 of user core. Jun 20 18:49:38.244268 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 20 18:49:38.259880 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 20 18:49:38.304490 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 20 18:49:38.314970 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 20 18:49:38.392873 (systemd)[1925]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 20 18:49:38.395212 systemd-logind[1706]: New session c1 of user core. Jun 20 18:49:39.191554 login[1840]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 18:49:39.196075 systemd-logind[1706]: New session 2 of user core. Jun 20 18:49:39.440480 systemd[1925]: Queued start job for default target default.target. Jun 20 18:49:39.451755 systemd[1925]: Created slice app.slice - User Application Slice. Jun 20 18:49:39.451787 systemd[1925]: Reached target paths.target - Paths. Jun 20 18:49:39.451836 systemd[1925]: Reached target timers.target - Timers. Jun 20 18:49:39.453205 systemd[1925]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 18:49:39.464456 systemd[1925]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:49:39.464584 systemd[1925]: Reached target sockets.target - Sockets. Jun 20 18:49:39.464640 systemd[1925]: Reached target basic.target - Basic System. Jun 20 18:49:39.464711 systemd[1925]: Reached target default.target - Main User Target. Jun 20 18:49:39.464748 systemd[1925]: Startup finished in 1.062s. Jun 20 18:49:39.464807 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 20 18:49:39.466272 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 20 18:49:39.467028 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 20 18:49:39.715987 waagent[1836]: 2025-06-20T18:49:39.715829Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jun 20 18:49:39.733701 kernel: EXT4-fs (sda1): mounted filesystem 1181c715-759a-48eb-b1a0-2d06bb68029a r/w with ordered data mode. Quota mode: none. Jun 20 18:49:39.733877 waagent[1836]: 2025-06-20T18:49:39.733796Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jun 20 18:49:39.736833 waagent[1836]: 2025-06-20T18:49:39.736778Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 18:49:39.737024 waagent[1836]: 2025-06-20T18:49:39.736981Z INFO Daemon Daemon Running default provisioning handler Jun 20 18:49:39.746928 waagent[1836]: 2025-06-20T18:49:39.745821Z 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. Jun 20 18:49:39.747763 waagent[1836]: 2025-06-20T18:49:39.747703Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jun 20 18:49:39.747975 waagent[1836]: 2025-06-20T18:49:39.747931Z INFO Daemon Daemon cloud-init is enabled: False Jun 20 18:49:39.748688 waagent[1836]: 2025-06-20T18:49:39.748632Z INFO Daemon Daemon Copying ovf-env.xml Jun 20 18:49:39.865466 waagent[1836]: 2025-06-20T18:49:39.865369Z INFO Daemon Daemon Successfully mounted dvd Jun 20 18:49:39.888852 waagent[1836]: 2025-06-20T18:49:39.888792Z INFO Daemon Daemon Detect protocol endpoint Jun 20 18:49:39.888849 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jun 20 18:49:39.889059 waagent[1836]: 2025-06-20T18:49:39.889009Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 18:49:39.890693 waagent[1836]: 2025-06-20T18:49:39.890618Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jun 20 18:49:39.891362 waagent[1836]: 2025-06-20T18:49:39.891318Z INFO Daemon Daemon Test for route to 168.63.129.16 Jun 20 18:49:39.893040 waagent[1836]: 2025-06-20T18:49:39.892994Z INFO Daemon Daemon Route to 168.63.129.16 exists Jun 20 18:49:39.893720 waagent[1836]: 2025-06-20T18:49:39.893677Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jun 20 18:49:39.958714 waagent[1836]: 2025-06-20T18:49:39.958642Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jun 20 18:49:39.959153 waagent[1836]: 2025-06-20T18:49:39.959117Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jun 20 18:49:39.960503 waagent[1836]: 2025-06-20T18:49:39.960460Z INFO Daemon Daemon Server preferred version:2015-04-05 Jun 20 18:49:40.063172 waagent[1836]: 2025-06-20T18:49:40.062951Z INFO Daemon Daemon Initializing goal state during protocol detection Jun 20 18:49:40.065793 waagent[1836]: 2025-06-20T18:49:40.065736Z INFO Daemon Daemon Forcing an update of the goal state. Jun 20 18:49:40.073747 waagent[1836]: 2025-06-20T18:49:40.073689Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 18:49:40.090906 waagent[1836]: 2025-06-20T18:49:40.090849Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jun 20 18:49:40.091480 waagent[1836]: 2025-06-20T18:49:40.091431Z INFO Daemon Jun 20 18:49:40.092597 waagent[1836]: 2025-06-20T18:49:40.092551Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 4ed04352-61a7-4473-a65a-212b7bc27ad0 eTag: 1806199435640351405 source: Fabric] Jun 20 18:49:40.094319 waagent[1836]: 2025-06-20T18:49:40.094268Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jun 20 18:49:40.096187 waagent[1836]: 2025-06-20T18:49:40.096138Z INFO Daemon Jun 20 18:49:40.096409 waagent[1836]: 2025-06-20T18:49:40.096368Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jun 20 18:49:40.101445 waagent[1836]: 2025-06-20T18:49:40.101398Z INFO Daemon Daemon Downloading artifacts profile blob Jun 20 18:49:40.222630 waagent[1836]: 2025-06-20T18:49:40.222546Z INFO Daemon Downloaded certificate {'thumbprint': '1AE70400C795BA50BD144EEC81D54348F28B8926', 'hasPrivateKey': True} Jun 20 18:49:40.223373 waagent[1836]: 2025-06-20T18:49:40.223323Z INFO Daemon Fetch goal state completed Jun 20 18:49:40.253850 waagent[1836]: 2025-06-20T18:49:40.253796Z INFO Daemon Daemon Starting provisioning Jun 20 18:49:40.254060 waagent[1836]: 2025-06-20T18:49:40.254011Z INFO Daemon Daemon Handle ovf-env.xml. Jun 20 18:49:40.255814 waagent[1836]: 2025-06-20T18:49:40.255730Z INFO Daemon Daemon Set hostname [ci-4230.2.0-a-ac90cfa922] Jun 20 18:49:40.289975 waagent[1836]: 2025-06-20T18:49:40.289903Z INFO Daemon Daemon Publish hostname [ci-4230.2.0-a-ac90cfa922] Jun 20 18:49:40.290403 waagent[1836]: 2025-06-20T18:49:40.290352Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jun 20 18:49:40.291957 waagent[1836]: 2025-06-20T18:49:40.291904Z INFO Daemon Daemon Primary interface is [eth0] Jun 20 18:49:40.300840 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:40.300851 systemd-networkd[1400]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:40.300884 systemd-networkd[1400]: eth0: DHCP lease lost Jun 20 18:49:40.301392 waagent[1836]: 2025-06-20T18:49:40.301329Z INFO Daemon Daemon Create user account if not exists Jun 20 18:49:40.302604 waagent[1836]: 2025-06-20T18:49:40.302551Z INFO Daemon Daemon User core already exists, skip useradd Jun 20 18:49:40.304807 waagent[1836]: 2025-06-20T18:49:40.304748Z INFO Daemon Daemon Configure sudoer Jun 20 18:49:40.305833 waagent[1836]: 2025-06-20T18:49:40.305780Z INFO Daemon Daemon Configure sshd Jun 20 18:49:40.306474 waagent[1836]: 2025-06-20T18:49:40.306422Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jun 20 18:49:40.307887 waagent[1836]: 2025-06-20T18:49:40.307684Z INFO Daemon Daemon Deploy ssh public key. Jun 20 18:49:40.377696 systemd-networkd[1400]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:41.469314 waagent[1836]: 2025-06-20T18:49:41.469253Z INFO Daemon Daemon Provisioning complete Jun 20 18:49:41.477730 waagent[1836]: 2025-06-20T18:49:41.477682Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jun 20 18:49:41.477973 waagent[1836]: 2025-06-20T18:49:41.477928Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jun 20 18:49:41.478723 waagent[1836]: 2025-06-20T18:49:41.478681Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Jun 20 18:49:41.620333 waagent[1982]: 2025-06-20T18:49:41.620248Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Jun 20 18:49:41.620649 waagent[1982]: 2025-06-20T18:49:41.620390Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.0 Jun 20 18:49:41.620649 waagent[1982]: 2025-06-20T18:49:41.620469Z INFO ExtHandler ExtHandler Python: 3.11.11 Jun 20 18:49:41.696132 waagent[1982]: 2025-06-20T18:49:41.696029Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jun 20 18:49:41.696359 waagent[1982]: 2025-06-20T18:49:41.696313Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:41.696444 waagent[1982]: 2025-06-20T18:49:41.696404Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:41.704420 waagent[1982]: 2025-06-20T18:49:41.704354Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 18:49:41.709121 waagent[1982]: 2025-06-20T18:49:41.709075Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jun 20 18:49:41.709646 waagent[1982]: 2025-06-20T18:49:41.709596Z INFO ExtHandler Jun 20 18:49:41.709756 waagent[1982]: 2025-06-20T18:49:41.709714Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 8dbaa9b9-d0e9-46ff-ba8e-02f5c0d08ab9 eTag: 1806199435640351405 source: Fabric] Jun 20 18:49:41.710096 waagent[1982]: 2025-06-20T18:49:41.710048Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jun 20 18:49:41.710987 waagent[1982]: 2025-06-20T18:49:41.710929Z INFO ExtHandler Jun 20 18:49:41.711064 waagent[1982]: 2025-06-20T18:49:41.711025Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jun 20 18:49:41.714586 waagent[1982]: 2025-06-20T18:49:41.714542Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jun 20 18:49:41.789696 waagent[1982]: 2025-06-20T18:49:41.789562Z INFO ExtHandler Downloaded certificate {'thumbprint': '1AE70400C795BA50BD144EEC81D54348F28B8926', 'hasPrivateKey': True} Jun 20 18:49:41.790171 waagent[1982]: 2025-06-20T18:49:41.790122Z INFO ExtHandler Fetch goal state completed Jun 20 18:49:41.801253 waagent[1982]: 2025-06-20T18:49:41.801204Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1982 Jun 20 18:49:41.801409 waagent[1982]: 2025-06-20T18:49:41.801366Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jun 20 18:49:41.803291 waagent[1982]: 2025-06-20T18:49:41.803244Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.0', '', 'Flatcar Container Linux by Kinvolk'] Jun 20 18:49:41.803684 waagent[1982]: 2025-06-20T18:49:41.803628Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jun 20 18:49:41.937563 waagent[1982]: 2025-06-20T18:49:41.937511Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jun 20 18:49:41.937784 waagent[1982]: 2025-06-20T18:49:41.937740Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jun 20 18:49:41.944373 waagent[1982]: 2025-06-20T18:49:41.944330Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jun 20 18:49:41.951575 systemd[1]: Reload requested from client PID 1995 ('systemctl') (unit waagent.service)... Jun 20 18:49:41.951592 systemd[1]: Reloading... Jun 20 18:49:42.020737 zram_generator::config[2035]: No configuration found. Jun 20 18:49:42.154175 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. Jun 20 18:49:42.299900 systemd[1]: Reloading finished in 347 ms. Jun 20 18:49:42.312487 waagent[1982]: 2025-06-20T18:49:42.312409Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Jun 20 18:49:42.318590 systemd[1]: Reload requested from client PID 2092 ('systemctl') (unit waagent.service)... Jun 20 18:49:42.318605 systemd[1]: Reloading... Jun 20 18:49:42.383710 zram_generator::config[2133]: No configuration found. Jun 20 18:49:42.515734 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. Jun 20 18:49:42.665501 systemd[1]: Reloading finished in 346 ms. Jun 20 18:49:42.679642 waagent[1982]: 2025-06-20T18:49:42.679566Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jun 20 18:49:42.679944 waagent[1982]: 2025-06-20T18:49:42.679739Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jun 20 18:49:43.558512 waagent[1982]: 2025-06-20T18:49:43.558424Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jun 20 18:49:43.559088 waagent[1982]: 2025-06-20T18:49:43.559028Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Jun 20 18:49:43.559884 waagent[1982]: 2025-06-20T18:49:43.559831Z INFO ExtHandler ExtHandler Starting env monitor service. Jun 20 18:49:43.560268 waagent[1982]: 2025-06-20T18:49:43.560213Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:43.560481 waagent[1982]: 2025-06-20T18:49:43.560419Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jun 20 18:49:43.561011 waagent[1982]: 2025-06-20T18:49:43.560939Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jun 20 18:49:43.561066 waagent[1982]: 2025-06-20T18:49:43.561008Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:43.561109 waagent[1982]: 2025-06-20T18:49:43.561067Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jun 20 18:49:43.561334 waagent[1982]: 2025-06-20T18:49:43.561280Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:43.561649 waagent[1982]: 2025-06-20T18:49:43.561592Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jun 20 18:49:43.562066 waagent[1982]: 2025-06-20T18:49:43.562000Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jun 20 18:49:43.562226 waagent[1982]: 2025-06-20T18:49:43.562177Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jun 20 18:49:43.562319 waagent[1982]: 2025-06-20T18:49:43.562267Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:43.562636 waagent[1982]: 2025-06-20T18:49:43.562581Z INFO EnvHandler ExtHandler Configure routes Jun 20 18:49:43.562869 waagent[1982]: 2025-06-20T18:49:43.562825Z INFO EnvHandler ExtHandler Gateway:None Jun 20 18:49:43.562975 waagent[1982]: 2025-06-20T18:49:43.562927Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jun 20 18:49:43.563202 waagent[1982]: 2025-06-20T18:49:43.563152Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jun 20 18:49:43.563202 waagent[1982]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jun 20 18:49:43.563202 waagent[1982]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jun 20 18:49:43.563202 waagent[1982]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jun 20 18:49:43.563202 waagent[1982]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.563202 waagent[1982]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.563202 waagent[1982]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.563538 waagent[1982]: 2025-06-20T18:49:43.563485Z INFO EnvHandler ExtHandler Routes:None Jun 20 18:49:43.569308 waagent[1982]: 2025-06-20T18:49:43.569257Z INFO ExtHandler ExtHandler Jun 20 18:49:43.569618 waagent[1982]: 2025-06-20T18:49:43.569572Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 0e8c8903-6b0f-4dc8-9840-197e0f3efc52 correlation bf45c68e-5af3-49eb-a6b9-1e562b6764d4 created: 2025-06-20T18:48:10.987869Z] Jun 20 18:49:43.570894 waagent[1982]: 2025-06-20T18:49:43.570841Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jun 20 18:49:43.572131 waagent[1982]: 2025-06-20T18:49:43.572086Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Jun 20 18:49:43.619798 waagent[1982]: 2025-06-20T18:49:43.619743Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 84E0627B-428C-41FC-9EAE-CBBC1781D660;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Jun 20 18:49:43.676744 waagent[1982]: 2025-06-20T18:49:43.676653Z INFO MonitorHandler ExtHandler Network interfaces: Jun 20 18:49:43.676744 waagent[1982]: Executing ['ip', '-a', '-o', 'link']: Jun 20 18:49:43.676744 waagent[1982]: 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 Jun 20 18:49:43.676744 waagent[1982]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:36:1f:c5 brd ff:ff:ff:ff:ff:ff Jun 20 18:49:43.676744 waagent[1982]: Executing ['ip', '-4', '-a', '-o', 'address']: Jun 20 18:49:43.676744 waagent[1982]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jun 20 18:49:43.676744 waagent[1982]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jun 20 18:49:43.676744 waagent[1982]: Executing ['ip', '-6', '-a', '-o', 'address']: Jun 20 18:49:43.676744 waagent[1982]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jun 20 18:49:43.676744 waagent[1982]: 2: eth0 inet6 fe80::7e1e:52ff:fe36:1fc5/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jun 20 18:49:43.760041 waagent[1982]: 2025-06-20T18:49:43.759968Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Jun 20 18:49:43.760041 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.760041 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.760041 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.760041 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.760041 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.760041 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.760041 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 18:49:43.760041 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 18:49:43.760041 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 18:49:43.763768 waagent[1982]: 2025-06-20T18:49:43.763716Z INFO EnvHandler ExtHandler Current Firewall rules: Jun 20 18:49:43.763768 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.763768 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.763768 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.763768 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.763768 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.763768 waagent[1982]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.763768 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 18:49:43.763768 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 18:49:43.763768 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 18:49:43.764031 waagent[1982]: 2025-06-20T18:49:43.763991Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jun 20 18:49:43.764192 waagent[1982]: 2025-06-20T18:49:43.764154Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jun 20 18:49:54.506568 chronyd[1705]: Selected source PHC0 Jun 20 18:49:56.832018 setup-nvidia[1861]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 479M 0 816k 0 0 639k 0 0:12:48 0:00:01 0:12:47 638k\u000d 4 479M 4 21.2M 0 0 9624k 0 0:00:51 0:00:02 0:00:49 9624k\u000d 9 479M 9 45.6M 0 0 14.0M 0 0:00:34 0:00:03 0:00:31 14.0M\u000d 14 479M 14 69.2M 0 0 16.3M 0 0:00:29 0:00:04 0:00:25 16.3M\u000d 19 479M 19 93.4M 0 0 17.8M 0 0:00:26 0:00:05 0:00:21 18.5M\u000d 24 479M 24 117M 0 0 18.9M 0 0:00:25 0:00:06 0:00:19 23.6M\u000d 29 479M 29 140M 0 0 19.5M 0 0:00:24 0:00:07 0:00:17 24.2M\u000d 34 479M 34 164M 0 0 20.0M 0 0:00:23 0:00:08 0:00:15 23.9M\u000d 34 479M 34 167M 0 0 18.2M 0 0:00:26 0:00:09 0:00:17 19.9M\u000d 34 479M 34 167M 0 0 16.3M 0 0:00:29 0:00:10 0:00:19 14.7M\u000d 35 479M 35 169M 0 0 15.0M 0 0:00:31 0:00:11 0:00:20 10.2M\u000d 37 479M 37 181M 0 0 14.8M 0 0:00:32 0:00:12 0:00:20 8385k\u000d 42 479M 42 204M 0 0 15.5M 0 0:00:30 0:00:13 0:00:17 8330k\u000d 47 479M 47 227M 0 0 16.0M 0 0:00:29 0:00:14 0:00:15 11.8M\u000d 52 479M 52 251M 0 0 16.5M 0 0:00:28 0:00:15 0:00:13 17.0M\u000d 57 479M 57 277M 0 0 17.0M 0 0:00:28 0:00:16 0:00:12 21.3M\u000d 63 479M 63 302M 0 0 17.5M 0 0:00:27 0:00:17 0:00:10 23.8M\u000d 67 479M 67 325M 0 0 17.9M 0 0:00:26 0:00:18 0:00:08 24.2M\u000d 72 479M 72 350M 0 0 18.1M 0 0:00:26 0:00:19 0:00:07 24.0M\u000d 77 479M 77 372M 0 0 18.3M 0 0:00:26 0:00:20 0:00:06 23.7M\u000d 82 479M 82 395M 0 0 18.5M 0 0:00:25 0:00:21 0:00:04 23.6M\u000d 87 479M 87 417M 0 0 18.7M 0 0:00:25 0:00:22 0:00:03 23.2M\u000d 91 479M 91 441M 0 0 18.9M 0 0:00:25 0:00:23 0:00:02 22.7M\u000d 96 479M 96 463M 0 0 19.1M 0 0:00:25 0:00:24 0:00:01 22.9M\u000d100 479M 100 479M 0 0 19.3M 0 0:00:24 0:00:24 --:--:-- 23.6M Jun 20 18:49:58.142783 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 20 18:49:58.153965 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:50364.service - OpenSSH per-connection server daemon (10.200.16.10:50364). Jun 20 18:49:59.067232 sshd[2232]: Accepted publickey for core from 10.200.16.10 port 50364 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:49:59.068681 sshd-session[2232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:49:59.072967 systemd-logind[1706]: New session 3 of user core. Jun 20 18:49:59.083896 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 20 18:49:59.679566 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:35534.service - OpenSSH per-connection server daemon (10.200.16.10:35534). Jun 20 18:50:00.315678 sshd[2237]: Accepted publickey for core from 10.200.16.10 port 35534 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:00.316012 sshd-session[2237]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:00.326165 systemd-logind[1706]: New session 4 of user core. Jun 20 18:50:00.330804 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 20 18:50:00.763708 sshd[2239]: Connection closed by 10.200.16.10 port 35534 Jun 20 18:50:00.762733 sshd-session[2237]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:00.768097 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:35534.service: Deactivated successfully. Jun 20 18:50:00.772836 systemd[1]: session-4.scope: Deactivated successfully. Jun 20 18:50:00.775104 systemd-logind[1706]: Session 4 logged out. Waiting for processes to exit. Jun 20 18:50:00.776135 systemd-logind[1706]: Removed session 4. Jun 20 18:50:00.883934 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:35546.service - OpenSSH per-connection server daemon (10.200.16.10:35546). Jun 20 18:50:01.517688 sshd[2245]: Accepted publickey for core from 10.200.16.10 port 35546 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:01.519285 sshd-session[2245]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:01.523790 systemd-logind[1706]: New session 5 of user core. Jun 20 18:50:01.535923 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 20 18:50:01.967289 sshd[2247]: Connection closed by 10.200.16.10 port 35546 Jun 20 18:50:01.967699 sshd-session[2245]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:01.973161 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:35546.service: Deactivated successfully. Jun 20 18:50:01.975101 systemd-logind[1706]: Session 5 logged out. Waiting for processes to exit. Jun 20 18:50:01.977947 systemd[1]: session-5.scope: Deactivated successfully. Jun 20 18:50:01.979066 systemd-logind[1706]: Removed session 5. Jun 20 18:50:08.010833 setup-nvidia[1698]: Downloading NVIDIA 535.230.02 Driver Jun 20 18:50:08.015172 setup-nvidia[2253]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 18:50:08.015401 setup-nvidia[2253]: Dload Upload Total Spent Left Speed Jun 20 18:50:09.127610 setup-nvidia[2253]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 3 326M 3 11.1M 0 0 65.8M 0 0:00:04 --:--:-- 0:00:04 65.6M\u000d100 326M 100 326M 0 0 293M 0 0:00:01 0:00:01 --:--:-- 293M Jun 20 18:50:09.202047 setup-nvidia[1698]: Extract the NVIDIA Driver Installer 535.230.02 Jun 20 18:50:09.202289 setup-nvidia[1698]: /opt/nvidia/workdir/nvidia-workdir / Jun 20 18:50:09.248820 setup-nvidia[2256]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jun 20 18:50:09.544794 setup-nvidia[2256]: Verifying archive integrity... OK Jun 20 18:50:10.269779 setup-nvidia[2256]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jun 20 18:50:12.080492 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:46200.service - OpenSSH per-connection server daemon (10.200.16.10:46200). Jun 20 18:50:12.177966 setup-nvidia[2282]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jun 20 18:50:12.252317 setup-nvidia[1698]: / Jun 20 18:50:12.252317 setup-nvidia[1698]: Spawn system-nspawn container to install the NVIDIA drivers Jun 20 18:50:12.630048 sudo[2292]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.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 Jun 20 18:50:12.631751 sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 18:50:12.641062 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jun 20 18:50:12.655897 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 18:50:12.671211 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 18:50:12.674075 systemd[1]: Starting user@0.service - User Manager for UID 0... Jun 20 18:50:12.683389 (systemd)[2295]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jun 20 18:50:12.685615 systemd-logind[1706]: New session c3 of user root. Jun 20 18:50:12.721797 sshd[2287]: Accepted publickey for core from 10.200.16.10 port 46200 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:12.723474 sshd-session[2287]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:12.728543 systemd-logind[1706]: New session 6 of user core. Jun 20 18:50:12.737785 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 20 18:50:12.854694 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jun 20 18:50:12.859958 systemd[2295]: Queued start job for default target default.target. Jun 20 18:50:12.868737 systemd[2295]: Created slice app.slice - User Application Slice. Jun 20 18:50:12.868767 systemd[2295]: Reached target paths.target - Paths. Jun 20 18:50:12.868815 systemd[2295]: Reached target timers.target - Timers. Jun 20 18:50:12.870055 systemd[2295]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 18:50:12.880948 systemd[2295]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:50:12.881131 systemd[2295]: Reached target sockets.target - Sockets. Jun 20 18:50:12.881191 systemd[2295]: Reached target basic.target - Basic System. Jun 20 18:50:12.881241 systemd[2295]: Reached target default.target - Main User Target. Jun 20 18:50:12.881274 systemd[2295]: Startup finished in 190ms. Jun 20 18:50:12.881329 systemd[1]: Started user@0.service - User Manager for UID 0. Jun 20 18:50:12.883430 systemd[1]: Started session-c2.scope - Session c2 of User root. Jun 20 18:50:12.951689 kernel: loop6: detected capacity change from 0 to 12644352 Jun 20 18:50:12.988683 kernel: loop6: p9 Jun 20 18:50:13.170387 sshd[2301]: Connection closed by 10.200.16.10 port 46200 Jun 20 18:50:13.170729 sshd-session[2287]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:13.173993 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:46200.service: Deactivated successfully. Jun 20 18:50:13.175600 systemd[1]: session-6.scope: Deactivated successfully. Jun 20 18:50:13.176244 systemd-logind[1706]: Session 6 logged out. Waiting for processes to exit. Jun 20 18:50:13.177028 systemd-logind[1706]: Removed session 6. Jun 20 18:50:13.235060 setup-nvidia[2305]: ░ Spawning container flatcardevelopercontainer-4230.2.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.0.bin. Jun 20 18:50:13.246678 kernel: EXT4-fs (loop6p9): mounted filesystem 290506de-cc1b-48db-af12-9d0ced62311c ro without journal. Quota mode: none. Jun 20 18:50:13.286047 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:46214.service - OpenSSH per-connection server daemon (10.200.16.10:46214). Jun 20 18:50:13.916106 sshd[2314]: Accepted publickey for core from 10.200.16.10 port 46214 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:13.917425 sshd-session[2314]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:13.921684 systemd-logind[1706]: New session 7 of user core. Jun 20 18:50:13.934780 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 20 18:50:14.364784 sshd[2318]: Connection closed by 10.200.16.10 port 46214 Jun 20 18:50:14.365188 sshd-session[2314]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:14.368416 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:46214.service: Deactivated successfully. Jun 20 18:50:14.370061 systemd[1]: session-7.scope: Deactivated successfully. Jun 20 18:50:14.370717 systemd-logind[1706]: Session 7 logged out. Waiting for processes to exit. Jun 20 18:50:14.371475 systemd-logind[1706]: Removed session 7. Jun 20 18:50:16.054629 update_engine[1713]: I20250620 18:50:16.053692 1713 update_attempter.cc:509] Updating boot flags... Jun 20 18:50:16.126682 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2388) Jun 20 18:50:16.222690 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2390) Jun 20 18:50:24.480367 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:56088.service - OpenSSH per-connection server daemon (10.200.16.10:56088). Jun 20 18:50:25.116169 sshd[3332]: Accepted publickey for core from 10.200.16.10 port 56088 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:25.117491 sshd-session[3332]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:25.121585 systemd-logind[1706]: New session 8 of user core. Jun 20 18:50:25.132785 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 20 18:50:25.559884 sshd[3456]: Connection closed by 10.200.16.10 port 56088 Jun 20 18:50:25.560186 sshd-session[3332]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:25.563340 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:56088.service: Deactivated successfully. Jun 20 18:50:25.564981 systemd[1]: session-8.scope: Deactivated successfully. Jun 20 18:50:25.565598 systemd-logind[1706]: Session 8 logged out. Waiting for processes to exit. Jun 20 18:50:25.566373 systemd-logind[1706]: Removed session 8. Jun 20 18:50:25.672048 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:56090.service - OpenSSH per-connection server daemon (10.200.16.10:56090). Jun 20 18:50:26.309372 sshd[3462]: Accepted publickey for core from 10.200.16.10 port 56090 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:26.310739 sshd-session[3462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:26.314881 systemd-logind[1706]: New session 9 of user core. Jun 20 18:50:26.323783 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 20 18:50:26.753620 sshd[3464]: Connection closed by 10.200.16.10 port 56090 Jun 20 18:50:26.754013 sshd-session[3462]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:26.757202 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:56090.service: Deactivated successfully. Jun 20 18:50:26.758874 systemd[1]: session-9.scope: Deactivated successfully. Jun 20 18:50:26.759482 systemd-logind[1706]: Session 9 logged out. Waiting for processes to exit. Jun 20 18:50:26.760248 systemd-logind[1706]: Removed session 9. Jun 20 18:50:36.874440 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:33530.service - OpenSSH per-connection server daemon (10.200.16.10:33530). Jun 20 18:50:37.505016 sshd[3514]: Accepted publickey for core from 10.200.16.10 port 33530 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:37.506323 sshd-session[3514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:37.510601 systemd-logind[1706]: New session 10 of user core. Jun 20 18:50:37.521790 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 20 18:50:37.948230 sshd[3516]: Connection closed by 10.200.16.10 port 33530 Jun 20 18:50:37.948629 sshd-session[3514]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:37.952110 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:33530.service: Deactivated successfully. Jun 20 18:50:37.953823 systemd[1]: session-10.scope: Deactivated successfully. Jun 20 18:50:37.954474 systemd-logind[1706]: Session 10 logged out. Waiting for processes to exit. Jun 20 18:50:37.955340 systemd-logind[1706]: Removed session 10. Jun 20 18:50:38.063746 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:33538.service - OpenSSH per-connection server daemon (10.200.16.10:33538). Jun 20 18:50:38.693840 sshd[3522]: Accepted publickey for core from 10.200.16.10 port 33538 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:38.695164 sshd-session[3522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:38.699335 systemd-logind[1706]: New session 11 of user core. Jun 20 18:50:38.710780 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 20 18:50:39.143214 sshd[3524]: Connection closed by 10.200.16.10 port 33538 Jun 20 18:50:39.143468 sshd-session[3522]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:39.146769 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:33538.service: Deactivated successfully. Jun 20 18:50:39.148406 systemd[1]: session-11.scope: Deactivated successfully. Jun 20 18:50:39.149062 systemd-logind[1706]: Session 11 logged out. Waiting for processes to exit. Jun 20 18:50:39.149949 systemd-logind[1706]: Removed session 11. Jun 20 18:50:49.257238 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:48854.service - OpenSSH per-connection server daemon (10.200.16.10:48854). Jun 20 18:50:49.888598 sshd[6312]: Accepted publickey for core from 10.200.16.10 port 48854 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:49.890493 sshd-session[6312]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:49.897248 systemd-logind[1706]: New session 12 of user core. Jun 20 18:50:49.901024 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 20 18:50:50.339429 sshd[6508]: Connection closed by 10.200.16.10 port 48854 Jun 20 18:50:50.343422 sshd-session[6312]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:50.347809 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:48854.service: Deactivated successfully. Jun 20 18:50:50.349984 systemd[1]: session-12.scope: Deactivated successfully. Jun 20 18:50:50.351870 systemd-logind[1706]: Session 12 logged out. Waiting for processes to exit. Jun 20 18:50:50.354210 systemd-logind[1706]: Removed session 12. Jun 20 18:50:50.466887 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:48858.service - OpenSSH per-connection server daemon (10.200.16.10:48858). Jun 20 18:50:51.094599 sshd[6686]: Accepted publickey for core from 10.200.16.10 port 48858 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:51.096512 sshd-session[6686]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:51.101868 systemd-logind[1706]: New session 13 of user core. Jun 20 18:50:51.115962 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 20 18:50:51.540754 sshd[6842]: Connection closed by 10.200.16.10 port 48858 Jun 20 18:50:51.540171 sshd-session[6686]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:51.542909 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:48858.service: Deactivated successfully. Jun 20 18:50:51.545572 systemd[1]: session-13.scope: Deactivated successfully. Jun 20 18:50:51.546572 systemd-logind[1706]: Session 13 logged out. Waiting for processes to exit. Jun 20 18:50:51.547530 systemd-logind[1706]: Removed session 13. Jun 20 18:51:01.671772 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:49232.service - OpenSSH per-connection server daemon (10.200.16.10:49232). Jun 20 18:51:02.303169 sshd[10323]: Accepted publickey for core from 10.200.16.10 port 49232 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:02.304975 sshd-session[10323]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:02.313037 systemd-logind[1706]: New session 14 of user core. Jun 20 18:51:02.319833 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 20 18:51:02.750897 sshd[10461]: Connection closed by 10.200.16.10 port 49232 Jun 20 18:51:02.754284 sshd-session[10323]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:02.760371 systemd-logind[1706]: Session 14 logged out. Waiting for processes to exit. Jun 20 18:51:02.764445 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:49232.service: Deactivated successfully. Jun 20 18:51:02.767312 systemd[1]: session-14.scope: Deactivated successfully. Jun 20 18:51:02.768926 systemd-logind[1706]: Removed session 14. Jun 20 18:51:02.870969 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:49244.service - OpenSSH per-connection server daemon (10.200.16.10:49244). Jun 20 18:51:03.508642 sshd[10647]: Accepted publickey for core from 10.200.16.10 port 49244 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:03.510473 sshd-session[10647]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:03.517327 systemd-logind[1706]: New session 15 of user core. Jun 20 18:51:03.527829 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 20 18:51:03.954760 sshd[10872]: Connection closed by 10.200.16.10 port 49244 Jun 20 18:51:03.956718 sshd-session[10647]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:03.959594 systemd-logind[1706]: Session 15 logged out. Waiting for processes to exit. Jun 20 18:51:03.961983 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:49244.service: Deactivated successfully. Jun 20 18:51:03.964561 systemd[1]: session-15.scope: Deactivated successfully. Jun 20 18:51:03.966059 systemd-logind[1706]: Removed session 15. Jun 20 18:51:14.068914 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:37256.service - OpenSSH per-connection server daemon (10.200.16.10:37256). Jun 20 18:51:14.703138 sshd[11912]: Accepted publickey for core from 10.200.16.10 port 37256 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:14.705071 sshd-session[11912]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:14.710559 systemd-logind[1706]: New session 16 of user core. Jun 20 18:51:14.720855 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 20 18:51:15.152269 sshd[11926]: Connection closed by 10.200.16.10 port 37256 Jun 20 18:51:15.153124 sshd-session[11912]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:15.156401 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:37256.service: Deactivated successfully. Jun 20 18:51:15.156750 systemd-logind[1706]: Session 16 logged out. Waiting for processes to exit. Jun 20 18:51:15.158631 systemd[1]: session-16.scope: Deactivated successfully. Jun 20 18:51:15.161037 systemd-logind[1706]: Removed session 16. Jun 20 18:51:15.418997 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:37270.service - OpenSSH per-connection server daemon (10.200.16.10:37270). Jun 20 18:51:16.053257 sshd[11945]: Accepted publickey for core from 10.200.16.10 port 37270 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:16.055095 sshd-session[11945]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:16.067682 systemd-logind[1706]: New session 17 of user core. Jun 20 18:51:16.076098 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 20 18:51:16.503616 sshd[11967]: Connection closed by 10.200.16.10 port 37270 Jun 20 18:51:16.504023 sshd-session[11945]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:16.507054 systemd-logind[1706]: Session 17 logged out. Waiting for processes to exit. Jun 20 18:51:16.508526 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:37270.service: Deactivated successfully. Jun 20 18:51:16.510870 systemd[1]: session-17.scope: Deactivated successfully. Jun 20 18:51:16.512646 systemd-logind[1706]: Removed session 17. Jun 20 18:51:26.628928 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:34652.service - OpenSSH per-connection server daemon (10.200.16.10:34652). Jun 20 18:51:27.264717 sshd[12294]: Accepted publickey for core from 10.200.16.10 port 34652 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:27.266136 sshd-session[12294]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:27.272910 systemd-logind[1706]: New session 18 of user core. Jun 20 18:51:27.281365 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 20 18:51:27.720315 sshd[12321]: Connection closed by 10.200.16.10 port 34652 Jun 20 18:51:27.720736 sshd-session[12294]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:27.726580 systemd-logind[1706]: Session 18 logged out. Waiting for processes to exit. Jun 20 18:51:27.728283 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:34652.service: Deactivated successfully. Jun 20 18:51:27.729925 systemd[1]: session-18.scope: Deactivated successfully. Jun 20 18:51:27.731733 systemd-logind[1706]: Removed session 18. Jun 20 18:51:27.844967 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:34654.service - OpenSSH per-connection server daemon (10.200.16.10:34654). Jun 20 18:51:28.483801 sshd[12338]: Accepted publickey for core from 10.200.16.10 port 34654 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:28.485587 sshd-session[12338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:28.492190 systemd-logind[1706]: New session 19 of user core. Jun 20 18:51:28.498828 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 20 18:51:28.940833 sshd[12364]: Connection closed by 10.200.16.10 port 34654 Jun 20 18:51:28.941280 sshd-session[12338]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:28.946808 systemd-logind[1706]: Session 19 logged out. Waiting for processes to exit. Jun 20 18:51:28.948479 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:34654.service: Deactivated successfully. Jun 20 18:51:28.950866 systemd[1]: session-19.scope: Deactivated successfully. Jun 20 18:51:28.952451 systemd-logind[1706]: Removed session 19. Jun 20 18:51:39.055795 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:50730.service - OpenSSH per-connection server daemon (10.200.16.10:50730). Jun 20 18:51:39.686334 sshd[12715]: Accepted publickey for core from 10.200.16.10 port 50730 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:39.687807 sshd-session[12715]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:39.692112 systemd-logind[1706]: New session 20 of user core. Jun 20 18:51:39.705875 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 20 18:51:40.130118 sshd[12717]: Connection closed by 10.200.16.10 port 50730 Jun 20 18:51:40.130516 sshd-session[12715]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:40.133824 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:50730.service: Deactivated successfully. Jun 20 18:51:40.135478 systemd[1]: session-20.scope: Deactivated successfully. Jun 20 18:51:40.136233 systemd-logind[1706]: Session 20 logged out. Waiting for processes to exit. Jun 20 18:51:40.137008 systemd-logind[1706]: Removed session 20. Jun 20 18:51:40.250420 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:50732.service - OpenSSH per-connection server daemon (10.200.16.10:50732). Jun 20 18:51:40.881389 sshd[12723]: Accepted publickey for core from 10.200.16.10 port 50732 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:40.882755 sshd-session[12723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:40.886984 systemd-logind[1706]: New session 21 of user core. Jun 20 18:51:40.897779 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 20 18:51:41.337568 sshd[12725]: Connection closed by 10.200.16.10 port 50732 Jun 20 18:51:41.337909 sshd-session[12723]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:41.341467 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:50732.service: Deactivated successfully. Jun 20 18:51:41.343122 systemd[1]: session-21.scope: Deactivated successfully. Jun 20 18:51:41.344113 systemd-logind[1706]: Session 21 logged out. Waiting for processes to exit. Jun 20 18:51:41.344920 systemd-logind[1706]: Removed session 21. Jun 20 18:51:46.953712 setup-nvidia[2305]: \u000d Jun 20 18:51:46.953712 setup-nvidia[2305]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jun 20 18:51:46.953712 setup-nvidia[2305]: frequently when this kernel module was built against the wrong or Jun 20 18:51:46.953712 setup-nvidia[2305]: improperly configured kernel sources, with a version of gcc that differs Jun 20 18:51:46.953712 setup-nvidia[2305]: from the one used to build the target kernel, or if another driver, such Jun 20 18:51:46.953712 setup-nvidia[2305]: as nouveau, is present and prevents the NVIDIA kernel module from Jun 20 18:51:46.953712 setup-nvidia[2305]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jun 20 18:51:46.953712 setup-nvidia[2305]: installed in this system is supported by this NVIDIA Linux graphics Jun 20 18:51:46.953712 setup-nvidia[2305]: driver release. Jun 20 18:51:46.953712 setup-nvidia[2305]: \u000d Jun 20 18:51:46.953712 setup-nvidia[2305]: Please see the log entries 'Kernel module load error' and 'Kernel Jun 20 18:51:46.953712 setup-nvidia[2305]: messages' at the end of the file Jun 20 18:51:46.953712 setup-nvidia[2305]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jun 20 18:51:46.953712 setup-nvidia[2305]: information. Jun 20 18:51:46.953712 setup-nvidia[2305]: \u000d Jun 20 18:51:46.968650 setup-nvidia[2305]: dmesg: read kernel buffer failed: Operation not permitted Jun 20 18:51:46.990801 setup-nvidia[2305]: \u000d Jun 20 18:51:46.990801 setup-nvidia[2305]: ERROR: Installation has failed. Please see the file Jun 20 18:51:46.990801 setup-nvidia[2305]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jun 20 18:51:46.990801 setup-nvidia[2305]: details. You may find suggestions on fixing installation problems in Jun 20 18:51:46.990801 setup-nvidia[2305]: the README available on the Linux driver download page at Jun 20 18:51:46.990801 setup-nvidia[2305]: www.nvidia.com. Jun 20 18:51:46.990801 setup-nvidia[2305]: \u000d Jun 20 18:51:46.991180 setup-nvidia[2305]: Last 50 lines of nvidia-installer.log: Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Jun 20 18:51:46.992021 setup-nvidia[2305]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jun 20 18:51:46.992021 setup-nvidia[2305]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jun 20 18:51:46.992021 setup-nvidia[2305]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jun 20 18:51:46.992021 setup-nvidia[2305]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jun 20 18:51:46.992021 setup-nvidia[2305]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jun 20 18:51:46.992021 setup-nvidia[2305]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jun 20 18:51:46.992021 setup-nvidia[2305]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jun 20 18:51:46.992021 setup-nvidia[2305]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jun 20 18:51:46.992021 setup-nvidia[2305]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jun 20 18:51:46.992021 setup-nvidia[2305]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jun 20 18:51:46.992021 setup-nvidia[2305]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 18:51:46.992021 setup-nvidia[2305]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jun 20 18:51:46.992021 setup-nvidia[2305]: make[2]: Leaving directory '/usr/lib/modules/6.6.94-flatcar/build' Jun 20 18:51:46.992021 setup-nvidia[2305]: make[1]: Leaving directory '/usr/lib/modules/6.6.94-flatcar/source' Jun 20 18:51:46.992021 setup-nvidia[2305]: -> done. Jun 20 18:51:46.992021 setup-nvidia[2305]: -> Kernel module compilation complete. Jun 20 18:51:46.992021 setup-nvidia[2305]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jun 20 18:51:46.992021 setup-nvidia[2305]: 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. Jun 20 18:51:46.992021 setup-nvidia[2305]: \u000d Jun 20 18:51:46.993287 setup-nvidia[2305]: 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. Jun 20 18:51:46.993287 setup-nvidia[2305]: -> Kernel module load error: Operation not permitted Jun 20 18:51:46.993287 setup-nvidia[2305]: -> Kernel messages: Jun 20 18:51:46.993287 setup-nvidia[2305]: 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. Jun 20 18:51:51.451031 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:58690.service - OpenSSH per-connection server daemon (10.200.16.10:58690). Jun 20 18:51:52.081409 sshd[12940]: Accepted publickey for core from 10.200.16.10 port 58690 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:52.082773 sshd-session[12940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:52.086896 systemd-logind[1706]: New session 22 of user core. Jun 20 18:51:52.100773 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 20 18:51:52.525887 sshd[12942]: Connection closed by 10.200.16.10 port 58690 Jun 20 18:51:52.526381 sshd-session[12940]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:52.529786 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:58690.service: Deactivated successfully. Jun 20 18:51:52.531632 systemd[1]: session-22.scope: Deactivated successfully. Jun 20 18:51:52.532393 systemd-logind[1706]: Session 22 logged out. Waiting for processes to exit. Jun 20 18:51:52.533196 systemd-logind[1706]: Removed session 22. Jun 20 18:51:52.644403 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:58702.service - OpenSSH per-connection server daemon (10.200.16.10:58702). Jun 20 18:51:53.275218 sshd[12948]: Accepted publickey for core from 10.200.16.10 port 58702 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:53.276564 sshd-session[12948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:53.280932 systemd-logind[1706]: New session 23 of user core. Jun 20 18:51:53.291784 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 20 18:51:53.724067 sshd[12950]: Connection closed by 10.200.16.10 port 58702 Jun 20 18:51:53.724453 sshd-session[12948]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:53.727555 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:58702.service: Deactivated successfully. Jun 20 18:51:53.729183 systemd[1]: session-23.scope: Deactivated successfully. Jun 20 18:51:53.729891 systemd-logind[1706]: Session 23 logged out. Waiting for processes to exit. Jun 20 18:51:53.730664 systemd-logind[1706]: Removed session 23. Jun 20 18:52:00.453687 kernel: EXT4-fs (loop6p9): unmounting filesystem 290506de-cc1b-48db-af12-9d0ced62311c. Jun 20 18:52:00.454098 setup-nvidia[2305]: Container flatcardevelopercontainer-4230.2.0.bin exited successfully. Jun 20 18:52:00.457194 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.0.bin.mount: Deactivated successfully. Jun 20 18:52:00.512539 sudo[2292]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:00.514341 systemd[1]: session-c2.scope: Deactivated successfully. Jun 20 18:52:00.514631 systemd[1]: session-c2.scope: Consumed 5min 13.366s CPU time, 883.3M memory peak. Jun 20 18:52:00.929942 setup-nvidia[1698]: /opt/nvidia / Jun 20 18:52:00.929942 setup-nvidia[1698]: / Jun 20 18:52:00.947243 setup-nvidia[12973]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 18:52:00.976681 kernel: IPMI message handler: version 39.2 Jun 20 18:52:00.979681 kernel: ipmi device interface Jun 20 18:52:00.980681 setup-nvidia[1698]: /opt/nvidia/current/usr/lib/modules/6.6.94-flatcar/video / Jun 20 18:52:01.032032 kernel: nvidia: loading out-of-tree module taints kernel. Jun 20 18:52:01.032233 kernel: nvidia: module license 'NVIDIA' taints kernel. Jun 20 18:52:01.032317 kernel: Disabling lock debugging due to kernel taint Jun 20 18:52:01.039710 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jun 20 18:52:01.039751 kernel: nvidia: module license taints kernel. Jun 20 18:52:01.444686 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Jun 20 18:52:01.840782 kernel: Jun 20 18:52:01.840921 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jun 20 18:52:02.049683 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jun 20 18:52:02.073684 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jun 20 18:52:02.098698 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jun 20 18:52:02.145247 setup-nvidia[1698]: / Jun 20 18:52:02.145679 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Jun 20 18:52:03.837934 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:38266.service - OpenSSH per-connection server daemon (10.200.16.10:38266). Jun 20 18:52:03.938107 setup-nvidia[13003]: Fri Jun 20 18:52:03 2025 Jun 20 18:52:03.938107 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Jun 20 18:52:03.938107 setup-nvidia[13003]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jun 20 18:52:03.938107 setup-nvidia[13003]: |-----------------------------------------+----------------------+----------------------+ Jun 20 18:52:03.938107 setup-nvidia[13003]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jun 20 18:52:03.938107 setup-nvidia[13003]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jun 20 18:52:03.938107 setup-nvidia[13003]: | | | MIG M. | Jun 20 18:52:03.938107 setup-nvidia[13003]: |=========================================+======================+======================| Jun 20 18:52:03.941648 setup-nvidia[13003]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jun 20 18:52:03.941648 setup-nvidia[13003]: | N/A 29C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jun 20 18:52:03.941648 setup-nvidia[13003]: | | | N/A | Jun 20 18:52:03.941648 setup-nvidia[13003]: +-----------------------------------------+----------------------+----------------------+ Jun 20 18:52:03.941781 setup-nvidia[13003]: Jun 20 18:52:03.941781 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Jun 20 18:52:03.941781 setup-nvidia[13003]: | Processes: | Jun 20 18:52:03.941781 setup-nvidia[13003]: | GPU GI CI PID Type Process name GPU Memory | Jun 20 18:52:03.941781 setup-nvidia[13003]: | ID ID Usage | Jun 20 18:52:03.941781 setup-nvidia[13003]: |=======================================================================================| Jun 20 18:52:03.941917 setup-nvidia[13003]: | No running processes found | Jun 20 18:52:03.941917 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Jun 20 18:52:04.320248 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 20 18:52:04.320909 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 20 18:52:04.321146 systemd[1]: Startup finished in 1.380s (firmware) + 28.380s (loader) + 1.821s (kernel) + 14.027s (initrd) + 2min 47.419s (userspace) = 3min 33.030s. Jun 20 18:52:04.468282 sshd[13008]: Accepted publickey for core from 10.200.16.10 port 38266 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:04.469669 sshd-session[13008]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:04.474075 systemd-logind[1706]: New session 24 of user core. Jun 20 18:52:04.485784 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 20 18:52:04.932782 sshd[13012]: Connection closed by 10.200.16.10 port 38266 Jun 20 18:52:04.933213 sshd-session[13008]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:04.936538 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:38266.service: Deactivated successfully. Jun 20 18:52:04.938215 systemd[1]: session-24.scope: Deactivated successfully. Jun 20 18:52:04.938878 systemd-logind[1706]: Session 24 logged out. Waiting for processes to exit. Jun 20 18:52:04.939643 systemd-logind[1706]: Removed session 24. Jun 20 18:52:05.042897 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:38268.service - OpenSSH per-connection server daemon (10.200.16.10:38268). Jun 20 18:52:05.674022 sshd[13018]: Accepted publickey for core from 10.200.16.10 port 38268 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:05.675370 sshd-session[13018]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:05.679487 systemd-logind[1706]: New session 25 of user core. Jun 20 18:52:05.692783 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 20 18:52:06.118895 sshd[13020]: Connection closed by 10.200.16.10 port 38268 Jun 20 18:52:06.119320 sshd-session[13018]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:06.122649 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:38268.service: Deactivated successfully. Jun 20 18:52:06.124365 systemd[1]: session-25.scope: Deactivated successfully. Jun 20 18:52:06.125039 systemd-logind[1706]: Session 25 logged out. Waiting for processes to exit. Jun 20 18:52:06.125814 systemd-logind[1706]: Removed session 25. Jun 20 18:52:06.230211 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:38280.service - OpenSSH per-connection server daemon (10.200.16.10:38280). Jun 20 18:52:06.862359 sshd[13026]: Accepted publickey for core from 10.200.16.10 port 38280 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:06.863757 sshd-session[13026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:06.867823 systemd-logind[1706]: New session 26 of user core. Jun 20 18:52:06.880789 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 20 18:52:07.306838 sshd[13028]: Connection closed by 10.200.16.10 port 38280 Jun 20 18:52:07.307159 sshd-session[13026]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:07.310441 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:38280.service: Deactivated successfully. Jun 20 18:52:07.312093 systemd[1]: session-26.scope: Deactivated successfully. Jun 20 18:52:07.312744 systemd-logind[1706]: Session 26 logged out. Waiting for processes to exit. Jun 20 18:52:07.313510 systemd-logind[1706]: Removed session 26. Jun 20 18:52:07.416995 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:38282.service - OpenSSH per-connection server daemon (10.200.16.10:38282). Jun 20 18:52:08.045761 sshd[13034]: Accepted publickey for core from 10.200.16.10 port 38282 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:08.047163 sshd-session[13034]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:08.051286 systemd-logind[1706]: New session 27 of user core. Jun 20 18:52:08.062817 systemd[1]: Started session-27.scope - Session 27 of User core. Jun 20 18:52:08.392196 sudo[13037]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 20 18:52:08.392516 sudo[13037]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 18:52:08.451997 sudo[13037]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:08.551936 sshd[13036]: Connection closed by 10.200.16.10 port 38282 Jun 20 18:52:08.552422 sshd-session[13034]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:08.556041 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:38282.service: Deactivated successfully. Jun 20 18:52:08.557710 systemd[1]: session-27.scope: Deactivated successfully. Jun 20 18:52:08.558366 systemd-logind[1706]: Session 27 logged out. Waiting for processes to exit. Jun 20 18:52:08.559227 systemd-logind[1706]: Removed session 27. Jun 20 18:52:08.663199 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:51716.service - OpenSSH per-connection server daemon (10.200.16.10:51716). Jun 20 18:52:09.292730 sshd[13043]: Accepted publickey for core from 10.200.16.10 port 51716 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:09.294155 sshd-session[13043]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:09.298314 systemd-logind[1706]: New session 28 of user core. Jun 20 18:52:09.308779 systemd[1]: Started session-28.scope - Session 28 of User core. Jun 20 18:52:09.638716 sudo[13047]: 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 Jun 20 18:52:09.639033 sudo[13047]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 18:52:09.642246 sudo[13047]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:09.647726 sudo[13046]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jun 20 18:52:09.648029 sudo[13046]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 18:52:09.669949 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 18:52:09.697345 augenrules[13069]: No rules Jun 20 18:52:09.698609 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 18:52:09.698909 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 18:52:09.699717 sudo[13046]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:09.800389 sshd[13045]: Connection closed by 10.200.16.10 port 51716 Jun 20 18:52:09.800779 sshd-session[13043]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:09.804181 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:51716.service: Deactivated successfully. Jun 20 18:52:09.805805 systemd[1]: session-28.scope: Deactivated successfully. Jun 20 18:52:09.806438 systemd-logind[1706]: Session 28 logged out. Waiting for processes to exit. Jun 20 18:52:09.807202 systemd-logind[1706]: Removed session 28. Jun 20 18:52:09.912227 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:51724.service - OpenSSH per-connection server daemon (10.200.16.10:51724). Jun 20 18:52:10.541651 sshd[13078]: Accepted publickey for core from 10.200.16.10 port 51724 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:10.543071 sshd-session[13078]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:10.547266 systemd-logind[1706]: New session 29 of user core. Jun 20 18:52:10.559792 systemd[1]: Started session-29.scope - Session 29 of User core. Jun 20 18:52:10.560927 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jun 20 18:52:10.561732 systemd[2295]: Activating special unit exit.target... Jun 20 18:52:10.561807 systemd[2295]: Stopped target default.target - Main User Target. Jun 20 18:52:10.561849 systemd[2295]: Stopped target basic.target - Basic System. Jun 20 18:52:10.561880 systemd[2295]: Stopped target paths.target - Paths. Jun 20 18:52:10.561908 systemd[2295]: Stopped target sockets.target - Sockets. Jun 20 18:52:10.561937 systemd[2295]: Stopped target timers.target - Timers. Jun 20 18:52:10.562306 systemd[2295]: Closed dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:52:10.562519 systemd[2295]: Removed slice app.slice - User Application Slice. Jun 20 18:52:10.562556 systemd[2295]: Reached target shutdown.target - Shutdown. Jun 20 18:52:10.562731 systemd[2295]: Finished systemd-exit.service - Exit the Session. Jun 20 18:52:10.562775 systemd[2295]: Reached target exit.target - Exit the Session. Jun 20 18:52:10.574273 (sd-pam)[2296]: pam_unix(systemd-user:session): session closed for user root Jun 20 18:52:10.575651 systemd[1]: user@0.service: Deactivated successfully. Jun 20 18:52:10.576048 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jun 20 18:52:10.590824 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 18:52:10.599791 systemd[1]: run-user-0.mount: Deactivated successfully. Jun 20 18:52:10.600832 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jun 20 18:52:10.601048 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 18:52:10.602847 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jun 20 18:52:10.602942 systemd[1]: user-0.slice: Consumed 5min 13.644s CPU time, 892.1M memory peak. Jun 20 18:52:10.603359 systemd-logind[1706]: Removed session c3. Jun 20 18:52:10.887524 sudo[13088]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jun 20 18:52:10.887852 sudo[13088]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 18:52:10.891313 sudo[13088]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:10.996245 sshd[13081]: Connection closed by 10.200.16.10 port 51724 Jun 20 18:52:10.996607 sshd-session[13078]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:10.999683 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:51724.service: Deactivated successfully. Jun 20 18:52:11.001340 systemd[1]: session-29.scope: Deactivated successfully. Jun 20 18:52:11.001981 systemd-logind[1706]: Session 29 logged out. Waiting for processes to exit. Jun 20 18:52:11.002723 systemd-logind[1706]: Removed session 29. Jun 20 18:52:11.108180 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:51726.service - OpenSSH per-connection server daemon (10.200.16.10:51726). Jun 20 18:52:11.738416 sshd[13094]: Accepted publickey for core from 10.200.16.10 port 51726 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:11.739775 sshd-session[13094]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:11.744420 systemd-logind[1706]: New session 30 of user core. Jun 20 18:52:11.754777 systemd[1]: Started session-30.scope - Session 30 of User core. Jun 20 18:52:12.084979 sudo[13097]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jun 20 18:52:12.085290 sudo[13097]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 18:52:16.535195 sudo[13097]: pam_unix(sudo:session): session closed for user root Jun 20 18:52:16.640545 sshd[13096]: Connection closed by 10.200.16.10 port 51726 Jun 20 18:52:16.641213 sshd-session[13094]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:16.644406 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:51726.service: Deactivated successfully. Jun 20 18:52:16.646102 systemd[1]: session-30.scope: Deactivated successfully. Jun 20 18:52:16.646732 systemd-logind[1706]: Session 30 logged out. Waiting for processes to exit. Jun 20 18:52:16.647488 systemd-logind[1706]: Removed session 30. Jun 20 18:52:16.756037 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:51738.service - OpenSSH per-connection server daemon (10.200.16.10:51738). Jun 20 18:52:17.387101 sshd[13103]: Accepted publickey for core from 10.200.16.10 port 51738 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:17.388488 sshd-session[13103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:17.392627 systemd-logind[1706]: New session 31 of user core. Jun 20 18:52:17.399791 systemd[1]: Started session-31.scope - Session 31 of User core. Jun 20 18:52:17.919577 sshd[13105]: Connection closed by 10.200.16.10 port 51738 Jun 20 18:52:17.919970 sshd-session[13103]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:17.923172 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:51738.service: Deactivated successfully. Jun 20 18:52:17.924913 systemd[1]: session-31.scope: Deactivated successfully. Jun 20 18:52:17.925559 systemd-logind[1706]: Session 31 logged out. Waiting for processes to exit. Jun 20 18:52:17.926374 systemd-logind[1706]: Removed session 31. Jun 20 18:52:18.034246 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:51752.service - OpenSSH per-connection server daemon (10.200.16.10:51752). Jun 20 18:52:18.663600 sshd[13121]: Accepted publickey for core from 10.200.16.10 port 51752 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:18.664943 sshd-session[13121]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:18.669019 systemd-logind[1706]: New session 32 of user core. Jun 20 18:52:18.687787 systemd[1]: Started session-32.scope - Session 32 of User core. Jun 20 18:52:19.835890 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 20 18:52:19.836044 (dockerd)[13143]: 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 Jun 20 18:52:22.087253 dockerd[13143]: time="2025-06-20T18:52:22.087188881Z" level=info msg="Starting up" Jun 20 18:52:22.714121 dockerd[13143]: time="2025-06-20T18:52:22.714023876Z" level=info msg="Loading containers: start." Jun 20 18:52:22.915679 kernel: Initializing XFRM netlink socket Jun 20 18:52:23.037260 systemd-networkd[1400]: docker0: Link UP Jun 20 18:52:23.088915 dockerd[13143]: time="2025-06-20T18:52:23.088874419Z" level=info msg="Loading containers: done." Jun 20 18:52:23.102549 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2699789025-merged.mount: Deactivated successfully. Jun 20 18:52:23.113023 dockerd[13143]: time="2025-06-20T18:52:23.112981034Z" 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 Jun 20 18:52:23.113123 dockerd[13143]: time="2025-06-20T18:52:23.113090530Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Jun 20 18:52:23.113235 dockerd[13143]: time="2025-06-20T18:52:23.113213426Z" level=info msg="Daemon has completed initialization" Jun 20 18:52:23.176841 dockerd[13143]: time="2025-06-20T18:52:23.176793592Z" level=info msg="API listen on /run/docker.sock" Jun 20 18:52:23.176951 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 20 18:52:24.277197 systemd[1]: var-lib-docker-overlay2-5cbdaa5ba3b31af84661ed2285e262ff3c9c7fa52f2b29cb83390e050516c0a1\x2dinit-merged.mount: Deactivated successfully. Jun 20 18:52:24.370073 kernel: docker0: port 1(veth1d2c30a) entered blocking state Jun 20 18:52:24.370184 kernel: docker0: port 1(veth1d2c30a) entered disabled state Jun 20 18:52:24.370208 kernel: veth1d2c30a: entered allmulticast mode Jun 20 18:52:24.375259 kernel: veth1d2c30a: entered promiscuous mode Jun 20 18:52:24.375792 systemd-networkd[1400]: veth1d2c30a: Link UP Jun 20 18:52:25.170394 containerd[1718]: time="2025-06-20T18:52:25.170260830Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jun 20 18:52:25.170889 containerd[1718]: time="2025-06-20T18:52:25.170388026Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jun 20 18:52:25.170889 containerd[1718]: time="2025-06-20T18:52:25.170414625Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 18:52:25.170889 containerd[1718]: time="2025-06-20T18:52:25.170504821Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 18:52:25.230808 systemd[1]: Started docker-dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac.scope - libcontainer container dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac. Jun 20 18:52:25.285683 kernel: eth0: renamed from veth5597a92 Jun 20 18:52:25.304235 kernel: docker0: port 1(veth1d2c30a) entered blocking state Jun 20 18:52:25.304293 kernel: docker0: port 1(veth1d2c30a) entered forwarding state Jun 20 18:52:25.304344 systemd-networkd[1400]: veth1d2c30a: Gained carrier Jun 20 18:52:25.304627 systemd-networkd[1400]: docker0: Gained carrier Jun 20 18:52:26.888802 systemd-networkd[1400]: veth1d2c30a: Gained IPv6LL Jun 20 18:52:26.889138 systemd-networkd[1400]: docker0: Gained IPv6LL Jun 20 18:52:28.586051 systemd[1]: docker-dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac.scope: Deactivated successfully. Jun 20 18:52:28.599191 dockerd[13143]: time="2025-06-20T18:52:28.599143385Z" level=info msg="ignoring event" container=dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 20 18:52:28.600097 containerd[1718]: time="2025-06-20T18:52:28.599263481Z" level=info msg="shim disconnected" id=dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac namespace=moby Jun 20 18:52:28.600097 containerd[1718]: time="2025-06-20T18:52:28.599359877Z" level=warning msg="cleaning up after shim disconnected" id=dc27654de1c5f232fb7f1b1c6fb588688aa9369c37f2c7a86f9d35233c721fac namespace=moby Jun 20 18:52:28.600097 containerd[1718]: time="2025-06-20T18:52:28.599370377Z" level=info msg="cleaning up dead shim" namespace=moby Jun 20 18:52:28.632677 kernel: docker0: port 1(veth1d2c30a) entered disabled state Jun 20 18:52:28.632752 kernel: veth5597a92: renamed from eth0 Jun 20 18:52:28.632700 systemd-networkd[1400]: veth1d2c30a: Lost carrier Jun 20 18:52:28.666565 systemd-networkd[1400]: veth1d2c30a: Link DOWN Jun 20 18:52:28.673553 kernel: docker0: port 1(veth1d2c30a) entered disabled state Jun 20 18:52:28.673606 kernel: veth1d2c30a (unregistering): left allmulticast mode Jun 20 18:52:28.676909 kernel: veth1d2c30a (unregistering): left promiscuous mode Jun 20 18:52:28.680157 kernel: docker0: port 1(veth1d2c30a) entered disabled state Jun 20 18:52:28.710738 systemd[1]: run-docker-netns-9bb46e1dabc4.mount: Deactivated successfully. Jun 20 18:52:28.717251 systemd[1]: var-lib-docker-overlay2-5cbdaa5ba3b31af84661ed2285e262ff3c9c7fa52f2b29cb83390e050516c0a1-merged.mount: Deactivated successfully. Jun 20 18:52:28.851222 sshd[13123]: Connection closed by 10.200.16.10 port 51752 Jun 20 18:52:28.851553 sshd-session[13121]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:28.854889 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:51752.service: Deactivated successfully. Jun 20 18:52:28.856579 systemd[1]: session-32.scope: Deactivated successfully. Jun 20 18:52:28.857265 systemd-logind[1706]: Session 32 logged out. Waiting for processes to exit. Jun 20 18:52:28.858057 systemd-logind[1706]: Removed session 32. Jun 20 18:52:28.963401 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:50008.service - OpenSSH per-connection server daemon (10.200.16.10:50008). Jun 20 18:52:29.593118 sshd[13418]: Accepted publickey for core from 10.200.16.10 port 50008 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:29.594458 sshd-session[13418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:29.598550 systemd-logind[1706]: New session 33 of user core. Jun 20 18:52:29.610788 systemd[1]: Started session-33.scope - Session 33 of User core. Jun 20 18:52:29.640868 systemd-networkd[1400]: docker0: Lost carrier Jun 20 18:52:29.990373 systemd[1]: var-lib-docker-overlay2-4d4c559c2953511d5a519234e5ac156e8ede92b8032ab87781212120e5d91358\x2dinit-merged.mount: Deactivated successfully. Jun 20 18:52:30.086366 kernel: docker0: port 1(veth6a2760b) entered blocking state Jun 20 18:52:30.086482 kernel: docker0: port 1(veth6a2760b) entered disabled state Jun 20 18:52:30.089214 kernel: veth6a2760b: entered allmulticast mode Jun 20 18:52:30.092010 kernel: veth6a2760b: entered promiscuous mode Jun 20 18:52:30.095465 kernel: docker0: port 1(veth6a2760b) entered blocking state Jun 20 18:52:30.098864 kernel: docker0: port 1(veth6a2760b) entered forwarding state Jun 20 18:52:30.099638 systemd-networkd[1400]: veth6a2760b: Link UP Jun 20 18:52:30.099792 kernel: docker0: port 1(veth6a2760b) entered disabled state Jun 20 18:52:30.198273 containerd[1718]: time="2025-06-20T18:52:30.198108100Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jun 20 18:52:30.198273 containerd[1718]: time="2025-06-20T18:52:30.198252095Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jun 20 18:52:30.198273 containerd[1718]: time="2025-06-20T18:52:30.198265494Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 18:52:30.198736 containerd[1718]: time="2025-06-20T18:52:30.198347491Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 20 18:52:30.217787 systemd[1]: Started docker-23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc.scope - libcontainer container 23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc. Jun 20 18:52:30.274689 kernel: eth0: renamed from veth0b6abe2 Jun 20 18:52:30.292676 kernel: docker0: port 1(veth6a2760b) entered blocking state Jun 20 18:52:30.292728 kernel: docker0: port 1(veth6a2760b) entered forwarding state Jun 20 18:52:30.293066 systemd-networkd[1400]: veth6a2760b: Gained carrier Jun 20 18:52:30.293318 systemd-networkd[1400]: docker0: Gained carrier Jun 20 18:52:30.331881 systemd[1]: docker-23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc.scope: Deactivated successfully. Jun 20 18:52:30.351376 dockerd[13143]: time="2025-06-20T18:52:30.351333776Z" level=info msg="ignoring event" container=23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 20 18:52:30.351694 containerd[1718]: time="2025-06-20T18:52:30.351334376Z" level=info msg="shim disconnected" id=23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc namespace=moby Jun 20 18:52:30.351694 containerd[1718]: time="2025-06-20T18:52:30.351383875Z" level=warning msg="cleaning up after shim disconnected" id=23391ce90cd7bdf973ead464757a5408e8325ad70bd55e3c637d1cf2a23bd0cc namespace=moby Jun 20 18:52:30.351694 containerd[1718]: time="2025-06-20T18:52:30.351393374Z" level=info msg="cleaning up dead shim" namespace=moby Jun 20 18:52:30.384287 systemd-networkd[1400]: veth6a2760b: Lost carrier Jun 20 18:52:30.384696 kernel: docker0: port 1(veth6a2760b) entered disabled state Jun 20 18:52:30.384785 kernel: veth0b6abe2: renamed from eth0 Jun 20 18:52:30.412155 systemd-networkd[1400]: veth6a2760b: Link DOWN Jun 20 18:52:30.419138 kernel: docker0: port 1(veth6a2760b) entered disabled state Jun 20 18:52:30.419190 kernel: veth6a2760b (unregistering): left allmulticast mode Jun 20 18:52:30.422423 kernel: veth6a2760b (unregistering): left promiscuous mode Jun 20 18:52:30.425785 kernel: docker0: port 1(veth6a2760b) entered disabled state Jun 20 18:52:30.586400 sshd[13420]: Connection closed by 10.200.16.10 port 50008 Jun 20 18:52:30.586855 sshd-session[13418]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:30.590083 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:50008.service: Deactivated successfully. Jun 20 18:52:30.591743 systemd[1]: session-33.scope: Deactivated successfully. Jun 20 18:52:30.592365 systemd-logind[1706]: Session 33 logged out. Waiting for processes to exit. Jun 20 18:52:30.593133 systemd-logind[1706]: Removed session 33. Jun 20 18:52:30.648857 systemd-networkd[1400]: docker0: Lost carrier Jun 20 18:52:30.704106 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:50018.service - OpenSSH per-connection server daemon (10.200.16.10:50018). Jun 20 18:52:30.987343 systemd[1]: var-lib-docker-overlay2-4d4c559c2953511d5a519234e5ac156e8ede92b8032ab87781212120e5d91358-merged.mount: Deactivated successfully. Jun 20 18:52:31.336979 sshd[13527]: Accepted publickey for core from 10.200.16.10 port 50018 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:31.338274 sshd-session[13527]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:31.342402 systemd-logind[1706]: New session 34 of user core. Jun 20 18:52:31.358777 systemd[1]: Started session-34.scope - Session 34 of User core. Jun 20 18:52:31.747270 ntpdate[13540]: ntpdate 4.2.8p17@1.4004-o Fri Jun 20 16:33:03 UTC 2025 (1) Jun 20 18:52:38.570354 ntpdate[13540]: adjust time server 89.234.64.77 offset +0.000334 sec Jun 20 18:52:38.671094 sshd[13529]: Connection closed by 10.200.16.10 port 50018 Jun 20 18:52:38.671499 sshd-session[13527]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:38.674733 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:50018.service: Deactivated successfully. Jun 20 18:52:38.676376 systemd[1]: session-34.scope: Deactivated successfully. Jun 20 18:52:38.677020 systemd-logind[1706]: Session 34 logged out. Waiting for processes to exit. Jun 20 18:52:38.677768 systemd-logind[1706]: Removed session 34.