Sep 4 23:46:37.217266 kernel: Linux version 6.6.103-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 Thu Sep 4 22:03:18 -00 2025 Sep 4 23:46:37.217294 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:37.217304 kernel: BIOS-provided physical RAM map: Sep 4 23:46:37.217311 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 4 23:46:37.217317 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 4 23:46:37.217324 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 4 23:46:37.217331 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 4 23:46:37.217341 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 4 23:46:37.217348 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 4 23:46:37.217355 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 4 23:46:37.217361 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 4 23:46:37.217368 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 4 23:46:37.217375 kernel: printk: bootconsole [earlyser0] enabled Sep 4 23:46:37.217381 kernel: NX (Execute Disable) protection: active Sep 4 23:46:37.217392 kernel: APIC: Static calls initialized Sep 4 23:46:37.217400 kernel: efi: EFI v2.7 by Microsoft Sep 4 23:46:37.217408 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 4 23:46:37.217415 kernel: random: crng init done Sep 4 23:46:37.217423 kernel: secureboot: Secure boot disabled Sep 4 23:46:37.217430 kernel: SMBIOS 3.1.0 present. Sep 4 23:46:37.217438 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 4 23:46:37.217445 kernel: Hypervisor detected: Microsoft Hyper-V Sep 4 23:46:37.217452 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 4 23:46:37.217460 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 4 23:46:37.217470 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 4 23:46:37.217477 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 4 23:46:37.217484 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 23:46:37.217492 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 23:46:37.217500 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 4 23:46:37.217508 kernel: tsc: Detected 2593.992 MHz processor Sep 4 23:46:37.217516 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 23:46:37.217523 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 23:46:37.217531 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 4 23:46:37.217541 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 4 23:46:37.217549 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 23:46:37.217556 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 4 23:46:37.217564 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 4 23:46:37.217571 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 4 23:46:37.217579 kernel: Using GB pages for direct mapping Sep 4 23:46:37.217586 kernel: ACPI: Early table checksum verification disabled Sep 4 23:46:37.217598 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 4 23:46:37.217608 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217617 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217625 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 4 23:46:37.217633 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 4 23:46:37.217640 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217649 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217659 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217668 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217676 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217684 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217692 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:37.217700 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 4 23:46:37.217766 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 4 23:46:37.217778 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 4 23:46:37.217791 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 4 23:46:37.217799 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 4 23:46:37.217807 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 4 23:46:37.217815 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 4 23:46:37.217823 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 4 23:46:37.217831 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 4 23:46:37.217839 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 4 23:46:37.217847 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 4 23:46:37.217855 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 4 23:46:37.217866 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 4 23:46:37.217874 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 4 23:46:37.217882 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 4 23:46:37.217890 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 4 23:46:37.217898 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 4 23:46:37.217906 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 4 23:46:37.217914 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 4 23:46:37.217922 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 4 23:46:37.217930 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 4 23:46:37.217941 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 4 23:46:37.217949 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 4 23:46:37.217957 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 4 23:46:37.217965 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 4 23:46:37.217973 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 4 23:46:37.217981 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 4 23:46:37.217989 kernel: Zone ranges: Sep 4 23:46:37.217998 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 23:46:37.218006 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 4 23:46:37.218016 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 4 23:46:37.218024 kernel: Movable zone start for each node Sep 4 23:46:37.218032 kernel: Early memory node ranges Sep 4 23:46:37.218040 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 4 23:46:37.218048 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 4 23:46:37.218056 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 4 23:46:37.218064 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 4 23:46:37.218072 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 4 23:46:37.218080 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 4 23:46:37.218091 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 23:46:37.218099 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 4 23:46:37.218110 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 4 23:46:37.218118 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 4 23:46:37.218126 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 4 23:46:37.218134 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 4 23:46:37.218142 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 23:46:37.218150 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 23:46:37.218158 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 4 23:46:37.218169 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 4 23:46:37.218177 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 4 23:46:37.218185 kernel: Booting paravirtualized kernel on Hyper-V Sep 4 23:46:37.218193 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 23:46:37.218201 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 4 23:46:37.218209 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 4 23:46:37.218217 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 4 23:46:37.218225 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 4 23:46:37.218233 kernel: Hyper-V: PV spinlocks enabled Sep 4 23:46:37.218244 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 4 23:46:37.218253 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:37.218262 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 23:46:37.218270 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 4 23:46:37.218278 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 4 23:46:37.218286 kernel: Fallback order for Node 0: 0 Sep 4 23:46:37.218294 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 4 23:46:37.218301 kernel: Policy zone: Normal Sep 4 23:46:37.218312 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 23:46:37.218328 kernel: software IO TLB: area num 8. Sep 4 23:46:37.218337 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2293K rwdata, 22868K rodata, 43508K init, 1568K bss, 2110660K reserved, 0K cma-reserved) Sep 4 23:46:37.218348 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 4 23:46:37.218357 kernel: Kernel/User page tables isolation: enabled Sep 4 23:46:37.218365 kernel: ftrace: allocating 37943 entries in 149 pages Sep 4 23:46:37.218374 kernel: ftrace: allocated 149 pages with 4 groups Sep 4 23:46:37.218382 kernel: Dynamic Preempt: voluntary Sep 4 23:46:37.218390 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 23:46:37.218399 kernel: rcu: RCU event tracing is enabled. Sep 4 23:46:37.218408 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 4 23:46:37.218417 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 23:46:37.218428 kernel: Rude variant of Tasks RCU enabled. Sep 4 23:46:37.218437 kernel: Tracing variant of Tasks RCU enabled. Sep 4 23:46:37.218445 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 23:46:37.218454 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 4 23:46:37.218465 kernel: Using NULL legacy PIC Sep 4 23:46:37.218473 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 4 23:46:37.218482 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 23:46:37.218490 kernel: Console: colour dummy device 80x25 Sep 4 23:46:37.218498 kernel: printk: console [tty1] enabled Sep 4 23:46:37.218507 kernel: printk: console [ttyS0] enabled Sep 4 23:46:37.218515 kernel: printk: bootconsole [earlyser0] disabled Sep 4 23:46:37.218524 kernel: ACPI: Core revision 20230628 Sep 4 23:46:37.218532 kernel: Failed to register legacy timer interrupt Sep 4 23:46:37.218540 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 23:46:37.218552 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 4 23:46:37.218560 kernel: Hyper-V: Using IPI hypercalls Sep 4 23:46:37.218568 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 4 23:46:37.218577 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 4 23:46:37.218585 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 4 23:46:37.218594 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 4 23:46:37.218602 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 4 23:46:37.218611 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 4 23:46:37.218619 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 4 23:46:37.218630 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 4 23:46:37.218639 kernel: APIC: read() replaced with hv_apic_read() Sep 4 23:46:37.218647 kernel: APIC: write() replaced with hv_apic_write() Sep 4 23:46:37.218655 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 4 23:46:37.218664 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 4 23:46:37.218672 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 4 23:46:37.218681 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 4 23:46:37.218689 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 4 23:46:37.218698 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 23:46:37.218709 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 23:46:37.218717 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 4 23:46:37.218774 kernel: Speculative Store Bypass: Vulnerable Sep 4 23:46:37.218790 kernel: MDS: Mitigation: Clear CPU buffers Sep 4 23:46:37.218798 kernel: TAA: Mitigation: Clear CPU buffers Sep 4 23:46:37.218807 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 4 23:46:37.218815 kernel: active return thunk: its_return_thunk Sep 4 23:46:37.218823 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 4 23:46:37.218832 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 4 23:46:37.218840 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 4 23:46:37.218848 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 4 23:46:37.218863 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 4 23:46:37.218871 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 4 23:46:37.218880 kernel: Freeing SMP alternatives memory: 32K Sep 4 23:46:37.218888 kernel: pid_max: default: 32768 minimum: 301 Sep 4 23:46:37.218896 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 23:46:37.218905 kernel: landlock: Up and running. Sep 4 23:46:37.218913 kernel: SELinux: Initializing. Sep 4 23:46:37.218921 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 23:46:37.218930 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 23:46:37.218938 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 4 23:46:37.218947 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:37.218958 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:37.218967 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:37.218976 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 4 23:46:37.218984 kernel: signal: max sigframe size: 1776 Sep 4 23:46:37.218993 kernel: rcu: Hierarchical SRCU implementation. Sep 4 23:46:37.219002 kernel: rcu: Max phase no-delay instances is 400. Sep 4 23:46:37.219011 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 4 23:46:37.219019 kernel: smp: Bringing up secondary CPUs ... Sep 4 23:46:37.219028 kernel: smpboot: x86: Booting SMP configuration: Sep 4 23:46:37.219039 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 4 23:46:37.219047 kernel: smp: Brought up 1 node, 6 CPUs Sep 4 23:46:37.219056 kernel: smpboot: Max logical packages: 1 Sep 4 23:46:37.219064 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 4 23:46:37.219073 kernel: devtmpfs: initialized Sep 4 23:46:37.219082 kernel: x86/mm: Memory block size: 1024MB Sep 4 23:46:37.219090 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 4 23:46:37.219099 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 23:46:37.219107 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 4 23:46:37.219118 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 23:46:37.219127 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 23:46:37.219135 kernel: audit: initializing netlink subsys (disabled) Sep 4 23:46:37.219144 kernel: audit: type=2000 audit(1757029595.029:1): state=initialized audit_enabled=0 res=1 Sep 4 23:46:37.219152 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 23:46:37.219161 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 23:46:37.219169 kernel: cpuidle: using governor menu Sep 4 23:46:37.219178 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 23:46:37.219186 kernel: dca service started, version 1.12.1 Sep 4 23:46:37.219197 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 4 23:46:37.219205 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 23:46:37.219214 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 23:46:37.219222 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 23:46:37.219231 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 23:46:37.219240 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 23:46:37.219248 kernel: ACPI: Added _OSI(Module Device) Sep 4 23:46:37.219256 kernel: ACPI: Added _OSI(Processor Device) Sep 4 23:46:37.219265 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 23:46:37.219276 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 23:46:37.219285 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 4 23:46:37.219293 kernel: ACPI: Interpreter enabled Sep 4 23:46:37.219302 kernel: ACPI: PM: (supports S0 S5) Sep 4 23:46:37.219310 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 23:46:37.219319 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 23:46:37.219327 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 4 23:46:37.219336 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 4 23:46:37.219344 kernel: iommu: Default domain type: Translated Sep 4 23:46:37.219355 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 23:46:37.219364 kernel: efivars: Registered efivars operations Sep 4 23:46:37.219372 kernel: PCI: Using ACPI for IRQ routing Sep 4 23:46:37.219381 kernel: PCI: System does not support PCI Sep 4 23:46:37.219389 kernel: vgaarb: loaded Sep 4 23:46:37.219398 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 4 23:46:37.219406 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 23:46:37.219415 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 23:46:37.219423 kernel: pnp: PnP ACPI init Sep 4 23:46:37.219434 kernel: pnp: PnP ACPI: found 3 devices Sep 4 23:46:37.219443 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 23:46:37.219451 kernel: NET: Registered PF_INET protocol family Sep 4 23:46:37.219460 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 23:46:37.219468 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 4 23:46:37.219477 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 23:46:37.219485 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 23:46:37.219494 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:37.219502 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 4 23:46:37.219513 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:37.219522 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:37.219530 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 23:46:37.219539 kernel: NET: Registered PF_XDP protocol family Sep 4 23:46:37.219547 kernel: PCI: CLS 0 bytes, default 64 Sep 4 23:46:37.219556 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 4 23:46:37.219564 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Sep 4 23:46:37.219573 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 4 23:46:37.219581 kernel: Initialise system trusted keyrings Sep 4 23:46:37.219592 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 4 23:46:37.219600 kernel: Key type asymmetric registered Sep 4 23:46:37.219608 kernel: Asymmetric key parser 'x509' registered Sep 4 23:46:37.219617 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 4 23:46:37.219625 kernel: io scheduler mq-deadline registered Sep 4 23:46:37.219634 kernel: io scheduler kyber registered Sep 4 23:46:37.219642 kernel: io scheduler bfq registered Sep 4 23:46:37.219650 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 23:46:37.219659 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 23:46:37.219670 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 23:46:37.219679 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 4 23:46:37.219687 kernel: i8042: PNP: No PS/2 controller found. Sep 4 23:46:37.219900 kernel: rtc_cmos 00:02: registered as rtc0 Sep 4 23:46:37.220011 kernel: rtc_cmos 00:02: setting system clock to 2025-09-04T23:46:36 UTC (1757029596) Sep 4 23:46:37.220114 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 4 23:46:37.220127 kernel: intel_pstate: CPU model not supported Sep 4 23:46:37.220141 kernel: efifb: probing for efifb Sep 4 23:46:37.220149 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 4 23:46:37.220158 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 4 23:46:37.220166 kernel: efifb: scrolling: redraw Sep 4 23:46:37.220175 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 23:46:37.220183 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 23:46:37.220192 kernel: fb0: EFI VGA frame buffer device Sep 4 23:46:37.220200 kernel: pstore: Using crash dump compression: deflate Sep 4 23:46:37.220209 kernel: pstore: Registered efi_pstore as persistent store backend Sep 4 23:46:37.220220 kernel: NET: Registered PF_INET6 protocol family Sep 4 23:46:37.220229 kernel: Segment Routing with IPv6 Sep 4 23:46:37.220237 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 23:46:37.220246 kernel: NET: Registered PF_PACKET protocol family Sep 4 23:46:37.220254 kernel: Key type dns_resolver registered Sep 4 23:46:37.220263 kernel: IPI shorthand broadcast: enabled Sep 4 23:46:37.220271 kernel: sched_clock: Marking stable (1707005500, 103260000)->(2088990700, -278725200) Sep 4 23:46:37.220280 kernel: registered taskstats version 1 Sep 4 23:46:37.220288 kernel: Loading compiled-in X.509 certificates Sep 4 23:46:37.220297 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.103-flatcar: f395d469db1520f53594f6c4948c5f8002e6cc8b' Sep 4 23:46:37.220308 kernel: Key type .fscrypt registered Sep 4 23:46:37.220316 kernel: Key type fscrypt-provisioning registered Sep 4 23:46:37.220325 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 23:46:37.220333 kernel: ima: Allocated hash algorithm: sha1 Sep 4 23:46:37.220342 kernel: ima: No architecture policies found Sep 4 23:46:37.220350 kernel: clk: Disabling unused clocks Sep 4 23:46:37.220359 kernel: Freeing unused kernel image (initmem) memory: 43508K Sep 4 23:46:37.220367 kernel: Write protecting the kernel read-only data: 38912k Sep 4 23:46:37.220378 kernel: Freeing unused kernel image (rodata/data gap) memory: 1708K Sep 4 23:46:37.220387 kernel: Run /init as init process Sep 4 23:46:37.220395 kernel: with arguments: Sep 4 23:46:37.220403 kernel: /init Sep 4 23:46:37.220412 kernel: with environment: Sep 4 23:46:37.220420 kernel: HOME=/ Sep 4 23:46:37.220428 kernel: TERM=linux Sep 4 23:46:37.220437 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 23:46:37.220446 systemd[1]: Successfully made /usr/ read-only. Sep 4 23:46:37.220460 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) Sep 4 23:46:37.220470 systemd[1]: Detected virtualization microsoft. Sep 4 23:46:37.220479 systemd[1]: Detected architecture x86-64. Sep 4 23:46:37.220488 systemd[1]: Running in initrd. Sep 4 23:46:37.220497 systemd[1]: No hostname configured, using default hostname. Sep 4 23:46:37.220506 systemd[1]: Hostname set to . Sep 4 23:46:37.220515 systemd[1]: Initializing machine ID from random generator. Sep 4 23:46:37.220526 systemd[1]: Queued start job for default target initrd.target. Sep 4 23:46:37.220536 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:37.220545 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:37.220555 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 23:46:37.220564 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:46:37.220573 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 23:46:37.220583 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 23:46:37.220595 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 23:46:37.220605 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 23:46:37.220614 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:37.220623 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:46:37.220631 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:46:37.220640 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:46:37.220649 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:46:37.220658 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:46:37.220667 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:46:37.220679 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:46:37.220688 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 23:46:37.220697 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 4 23:46:37.220706 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:37.220715 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:37.220724 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:37.220733 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:46:37.220756 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 23:46:37.220769 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:46:37.220778 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 23:46:37.220787 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 23:46:37.220796 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:46:37.220805 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:46:37.220834 systemd-journald[205]: Collecting audit messages is disabled. Sep 4 23:46:37.220860 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:37.220870 systemd-journald[205]: Journal started Sep 4 23:46:37.220890 systemd-journald[205]: Runtime Journal (/run/log/journal/b443f9bbeab8498ebfbdf0ca5150754f) is 8M, max 2.2G, 2.1G free. Sep 4 23:46:37.228255 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:46:37.231901 systemd-modules-load[207]: Inserted module 'overlay' Sep 4 23:46:37.232256 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 23:46:37.234317 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:37.235021 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 23:46:37.237251 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 23:46:37.261165 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:46:37.271347 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:37.280759 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:46:37.291667 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:37.301185 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:46:37.310859 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:37.337924 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:37.352062 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 23:46:37.346709 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:37.364149 kernel: Bridge firewalling registered Sep 4 23:46:37.364173 systemd-modules-load[207]: Inserted module 'br_netfilter' Sep 4 23:46:37.379891 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 23:46:37.381447 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:37.390216 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:46:37.404454 dracut-cmdline[236]: dracut-dracut-053 Sep 4 23:46:37.404454 dracut-cmdline[236]: 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:37.412133 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:37.432833 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:46:37.473692 systemd-resolved[259]: Positive Trust Anchors: Sep 4 23:46:37.473709 systemd-resolved[259]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:46:37.473751 systemd-resolved[259]: 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 Sep 4 23:46:37.476255 systemd-resolved[259]: Defaulting to hostname 'linux'. Sep 4 23:46:37.477290 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:46:37.486838 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:46:37.574768 kernel: SCSI subsystem initialized Sep 4 23:46:37.585760 kernel: Loading iSCSI transport class v2.0-870. Sep 4 23:46:37.598763 kernel: iscsi: registered transport (tcp) Sep 4 23:46:37.623270 kernel: iscsi: registered transport (qla4xxx) Sep 4 23:46:37.623298 kernel: QLogic iSCSI HBA Driver Sep 4 23:46:37.662628 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 23:46:37.681913 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 23:46:37.711700 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 23:46:37.711776 kernel: device-mapper: uevent: version 1.0.3 Sep 4 23:46:37.716354 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 23:46:37.759799 kernel: raid6: avx2x4 gen() 30665 MB/s Sep 4 23:46:37.779755 kernel: raid6: avx2x2 gen() 31138 MB/s Sep 4 23:46:37.800922 kernel: raid6: avx2x1 gen() 28066 MB/s Sep 4 23:46:37.800949 kernel: raid6: using algorithm avx2x2 gen() 31138 MB/s Sep 4 23:46:37.822999 kernel: raid6: .... xor() 18494 MB/s, rmw enabled Sep 4 23:46:37.823024 kernel: raid6: using avx2x2 recovery algorithm Sep 4 23:46:37.846764 kernel: xor: automatically using best checksumming function avx Sep 4 23:46:38.018774 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 23:46:38.029447 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:46:38.059937 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:38.082355 systemd-udevd[429]: Using default interface naming scheme 'v255'. Sep 4 23:46:38.087988 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:38.103881 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 23:46:38.119915 dracut-pre-trigger[436]: rd.md=0: removing MD RAID activation Sep 4 23:46:38.148257 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:46:38.163872 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:46:38.221451 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:38.237918 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 23:46:38.249302 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 23:46:38.260657 kernel: cryptd: max_cpu_qlen set to 1000 Sep 4 23:46:38.254321 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:46:38.262080 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:38.267143 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:46:38.297228 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 23:46:38.311784 kernel: AVX2 version of gcm_enc/dec engaged. Sep 4 23:46:38.311820 kernel: hv_vmbus: Vmbus version:4.0 Sep 4 23:46:38.312970 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:46:38.325033 kernel: AES CTR mode by8 optimization enabled Sep 4 23:46:38.325297 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 23:46:38.325466 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:38.341798 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:38.402944 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 4 23:46:38.402972 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 4 23:46:38.402994 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 23:46:38.403014 kernel: hv_vmbus: registering driver hv_storvsc Sep 4 23:46:38.403056 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 4 23:46:38.403120 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 Sep 4 23:46:38.403165 kernel: scsi host0: storvsc_host_t Sep 4 23:46:38.403692 kernel: hv_vmbus: registering driver hv_netvsc Sep 4 23:46:38.403768 kernel: scsi host1: storvsc_host_t Sep 4 23:46:38.404232 kernel: hv_vmbus: registering driver hid_hyperv Sep 4 23:46:38.404283 kernel: PTP clock support registered Sep 4 23:46:38.404300 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 23:46:38.404328 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 4 23:46:38.404340 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 4 23:46:38.404693 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 23:46:38.404801 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 4 23:46:38.375095 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:38.375380 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:38.415349 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:38.433070 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:38.439129 kernel: hv_vmbus: registering driver hv_pci Sep 4 23:46:38.438705 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:38.447885 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:38.469589 kernel: hv_utils: Registering HyperV Utility Driver Sep 4 23:46:38.469619 kernel: hv_vmbus: registering driver hv_utils Sep 4 23:46:38.469632 kernel: hv_utils: Heartbeat IC version 3.0 Sep 4 23:46:38.469643 kernel: hv_utils: Shutdown IC version 3.2 Sep 4 23:46:38.469654 kernel: hv_utils: TimeSync IC version 4.0 Sep 4 23:46:38.447983 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:38.464948 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:38.729313 systemd-resolved[259]: Clock change detected. Flushing caches. Sep 4 23:46:38.769790 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 4 23:46:38.769980 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 4 23:46:38.770201 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Sep 4 23:46:38.770447 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 4 23:46:38.770606 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 23:46:38.770620 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 4 23:46:38.770743 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Sep 4 23:46:38.775691 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 4 23:46:38.780415 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 4 23:46:38.781350 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:38.803461 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 4 23:46:38.803500 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 4 23:46:38.803527 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 4 23:46:38.803554 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 4 23:46:38.817418 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 4 23:46:38.817687 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 4 23:46:38.817955 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 4 23:46:38.818118 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 4 23:46:38.818379 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 4 23:46:38.818591 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 4 23:46:38.823195 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Sep 4 23:46:38.823407 kernel: sd 1:0:0:1: [sdb] Write Protect is off Sep 4 23:46:38.823557 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Sep 4 23:46:38.823699 kernel: sd 1:0:0:0: [sda] Write Protect is off Sep 4 23:46:38.830386 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 4 23:46:38.830612 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 4 23:46:38.841691 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 23:46:38.841900 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 23:46:38.844237 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:38.871882 kernel: sdb: sdb1 Sep 4 23:46:38.871901 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:38.871914 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Sep 4 23:46:38.872462 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Sep 4 23:46:38.892202 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:39.329793 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 4 23:46:39.379366 kernel: BTRFS: device fsid 185ffa67-4184-4488-b7c8-7c0711a63b2d devid 1 transid 38 /dev/sda3 scanned by (udev-worker) (499) Sep 4 23:46:39.391393 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (478) Sep 4 23:46:39.400204 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 4 23:46:39.401734 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 4 23:46:39.422838 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 4 23:46:39.443720 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 23:46:39.462470 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 23:46:39.485352 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:40.501351 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:40.501713 disk-uuid[639]: The operation has completed successfully. Sep 4 23:46:41.882250 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 23:46:41.882381 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 23:46:41.903467 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 23:46:41.914308 sh[726]: Success Sep 4 23:46:41.942349 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 4 23:46:42.368201 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 23:46:42.391773 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 23:46:42.394123 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 23:46:42.432349 kernel: BTRFS info (device dm-0): first mount of filesystem 185ffa67-4184-4488-b7c8-7c0711a63b2d Sep 4 23:46:42.432388 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:42.440613 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 23:46:42.445000 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 23:46:42.448159 kernel: BTRFS info (device dm-0): using free space tree Sep 4 23:46:42.923113 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 23:46:42.927246 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 23:46:42.945472 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 23:46:42.950259 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 23:46:42.980091 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:42.980126 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:42.983263 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:43.025354 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:43.033350 kernel: BTRFS info (device sda6): last unmount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:43.039116 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 23:46:43.057471 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 23:46:43.062657 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:46:43.068698 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:46:43.106995 systemd-networkd[908]: lo: Link UP Sep 4 23:46:43.107007 systemd-networkd[908]: lo: Gained carrier Sep 4 23:46:43.108140 systemd-networkd[908]: Enumeration completed Sep 4 23:46:43.108221 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:46:43.108521 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:46:43.108525 systemd-networkd[908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:46:43.109308 systemd-networkd[908]: eth0: Link UP Sep 4 23:46:43.113820 systemd[1]: Reached target network.target - Network. Sep 4 23:46:43.113855 systemd-networkd[908]: eth0: Gained carrier Sep 4 23:46:43.113866 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:46:43.173377 systemd-networkd[908]: eth0: DHCPv4 address 10.200.8.21/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:46:44.379391 ignition[890]: Ignition 2.20.0 Sep 4 23:46:44.379404 ignition[890]: Stage: fetch-offline Sep 4 23:46:44.379443 ignition[890]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.379454 ignition[890]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.379567 ignition[890]: parsed url from cmdline: "" Sep 4 23:46:44.379571 ignition[890]: no config URL provided Sep 4 23:46:44.379577 ignition[890]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 23:46:44.379587 ignition[890]: no config at "/usr/lib/ignition/user.ign" Sep 4 23:46:44.379593 ignition[890]: failed to fetch config: resource requires networking Sep 4 23:46:44.379767 ignition[890]: Ignition finished successfully Sep 4 23:46:44.406884 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:46:44.427502 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 23:46:44.439295 ignition[923]: Ignition 2.20.0 Sep 4 23:46:44.439307 ignition[923]: Stage: fetch Sep 4 23:46:44.439532 ignition[923]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.439546 ignition[923]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.439756 ignition[923]: parsed url from cmdline: "" Sep 4 23:46:44.439762 ignition[923]: no config URL provided Sep 4 23:46:44.439768 ignition[923]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 23:46:44.439780 ignition[923]: no config at "/usr/lib/ignition/user.ign" Sep 4 23:46:44.439804 ignition[923]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 4 23:46:44.579226 ignition[923]: GET result: OK Sep 4 23:46:44.579281 ignition[923]: config has been read from IMDS userdata Sep 4 23:46:44.582791 unknown[923]: fetched base config from "system" Sep 4 23:46:44.579292 ignition[923]: parsing config with SHA512: 97254d619c318bdb902d2a513dc36db22acda84bfdc19c23220dbff20458b050c885d4975e6bd308fc9a285819c5514d29f1491d9c38104e0ebf17b2fe7e1bde Sep 4 23:46:44.582800 unknown[923]: fetched base config from "system" Sep 4 23:46:44.582970 ignition[923]: fetch: fetch complete Sep 4 23:46:44.582806 unknown[923]: fetched user config from "azure" Sep 4 23:46:44.582975 ignition[923]: fetch: fetch passed Sep 4 23:46:44.585084 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 23:46:44.583020 ignition[923]: Ignition finished successfully Sep 4 23:46:44.607473 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 23:46:44.634112 ignition[934]: Ignition 2.20.0 Sep 4 23:46:44.634124 ignition[934]: Stage: kargs Sep 4 23:46:44.634277 ignition[934]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.634314 ignition[934]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.634999 ignition[934]: kargs: kargs passed Sep 4 23:46:44.642809 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 23:46:44.635046 ignition[934]: Ignition finished successfully Sep 4 23:46:44.665512 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 23:46:44.676089 ignition[944]: Ignition 2.20.0 Sep 4 23:46:44.676101 ignition[944]: Stage: disks Sep 4 23:46:44.676272 ignition[944]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.676286 ignition[944]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.681541 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 23:46:44.676872 ignition[944]: disks: disks passed Sep 4 23:46:44.687964 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 23:46:44.676914 ignition[944]: Ignition finished successfully Sep 4 23:46:44.696609 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 23:46:44.698041 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:46:44.708293 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:46:44.719826 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:46:44.745467 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 23:46:44.815956 systemd-fsck[955]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 4 23:46:44.825413 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 23:46:44.846435 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 23:46:44.879431 systemd-networkd[908]: eth0: Gained IPv6LL Sep 4 23:46:44.944349 kernel: EXT4-fs (sda9): mounted filesystem 86dd2c20-900e-43ec-8fda-e9f0f484a013 r/w with ordered data mode. Quota mode: none. Sep 4 23:46:44.944692 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 23:46:44.947033 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 23:46:44.986417 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:46:45.001522 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 23:46:45.008692 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (966) Sep 4 23:46:45.005751 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 4 23:46:45.010124 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 23:46:45.033603 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:45.033626 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:45.033639 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:45.033651 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:45.010159 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:46:45.047364 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:46:45.051529 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 23:46:45.062959 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 23:46:45.903734 coreos-metadata[968]: Sep 04 23:46:45.903 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 23:46:45.912501 coreos-metadata[968]: Sep 04 23:46:45.906 INFO Fetch successful Sep 4 23:46:45.912501 coreos-metadata[968]: Sep 04 23:46:45.906 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 4 23:46:45.924293 coreos-metadata[968]: Sep 04 23:46:45.914 INFO Fetch successful Sep 4 23:46:45.928037 coreos-metadata[968]: Sep 04 23:46:45.925 INFO wrote hostname ci-4230.2.2-n-3f20fbd7a4 to /sysroot/etc/hostname Sep 4 23:46:45.934932 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 23:46:46.084602 initrd-setup-root[996]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 23:46:46.128150 initrd-setup-root[1003]: cut: /sysroot/etc/group: No such file or directory Sep 4 23:46:46.158973 initrd-setup-root[1010]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 23:46:46.177862 initrd-setup-root[1017]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 23:46:47.143400 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 23:46:47.164054 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 23:46:47.179657 kernel: BTRFS info (device sda6): last unmount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:47.169058 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 23:46:47.181190 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 23:46:47.200940 ignition[1084]: INFO : Ignition 2.20.0 Sep 4 23:46:47.200940 ignition[1084]: INFO : Stage: mount Sep 4 23:46:47.205867 ignition[1084]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:47.205867 ignition[1084]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:47.205867 ignition[1084]: INFO : mount: mount passed Sep 4 23:46:47.205867 ignition[1084]: INFO : Ignition finished successfully Sep 4 23:46:47.204609 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 23:46:47.208669 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 23:46:47.220903 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 23:46:47.232286 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:46:47.258355 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1100) Sep 4 23:46:47.267650 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:47.267682 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:47.270902 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:47.278346 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:47.279685 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:46:47.309132 ignition[1117]: INFO : Ignition 2.20.0 Sep 4 23:46:47.309132 ignition[1117]: INFO : Stage: files Sep 4 23:46:47.315210 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:47.315210 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:47.315210 ignition[1117]: DEBUG : files: compiled without relabeling support, skipping Sep 4 23:46:47.328866 ignition[1117]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 23:46:47.328866 ignition[1117]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 23:46:47.443827 ignition[1117]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 23:46:47.449319 ignition[1117]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 23:46:47.449319 ignition[1117]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 23:46:47.444587 unknown[1117]: wrote ssh authorized keys file for user: core Sep 4 23:46:47.514817 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:46:47.522352 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:46:47.522352 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:46:47.522352 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:46:47.522352 ignition[1117]: INFO : files: files passed Sep 4 23:46:47.522352 ignition[1117]: INFO : Ignition finished successfully Sep 4 23:46:47.530954 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 23:46:47.559514 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 23:46:47.561010 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 23:46:47.569676 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 23:46:47.569765 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 23:46:47.607478 initrd-setup-root-after-ignition[1148]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.613574 initrd-setup-root-after-ignition[1148]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.620864 initrd-setup-root-after-ignition[1152]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.622201 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:46:47.632288 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 23:46:47.658462 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 23:46:47.681593 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 23:46:47.681704 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 23:46:47.690540 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 23:46:47.692503 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 23:46:47.704400 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 23:46:47.705236 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 23:46:47.735849 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:46:47.746745 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 23:46:47.765455 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:46:47.767139 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:47.776263 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 23:46:47.785407 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 23:46:47.785531 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:46:47.800075 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 23:46:47.808858 systemd[1]: Stopped target basic.target - Basic System. Sep 4 23:46:47.816790 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 23:46:47.824738 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:46:47.826936 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 23:46:47.846887 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 23:46:47.854983 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:46:47.856581 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 23:46:47.866328 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 23:46:47.874402 systemd[1]: Stopped target swap.target - Swaps. Sep 4 23:46:47.882134 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 23:46:47.882283 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:46:47.895248 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:46:47.903481 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:47.912204 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 23:46:47.916795 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:47.925948 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 23:46:47.926072 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 23:46:47.939435 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 23:46:47.939567 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:46:47.941761 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 23:46:47.941880 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 23:46:47.954359 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 4 23:46:47.954485 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 23:46:47.984478 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 23:46:47.985949 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 23:46:47.993405 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 23:46:48.007623 ignition[1172]: INFO : Ignition 2.20.0 Sep 4 23:46:48.007623 ignition[1172]: INFO : Stage: umount Sep 4 23:46:48.007623 ignition[1172]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:48.007623 ignition[1172]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:48.007623 ignition[1172]: INFO : umount: umount passed Sep 4 23:46:48.007623 ignition[1172]: INFO : Ignition finished successfully Sep 4 23:46:47.993549 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:48.000753 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 23:46:48.000882 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:46:48.019539 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 23:46:48.020461 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 23:46:48.020560 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 23:46:48.025232 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 23:46:48.025349 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 23:46:48.032549 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 23:46:48.032658 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 23:46:48.040191 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 23:46:48.040247 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 23:46:48.047589 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 23:46:48.047644 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 23:46:48.055874 systemd[1]: Stopped target network.target - Network. Sep 4 23:46:48.063549 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 23:46:48.063639 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:46:48.075127 systemd[1]: Stopped target paths.target - Path Units. Sep 4 23:46:48.083042 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 23:46:48.087422 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:48.089940 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 23:46:48.098510 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 23:46:48.105682 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 23:46:48.105812 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:46:48.113246 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 23:46:48.113401 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:46:48.123822 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 23:46:48.123883 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 23:46:48.131430 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 23:46:48.131480 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 23:46:48.139759 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 23:46:48.147571 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 23:46:48.181453 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 23:46:48.181555 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 23:46:48.183796 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 23:46:48.183873 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 23:46:48.193479 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 23:46:48.193591 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 23:46:48.210632 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 4 23:46:48.210873 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 23:46:48.211023 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 23:46:48.227745 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 4 23:46:48.228837 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 23:46:48.228883 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:48.250491 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 23:46:48.253034 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 23:46:48.253093 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:46:48.260555 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 23:46:48.260604 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:48.275553 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 23:46:48.275602 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:48.277027 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 23:46:48.277072 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:48.297859 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:48.306284 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 4 23:46:48.306370 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:48.329073 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 23:46:48.329212 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 23:46:48.337029 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 23:46:48.337202 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:48.340808 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 23:46:48.340868 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:48.349210 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 23:46:48.349252 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:48.365707 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 23:46:48.365764 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:46:48.377825 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 23:46:48.377877 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 23:46:48.390761 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 23:46:48.390813 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:48.445270 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 23:46:48.458623 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 23:46:48.458723 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:48.467715 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 4 23:46:48.467771 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:46:48.469328 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 23:46:48.469390 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:48.481773 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:48.481821 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:48.491890 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 4 23:46:48.491953 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:48.492399 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 23:46:48.492496 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 23:46:48.496098 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 23:46:48.528667 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 23:46:48.549621 systemd[1]: Switching root. Sep 4 23:46:48.651892 systemd-journald[205]: Journal stopped Sep 4 23:46:57.083101 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Sep 4 23:46:57.083134 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 23:46:57.083153 kernel: SELinux: policy capability open_perms=1 Sep 4 23:46:57.083163 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 23:46:57.083172 kernel: SELinux: policy capability always_check_network=0 Sep 4 23:46:57.083182 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 23:46:57.083192 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 23:46:57.083205 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 23:46:57.083215 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 23:46:57.083225 kernel: audit: type=1403 audit(1757029609.405:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 23:46:57.083236 systemd[1]: Successfully loaded SELinux policy in 189.350ms. Sep 4 23:46:57.083252 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.334ms. Sep 4 23:46:57.083263 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) Sep 4 23:46:57.083274 systemd[1]: Detected virtualization microsoft. Sep 4 23:46:57.083289 systemd[1]: Detected architecture x86-64. Sep 4 23:46:57.083300 systemd[1]: Detected first boot. Sep 4 23:46:57.083311 systemd[1]: Hostname set to . Sep 4 23:46:57.083322 systemd[1]: Initializing machine ID from random generator. Sep 4 23:46:57.083378 zram_generator::config[1219]: No configuration found. Sep 4 23:46:57.083396 kernel: Guest personality initialized and is inactive Sep 4 23:46:57.083407 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 4 23:46:57.083417 kernel: Initialized host personality Sep 4 23:46:57.083427 kernel: NET: Registered PF_VSOCK protocol family Sep 4 23:46:57.083437 systemd[1]: Populated /etc with preset unit settings. Sep 4 23:46:57.083449 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 4 23:46:57.083459 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 23:46:57.083470 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 23:46:57.083484 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 23:46:57.083495 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 23:46:57.083506 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 23:46:57.083517 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 23:46:57.083528 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 23:46:57.083542 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 23:46:57.083553 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 23:46:57.083568 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 23:46:57.083579 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 23:46:57.083590 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:57.083601 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:57.083612 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 23:46:57.083623 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 23:46:57.083638 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 23:46:57.083650 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:46:57.083664 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 23:46:57.083676 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:57.083687 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 23:46:57.083698 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 23:46:57.083709 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 23:46:57.083721 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 23:46:57.083732 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:57.083743 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:46:57.083758 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:46:57.083769 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:46:57.083780 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 23:46:57.083795 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 23:46:57.083806 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 4 23:46:57.083821 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:57.083832 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:57.083844 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:57.083855 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 23:46:57.083867 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 23:46:57.083878 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 23:46:57.083890 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 23:46:57.083901 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:57.083916 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 23:46:57.083928 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 23:46:57.083939 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 23:46:57.083951 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). Sep 4 23:46:57.083963 systemd[1]: Reached target machines.target - Containers. Sep 4 23:46:57.083974 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 23:46:57.083986 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:57.083997 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:46:57.084012 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 23:46:57.084023 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:57.084035 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:46:57.084050 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:57.084061 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 23:46:57.084072 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:57.084084 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 23:46:57.084096 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 23:46:57.084110 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 23:46:57.084122 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 23:46:57.084133 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 23:46:57.084145 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). Sep 4 23:46:57.084156 kernel: loop: module loaded Sep 4 23:46:57.084167 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:46:57.084178 kernel: fuse: init (API version 7.39) Sep 4 23:46:57.084189 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:46:57.084203 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 23:46:57.084236 systemd-journald[1326]: Collecting audit messages is disabled. Sep 4 23:46:57.084261 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 23:46:57.084274 systemd-journald[1326]: Journal started Sep 4 23:46:57.084302 systemd-journald[1326]: Runtime Journal (/run/log/journal/6a542a54b1e24bc6a75879e32d057cd9) is 8M, max 2.2G, 2.1G free. Sep 4 23:46:56.264668 systemd[1]: Queued start job for default target multi-user.target. Sep 4 23:46:56.277630 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 4 23:46:56.278085 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 23:46:57.100385 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 4 23:46:57.110604 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:46:57.121633 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 23:46:57.121673 systemd[1]: Stopped verity-setup.service. Sep 4 23:46:57.134361 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:57.134412 kernel: ACPI: bus type drm_connector registered Sep 4 23:46:57.139351 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:46:57.148925 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 23:46:57.153281 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 23:46:57.157868 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 23:46:57.161993 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 23:46:57.166481 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 23:46:57.171111 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 23:46:57.175348 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 23:46:57.180617 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:57.185893 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 23:46:57.186087 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 23:46:57.191155 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:57.191356 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:57.196262 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:46:57.196467 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:46:57.200936 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:57.201121 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:57.206374 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 23:46:57.206563 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 23:46:57.211206 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:57.211414 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:57.216034 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:57.220843 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 23:46:57.226269 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 23:46:57.231583 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 4 23:46:57.246504 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 23:46:57.265424 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 23:46:57.270908 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 23:46:57.275284 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 23:46:57.275322 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:46:57.280549 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 4 23:46:57.286688 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 23:46:57.292297 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 23:46:57.296500 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:57.308555 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 23:46:57.313761 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 23:46:57.319759 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:46:57.320747 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 23:46:57.325270 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:46:57.326224 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:46:57.331235 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 23:46:57.336749 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 23:46:57.344298 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:57.349443 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 23:46:57.355397 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 23:46:57.360636 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 23:46:57.364462 systemd-journald[1326]: Time spent on flushing to /var/log/journal/6a542a54b1e24bc6a75879e32d057cd9 is 13.909ms for 943 entries. Sep 4 23:46:57.364462 systemd-journald[1326]: System Journal (/var/log/journal/6a542a54b1e24bc6a75879e32d057cd9) is 8M, max 2.6G, 2.6G free. Sep 4 23:46:57.424072 systemd-journald[1326]: Received client request to flush runtime journal. Sep 4 23:46:57.424130 kernel: loop0: detected capacity change from 0 to 138176 Sep 4 23:46:57.370160 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 23:46:57.377720 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 23:46:57.390468 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 4 23:46:57.396004 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 23:46:57.410447 udevadm[1371]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 23:46:57.424975 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 23:46:57.460834 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:57.491468 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 23:46:57.492148 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 4 23:46:57.549808 systemd-tmpfiles[1362]: ACLs are not supported, ignoring. Sep 4 23:46:57.549830 systemd-tmpfiles[1362]: ACLs are not supported, ignoring. Sep 4 23:46:57.555350 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:46:57.573532 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 23:46:58.075377 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 23:46:58.081901 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 23:46:58.097532 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:46:58.115961 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Sep 4 23:46:58.115983 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Sep 4 23:46:58.122211 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:58.146359 kernel: loop1: detected capacity change from 0 to 28272 Sep 4 23:46:58.716456 kernel: loop2: detected capacity change from 0 to 147912 Sep 4 23:46:59.153363 kernel: loop3: detected capacity change from 0 to 138176 Sep 4 23:46:59.174357 kernel: loop4: detected capacity change from 0 to 28272 Sep 4 23:46:59.181895 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 23:46:59.190351 kernel: loop5: detected capacity change from 0 to 147912 Sep 4 23:46:59.207587 (sd-merge)[1388]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 4 23:46:59.208082 (sd-merge)[1388]: Merged extensions into '/usr'. Sep 4 23:46:59.209498 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:59.215697 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 23:46:59.222735 systemd[1]: Starting ensure-sysext.service... Sep 4 23:46:59.226409 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:46:59.244577 systemd-udevd[1390]: Using default interface naming scheme 'v255'. Sep 4 23:46:59.273683 systemd[1]: Reload requested from client PID 1392 ('systemctl') (unit ensure-sysext.service)... Sep 4 23:46:59.273704 systemd[1]: Reloading... Sep 4 23:46:59.325375 zram_generator::config[1423]: No configuration found. Sep 4 23:46:59.357250 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 23:46:59.357574 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 23:46:59.358515 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 23:46:59.358809 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Sep 4 23:46:59.358880 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Sep 4 23:46:59.393214 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:46:59.393229 systemd-tmpfiles[1393]: Skipping /boot Sep 4 23:46:59.405011 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:46:59.405026 systemd-tmpfiles[1393]: Skipping /boot Sep 4 23:46:59.459820 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. Sep 4 23:46:59.558566 systemd[1]: Reloading finished in 284 ms. Sep 4 23:46:59.595108 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:59.606931 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 23:46:59.622666 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 23:46:59.628571 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 23:46:59.635131 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:46:59.640173 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 23:46:59.647291 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.647501 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:59.648580 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:59.653707 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:59.659436 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:59.663500 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:59.663686 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). Sep 4 23:46:59.663795 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.665003 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:59.665185 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:59.670275 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:59.679445 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:59.685018 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:59.685248 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:59.696596 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.696824 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:59.707638 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:59.712885 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:59.718659 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:59.722723 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:59.722851 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). Sep 4 23:46:59.723007 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.724623 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 23:46:59.730275 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:59.730487 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:59.735672 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:59.735851 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:59.741283 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:59.741474 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:59.752439 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 4 23:46:59.756564 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.756850 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:59.771636 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:59.777561 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:46:59.782524 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:59.788249 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:59.792387 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:59.792517 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). Sep 4 23:46:59.792709 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 23:46:59.796836 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.798439 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:59.798633 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:59.803741 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:46:59.803922 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:46:59.808647 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:59.808834 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:59.814503 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:59.814686 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:59.819426 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:59.829684 systemd[1]: Finished ensure-sysext.service. Sep 4 23:46:59.852586 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:46:59.856891 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:46:59.856962 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:46:59.858565 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 23:46:59.862232 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 23:46:59.909585 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 23:46:59.917217 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 4 23:46:59.924355 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 23:46:59.930590 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:59.935385 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 23:46:59.958352 kernel: hv_vmbus: registering driver hyperv_fb Sep 4 23:46:59.966107 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 4 23:46:59.966169 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 4 23:46:59.967349 kernel: hv_vmbus: registering driver hv_balloon Sep 4 23:46:59.967397 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 4 23:46:59.973349 kernel: Console: switching to colour dummy device 80x25 Sep 4 23:46:59.976420 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 23:46:59.986079 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:59.986339 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:59.992295 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:47:00.008566 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:47:00.055358 kernel: EDAC MC: Ver: 3.0.0 Sep 4 23:47:00.061171 augenrules[1595]: No rules Sep 4 23:47:00.062630 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 23:47:00.062860 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 23:47:00.127357 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1550) Sep 4 23:47:00.154259 systemd-resolved[1486]: Positive Trust Anchors: Sep 4 23:47:00.154279 systemd-resolved[1486]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:47:00.154309 systemd-resolved[1486]: 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 Sep 4 23:47:00.156307 systemd-networkd[1548]: lo: Link UP Sep 4 23:47:00.156318 systemd-networkd[1548]: lo: Gained carrier Sep 4 23:47:00.158291 systemd-networkd[1548]: Enumeration completed Sep 4 23:47:00.158681 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:00.158694 systemd-networkd[1548]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:47:00.159271 systemd-networkd[1548]: eth0: Link UP Sep 4 23:47:00.159286 systemd-networkd[1548]: eth0: Gained carrier Sep 4 23:47:00.159301 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:00.159418 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:47:00.185432 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 23:47:00.201404 systemd-networkd[1548]: eth0: DHCPv4 address 10.200.8.21/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:47:00.201553 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 23:47:00.205202 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 4 23:47:00.208678 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 23:47:00.209773 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 23:47:00.213759 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 23:47:00.227092 systemd-resolved[1486]: Using system hostname 'ci-4230.2.2-n-3f20fbd7a4'. Sep 4 23:47:00.229137 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:47:00.230725 systemd[1]: Reached target network.target - Network. Sep 4 23:47:00.231327 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:47:00.254736 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 4 23:47:00.278865 lvm[1669]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 23:47:00.293158 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 23:47:00.323008 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 23:47:00.324733 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:47:00.341466 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 23:47:00.344906 lvm[1674]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 23:47:00.381895 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 23:47:01.197806 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:47:01.391471 systemd-networkd[1548]: eth0: Gained IPv6LL Sep 4 23:47:01.393745 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 23:47:01.399113 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 23:47:01.922711 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 23:47:01.928038 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). Sep 4 23:47:06.531258 ldconfig[1356]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 23:47:06.548230 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 23:47:06.565479 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 23:47:06.585754 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 23:47:06.590373 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:47:06.594675 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 23:47:06.599596 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 23:47:06.605051 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 23:47:06.609700 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 23:47:06.614700 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 23:47:06.619788 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 23:47:06.619822 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:47:06.623265 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:47:06.649989 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 23:47:06.655709 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 23:47:06.662781 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 4 23:47:06.668328 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 4 23:47:06.676852 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 4 23:47:06.690864 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 23:47:06.695354 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 4 23:47:06.700676 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 23:47:06.705050 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:47:06.708956 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:47:06.712658 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:47:06.712690 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:47:06.737424 systemd[1]: Starting chronyd.service - NTP client/server... Sep 4 23:47:06.741876 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 23:47:06.747240 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 23:47:06.752367 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 23:47:06.756836 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 23:47:06.761831 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 23:47:06.765774 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). Sep 4 23:47:06.765813 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Sep 4 23:47:06.766921 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 4 23:47:06.771205 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 4 23:47:06.772199 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 23:47:06.777140 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 23:47:06.782704 jq[1693]: false Sep 4 23:47:06.783508 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 23:47:06.789215 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 23:47:06.795157 (chronyd)[1686]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 4 23:47:06.795868 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 23:47:06.800460 KVP[1695]: KVP starting; pid is:1695 Sep 4 23:47:06.800879 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 23:47:06.801313 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 23:47:06.801910 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 23:47:06.806270 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 23:47:06.813007 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 23:47:06.813235 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 23:47:06.813606 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 23:47:06.813813 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 23:47:06.814128 jq[1711]: true Sep 4 23:47:06.820292 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 23:47:06.820523 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 23:47:06.833161 extend-filesystems[1694]: Found loop3 Sep 4 23:47:06.833161 extend-filesystems[1694]: Found loop4 Sep 4 23:47:06.833161 extend-filesystems[1694]: Found loop5 Sep 4 23:47:06.833161 extend-filesystems[1694]: Found sda Sep 4 23:47:06.833161 extend-filesystems[1694]: Found sda1 Sep 4 23:47:06.848842 kernel: hv_utils: KVP IC version 4.0 Sep 4 23:47:06.841972 (ntainerd)[1714]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda2 Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda3 Sep 4 23:47:06.849126 extend-filesystems[1694]: Found usr Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda4 Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda6 Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda7 Sep 4 23:47:06.849126 extend-filesystems[1694]: Found sda9 Sep 4 23:47:06.849126 extend-filesystems[1694]: Checking size of /dev/sda9 Sep 4 23:47:06.835871 chronyd[1717]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 4 23:47:06.840212 KVP[1695]: KVP LIC Version: 3.1 Sep 4 23:47:06.872157 jq[1713]: true Sep 4 23:47:06.875445 chronyd[1717]: Timezone right/UTC failed leap second check, ignoring Sep 4 23:47:06.875618 chronyd[1717]: Loaded seccomp filter (level 2) Sep 4 23:47:06.876928 systemd[1]: Started chronyd.service - NTP client/server. Sep 4 23:47:06.879850 update_engine[1710]: I20250904 23:47:06.878969 1710 main.cc:92] Flatcar Update Engine starting Sep 4 23:47:06.904581 extend-filesystems[1694]: Old size kept for /dev/sda9 Sep 4 23:47:06.908054 extend-filesystems[1694]: Found sdb Sep 4 23:47:06.908054 extend-filesystems[1694]: Found sdb1 Sep 4 23:47:06.908054 extend-filesystems[1694]: Found sr0 Sep 4 23:47:06.906491 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 23:47:06.906723 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 23:47:06.921189 systemd-logind[1703]: New seat seat0. Sep 4 23:47:06.922380 systemd-logind[1703]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 23:47:06.922576 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 23:47:06.946364 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1749) Sep 4 23:47:06.974353 bash[1746]: Updated "/home/core/.ssh/authorized_keys" Sep 4 23:47:06.976993 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 23:47:06.988624 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 23:47:07.044776 setup-nvidia[1697]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2+nightly-20250904-2100 Sep 4 23:47:07.113687 dbus-daemon[1689]: [system] SELinux support is enabled Sep 4 23:47:07.113897 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 23:47:07.116050 update_engine[1710]: I20250904 23:47:07.115993 1710 update_check_scheduler.cc:74] Next update check in 7m48s Sep 4 23:47:07.120315 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). Sep 4 23:47:07.120370 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 23:47:07.120903 dbus-daemon[1689]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 4 23:47:07.125423 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). Sep 4 23:47:07.125448 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 23:47:07.130472 systemd[1]: Started update-engine.service - Update Engine. Sep 4 23:47:07.144573 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 23:47:07.183941 coreos-metadata[1688]: Sep 04 23:47:07.183 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 23:47:07.186316 coreos-metadata[1688]: Sep 04 23:47:07.186 INFO Fetch successful Sep 4 23:47:07.186486 coreos-metadata[1688]: Sep 04 23:47:07.186 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 4 23:47:07.190420 coreos-metadata[1688]: Sep 04 23:47:07.190 INFO Fetch successful Sep 4 23:47:07.190722 coreos-metadata[1688]: Sep 04 23:47:07.190 INFO Fetching http://168.63.129.16/machine/43d3a3bf-0ec9-4339-bb55-2b1ec2c35676/851c8a92%2D845c%2D44af%2D9162%2D0a98d59b020d.%5Fci%2D4230.2.2%2Dn%2D3f20fbd7a4?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 4 23:47:07.216853 coreos-metadata[1688]: Sep 04 23:47:07.216 INFO Fetch successful Sep 4 23:47:07.217014 coreos-metadata[1688]: Sep 04 23:47:07.216 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 4 23:47:07.224527 coreos-metadata[1688]: Sep 04 23:47:07.224 INFO Fetch successful Sep 4 23:47:07.246037 sshd_keygen[1726]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 23:47:07.249931 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 23:47:07.255260 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 23:47:07.271173 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 23:47:07.287562 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 23:47:07.292125 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 4 23:47:07.296531 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 23:47:07.296721 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 23:47:07.304225 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 23:47:07.312057 locksmithd[1820]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 23:47:07.314482 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 4 23:47:07.369405 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 23:47:07.392614 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 23:47:07.397536 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 23:47:07.402326 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 23:47:07.661362 setup-nvidia[1858]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 23:47:07.661720 setup-nvidia[1858]: Dload Upload Total Spent Left Speed Sep 4 23:47:08.044078 containerd[1714]: time="2025-09-04T23:47:08.043955600Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 4 23:47:08.064918 containerd[1714]: time="2025-09-04T23:47:08.064870700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.066523 containerd[1714]: time="2025-09-04T23:47:08.066489800Z" 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.103-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:08.066557 containerd[1714]: time="2025-09-04T23:47:08.066521900Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 23:47:08.066557 containerd[1714]: time="2025-09-04T23:47:08.066539900Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 23:47:08.066715 containerd[1714]: time="2025-09-04T23:47:08.066692300Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 23:47:08.066759 containerd[1714]: time="2025-09-04T23:47:08.066715700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.066799 containerd[1714]: time="2025-09-04T23:47:08.066782400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:08.066830 containerd[1714]: time="2025-09-04T23:47:08.066799000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067123 containerd[1714]: time="2025-09-04T23:47:08.067097300Z" 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 Sep 4 23:47:08.067123 containerd[1714]: time="2025-09-04T23:47:08.067120400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067179 containerd[1714]: time="2025-09-04T23:47:08.067135600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067179 containerd[1714]: time="2025-09-04T23:47:08.067158900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067275 containerd[1714]: time="2025-09-04T23:47:08.067253500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067630 containerd[1714]: time="2025-09-04T23:47:08.067606600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:08.067768 containerd[1714]: time="2025-09-04T23:47:08.067744800Z" 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 Sep 4 23:47:08.067768 containerd[1714]: time="2025-09-04T23:47:08.067765000Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 23:47:08.067949 containerd[1714]: time="2025-09-04T23:47:08.067899900Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 23:47:08.068063 containerd[1714]: time="2025-09-04T23:47:08.068041700Z" level=info msg="metadata content store policy set" policy=shared Sep 4 23:47:08.105882 containerd[1714]: time="2025-09-04T23:47:08.105830800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 23:47:08.105979 containerd[1714]: time="2025-09-04T23:47:08.105891800Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 23:47:08.105979 containerd[1714]: time="2025-09-04T23:47:08.105911600Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 23:47:08.105979 containerd[1714]: time="2025-09-04T23:47:08.105929100Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 23:47:08.105979 containerd[1714]: time="2025-09-04T23:47:08.105944600Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 23:47:08.106131 containerd[1714]: time="2025-09-04T23:47:08.106106800Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 23:47:08.106413 containerd[1714]: time="2025-09-04T23:47:08.106383400Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 23:47:08.106557 containerd[1714]: time="2025-09-04T23:47:08.106534100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 23:47:08.106632 containerd[1714]: time="2025-09-04T23:47:08.106559100Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 23:47:08.106632 containerd[1714]: time="2025-09-04T23:47:08.106576300Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 23:47:08.106632 containerd[1714]: time="2025-09-04T23:47:08.106592600Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106632 containerd[1714]: time="2025-09-04T23:47:08.106608000Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106632 containerd[1714]: time="2025-09-04T23:47:08.106621600Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106636200Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106651400Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106665200Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106678400Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106691600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106719300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106747 containerd[1714]: time="2025-09-04T23:47:08.106735400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106748200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106761700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106775000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106788800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106800600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106813000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106825800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106841500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106853200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106865000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106877500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.106903 containerd[1714]: time="2025-09-04T23:47:08.106891600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.106912700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.106928400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.106941200Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.106992100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107014200Z" 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 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107026800Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107039200Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107050300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107063700Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107074300Z" level=info msg="NRI interface is disabled by configuration." Sep 4 23:47:08.107154 containerd[1714]: time="2025-09-04T23:47:08.107084500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 23:47:08.107444 containerd[1714]: time="2025-09-04T23:47:08.107375900Z" 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}" Sep 4 23:47:08.107444 containerd[1714]: time="2025-09-04T23:47:08.107427400Z" level=info msg="Connect containerd service" Sep 4 23:47:08.107626 containerd[1714]: time="2025-09-04T23:47:08.107454200Z" level=info msg="using legacy CRI server" Sep 4 23:47:08.107626 containerd[1714]: time="2025-09-04T23:47:08.107462400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 23:47:08.107626 containerd[1714]: time="2025-09-04T23:47:08.107582200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 23:47:08.108702 containerd[1714]: time="2025-09-04T23:47:08.108668400Z" 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" Sep 4 23:47:08.108875 containerd[1714]: time="2025-09-04T23:47:08.108795800Z" level=info msg="Start subscribing containerd event" Sep 4 23:47:08.108875 containerd[1714]: time="2025-09-04T23:47:08.108853100Z" level=info msg="Start recovering state" Sep 4 23:47:08.109088 containerd[1714]: time="2025-09-04T23:47:08.108919000Z" level=info msg="Start event monitor" Sep 4 23:47:08.109088 containerd[1714]: time="2025-09-04T23:47:08.108941100Z" level=info msg="Start snapshots syncer" Sep 4 23:47:08.109088 containerd[1714]: time="2025-09-04T23:47:08.108951500Z" level=info msg="Start cni network conf syncer for default" Sep 4 23:47:08.109088 containerd[1714]: time="2025-09-04T23:47:08.108959400Z" level=info msg="Start streaming server" Sep 4 23:47:08.109088 containerd[1714]: time="2025-09-04T23:47:08.109015600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 23:47:08.109189 containerd[1714]: time="2025-09-04T23:47:08.109087300Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 23:47:08.109189 containerd[1714]: time="2025-09-04T23:47:08.109162200Z" level=info msg="containerd successfully booted in 0.066154s" Sep 4 23:47:08.109249 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 23:47:09.441243 waagent[1849]: 2025-09-04T23:47:09.441048Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 4 23:47:09.445476 waagent[1849]: 2025-09-04T23:47:09.445403Z INFO Daemon Daemon OS: flatcar 4230.2.2 Sep 4 23:47:09.449002 waagent[1849]: 2025-09-04T23:47:09.448946Z INFO Daemon Daemon Python: 3.11.11 Sep 4 23:47:09.452416 waagent[1849]: 2025-09-04T23:47:09.452364Z INFO Daemon Daemon Run daemon Sep 4 23:47:09.455471 waagent[1849]: 2025-09-04T23:47:09.455419Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Sep 4 23:47:09.461959 waagent[1849]: 2025-09-04T23:47:09.461892Z INFO Daemon Daemon Using waagent for provisioning Sep 4 23:47:09.466032 waagent[1849]: 2025-09-04T23:47:09.465974Z INFO Daemon Daemon Activate resource disk Sep 4 23:47:09.469568 waagent[1849]: 2025-09-04T23:47:09.469517Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 4 23:47:09.481113 waagent[1849]: 2025-09-04T23:47:09.481057Z INFO Daemon Daemon Found device: sdb Sep 4 23:47:09.490118 waagent[1849]: 2025-09-04T23:47:09.490059Z INFO Daemon Daemon Examining partition table Sep 4 23:47:09.565695 waagent[1849]: 2025-09-04T23:47:09.565595Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 4 23:47:09.612556 waagent[1849]: 2025-09-04T23:47:09.612491Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 4 23:47:09.617385 waagent[1849]: 2025-09-04T23:47:09.617312Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 4 23:47:10.084356 kernel: sdb: sdb1 Sep 4 23:47:10.088358 kernel: sdb: sdb1 Sep 4 23:47:10.102981 waagent[1849]: 2025-09-04T23:47:10.102884Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 4 23:47:10.119360 kernel: sdb: sdb1 Sep 4 23:47:10.119475 waagent[1849]: 2025-09-04T23:47:10.119424Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 4 23:47:13.226887 login[1851]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 23:47:13.228368 login[1852]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 23:47:13.238045 systemd-logind[1703]: New session 2 of user core. Sep 4 23:47:13.239118 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 23:47:13.261780 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 23:47:13.264785 systemd-logind[1703]: New session 1 of user core. Sep 4 23:47:13.285315 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 23:47:13.287580 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 23:47:13.305949 (systemd)[1944]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 23:47:13.308912 systemd-logind[1703]: New session c1 of user core. Sep 4 23:47:13.801631 systemd[1944]: Queued start job for default target default.target. Sep 4 23:47:13.810465 systemd[1944]: Created slice app.slice - User Application Slice. Sep 4 23:47:13.812242 systemd[1944]: Reached target paths.target - Paths. Sep 4 23:47:13.812310 systemd[1944]: Reached target timers.target - Timers. Sep 4 23:47:13.813667 systemd[1944]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 23:47:13.824359 systemd[1944]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:47:13.824534 systemd[1944]: Reached target sockets.target - Sockets. Sep 4 23:47:13.824588 systemd[1944]: Reached target basic.target - Basic System. Sep 4 23:47:13.824635 systemd[1944]: Reached target default.target - Main User Target. Sep 4 23:47:13.824672 systemd[1944]: Startup finished in 509ms. Sep 4 23:47:13.824738 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 23:47:13.826164 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 23:47:13.826908 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 23:47:15.270749 waagent[1849]: 2025-09-04T23:47:15.270653Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 4 23:47:15.296361 kernel: EXT4-fs (sdb1): mounted filesystem 9d439346-6235-4921-8a69-cc0e0db06385 r/w with ordered data mode. Quota mode: none. Sep 4 23:47:15.296796 waagent[1849]: 2025-09-04T23:47:15.296718Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 4 23:47:15.303431 waagent[1849]: 2025-09-04T23:47:15.303372Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 23:47:15.308250 waagent[1849]: 2025-09-04T23:47:15.308197Z INFO Daemon Daemon Running default provisioning handler Sep 4 23:47:15.318792 waagent[1849]: 2025-09-04T23:47:15.318740Z 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. Sep 4 23:47:15.329132 waagent[1849]: 2025-09-04T23:47:15.329080Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 4 23:47:15.330792 waagent[1849]: 2025-09-04T23:47:15.330733Z INFO Daemon Daemon cloud-init is enabled: False Sep 4 23:47:15.332285 waagent[1849]: 2025-09-04T23:47:15.332238Z INFO Daemon Daemon Copying ovf-env.xml Sep 4 23:47:15.485211 waagent[1849]: 2025-09-04T23:47:15.485117Z INFO Daemon Daemon Successfully mounted dvd Sep 4 23:47:15.516542 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 4 23:47:15.516833 waagent[1849]: 2025-09-04T23:47:15.516574Z INFO Daemon Daemon Detect protocol endpoint Sep 4 23:47:15.518219 waagent[1849]: 2025-09-04T23:47:15.518152Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 23:47:15.519525 waagent[1849]: 2025-09-04T23:47:15.519475Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 4 23:47:15.520242 waagent[1849]: 2025-09-04T23:47:15.520199Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 4 23:47:15.521898 waagent[1849]: 2025-09-04T23:47:15.521808Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 4 23:47:15.523445 waagent[1849]: 2025-09-04T23:47:15.523399Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 4 23:47:15.558490 waagent[1849]: 2025-09-04T23:47:15.558440Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 4 23:47:15.560217 waagent[1849]: 2025-09-04T23:47:15.560182Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 4 23:47:15.561473 waagent[1849]: 2025-09-04T23:47:15.561427Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 4 23:47:15.681377 waagent[1849]: 2025-09-04T23:47:15.681256Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 4 23:47:15.683091 waagent[1849]: 2025-09-04T23:47:15.683040Z INFO Daemon Daemon Forcing an update of the goal state. Sep 4 23:47:15.687931 waagent[1849]: 2025-09-04T23:47:15.687875Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 23:47:15.719575 waagent[1849]: 2025-09-04T23:47:15.719527Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 4 23:47:15.721829 waagent[1849]: 2025-09-04T23:47:15.721767Z INFO Daemon Sep 4 23:47:15.724991 waagent[1849]: 2025-09-04T23:47:15.724930Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 15db9a58-ee26-4f26-910f-69e2a99d899c eTag: 15657121172149303647 source: Fabric] Sep 4 23:47:15.728147 waagent[1849]: 2025-09-04T23:47:15.728090Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 4 23:47:15.729304 waagent[1849]: 2025-09-04T23:47:15.729251Z INFO Daemon Sep 4 23:47:15.729481 waagent[1849]: 2025-09-04T23:47:15.729440Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 4 23:47:15.734743 waagent[1849]: 2025-09-04T23:47:15.734701Z INFO Daemon Daemon Downloading artifacts profile blob Sep 4 23:47:15.864505 waagent[1849]: 2025-09-04T23:47:15.864380Z INFO Daemon Downloaded certificate {'thumbprint': '9E3C7EFCD43EB15E63B8A25D35DFADF2E9FBC3AF', 'hasPrivateKey': True} Sep 4 23:47:15.872834 waagent[1849]: 2025-09-04T23:47:15.872778Z INFO Daemon Fetch goal state completed Sep 4 23:47:15.907051 waagent[1849]: 2025-09-04T23:47:15.906988Z INFO Daemon Daemon Starting provisioning Sep 4 23:47:15.912320 waagent[1849]: 2025-09-04T23:47:15.912257Z INFO Daemon Daemon Handle ovf-env.xml. Sep 4 23:47:15.914135 waagent[1849]: 2025-09-04T23:47:15.914074Z INFO Daemon Daemon Set hostname [ci-4230.2.2-n-3f20fbd7a4] Sep 4 23:47:15.947901 waagent[1849]: 2025-09-04T23:47:15.947847Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-n-3f20fbd7a4] Sep 4 23:47:15.960700 waagent[1849]: 2025-09-04T23:47:15.949651Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 4 23:47:15.960700 waagent[1849]: 2025-09-04T23:47:15.950449Z INFO Daemon Daemon Primary interface is [eth0] Sep 4 23:47:15.971841 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:15.971850 systemd-networkd[1548]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:47:15.971876 systemd-networkd[1548]: eth0: DHCP lease lost Sep 4 23:47:15.972304 waagent[1849]: 2025-09-04T23:47:15.972247Z INFO Daemon Daemon Create user account if not exists Sep 4 23:47:15.973855 waagent[1849]: 2025-09-04T23:47:15.973804Z INFO Daemon Daemon User core already exists, skip useradd Sep 4 23:47:15.975310 waagent[1849]: 2025-09-04T23:47:15.975268Z INFO Daemon Daemon Configure sudoer Sep 4 23:47:15.976341 waagent[1849]: 2025-09-04T23:47:15.976290Z INFO Daemon Daemon Configure sshd Sep 4 23:47:15.977034 waagent[1849]: 2025-09-04T23:47:15.976989Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 4 23:47:15.978391 waagent[1849]: 2025-09-04T23:47:15.978344Z INFO Daemon Daemon Deploy ssh public key. Sep 4 23:47:16.053370 systemd-networkd[1548]: eth0: DHCPv4 address 10.200.8.21/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:47:17.175106 waagent[1849]: 2025-09-04T23:47:17.175034Z INFO Daemon Daemon Provisioning complete Sep 4 23:47:17.185014 waagent[1849]: 2025-09-04T23:47:17.184962Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 4 23:47:17.186649 waagent[1849]: 2025-09-04T23:47:17.186597Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 4 23:47:17.187384 waagent[1849]: 2025-09-04T23:47:17.187326Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 4 23:47:17.382918 waagent[2004]: 2025-09-04T23:47:17.382824Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 4 23:47:17.383259 waagent[2004]: 2025-09-04T23:47:17.382976Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Sep 4 23:47:17.383259 waagent[2004]: 2025-09-04T23:47:17.383058Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 4 23:47:17.457182 waagent[2004]: 2025-09-04T23:47:17.457044Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Sep 4 23:47:17.457366 waagent[2004]: 2025-09-04T23:47:17.457303Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:17.457464 waagent[2004]: 2025-09-04T23:47:17.457420Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:17.465212 waagent[2004]: 2025-09-04T23:47:17.465148Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 23:47:17.470254 waagent[2004]: 2025-09-04T23:47:17.470207Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 4 23:47:17.470724 waagent[2004]: 2025-09-04T23:47:17.470677Z INFO ExtHandler Sep 4 23:47:17.470816 waagent[2004]: 2025-09-04T23:47:17.470776Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: e9618f0b-3eed-4d59-a75a-507cca9156fc eTag: 15657121172149303647 source: Fabric] Sep 4 23:47:17.471191 waagent[2004]: 2025-09-04T23:47:17.471102Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 4 23:47:17.471880 waagent[2004]: 2025-09-04T23:47:17.471831Z INFO ExtHandler Sep 4 23:47:17.471964 waagent[2004]: 2025-09-04T23:47:17.471926Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 4 23:47:17.475577 waagent[2004]: 2025-09-04T23:47:17.475533Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 4 23:47:17.553262 waagent[2004]: 2025-09-04T23:47:17.553189Z INFO ExtHandler Downloaded certificate {'thumbprint': '9E3C7EFCD43EB15E63B8A25D35DFADF2E9FBC3AF', 'hasPrivateKey': True} Sep 4 23:47:17.553849 waagent[2004]: 2025-09-04T23:47:17.553794Z INFO ExtHandler Fetch goal state completed Sep 4 23:47:17.564957 waagent[2004]: 2025-09-04T23:47:17.564904Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2004 Sep 4 23:47:17.565101 waagent[2004]: 2025-09-04T23:47:17.565058Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 4 23:47:17.566866 waagent[2004]: 2025-09-04T23:47:17.566818Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Sep 4 23:47:17.567954 waagent[2004]: 2025-09-04T23:47:17.567203Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 4 23:47:17.661319 waagent[2004]: 2025-09-04T23:47:17.661261Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 4 23:47:17.661542 waagent[2004]: 2025-09-04T23:47:17.661496Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 4 23:47:17.668018 waagent[2004]: 2025-09-04T23:47:17.667972Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 4 23:47:17.675264 systemd[1]: Reload requested from client PID 2017 ('systemctl') (unit waagent.service)... Sep 4 23:47:17.675280 systemd[1]: Reloading... Sep 4 23:47:17.752361 zram_generator::config[2057]: No configuration found. Sep 4 23:47:17.893053 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. Sep 4 23:47:18.046254 systemd[1]: Reloading finished in 370 ms. Sep 4 23:47:18.063255 waagent[2004]: 2025-09-04T23:47:18.063163Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 4 23:47:18.069287 systemd[1]: Reload requested from client PID 2114 ('systemctl') (unit waagent.service)... Sep 4 23:47:18.069302 systemd[1]: Reloading... Sep 4 23:47:18.145361 zram_generator::config[2155]: No configuration found. Sep 4 23:47:18.287784 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. Sep 4 23:47:18.431970 systemd[1]: Reloading finished in 362 ms. Sep 4 23:47:18.447985 waagent[2004]: 2025-09-04T23:47:18.447894Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 4 23:47:18.448421 waagent[2004]: 2025-09-04T23:47:18.448052Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 4 23:47:19.212144 waagent[2004]: 2025-09-04T23:47:19.212044Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 4 23:47:19.212724 waagent[2004]: 2025-09-04T23:47:19.212665Z 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] Sep 4 23:47:19.213533 waagent[2004]: 2025-09-04T23:47:19.213463Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 4 23:47:19.213911 waagent[2004]: 2025-09-04T23:47:19.213847Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:19.214107 waagent[2004]: 2025-09-04T23:47:19.214055Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 4 23:47:19.214310 waagent[2004]: 2025-09-04T23:47:19.214264Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:19.214493 waagent[2004]: 2025-09-04T23:47:19.214438Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:19.214933 waagent[2004]: 2025-09-04T23:47:19.214865Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 4 23:47:19.215107 waagent[2004]: 2025-09-04T23:47:19.215051Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 4 23:47:19.215166 waagent[2004]: 2025-09-04T23:47:19.215116Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 4 23:47:19.215590 waagent[2004]: 2025-09-04T23:47:19.215540Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:19.216296 waagent[2004]: 2025-09-04T23:47:19.216241Z INFO EnvHandler ExtHandler Configure routes Sep 4 23:47:19.216485 waagent[2004]: 2025-09-04T23:47:19.216428Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 4 23:47:19.216485 waagent[2004]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 4 23:47:19.216485 waagent[2004]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 4 23:47:19.216485 waagent[2004]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 4 23:47:19.216485 waagent[2004]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.216485 waagent[2004]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.216485 waagent[2004]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.216687 waagent[2004]: 2025-09-04T23:47:19.216537Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 4 23:47:19.216833 waagent[2004]: 2025-09-04T23:47:19.216780Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 4 23:47:19.216880 waagent[2004]: 2025-09-04T23:47:19.216836Z INFO EnvHandler ExtHandler Gateway:None Sep 4 23:47:19.217224 waagent[2004]: 2025-09-04T23:47:19.217184Z INFO EnvHandler ExtHandler Routes:None Sep 4 23:47:19.217389 waagent[2004]: 2025-09-04T23:47:19.217316Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 4 23:47:19.223911 waagent[2004]: 2025-09-04T23:47:19.223855Z INFO ExtHandler ExtHandler Sep 4 23:47:19.224252 waagent[2004]: 2025-09-04T23:47:19.224206Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 299b3ca7-ad4a-4459-9b33-afa0a88b0a42 correlation cdf43dca-e3ac-4c54-90f7-7706c2d24f60 created: 2025-09-04T23:45:46.246017Z] Sep 4 23:47:19.225288 waagent[2004]: 2025-09-04T23:47:19.225233Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 4 23:47:19.227492 waagent[2004]: 2025-09-04T23:47:19.227446Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 4 23:47:19.276419 waagent[2004]: 2025-09-04T23:47:19.276363Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 790ED0A0-CAF3-4006-BC12-85BD3AB67CCA;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 4 23:47:19.292180 waagent[2004]: 2025-09-04T23:47:19.292119Z INFO MonitorHandler ExtHandler Network interfaces: Sep 4 23:47:19.292180 waagent[2004]: Executing ['ip', '-a', '-o', 'link']: Sep 4 23:47:19.292180 waagent[2004]: 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 Sep 4 23:47:19.292180 waagent[2004]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:34:50:22 brd ff:ff:ff:ff:ff:ff Sep 4 23:47:19.292180 waagent[2004]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 4 23:47:19.292180 waagent[2004]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 4 23:47:19.292180 waagent[2004]: 2: eth0 inet 10.200.8.21/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 4 23:47:19.292180 waagent[2004]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 4 23:47:19.292180 waagent[2004]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 4 23:47:19.292180 waagent[2004]: 2: eth0 inet6 fe80::7e1e:52ff:fe34:5022/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 4 23:47:19.458255 waagent[2004]: 2025-09-04T23:47:19.458142Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 4 23:47:19.458255 waagent[2004]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.458255 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.458255 waagent[2004]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.458255 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.458255 waagent[2004]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.458255 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.458255 waagent[2004]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 23:47:19.458255 waagent[2004]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 23:47:19.458255 waagent[2004]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 23:47:19.462500 waagent[2004]: 2025-09-04T23:47:19.462389Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 4 23:47:19.462500 waagent[2004]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.462500 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.462500 waagent[2004]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.462500 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.462500 waagent[2004]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.462500 waagent[2004]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.462500 waagent[2004]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 23:47:19.462500 waagent[2004]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 23:47:19.462500 waagent[2004]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 23:47:19.462782 waagent[2004]: 2025-09-04T23:47:19.462741Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 4 23:47:19.462945 waagent[2004]: 2025-09-04T23:47:19.462908Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 4 23:47:30.656065 chronyd[1717]: Selected source PHC0 Sep 4 23:47:33.186245 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 23:47:33.195564 systemd[1]: Started sshd@0-10.200.8.21:22-10.200.16.10:49082.service - OpenSSH per-connection server daemon (10.200.16.10:49082). Sep 4 23:47:33.809658 setup-nvidia[1858]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 540M 0 65536 0 0 89542 0 1:45:30 --:--:-- 1:45:30 89530\u000d 1 540M 1 6752k 0 0 3948k 0 0:02:20 0:00:01 0:02:19 3948k\u000d 5 540M 5 29.0M 0 0 10.6M 0 0:00:50 0:00:02 0:00:48 10.6M\u000d 9 540M 9 51.2M 0 0 13.7M 0 0:00:39 0:00:03 0:00:36 13.7M\u000d 13 540M 13 73.6M 0 0 15.9M 0 0:00:33 0:00:04 0:00:29 15.9M\u000d 17 540M 17 94.3M 0 0 16.8M 0 0:00:32 0:00:05 0:00:27 19.3M\u000d 21 540M 21 114M 0 0 17.3M 0 0:00:31 0:00:06 0:00:25 22.0M\u000d 25 540M 25 135M 0 0 17.7M 0 0:00:30 0:00:07 0:00:23 21.7M\u000d 29 540M 29 158M 0 0 18.3M 0 0:00:29 0:00:08 0:00:21 21.8M\u000d 33 540M 33 179M 0 0 18.5M 0 0:00:29 0:00:09 0:00:20 21.0M\u000d 37 540M 37 200M 0 0 18.8M 0 0:00:28 0:00:10 0:00:18 21.0M\u000d 40 540M 40 220M 0 0 18.9M 0 0:00:28 0:00:11 0:00:17 21.0M\u000d 44 540M 44 238M 0 0 18.8M 0 0:00:28 0:00:12 0:00:16 20.4M\u000d 48 540M 48 263M 0 0 19.2M 0 0:00:28 0:00:13 0:00:15 20.6M\u000d 52 540M 52 285M 0 0 19.4M 0 0:00:27 0:00:14 0:00:13 20.9M\u000d 57 540M 57 309M 0 0 19.7M 0 0:00:27 0:00:15 0:00:12 21.5M\u000d 61 540M 61 329M 0 0 19.7M 0 0:00:27 0:00:16 0:00:11 21.4M\u000d 65 540M 65 352M 0 0 20.0M 0 0:00:27 0:00:17 0:00:10 23.0M\u000d 69 540M 69 373M 0 0 20.0M 0 0:00:26 0:00:18 0:00:08 22.3M\u000d 73 540M 73 396M 0 0 20.1M 0 0:00:26 0:00:19 0:00:07 22.5M\u000d 77 540M 77 418M 0 0 20.2M 0 0:00:26 0:00:20 0:00:06 22.1M\u000d 81 540M 81 440M 0 0 20.3M 0 0:00:26 0:00:21 0:00:05 22.4M\u000d 85 540M 85 461M 0 0 20.3M 0 0:00:26 0:00:22 0:00:04 21.6M\u000d 89 540M 89 486M 0 0 20.5M 0 0:00:26 0:00:23 0:00:03 22.1M\u000d 94 540M 94 510M 0 0 20.6M 0 0:00:26 0:00:24 0:00:02 22.3M\u000d 97 540M 97 529M 0 0 20.6M 0 0:00:26 0:00:25 0:00:01 21.9M\u000d100 540M 100 540M 0 0 20.6M 0 0:00:26 0:00:26 --:--:-- 22.2M Sep 4 23:47:34.038225 sshd[2245]: Accepted publickey for core from 10.200.16.10 port 49082 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:34.039743 sshd-session[2245]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:34.050949 systemd-logind[1703]: New session 3 of user core. Sep 4 23:47:34.064564 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 23:47:34.607628 systemd[1]: Started sshd@1-10.200.8.21:22-10.200.16.10:49094.service - OpenSSH per-connection server daemon (10.200.16.10:49094). Sep 4 23:47:35.241570 sshd[2260]: Accepted publickey for core from 10.200.16.10 port 49094 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:35.243020 sshd-session[2260]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:35.250018 systemd-logind[1703]: New session 4 of user core. Sep 4 23:47:35.257473 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 23:47:35.697513 sshd[2262]: Connection closed by 10.200.16.10 port 49094 Sep 4 23:47:35.696544 sshd-session[2260]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:35.701944 systemd[1]: sshd@1-10.200.8.21:22-10.200.16.10:49094.service: Deactivated successfully. Sep 4 23:47:35.703593 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 23:47:35.706561 systemd-logind[1703]: Session 4 logged out. Waiting for processes to exit. Sep 4 23:47:35.707647 systemd-logind[1703]: Removed session 4. Sep 4 23:47:35.820608 systemd[1]: Started sshd@2-10.200.8.21:22-10.200.16.10:49104.service - OpenSSH per-connection server daemon (10.200.16.10:49104). Sep 4 23:47:36.459248 sshd[2268]: Accepted publickey for core from 10.200.16.10 port 49104 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:36.460605 sshd-session[2268]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:36.467717 systemd-logind[1703]: New session 5 of user core. Sep 4 23:47:36.474470 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 23:47:36.907576 sshd[2270]: Connection closed by 10.200.16.10 port 49104 Sep 4 23:47:36.906276 sshd-session[2268]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:36.909758 systemd[1]: sshd@2-10.200.8.21:22-10.200.16.10:49104.service: Deactivated successfully. Sep 4 23:47:36.912489 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 23:47:36.913180 systemd-logind[1703]: Session 5 logged out. Waiting for processes to exit. Sep 4 23:47:36.914181 systemd-logind[1703]: Removed session 5. Sep 4 23:47:44.227539 setup-nvidia[1697]: Downloading NVIDIA 535.230.02 Driver Sep 4 23:47:44.231900 setup-nvidia[2276]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 23:47:44.232146 setup-nvidia[2276]: Dload Upload Total Spent Left Speed Sep 4 23:47:45.912325 setup-nvidia[2276]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 57 326M 57 186M 0 0 179M 0 0:00:01 0:00:01 --:--:-- 179M\u000d100 326M 100 326M 0 0 194M 0 0:00:01 0:00:01 --:--:-- 193M Sep 4 23:47:46.034280 setup-nvidia[1697]: Extract the NVIDIA Driver Installer 535.230.02 Sep 4 23:47:46.034597 setup-nvidia[1697]: /opt/nvidia/workdir/nvidia-workdir / Sep 4 23:47:46.069099 setup-nvidia[2279]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 4 23:47:46.347966 setup-nvidia[2279]: Verifying archive integrity... OK Sep 4 23:47:47.021941 systemd[1]: Started sshd@3-10.200.8.21:22-10.200.16.10:44360.service - OpenSSH per-connection server daemon (10.200.16.10:44360). Sep 4 23:47:47.099349 setup-nvidia[2279]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 4 23:47:47.657382 sshd[2307]: Accepted publickey for core from 10.200.16.10 port 44360 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:47.658790 sshd-session[2307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:47.663094 systemd-logind[1703]: New session 6 of user core. Sep 4 23:47:47.677507 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 23:47:48.095703 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 4 23:47:48.103431 sshd[2312]: Connection closed by 10.200.16.10 port 44360 Sep 4 23:47:48.103835 sshd-session[2307]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:48.107111 systemd[1]: sshd@3-10.200.8.21:22-10.200.16.10:44360.service: Deactivated successfully. Sep 4 23:47:48.108777 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 23:47:48.109537 systemd-logind[1703]: Session 6 logged out. Waiting for processes to exit. Sep 4 23:47:48.110408 systemd-logind[1703]: Removed session 6. Sep 4 23:47:48.215878 systemd[1]: Started sshd@4-10.200.8.21:22-10.200.16.10:44376.service - OpenSSH per-connection server daemon (10.200.16.10:44376). Sep 4 23:47:48.847178 sshd[2318]: Accepted publickey for core from 10.200.16.10 port 44376 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:48.848569 sshd-session[2318]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:48.854131 systemd-logind[1703]: New session 7 of user core. Sep 4 23:47:48.862550 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 23:47:48.975615 setup-nvidia[2305]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 4 23:47:49.061112 setup-nvidia[1697]: / Sep 4 23:47:49.061382 setup-nvidia[1697]: Spawn system-nspawn container to install the NVIDIA drivers Sep 4 23:47:49.300800 sshd[2320]: Connection closed by 10.200.16.10 port 44376 Sep 4 23:47:49.301176 sshd-session[2318]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:49.304294 systemd[1]: sshd@4-10.200.8.21:22-10.200.16.10:44376.service: Deactivated successfully. Sep 4 23:47:49.305914 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 23:47:49.306558 systemd-logind[1703]: Session 7 logged out. Waiting for processes to exit. Sep 4 23:47:49.307315 systemd-logind[1703]: Removed session 7. Sep 4 23:47:49.356867 sudo[2324]: 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.2+nightly-20250904-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 4 23:47:49.358609 sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 4 23:47:49.365841 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 4 23:47:49.379549 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 23:47:49.390022 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 23:47:49.397652 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 4 23:47:49.403764 (systemd)[2331]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 4 23:47:49.406010 systemd-logind[1703]: New session c3 of user root. Sep 4 23:47:49.580026 systemd[2331]: Queued start job for default target default.target. Sep 4 23:47:49.589450 systemd[2331]: Created slice app.slice - User Application Slice. Sep 4 23:47:49.589481 systemd[2331]: Reached target paths.target - Paths. Sep 4 23:47:49.589548 systemd[2331]: Reached target timers.target - Timers. Sep 4 23:47:49.592992 systemd[2331]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 23:47:49.603945 systemd[2331]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:47:49.604066 systemd[2331]: Reached target sockets.target - Sockets. Sep 4 23:47:49.604120 systemd[2331]: Reached target basic.target - Basic System. Sep 4 23:47:49.604167 systemd[2331]: Reached target default.target - Main User Target. Sep 4 23:47:49.604204 systemd[2331]: Startup finished in 192ms. Sep 4 23:47:49.604227 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 4 23:47:49.607248 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 4 23:47:49.715359 kernel: loop6: detected capacity change from 0 to 12644352 Sep 4 23:47:49.726352 kernel: loop6: p9 Sep 4 23:47:49.949155 setup-nvidia[2340]: ░ Spawning container flatcardevelopercontainer-4230.2.2nightly-20250904-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.2+nightly-20250904-2100.bin. Sep 4 23:47:50.001382 kernel: EXT4-fs (loop6p9): mounted filesystem 31317368-05db-4c20-ad22-912e33323410 ro without journal. Quota mode: none. Sep 4 23:47:52.143455 update_engine[1710]: I20250904 23:47:52.143378 1710 update_attempter.cc:509] Updating boot flags... Sep 4 23:47:52.322362 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2370) Sep 4 23:47:59.415913 systemd[1]: Started sshd@5-10.200.8.21:22-10.200.16.10:55688.service - OpenSSH per-connection server daemon (10.200.16.10:55688). Sep 4 23:48:00.046236 sshd[2518]: Accepted publickey for core from 10.200.16.10 port 55688 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:00.047588 sshd-session[2518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:00.052024 systemd-logind[1703]: New session 8 of user core. Sep 4 23:48:00.069501 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 23:48:00.489255 sshd[2536]: Connection closed by 10.200.16.10 port 55688 Sep 4 23:48:00.489649 sshd-session[2518]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:00.493570 systemd[1]: sshd@5-10.200.8.21:22-10.200.16.10:55688.service: Deactivated successfully. Sep 4 23:48:00.495170 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 23:48:00.495868 systemd-logind[1703]: Session 8 logged out. Waiting for processes to exit. Sep 4 23:48:00.496730 systemd-logind[1703]: Removed session 8. Sep 4 23:48:00.602828 systemd[1]: Started sshd@6-10.200.8.21:22-10.200.16.10:47600.service - OpenSSH per-connection server daemon (10.200.16.10:47600). Sep 4 23:48:01.234284 sshd[2596]: Accepted publickey for core from 10.200.16.10 port 47600 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:01.235598 sshd-session[2596]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:01.239715 systemd-logind[1703]: New session 9 of user core. Sep 4 23:48:01.246457 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 23:48:01.687482 sshd[2858]: Connection closed by 10.200.16.10 port 47600 Sep 4 23:48:01.687905 sshd-session[2596]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:01.690970 systemd[1]: sshd@6-10.200.8.21:22-10.200.16.10:47600.service: Deactivated successfully. Sep 4 23:48:01.692541 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 23:48:01.693211 systemd-logind[1703]: Session 9 logged out. Waiting for processes to exit. Sep 4 23:48:01.694005 systemd-logind[1703]: Removed session 9. Sep 4 23:48:11.799867 systemd[1]: Started sshd@7-10.200.8.21:22-10.200.16.10:54410.service - OpenSSH per-connection server daemon (10.200.16.10:54410). Sep 4 23:48:12.434181 sshd[3486]: Accepted publickey for core from 10.200.16.10 port 54410 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:12.435583 sshd-session[3486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:12.439833 systemd-logind[1703]: New session 10 of user core. Sep 4 23:48:12.451460 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 23:48:12.879904 sshd[3488]: Connection closed by 10.200.16.10 port 54410 Sep 4 23:48:12.880232 sshd-session[3486]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:12.883498 systemd[1]: sshd@7-10.200.8.21:22-10.200.16.10:54410.service: Deactivated successfully. Sep 4 23:48:12.885052 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 23:48:12.885685 systemd-logind[1703]: Session 10 logged out. Waiting for processes to exit. Sep 4 23:48:12.886451 systemd-logind[1703]: Removed session 10. Sep 4 23:48:12.993056 systemd[1]: Started sshd@8-10.200.8.21:22-10.200.16.10:54426.service - OpenSSH per-connection server daemon (10.200.16.10:54426). Sep 4 23:48:13.623001 sshd[3494]: Accepted publickey for core from 10.200.16.10 port 54426 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:13.624288 sshd-session[3494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:13.628426 systemd-logind[1703]: New session 11 of user core. Sep 4 23:48:13.639457 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 23:48:14.066349 sshd[3496]: Connection closed by 10.200.16.10 port 54426 Sep 4 23:48:14.066754 sshd-session[3494]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:14.070306 systemd[1]: sshd@8-10.200.8.21:22-10.200.16.10:54426.service: Deactivated successfully. Sep 4 23:48:14.071991 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 23:48:14.072871 systemd-logind[1703]: Session 11 logged out. Waiting for processes to exit. Sep 4 23:48:14.073711 systemd-logind[1703]: Removed session 11. Sep 4 23:48:24.179935 systemd[1]: Started sshd@9-10.200.8.21:22-10.200.16.10:43182.service - OpenSSH per-connection server daemon (10.200.16.10:43182). Sep 4 23:48:24.812156 sshd[3599]: Accepted publickey for core from 10.200.16.10 port 43182 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:24.813494 sshd-session[3599]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:24.817602 systemd-logind[1703]: New session 12 of user core. Sep 4 23:48:24.828458 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 4 23:48:25.264826 sshd[3601]: Connection closed by 10.200.16.10 port 43182 Sep 4 23:48:25.265209 sshd-session[3599]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:25.268420 systemd[1]: sshd@9-10.200.8.21:22-10.200.16.10:43182.service: Deactivated successfully. Sep 4 23:48:25.270002 systemd[1]: session-12.scope: Deactivated successfully. Sep 4 23:48:25.270647 systemd-logind[1703]: Session 12 logged out. Waiting for processes to exit. Sep 4 23:48:25.271478 systemd-logind[1703]: Removed session 12. Sep 4 23:48:25.377666 systemd[1]: Started sshd@10-10.200.8.21:22-10.200.16.10:43184.service - OpenSSH per-connection server daemon (10.200.16.10:43184). Sep 4 23:48:26.007844 sshd[3607]: Accepted publickey for core from 10.200.16.10 port 43184 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:26.009137 sshd-session[3607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:26.013301 systemd-logind[1703]: New session 13 of user core. Sep 4 23:48:26.026467 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 4 23:48:26.459630 sshd[3609]: Connection closed by 10.200.16.10 port 43184 Sep 4 23:48:26.460036 sshd-session[3607]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:26.463281 systemd[1]: sshd@10-10.200.8.21:22-10.200.16.10:43184.service: Deactivated successfully. Sep 4 23:48:26.464873 systemd[1]: session-13.scope: Deactivated successfully. Sep 4 23:48:26.465501 systemd-logind[1703]: Session 13 logged out. Waiting for processes to exit. Sep 4 23:48:26.466265 systemd-logind[1703]: Removed session 13. Sep 4 23:48:36.576452 systemd[1]: Started sshd@11-10.200.8.21:22-10.200.16.10:57136.service - OpenSSH per-connection server daemon (10.200.16.10:57136). Sep 4 23:48:37.207210 sshd[6610]: Accepted publickey for core from 10.200.16.10 port 57136 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:37.209730 sshd-session[6610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:37.214954 systemd-logind[1703]: New session 14 of user core. Sep 4 23:48:37.223483 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 4 23:48:37.656482 sshd[6780]: Connection closed by 10.200.16.10 port 57136 Sep 4 23:48:37.656771 sshd-session[6610]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:37.664496 systemd-logind[1703]: Session 14 logged out. Waiting for processes to exit. Sep 4 23:48:37.665752 systemd[1]: sshd@11-10.200.8.21:22-10.200.16.10:57136.service: Deactivated successfully. Sep 4 23:48:37.667953 systemd[1]: session-14.scope: Deactivated successfully. Sep 4 23:48:37.669312 systemd-logind[1703]: Removed session 14. Sep 4 23:48:37.782615 systemd[1]: Started sshd@12-10.200.8.21:22-10.200.16.10:57148.service - OpenSSH per-connection server daemon (10.200.16.10:57148). Sep 4 23:48:38.416772 sshd[6932]: Accepted publickey for core from 10.200.16.10 port 57148 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:38.418177 sshd-session[6932]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:38.422987 systemd-logind[1703]: New session 15 of user core. Sep 4 23:48:38.432659 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 4 23:48:38.873669 sshd[7240]: Connection closed by 10.200.16.10 port 57148 Sep 4 23:48:38.875576 sshd-session[6932]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:38.879091 systemd-logind[1703]: Session 15 logged out. Waiting for processes to exit. Sep 4 23:48:38.880454 systemd[1]: sshd@12-10.200.8.21:22-10.200.16.10:57148.service: Deactivated successfully. Sep 4 23:48:38.882268 systemd[1]: session-15.scope: Deactivated successfully. Sep 4 23:48:38.884453 systemd-logind[1703]: Removed session 15. Sep 4 23:48:48.998192 systemd[1]: Started sshd@13-10.200.8.21:22-10.200.16.10:58418.service - OpenSSH per-connection server daemon (10.200.16.10:58418). Sep 4 23:48:49.634577 sshd[10897]: Accepted publickey for core from 10.200.16.10 port 58418 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:49.635999 sshd-session[10897]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:49.642385 systemd-logind[1703]: New session 16 of user core. Sep 4 23:48:49.649474 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 4 23:48:50.090585 sshd[11099]: Connection closed by 10.200.16.10 port 58418 Sep 4 23:48:50.089617 sshd-session[10897]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:50.094770 systemd-logind[1703]: Session 16 logged out. Waiting for processes to exit. Sep 4 23:48:50.095281 systemd[1]: sshd@13-10.200.8.21:22-10.200.16.10:58418.service: Deactivated successfully. Sep 4 23:48:50.097141 systemd[1]: session-16.scope: Deactivated successfully. Sep 4 23:48:50.097742 systemd-logind[1703]: Removed session 16. Sep 4 23:48:50.210895 systemd[1]: Started sshd@14-10.200.8.21:22-10.200.16.10:52986.service - OpenSSH per-connection server daemon (10.200.16.10:52986). Sep 4 23:48:50.842302 sshd[11409]: Accepted publickey for core from 10.200.16.10 port 52986 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:50.843640 sshd-session[11409]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:50.847758 systemd-logind[1703]: New session 17 of user core. Sep 4 23:48:50.865480 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 4 23:48:51.293591 sshd[11515]: Connection closed by 10.200.16.10 port 52986 Sep 4 23:48:51.294001 sshd-session[11409]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:51.298771 systemd[1]: sshd@14-10.200.8.21:22-10.200.16.10:52986.service: Deactivated successfully. Sep 4 23:48:51.300483 systemd[1]: session-17.scope: Deactivated successfully. Sep 4 23:48:51.301881 systemd-logind[1703]: Session 17 logged out. Waiting for processes to exit. Sep 4 23:48:51.303192 systemd-logind[1703]: Removed session 17. Sep 4 23:49:01.420556 systemd[1]: Started sshd@15-10.200.8.21:22-10.200.16.10:60684.service - OpenSSH per-connection server daemon (10.200.16.10:60684). Sep 4 23:49:02.104200 sshd[11942]: Accepted publickey for core from 10.200.16.10 port 60684 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:02.107948 sshd-session[11942]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:02.117222 systemd-logind[1703]: New session 18 of user core. Sep 4 23:49:02.127012 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 4 23:49:02.632701 sshd[11968]: Connection closed by 10.200.16.10 port 60684 Sep 4 23:49:02.634690 sshd-session[11942]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:02.639648 systemd[1]: sshd@15-10.200.8.21:22-10.200.16.10:60684.service: Deactivated successfully. Sep 4 23:49:02.644045 systemd[1]: session-18.scope: Deactivated successfully. Sep 4 23:49:02.645076 systemd-logind[1703]: Session 18 logged out. Waiting for processes to exit. Sep 4 23:49:02.646117 systemd-logind[1703]: Removed session 18. Sep 4 23:49:02.755671 systemd[1]: Started sshd@16-10.200.8.21:22-10.200.16.10:60700.service - OpenSSH per-connection server daemon (10.200.16.10:60700). Sep 4 23:49:03.442530 sshd[11998]: Accepted publickey for core from 10.200.16.10 port 60700 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:03.443964 sshd-session[11998]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:03.452004 systemd-logind[1703]: New session 19 of user core. Sep 4 23:49:03.460670 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 4 23:49:03.890831 sshd[12007]: Connection closed by 10.200.16.10 port 60700 Sep 4 23:49:03.892952 sshd-session[11998]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:03.895875 systemd-logind[1703]: Session 19 logged out. Waiting for processes to exit. Sep 4 23:49:03.897830 systemd[1]: sshd@16-10.200.8.21:22-10.200.16.10:60700.service: Deactivated successfully. Sep 4 23:49:03.900202 systemd[1]: session-19.scope: Deactivated successfully. Sep 4 23:49:03.901713 systemd-logind[1703]: Removed session 19. Sep 4 23:49:14.007163 systemd[1]: Started sshd@17-10.200.8.21:22-10.200.16.10:39398.service - OpenSSH per-connection server daemon (10.200.16.10:39398). Sep 4 23:49:14.643641 sshd[12342]: Accepted publickey for core from 10.200.16.10 port 39398 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:14.645582 sshd-session[12342]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:14.653693 systemd-logind[1703]: New session 20 of user core. Sep 4 23:49:14.661555 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 4 23:49:15.100219 sshd[12368]: Connection closed by 10.200.16.10 port 39398 Sep 4 23:49:15.101069 sshd-session[12342]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:15.106284 systemd[1]: sshd@17-10.200.8.21:22-10.200.16.10:39398.service: Deactivated successfully. Sep 4 23:49:15.110776 systemd[1]: session-20.scope: Deactivated successfully. Sep 4 23:49:15.111809 systemd-logind[1703]: Session 20 logged out. Waiting for processes to exit. Sep 4 23:49:15.112909 systemd-logind[1703]: Removed session 20. Sep 4 23:49:15.231361 systemd[1]: Started sshd@18-10.200.8.21:22-10.200.16.10:39402.service - OpenSSH per-connection server daemon (10.200.16.10:39402). Sep 4 23:49:15.864979 sshd[12380]: Accepted publickey for core from 10.200.16.10 port 39402 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:15.866852 sshd-session[12380]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:15.872058 systemd-logind[1703]: New session 21 of user core. Sep 4 23:49:15.883508 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 4 23:49:16.319194 sshd[12412]: Connection closed by 10.200.16.10 port 39402 Sep 4 23:49:16.320094 sshd-session[12380]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:16.323540 systemd[1]: sshd@18-10.200.8.21:22-10.200.16.10:39402.service: Deactivated successfully. Sep 4 23:49:16.323926 systemd-logind[1703]: Session 21 logged out. Waiting for processes to exit. Sep 4 23:49:16.325899 systemd[1]: session-21.scope: Deactivated successfully. Sep 4 23:49:16.327974 systemd-logind[1703]: Removed session 21. Sep 4 23:49:26.435870 systemd[1]: Started sshd@19-10.200.8.21:22-10.200.16.10:58366.service - OpenSSH per-connection server daemon (10.200.16.10:58366). Sep 4 23:49:27.065026 sshd[12703]: Accepted publickey for core from 10.200.16.10 port 58366 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:27.066448 sshd-session[12703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:27.070664 systemd-logind[1703]: New session 22 of user core. Sep 4 23:49:27.085481 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 4 23:49:27.509852 sshd[12705]: Connection closed by 10.200.16.10 port 58366 Sep 4 23:49:27.510278 sshd-session[12703]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:27.513630 systemd[1]: sshd@19-10.200.8.21:22-10.200.16.10:58366.service: Deactivated successfully. Sep 4 23:49:27.515215 systemd[1]: session-22.scope: Deactivated successfully. Sep 4 23:49:27.515877 systemd-logind[1703]: Session 22 logged out. Waiting for processes to exit. Sep 4 23:49:27.516662 systemd-logind[1703]: Removed session 22. Sep 4 23:49:27.626040 systemd[1]: Started sshd@20-10.200.8.21:22-10.200.16.10:58370.service - OpenSSH per-connection server daemon (10.200.16.10:58370). Sep 4 23:49:28.255649 sshd[12711]: Accepted publickey for core from 10.200.16.10 port 58370 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:28.257057 sshd-session[12711]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:28.261170 systemd-logind[1703]: New session 23 of user core. Sep 4 23:49:28.274458 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 4 23:49:28.701309 sshd[12713]: Connection closed by 10.200.16.10 port 58370 Sep 4 23:49:28.701715 sshd-session[12711]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:28.705036 systemd[1]: sshd@20-10.200.8.21:22-10.200.16.10:58370.service: Deactivated successfully. Sep 4 23:49:28.706729 systemd[1]: session-23.scope: Deactivated successfully. Sep 4 23:49:28.707371 systemd-logind[1703]: Session 23 logged out. Waiting for processes to exit. Sep 4 23:49:28.708143 systemd-logind[1703]: Removed session 23. Sep 4 23:49:32.642513 setup-nvidia[2340]: \u000d Sep 4 23:49:32.642513 setup-nvidia[2340]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 4 23:49:32.642513 setup-nvidia[2340]: frequently when this kernel module was built against the wrong or Sep 4 23:49:32.642513 setup-nvidia[2340]: improperly configured kernel sources, with a version of gcc that differs Sep 4 23:49:32.642513 setup-nvidia[2340]: from the one used to build the target kernel, or if another driver, such Sep 4 23:49:32.642513 setup-nvidia[2340]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 4 23:49:32.642513 setup-nvidia[2340]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 4 23:49:32.642513 setup-nvidia[2340]: installed in this system is supported by this NVIDIA Linux graphics Sep 4 23:49:32.642513 setup-nvidia[2340]: driver release. Sep 4 23:49:32.642513 setup-nvidia[2340]: \u000d Sep 4 23:49:32.642513 setup-nvidia[2340]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 4 23:49:32.642513 setup-nvidia[2340]: messages' at the end of the file Sep 4 23:49:32.642513 setup-nvidia[2340]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 4 23:49:32.642513 setup-nvidia[2340]: information. Sep 4 23:49:32.642513 setup-nvidia[2340]: \u000d Sep 4 23:49:32.657180 setup-nvidia[2340]: dmesg: read kernel buffer failed: Operation not permitted Sep 4 23:49:32.678165 setup-nvidia[2340]: \u000d Sep 4 23:49:32.678165 setup-nvidia[2340]: ERROR: Installation has failed. Please see the file Sep 4 23:49:32.678165 setup-nvidia[2340]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 4 23:49:32.678165 setup-nvidia[2340]: details. You may find suggestions on fixing installation problems in Sep 4 23:49:32.678165 setup-nvidia[2340]: the README available on the Linux driver download page at Sep 4 23:49:32.678165 setup-nvidia[2340]: www.nvidia.com. Sep 4 23:49:32.678165 setup-nvidia[2340]: \u000d Sep 4 23:49:32.678494 setup-nvidia[2340]: Last 50 lines of nvidia-installer.log: Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Sep 4 23:49:32.679298 setup-nvidia[2340]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 4 23:49:32.679298 setup-nvidia[2340]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 4 23:49:32.679298 setup-nvidia[2340]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 4 23:49:32.679298 setup-nvidia[2340]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 4 23:49:32.679298 setup-nvidia[2340]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 4 23:49:32.679298 setup-nvidia[2340]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 4 23:49:32.679298 setup-nvidia[2340]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 4 23:49:32.679298 setup-nvidia[2340]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 4 23:49:32.679298 setup-nvidia[2340]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 4 23:49:32.679298 setup-nvidia[2340]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 4 23:49:32.679298 setup-nvidia[2340]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 23:49:32.679298 setup-nvidia[2340]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 4 23:49:32.679298 setup-nvidia[2340]: make[2]: Leaving directory '/usr/lib/modules/6.6.103-flatcar/build' Sep 4 23:49:32.679298 setup-nvidia[2340]: make[1]: Leaving directory '/usr/lib/modules/6.6.103-flatcar/source' Sep 4 23:49:32.679298 setup-nvidia[2340]: -> done. Sep 4 23:49:32.679298 setup-nvidia[2340]: -> Kernel module compilation complete. Sep 4 23:49:32.679298 setup-nvidia[2340]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 4 23:49:32.680630 setup-nvidia[2340]: 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. Sep 4 23:49:32.680630 setup-nvidia[2340]: \u000d Sep 4 23:49:32.680630 setup-nvidia[2340]: 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. Sep 4 23:49:32.680630 setup-nvidia[2340]: -> Kernel module load error: Operation not permitted Sep 4 23:49:32.680630 setup-nvidia[2340]: -> Kernel messages: Sep 4 23:49:32.680630 setup-nvidia[2340]: 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. Sep 4 23:49:38.812832 systemd[1]: Started sshd@21-10.200.8.21:22-10.200.16.10:45268.service - OpenSSH per-connection server daemon (10.200.16.10:45268). Sep 4 23:49:39.449106 sshd[12928]: Accepted publickey for core from 10.200.16.10 port 45268 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:39.450492 sshd-session[12928]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:39.454556 systemd-logind[1703]: New session 24 of user core. Sep 4 23:49:39.465453 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 4 23:49:39.892963 sshd[12930]: Connection closed by 10.200.16.10 port 45268 Sep 4 23:49:39.893293 sshd-session[12928]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:39.896444 systemd[1]: sshd@21-10.200.8.21:22-10.200.16.10:45268.service: Deactivated successfully. Sep 4 23:49:39.898014 systemd[1]: session-24.scope: Deactivated successfully. Sep 4 23:49:39.898664 systemd-logind[1703]: Session 24 logged out. Waiting for processes to exit. Sep 4 23:49:39.899442 systemd-logind[1703]: Removed session 24. Sep 4 23:49:40.016806 systemd[1]: Started sshd@22-10.200.8.21:22-10.200.16.10:56538.service - OpenSSH per-connection server daemon (10.200.16.10:56538). Sep 4 23:49:40.648030 sshd[12936]: Accepted publickey for core from 10.200.16.10 port 56538 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:40.649405 sshd-session[12936]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:40.653566 systemd-logind[1703]: New session 25 of user core. Sep 4 23:49:40.664472 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 4 23:49:41.099719 sshd[12938]: Connection closed by 10.200.16.10 port 56538 Sep 4 23:49:41.100085 sshd-session[12936]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:41.103223 systemd[1]: sshd@22-10.200.8.21:22-10.200.16.10:56538.service: Deactivated successfully. Sep 4 23:49:41.104825 systemd[1]: session-25.scope: Deactivated successfully. Sep 4 23:49:41.106505 systemd-logind[1703]: Session 25 logged out. Waiting for processes to exit. Sep 4 23:49:41.107303 systemd-logind[1703]: Removed session 25. Sep 4 23:49:51.325814 systemd[1]: Started sshd@23-10.200.8.21:22-10.200.16.10:34008.service - OpenSSH per-connection server daemon (10.200.16.10:34008). Sep 4 23:49:51.956714 sshd[12944]: Accepted publickey for core from 10.200.16.10 port 34008 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:51.958079 sshd-session[12944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:51.962150 systemd-logind[1703]: New session 26 of user core. Sep 4 23:49:51.974449 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 4 23:49:52.409598 sshd[12946]: Connection closed by 10.200.16.10 port 34008 Sep 4 23:49:52.409913 sshd-session[12944]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:52.413709 systemd[1]: sshd@23-10.200.8.21:22-10.200.16.10:34008.service: Deactivated successfully. Sep 4 23:49:52.415511 systemd[1]: session-26.scope: Deactivated successfully. Sep 4 23:49:52.416270 systemd-logind[1703]: Session 26 logged out. Waiting for processes to exit. Sep 4 23:49:52.417074 systemd-logind[1703]: Removed session 26. Sep 4 23:49:52.531992 systemd[1]: Started sshd@24-10.200.8.21:22-10.200.16.10:34020.service - OpenSSH per-connection server daemon (10.200.16.10:34020). Sep 4 23:49:53.167687 sshd[12952]: Accepted publickey for core from 10.200.16.10 port 34020 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:53.169125 sshd-session[12952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:53.173342 systemd-logind[1703]: New session 27 of user core. Sep 4 23:49:53.188462 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 4 23:49:53.620985 sshd[12954]: Connection closed by 10.200.16.10 port 34020 Sep 4 23:49:53.621542 sshd-session[12952]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:53.624570 systemd[1]: sshd@24-10.200.8.21:22-10.200.16.10:34020.service: Deactivated successfully. Sep 4 23:49:53.626116 systemd[1]: session-27.scope: Deactivated successfully. Sep 4 23:49:53.626743 systemd-logind[1703]: Session 27 logged out. Waiting for processes to exit. Sep 4 23:49:53.627501 systemd-logind[1703]: Removed session 27. Sep 4 23:49:57.518359 kernel: EXT4-fs (loop6p9): unmounting filesystem 31317368-05db-4c20-ad22-912e33323410. Sep 4 23:49:57.518675 setup-nvidia[2340]: Container flatcardevelopercontainer-4230.2.2nightly-20250904-2100.bin exited successfully. Sep 4 23:49:57.521074 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2nightly\x2d20250904\x2d2100.bin.mount: Deactivated successfully. Sep 4 23:49:57.584483 sudo[2324]: pam_unix(sudo:session): session closed for user root Sep 4 23:49:57.586195 systemd[1]: session-c2.scope: Deactivated successfully. Sep 4 23:49:57.586485 systemd[1]: session-c2.scope: Consumed 5min 12.135s CPU time, 883.1M memory peak. Sep 4 23:49:57.996028 setup-nvidia[1697]: /opt/nvidia / Sep 4 23:49:57.997003 setup-nvidia[1697]: / Sep 4 23:49:58.012586 setup-nvidia[12977]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 23:49:58.039359 kernel: IPMI message handler: version 39.2 Sep 4 23:49:58.043355 kernel: ipmi device interface Sep 4 23:49:58.044156 setup-nvidia[1697]: /opt/nvidia/current/usr/lib/modules/6.6.103-flatcar/video / Sep 4 23:49:58.098049 kernel: nvidia: loading out-of-tree module taints kernel. Sep 4 23:49:58.098253 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 4 23:49:58.098399 kernel: Disabling lock debugging due to kernel taint Sep 4 23:49:58.105322 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 4 23:49:58.105378 kernel: nvidia: module license taints kernel. Sep 4 23:49:58.501354 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 4 23:49:58.908736 kernel: Sep 4 23:49:58.908878 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 4 23:49:59.120356 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 4 23:49:59.141357 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 4 23:49:59.161363 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 4 23:49:59.206043 setup-nvidia[1697]: / Sep 4 23:49:59.206391 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 4 23:50:01.001842 setup-nvidia[13007]: Thu Sep 4 23:50:00 2025 Sep 4 23:50:01.001842 setup-nvidia[13007]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:01.001842 setup-nvidia[13007]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 4 23:50:01.001842 setup-nvidia[13007]: |-----------------------------------------+----------------------+----------------------+ Sep 4 23:50:01.001842 setup-nvidia[13007]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 4 23:50:01.001842 setup-nvidia[13007]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 4 23:50:01.001842 setup-nvidia[13007]: | | | MIG M. | Sep 4 23:50:01.001842 setup-nvidia[13007]: |=========================================+======================+======================| Sep 4 23:50:01.005325 setup-nvidia[13007]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 4 23:50:01.005325 setup-nvidia[13007]: | N/A 25C P0 34W / 250W | 0MiB / 16384MiB | 0% Default | Sep 4 23:50:01.005325 setup-nvidia[13007]: | | | N/A | Sep 4 23:50:01.005325 setup-nvidia[13007]: +-----------------------------------------+----------------------+----------------------+ Sep 4 23:50:01.005449 setup-nvidia[13007]: Sep 4 23:50:01.005449 setup-nvidia[13007]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:01.005449 setup-nvidia[13007]: | Processes: | Sep 4 23:50:01.005449 setup-nvidia[13007]: | GPU GI CI PID Type Process name GPU Memory | Sep 4 23:50:01.005449 setup-nvidia[13007]: | ID ID Usage | Sep 4 23:50:01.005449 setup-nvidia[13007]: |=======================================================================================| Sep 4 23:50:01.005595 setup-nvidia[13007]: | No running processes found | Sep 4 23:50:01.005595 setup-nvidia[13007]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:01.377868 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 23:50:01.379904 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 23:50:01.380232 systemd[1]: Startup finished in 1.164s (firmware) + 25.375s (loader) + 1.890s (kernel) + 12.184s (initrd) + 3min 12.163s (userspace) = 3min 52.777s. Sep 4 23:50:03.732859 systemd[1]: Started sshd@25-10.200.8.21:22-10.200.16.10:38220.service - OpenSSH per-connection server daemon (10.200.16.10:38220). Sep 4 23:50:04.363204 sshd[13014]: Accepted publickey for core from 10.200.16.10 port 38220 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:04.364567 sshd-session[13014]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:04.368737 systemd-logind[1703]: New session 28 of user core. Sep 4 23:50:04.378479 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 4 23:50:04.807960 sshd[13016]: Connection closed by 10.200.16.10 port 38220 Sep 4 23:50:04.808375 sshd-session[13014]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:04.811643 systemd[1]: sshd@25-10.200.8.21:22-10.200.16.10:38220.service: Deactivated successfully. Sep 4 23:50:04.813252 systemd[1]: session-28.scope: Deactivated successfully. Sep 4 23:50:04.813940 systemd-logind[1703]: Session 28 logged out. Waiting for processes to exit. Sep 4 23:50:04.814746 systemd-logind[1703]: Removed session 28. Sep 4 23:50:04.918666 systemd[1]: Started sshd@26-10.200.8.21:22-10.200.16.10:38228.service - OpenSSH per-connection server daemon (10.200.16.10:38228). Sep 4 23:50:05.547574 sshd[13022]: Accepted publickey for core from 10.200.16.10 port 38228 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:05.548874 sshd-session[13022]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:05.553023 systemd-logind[1703]: New session 29 of user core. Sep 4 23:50:05.565455 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 4 23:50:05.988666 sshd[13024]: Connection closed by 10.200.16.10 port 38228 Sep 4 23:50:05.989038 sshd-session[13022]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:05.992366 systemd[1]: sshd@26-10.200.8.21:22-10.200.16.10:38228.service: Deactivated successfully. Sep 4 23:50:05.993958 systemd[1]: session-29.scope: Deactivated successfully. Sep 4 23:50:05.994605 systemd-logind[1703]: Session 29 logged out. Waiting for processes to exit. Sep 4 23:50:05.995390 systemd-logind[1703]: Removed session 29. Sep 4 23:50:06.126765 systemd[1]: Started sshd@27-10.200.8.21:22-10.200.16.10:38232.service - OpenSSH per-connection server daemon (10.200.16.10:38232). Sep 4 23:50:06.755602 sshd[13030]: Accepted publickey for core from 10.200.16.10 port 38232 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:06.756923 sshd-session[13030]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:06.761231 systemd-logind[1703]: New session 30 of user core. Sep 4 23:50:06.773527 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 4 23:50:07.208209 sshd[13032]: Connection closed by 10.200.16.10 port 38232 Sep 4 23:50:07.208620 sshd-session[13030]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:07.211726 systemd[1]: sshd@27-10.200.8.21:22-10.200.16.10:38232.service: Deactivated successfully. Sep 4 23:50:07.213309 systemd[1]: session-30.scope: Deactivated successfully. Sep 4 23:50:07.213961 systemd-logind[1703]: Session 30 logged out. Waiting for processes to exit. Sep 4 23:50:07.214830 systemd-logind[1703]: Removed session 30. Sep 4 23:50:07.318653 systemd[1]: Started sshd@28-10.200.8.21:22-10.200.16.10:38246.service - OpenSSH per-connection server daemon (10.200.16.10:38246). Sep 4 23:50:07.661092 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 4 23:50:07.661890 systemd[2331]: Activating special unit exit.target... Sep 4 23:50:07.661976 systemd[2331]: Stopped target default.target - Main User Target. Sep 4 23:50:07.662017 systemd[2331]: Stopped target basic.target - Basic System. Sep 4 23:50:07.662048 systemd[2331]: Stopped target paths.target - Paths. Sep 4 23:50:07.662075 systemd[2331]: Stopped target sockets.target - Sockets. Sep 4 23:50:07.662103 systemd[2331]: Stopped target timers.target - Timers. Sep 4 23:50:07.662479 systemd[2331]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:50:07.662692 systemd[2331]: Removed slice app.slice - User Application Slice. Sep 4 23:50:07.662725 systemd[2331]: Reached target shutdown.target - Shutdown. Sep 4 23:50:07.662879 systemd[2331]: Finished systemd-exit.service - Exit the Session. Sep 4 23:50:07.662918 systemd[2331]: Reached target exit.target - Exit the Session. Sep 4 23:50:07.677402 (sd-pam)[2332]: pam_unix(systemd-user:session): session closed for user root Sep 4 23:50:07.678232 systemd[1]: user@0.service: Deactivated successfully. Sep 4 23:50:07.678579 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 4 23:50:07.688671 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 23:50:07.697192 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 4 23:50:07.698063 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 4 23:50:07.698257 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 23:50:07.700090 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 4 23:50:07.700179 systemd[1]: user-0.slice: Consumed 5min 12.388s CPU time, 892.2M memory peak. Sep 4 23:50:07.700569 systemd-logind[1703]: Removed session c3. Sep 4 23:50:07.947846 sshd[13038]: Accepted publickey for core from 10.200.16.10 port 38246 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:07.949178 sshd-session[13038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:07.953606 systemd-logind[1703]: New session 31 of user core. Sep 4 23:50:07.967492 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 4 23:50:08.599577 sudo[13045]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 4 23:50:08.599899 sudo[13045]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:50:09.302731 sudo[13045]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:09.406015 sshd[13044]: Connection closed by 10.200.16.10 port 38246 Sep 4 23:50:09.406519 sshd-session[13038]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:09.409971 systemd[1]: sshd@28-10.200.8.21:22-10.200.16.10:38246.service: Deactivated successfully. Sep 4 23:50:09.411631 systemd[1]: session-31.scope: Deactivated successfully. Sep 4 23:50:09.412414 systemd-logind[1703]: Session 31 logged out. Waiting for processes to exit. Sep 4 23:50:09.413261 systemd-logind[1703]: Removed session 31. Sep 4 23:50:09.518117 systemd[1]: Started sshd@29-10.200.8.21:22-10.200.16.10:38258.service - OpenSSH per-connection server daemon (10.200.16.10:38258). Sep 4 23:50:10.149273 sshd[13051]: Accepted publickey for core from 10.200.16.10 port 38258 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:10.150672 sshd-session[13051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:10.154820 systemd-logind[1703]: New session 32 of user core. Sep 4 23:50:10.170454 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 4 23:50:10.494375 sudo[13055]: 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 Sep 4 23:50:10.494676 sudo[13055]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:50:10.497900 sudo[13055]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:10.503505 sudo[13054]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 4 23:50:10.503797 sudo[13054]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:50:10.526619 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 23:50:10.552823 augenrules[13077]: No rules Sep 4 23:50:10.554029 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 23:50:10.554269 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 23:50:10.555070 sudo[13054]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:10.657142 sshd[13053]: Connection closed by 10.200.16.10 port 38258 Sep 4 23:50:10.657517 sshd-session[13051]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:10.660920 systemd[1]: sshd@29-10.200.8.21:22-10.200.16.10:38258.service: Deactivated successfully. Sep 4 23:50:10.662689 systemd[1]: session-32.scope: Deactivated successfully. Sep 4 23:50:10.663304 systemd-logind[1703]: Session 32 logged out. Waiting for processes to exit. Sep 4 23:50:10.664075 systemd-logind[1703]: Removed session 32. Sep 4 23:50:10.768801 systemd[1]: Started sshd@30-10.200.8.21:22-10.200.16.10:36906.service - OpenSSH per-connection server daemon (10.200.16.10:36906). Sep 4 23:50:11.400954 sshd[13086]: Accepted publickey for core from 10.200.16.10 port 36906 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:11.402270 sshd-session[13086]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:11.406362 systemd-logind[1703]: New session 33 of user core. Sep 4 23:50:11.417460 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 4 23:50:11.746302 sudo[13089]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Sep 4 23:50:11.746696 sudo[13089]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:50:11.750200 sudo[13089]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:11.851456 sshd[13088]: Connection closed by 10.200.16.10 port 36906 Sep 4 23:50:11.851886 sshd-session[13086]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:11.855155 systemd[1]: sshd@30-10.200.8.21:22-10.200.16.10:36906.service: Deactivated successfully. Sep 4 23:50:11.856773 systemd[1]: session-33.scope: Deactivated successfully. Sep 4 23:50:11.857433 systemd-logind[1703]: Session 33 logged out. Waiting for processes to exit. Sep 4 23:50:11.858214 systemd-logind[1703]: Removed session 33. Sep 4 23:50:11.962748 systemd[1]: Started sshd@31-10.200.8.21:22-10.200.16.10:36916.service - OpenSSH per-connection server daemon (10.200.16.10:36916). Sep 4 23:50:12.590699 sshd[13095]: Accepted publickey for core from 10.200.16.10 port 36916 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:12.592046 sshd-session[13095]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:12.596456 systemd-logind[1703]: New session 34 of user core. Sep 4 23:50:12.607455 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 4 23:50:12.936053 sudo[13098]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Sep 4 23:50:12.936381 sudo[13098]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:50:16.540315 sudo[13098]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:16.649423 sshd[13097]: Connection closed by 10.200.16.10 port 36916 Sep 4 23:50:16.649734 sshd-session[13095]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:16.653536 systemd[1]: sshd@31-10.200.8.21:22-10.200.16.10:36916.service: Deactivated successfully. Sep 4 23:50:16.655135 systemd[1]: session-34.scope: Deactivated successfully. Sep 4 23:50:16.655811 systemd-logind[1703]: Session 34 logged out. Waiting for processes to exit. Sep 4 23:50:16.656591 systemd-logind[1703]: Removed session 34. Sep 4 23:50:16.760617 systemd[1]: Started sshd@32-10.200.8.21:22-10.200.16.10:36930.service - OpenSSH per-connection server daemon (10.200.16.10:36930). Sep 4 23:50:17.389401 sshd[13105]: Accepted publickey for core from 10.200.16.10 port 36930 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:17.390710 sshd-session[13105]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:17.394789 systemd-logind[1703]: New session 35 of user core. Sep 4 23:50:17.406454 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 4 23:50:17.788885 ntpdate[13118]: ntpdate 4.2.8p17@1.4004-o Thu Sep 4 21:32:02 UTC 2025 (1) Sep 4 23:50:24.610505 ntpdate[13118]: adjust time server 193.1.8.98 offset -0.027672 sec Sep 4 23:50:24.711872 sshd[13107]: Connection closed by 10.200.16.10 port 36930 Sep 4 23:50:24.712291 sshd-session[13105]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:24.714878 systemd[1]: sshd@32-10.200.8.21:22-10.200.16.10:36930.service: Deactivated successfully. Sep 4 23:50:24.716571 systemd[1]: session-35.scope: Deactivated successfully. Sep 4 23:50:24.717841 systemd-logind[1703]: Session 35 logged out. Waiting for processes to exit. Sep 4 23:50:24.718603 systemd-logind[1703]: Removed session 35. Sep 4 23:50:24.823857 systemd[1]: Started sshd@33-10.200.8.21:22-10.200.16.10:33060.service - OpenSSH per-connection server daemon (10.200.16.10:33060). Sep 4 23:50:25.454161 sshd[13123]: Accepted publickey for core from 10.200.16.10 port 33060 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:25.455583 sshd-session[13123]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:25.459720 systemd-logind[1703]: New session 36 of user core. Sep 4 23:50:25.470454 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 4 23:50:25.990358 sshd[13125]: Connection closed by 10.200.16.10 port 33060 Sep 4 23:50:25.990796 sshd-session[13123]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:25.994213 systemd[1]: sshd@33-10.200.8.21:22-10.200.16.10:33060.service: Deactivated successfully. Sep 4 23:50:25.995936 systemd[1]: session-36.scope: Deactivated successfully. Sep 4 23:50:25.996589 systemd-logind[1703]: Session 36 logged out. Waiting for processes to exit. Sep 4 23:50:25.997359 systemd-logind[1703]: Removed session 36. Sep 4 23:50:26.104820 systemd[1]: Started sshd@34-10.200.8.21:22-10.200.16.10:33064.service - OpenSSH per-connection server daemon (10.200.16.10:33064). Sep 4 23:50:26.735321 sshd[13140]: Accepted publickey for core from 10.200.16.10 port 33064 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:26.736706 sshd-session[13140]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:26.740908 systemd-logind[1703]: New session 37 of user core. Sep 4 23:50:26.749460 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 4 23:50:27.739566 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 23:50:27.739777 (dockerd)[13162]: 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 Sep 4 23:50:29.999673 dockerd[13162]: time="2025-09-04T23:50:29.999609654Z" level=info msg="Starting up" Sep 4 23:50:30.465680 systemd[1]: var-lib-docker-metacopy\x2dcheck4102646160-merged.mount: Deactivated successfully. Sep 4 23:50:30.500267 dockerd[13162]: time="2025-09-04T23:50:30.500229961Z" level=info msg="Loading containers: start." Sep 4 23:50:30.720358 kernel: Initializing XFRM netlink socket Sep 4 23:50:30.826461 systemd-networkd[1548]: docker0: Link UP Sep 4 23:50:30.892601 dockerd[13162]: time="2025-09-04T23:50:30.892564138Z" level=info msg="Loading containers: done." Sep 4 23:50:30.906022 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2434255416-merged.mount: Deactivated successfully. Sep 4 23:50:30.939313 dockerd[13162]: time="2025-09-04T23:50:30.939269814Z" 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 Sep 4 23:50:30.939411 dockerd[13162]: time="2025-09-04T23:50:30.939377711Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Sep 4 23:50:30.939522 dockerd[13162]: time="2025-09-04T23:50:30.939498907Z" level=info msg="Daemon has completed initialization" Sep 4 23:50:31.006152 dockerd[13162]: time="2025-09-04T23:50:31.006100319Z" level=info msg="API listen on /run/docker.sock" Sep 4 23:50:31.006237 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 23:50:32.189257 systemd[1]: var-lib-docker-overlay2-b1b20857d2ada8c303d0bdd2ec898b6571093dd6613c0405ef56f23e84a95cb2\x2dinit-merged.mount: Deactivated successfully. Sep 4 23:50:32.304867 kernel: docker0: port 1(veth4a23971) entered blocking state Sep 4 23:50:32.304981 kernel: docker0: port 1(veth4a23971) entered disabled state Sep 4 23:50:32.305014 kernel: veth4a23971: entered allmulticast mode Sep 4 23:50:32.310117 kernel: veth4a23971: entered promiscuous mode Sep 4 23:50:32.310713 systemd-networkd[1548]: veth4a23971: Link UP Sep 4 23:50:33.047798 containerd[1714]: time="2025-09-04T23:50:33.047699795Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 23:50:33.047798 containerd[1714]: time="2025-09-04T23:50:33.047763693Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 23:50:33.047798 containerd[1714]: time="2025-09-04T23:50:33.047777192Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 23:50:33.048273 containerd[1714]: time="2025-09-04T23:50:33.047856989Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 23:50:33.100471 systemd[1]: Started docker-24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e.scope - libcontainer container 24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e. Sep 4 23:50:33.157358 kernel: eth0: renamed from veth1330f2b Sep 4 23:50:33.175411 kernel: docker0: port 1(veth4a23971) entered blocking state Sep 4 23:50:33.175461 kernel: docker0: port 1(veth4a23971) entered forwarding state Sep 4 23:50:33.175519 systemd-networkd[1548]: veth4a23971: Gained carrier Sep 4 23:50:33.175795 systemd-networkd[1548]: docker0: Gained carrier Sep 4 23:50:34.447481 systemd-networkd[1548]: docker0: Gained IPv6LL Sep 4 23:50:34.575427 systemd-networkd[1548]: veth4a23971: Gained IPv6LL Sep 4 23:50:36.464455 systemd[1]: docker-24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e.scope: Deactivated successfully. Sep 4 23:50:36.477240 containerd[1714]: time="2025-09-04T23:50:36.477051037Z" level=info msg="shim disconnected" id=24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e namespace=moby Sep 4 23:50:36.477240 containerd[1714]: time="2025-09-04T23:50:36.477109636Z" level=warning msg="cleaning up after shim disconnected" id=24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e namespace=moby Sep 4 23:50:36.477240 containerd[1714]: time="2025-09-04T23:50:36.477120035Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 23:50:36.477633 dockerd[13162]: time="2025-09-04T23:50:36.477093636Z" level=info msg="ignoring event" container=24d3c620a572ce05b965e7c73c01caeff33445398a9124ced8a594bd9766f35e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 23:50:36.508789 systemd-networkd[1548]: veth4a23971: Lost carrier Sep 4 23:50:36.509352 kernel: docker0: port 1(veth4a23971) entered disabled state Sep 4 23:50:36.509398 kernel: veth1330f2b: renamed from eth0 Sep 4 23:50:36.537477 systemd-networkd[1548]: veth4a23971: Link DOWN Sep 4 23:50:36.544174 kernel: docker0: port 1(veth4a23971) entered disabled state Sep 4 23:50:36.544232 kernel: veth4a23971 (unregistering): left allmulticast mode Sep 4 23:50:36.547598 kernel: veth4a23971 (unregistering): left promiscuous mode Sep 4 23:50:36.551049 kernel: docker0: port 1(veth4a23971) entered disabled state Sep 4 23:50:36.586126 systemd[1]: run-docker-netns-d7410ab76c95.mount: Deactivated successfully. Sep 4 23:50:36.590203 systemd[1]: var-lib-docker-overlay2-b1b20857d2ada8c303d0bdd2ec898b6571093dd6613c0405ef56f23e84a95cb2-merged.mount: Deactivated successfully. Sep 4 23:50:36.760606 sshd[13142]: Connection closed by 10.200.16.10 port 33064 Sep 4 23:50:36.760976 sshd-session[13140]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:36.764237 systemd[1]: sshd@34-10.200.8.21:22-10.200.16.10:33064.service: Deactivated successfully. Sep 4 23:50:36.765838 systemd[1]: session-37.scope: Deactivated successfully. Sep 4 23:50:36.766479 systemd-logind[1703]: Session 37 logged out. Waiting for processes to exit. Sep 4 23:50:36.767228 systemd-logind[1703]: Removed session 37. Sep 4 23:50:36.871895 systemd[1]: Started sshd@35-10.200.8.21:22-10.200.16.10:45446.service - OpenSSH per-connection server daemon (10.200.16.10:45446). Sep 4 23:50:37.502362 sshd[13438]: Accepted publickey for core from 10.200.16.10 port 45446 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:37.503740 sshd-session[13438]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:37.507782 systemd-logind[1703]: New session 38 of user core. Sep 4 23:50:37.519500 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 4 23:50:37.519538 systemd-networkd[1548]: docker0: Lost carrier Sep 4 23:50:37.902242 systemd[1]: var-lib-docker-overlay2-ea8917e45ddbb91ba163923edcdbf675b7acfbe7decf46cf951b258604235cf5\x2dinit-merged.mount: Deactivated successfully. Sep 4 23:50:38.017068 kernel: docker0: port 1(veth5770799) entered blocking state Sep 4 23:50:38.017175 kernel: docker0: port 1(veth5770799) entered disabled state Sep 4 23:50:38.020712 kernel: veth5770799: entered allmulticast mode Sep 4 23:50:38.020765 kernel: veth5770799: entered promiscuous mode Sep 4 23:50:38.026306 kernel: docker0: port 1(veth5770799) entered blocking state Sep 4 23:50:38.029717 kernel: docker0: port 1(veth5770799) entered forwarding state Sep 4 23:50:38.030524 kernel: docker0: port 1(veth5770799) entered disabled state Sep 4 23:50:38.030613 systemd-networkd[1548]: veth5770799: Link UP Sep 4 23:50:38.118533 containerd[1714]: time="2025-09-04T23:50:38.118449186Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 23:50:38.118533 containerd[1714]: time="2025-09-04T23:50:38.118498384Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 23:50:38.118533 containerd[1714]: time="2025-09-04T23:50:38.118509184Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 23:50:38.118981 containerd[1714]: time="2025-09-04T23:50:38.118585682Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 23:50:38.139471 systemd[1]: Started docker-2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595.scope - libcontainer container 2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595. Sep 4 23:50:38.198352 kernel: eth0: renamed from veth8b8613d Sep 4 23:50:38.214612 kernel: docker0: port 1(veth5770799) entered blocking state Sep 4 23:50:38.214675 kernel: docker0: port 1(veth5770799) entered forwarding state Sep 4 23:50:38.214997 systemd-networkd[1548]: veth5770799: Gained carrier Sep 4 23:50:38.215244 systemd-networkd[1548]: docker0: Gained carrier Sep 4 23:50:38.258950 systemd[1]: docker-2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595.scope: Deactivated successfully. Sep 4 23:50:38.283624 dockerd[13162]: time="2025-09-04T23:50:38.283485896Z" level=info msg="ignoring event" container=2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 4 23:50:38.283957 containerd[1714]: time="2025-09-04T23:50:38.283727489Z" level=info msg="shim disconnected" id=2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595 namespace=moby Sep 4 23:50:38.283957 containerd[1714]: time="2025-09-04T23:50:38.283779888Z" level=warning msg="cleaning up after shim disconnected" id=2d214a3c9bfd9f3b2fd9dd6df9097d77eef344d75c28280cb0864cd992897595 namespace=moby Sep 4 23:50:38.283957 containerd[1714]: time="2025-09-04T23:50:38.283796587Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 23:50:38.316577 systemd-networkd[1548]: veth5770799: Lost carrier Sep 4 23:50:38.317369 kernel: docker0: port 1(veth5770799) entered disabled state Sep 4 23:50:38.317525 kernel: veth8b8613d: renamed from eth0 Sep 4 23:50:38.346517 systemd-networkd[1548]: veth5770799: Link DOWN Sep 4 23:50:38.350345 kernel: docker0: port 1(veth5770799) entered disabled state Sep 4 23:50:38.356966 kernel: veth5770799 (unregistering): left allmulticast mode Sep 4 23:50:38.357015 kernel: veth5770799 (unregistering): left promiscuous mode Sep 4 23:50:38.360292 kernel: docker0: port 1(veth5770799) entered disabled state Sep 4 23:50:38.527553 systemd-networkd[1548]: docker0: Lost carrier Sep 4 23:50:38.543395 sshd[13440]: Connection closed by 10.200.16.10 port 45446 Sep 4 23:50:38.543780 sshd-session[13438]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:38.546951 systemd[1]: sshd@35-10.200.8.21:22-10.200.16.10:45446.service: Deactivated successfully. Sep 4 23:50:38.548579 systemd[1]: session-38.scope: Deactivated successfully. Sep 4 23:50:38.549211 systemd-logind[1703]: Session 38 logged out. Waiting for processes to exit. Sep 4 23:50:38.549978 systemd-logind[1703]: Removed session 38. Sep 4 23:50:38.899034 systemd[1]: var-lib-docker-overlay2-ea8917e45ddbb91ba163923edcdbf675b7acfbe7decf46cf951b258604235cf5-merged.mount: Deactivated successfully.