Aug 19 08:13:40.190403 kernel: Linux version 6.12.41-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Aug 18 22:19:37 -00 2025 Aug 19 08:13:40.190431 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=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.190441 kernel: BIOS-provided physical RAM map: Aug 19 08:13:40.190448 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Aug 19 08:13:40.190454 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Aug 19 08:13:40.190461 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Aug 19 08:13:40.190468 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Aug 19 08:13:40.190478 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Aug 19 08:13:40.190485 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Aug 19 08:13:40.190491 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Aug 19 08:13:40.190498 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Aug 19 08:13:40.190505 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Aug 19 08:13:40.190511 kernel: printk: legacy bootconsole [earlyser0] enabled Aug 19 08:13:40.190518 kernel: NX (Execute Disable) protection: active Aug 19 08:13:40.190529 kernel: APIC: Static calls initialized Aug 19 08:13:40.190536 kernel: efi: EFI v2.7 by Microsoft Aug 19 08:13:40.190544 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Aug 19 08:13:40.190551 kernel: random: crng init done Aug 19 08:13:40.190559 kernel: secureboot: Secure boot disabled Aug 19 08:13:40.190566 kernel: SMBIOS 3.1.0 present. Aug 19 08:13:40.190574 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Aug 19 08:13:40.190581 kernel: DMI: Memory slots populated: 3/3 Aug 19 08:13:40.190588 kernel: Hypervisor detected: Microsoft Hyper-V Aug 19 08:13:40.190596 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Aug 19 08:13:40.190605 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Aug 19 08:13:40.190613 kernel: Hyper-V: Using hypercall for remote TLB flush Aug 19 08:13:40.190620 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 19 08:13:40.190628 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 19 08:13:40.190636 kernel: tsc: Marking TSC unstable due to running on Hyper-V Aug 19 08:13:40.190643 kernel: tsc: Detected 2593.992 MHz processor Aug 19 08:13:40.190664 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 19 08:13:40.190672 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 19 08:13:40.190680 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Aug 19 08:13:40.190690 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Aug 19 08:13:40.190698 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 19 08:13:40.190706 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Aug 19 08:13:40.190713 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Aug 19 08:13:40.190721 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Aug 19 08:13:40.190728 kernel: Using GB pages for direct mapping Aug 19 08:13:40.190736 kernel: ACPI: Early table checksum verification disabled Aug 19 08:13:40.190747 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Aug 19 08:13:40.190758 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190766 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190774 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Aug 19 08:13:40.190782 kernel: ACPI: FACS 0x000000003FFFE000 000040 Aug 19 08:13:40.190790 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190798 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190808 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190816 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190824 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190832 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190840 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.190848 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Aug 19 08:13:40.190856 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Aug 19 08:13:40.190864 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Aug 19 08:13:40.190872 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Aug 19 08:13:40.190882 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Aug 19 08:13:40.190890 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Aug 19 08:13:40.190898 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Aug 19 08:13:40.190906 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Aug 19 08:13:40.190914 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Aug 19 08:13:40.190922 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Aug 19 08:13:40.190930 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Aug 19 08:13:40.190938 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Aug 19 08:13:40.190949 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Aug 19 08:13:40.190957 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Aug 19 08:13:40.190964 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Aug 19 08:13:40.190972 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Aug 19 08:13:40.190980 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Aug 19 08:13:40.190988 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Aug 19 08:13:40.190996 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Aug 19 08:13:40.191005 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Aug 19 08:13:40.191013 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Aug 19 08:13:40.191030 kernel: Zone ranges: Aug 19 08:13:40.191038 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 19 08:13:40.191046 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 19 08:13:40.191054 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Aug 19 08:13:40.191061 kernel: Device empty Aug 19 08:13:40.191069 kernel: Movable zone start for each node Aug 19 08:13:40.191077 kernel: Early memory node ranges Aug 19 08:13:40.191085 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Aug 19 08:13:40.191093 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Aug 19 08:13:40.191103 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Aug 19 08:13:40.191111 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Aug 19 08:13:40.191119 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Aug 19 08:13:40.191127 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Aug 19 08:13:40.191135 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 19 08:13:40.191145 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Aug 19 08:13:40.191153 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Aug 19 08:13:40.191161 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 19 08:13:40.191169 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Aug 19 08:13:40.191180 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Aug 19 08:13:40.191188 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 19 08:13:40.191196 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 19 08:13:40.191204 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Aug 19 08:13:40.191212 kernel: CPU topo: Max. logical packages: 1 Aug 19 08:13:40.191219 kernel: CPU topo: Max. logical dies: 1 Aug 19 08:13:40.191227 kernel: CPU topo: Max. dies per package: 1 Aug 19 08:13:40.191235 kernel: CPU topo: Max. threads per core: 1 Aug 19 08:13:40.191243 kernel: CPU topo: Num. cores per package: 6 Aug 19 08:13:40.191254 kernel: CPU topo: Num. threads per package: 6 Aug 19 08:13:40.191262 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Aug 19 08:13:40.191270 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Aug 19 08:13:40.191277 kernel: Booting paravirtualized kernel on Hyper-V Aug 19 08:13:40.191285 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 19 08:13:40.191293 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Aug 19 08:13:40.191301 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Aug 19 08:13:40.191313 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Aug 19 08:13:40.191322 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Aug 19 08:13:40.191332 kernel: Hyper-V: PV spinlocks enabled Aug 19 08:13:40.191340 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 19 08:13:40.191349 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=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.191358 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 19 08:13:40.191366 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Aug 19 08:13:40.191374 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Aug 19 08:13:40.191382 kernel: Fallback order for Node 0: 0 Aug 19 08:13:40.191390 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Aug 19 08:13:40.191400 kernel: Policy zone: Normal Aug 19 08:13:40.191416 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 19 08:13:40.191424 kernel: software IO TLB: area num 8. Aug 19 08:13:40.191435 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Aug 19 08:13:40.191443 kernel: Kernel/User page tables isolation: enabled Aug 19 08:13:40.191452 kernel: ftrace: allocating 40101 entries in 157 pages Aug 19 08:13:40.191460 kernel: ftrace: allocated 157 pages with 5 groups Aug 19 08:13:40.191468 kernel: Dynamic Preempt: voluntary Aug 19 08:13:40.191477 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 19 08:13:40.191486 kernel: rcu: RCU event tracing is enabled. Aug 19 08:13:40.191494 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Aug 19 08:13:40.191503 kernel: Trampoline variant of Tasks RCU enabled. Aug 19 08:13:40.191514 kernel: Rude variant of Tasks RCU enabled. Aug 19 08:13:40.191522 kernel: Tracing variant of Tasks RCU enabled. Aug 19 08:13:40.191531 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 19 08:13:40.191539 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Aug 19 08:13:40.191548 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.191559 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.191567 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.191576 kernel: Using NULL legacy PIC Aug 19 08:13:40.191584 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Aug 19 08:13:40.191593 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 19 08:13:40.191601 kernel: Console: colour dummy device 80x25 Aug 19 08:13:40.191609 kernel: printk: legacy console [tty1] enabled Aug 19 08:13:40.191618 kernel: printk: legacy console [ttyS0] enabled Aug 19 08:13:40.191626 kernel: printk: legacy bootconsole [earlyser0] disabled Aug 19 08:13:40.191637 kernel: ACPI: Core revision 20240827 Aug 19 08:13:40.191645 kernel: Failed to register legacy timer interrupt Aug 19 08:13:40.191661 kernel: APIC: Switch to symmetric I/O mode setup Aug 19 08:13:40.191670 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Aug 19 08:13:40.191678 kernel: Hyper-V: enabling crash_kexec_post_notifiers Aug 19 08:13:40.191686 kernel: Hyper-V: Using IPI hypercalls Aug 19 08:13:40.191695 kernel: APIC: send_IPI() replaced with hv_send_ipi() Aug 19 08:13:40.191703 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Aug 19 08:13:40.191712 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Aug 19 08:13:40.191723 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Aug 19 08:13:40.191731 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Aug 19 08:13:40.191740 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Aug 19 08:13:40.191748 kernel: Hyper-V: Using enlightened APIC (xapic mode) Aug 19 08:13:40.191756 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Aug 19 08:13:40.191765 kernel: APIC: read() replaced with hv_apic_read() Aug 19 08:13:40.191773 kernel: APIC: write() replaced with hv_apic_write() Aug 19 08:13:40.191782 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Aug 19 08:13:40.191790 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Aug 19 08:13:40.191801 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Aug 19 08:13:40.191810 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Aug 19 08:13:40.191818 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Aug 19 08:13:40.191826 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 19 08:13:40.191834 kernel: Spectre V2 : Mitigation: Retpolines Aug 19 08:13:40.191843 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 19 08:13:40.191851 kernel: Speculative Store Bypass: Vulnerable Aug 19 08:13:40.191859 kernel: MDS: Mitigation: Clear CPU buffers Aug 19 08:13:40.191867 kernel: TAA: Mitigation: Clear CPU buffers Aug 19 08:13:40.191875 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 19 08:13:40.191886 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 19 08:13:40.191894 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 19 08:13:40.191903 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 19 08:13:40.191911 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 19 08:13:40.191919 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 19 08:13:40.191927 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 19 08:13:40.191936 kernel: Freeing SMP alternatives memory: 32K Aug 19 08:13:40.191944 kernel: pid_max: default: 32768 minimum: 301 Aug 19 08:13:40.191952 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Aug 19 08:13:40.191960 kernel: landlock: Up and running. Aug 19 08:13:40.191968 kernel: SELinux: Initializing. Aug 19 08:13:40.191979 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.191987 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.191996 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Aug 19 08:13:40.192004 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 19 08:13:40.192013 kernel: signal: max sigframe size: 1776 Aug 19 08:13:40.192021 kernel: rcu: Hierarchical SRCU implementation. Aug 19 08:13:40.192030 kernel: rcu: Max phase no-delay instances is 400. Aug 19 08:13:40.192038 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Aug 19 08:13:40.192046 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 19 08:13:40.192055 kernel: smp: Bringing up secondary CPUs ... Aug 19 08:13:40.192066 kernel: smpboot: x86: Booting SMP configuration: Aug 19 08:13:40.192074 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Aug 19 08:13:40.192082 kernel: smp: Brought up 1 node, 6 CPUs Aug 19 08:13:40.192091 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Aug 19 08:13:40.192099 kernel: Memory: 115339092K/117439364K available (14336K kernel code, 2430K rwdata, 9960K rodata, 54040K init, 2928K bss, 2089996K reserved, 0K cma-reserved) Aug 19 08:13:40.192108 kernel: devtmpfs: initialized Aug 19 08:13:40.192116 kernel: x86/mm: Memory block size: 1024MB Aug 19 08:13:40.192125 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Aug 19 08:13:40.192133 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 19 08:13:40.192144 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Aug 19 08:13:40.192152 kernel: pinctrl core: initialized pinctrl subsystem Aug 19 08:13:40.192161 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 19 08:13:40.192169 kernel: audit: initializing netlink subsys (disabled) Aug 19 08:13:40.192177 kernel: audit: type=2000 audit(1755591217.028:1): state=initialized audit_enabled=0 res=1 Aug 19 08:13:40.192186 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 19 08:13:40.192194 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 19 08:13:40.192202 kernel: cpuidle: using governor menu Aug 19 08:13:40.192211 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 19 08:13:40.192221 kernel: dca service started, version 1.12.1 Aug 19 08:13:40.192230 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Aug 19 08:13:40.192238 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 19 08:13:40.192246 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 19 08:13:40.192255 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 19 08:13:40.192263 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 19 08:13:40.192272 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 19 08:13:40.192280 kernel: ACPI: Added _OSI(Module Device) Aug 19 08:13:40.192288 kernel: ACPI: Added _OSI(Processor Device) Aug 19 08:13:40.192299 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 19 08:13:40.192307 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 19 08:13:40.192316 kernel: ACPI: Interpreter enabled Aug 19 08:13:40.192324 kernel: ACPI: PM: (supports S0 S5) Aug 19 08:13:40.192332 kernel: ACPI: Using IOAPIC for interrupt routing Aug 19 08:13:40.192341 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 19 08:13:40.192349 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 19 08:13:40.192358 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Aug 19 08:13:40.192366 kernel: iommu: Default domain type: Translated Aug 19 08:13:40.192377 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 19 08:13:40.192385 kernel: efivars: Registered efivars operations Aug 19 08:13:40.192393 kernel: PCI: Using ACPI for IRQ routing Aug 19 08:13:40.192402 kernel: PCI: System does not support PCI Aug 19 08:13:40.192410 kernel: vgaarb: loaded Aug 19 08:13:40.192419 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Aug 19 08:13:40.192427 kernel: VFS: Disk quotas dquot_6.6.0 Aug 19 08:13:40.192435 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 19 08:13:40.192444 kernel: pnp: PnP ACPI init Aug 19 08:13:40.192455 kernel: pnp: PnP ACPI: found 3 devices Aug 19 08:13:40.192463 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 19 08:13:40.192471 kernel: NET: Registered PF_INET protocol family Aug 19 08:13:40.192480 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.192488 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.192497 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 19 08:13:40.192505 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 19 08:13:40.192514 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.192524 kernel: TCP: Hash tables configured (established 524288 bind 65536) Aug 19 08:13:40.192533 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.192541 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.192550 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 19 08:13:40.192558 kernel: NET: Registered PF_XDP protocol family Aug 19 08:13:40.192566 kernel: PCI: CLS 0 bytes, default 64 Aug 19 08:13:40.192575 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 19 08:13:40.192583 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Aug 19 08:13:40.192591 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 19 08:13:40.192602 kernel: Initialise system trusted keyrings Aug 19 08:13:40.192610 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Aug 19 08:13:40.192619 kernel: Key type asymmetric registered Aug 19 08:13:40.192627 kernel: Asymmetric key parser 'x509' registered Aug 19 08:13:40.192635 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 19 08:13:40.192643 kernel: io scheduler mq-deadline registered Aug 19 08:13:40.192700 kernel: io scheduler kyber registered Aug 19 08:13:40.192710 kernel: io scheduler bfq registered Aug 19 08:13:40.192718 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 19 08:13:40.192726 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 19 08:13:40.192738 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 19 08:13:40.192746 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 19 08:13:40.192755 kernel: i8042: PNP: No PS/2 controller found. Aug 19 08:13:40.192898 kernel: rtc_cmos 00:02: registered as rtc0 Aug 19 08:13:40.193000 kernel: rtc_cmos 00:02: setting system clock to 2025-08-19T08:13:39 UTC (1755591219) Aug 19 08:13:40.193093 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Aug 19 08:13:40.193105 kernel: intel_pstate: CPU model not supported Aug 19 08:13:40.193118 kernel: efifb: probing for efifb Aug 19 08:13:40.193126 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Aug 19 08:13:40.193135 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Aug 19 08:13:40.193143 kernel: efifb: scrolling: redraw Aug 19 08:13:40.193152 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 19 08:13:40.193160 kernel: Console: switching to colour frame buffer device 128x48 Aug 19 08:13:40.193168 kernel: fb0: EFI VGA frame buffer device Aug 19 08:13:40.193177 kernel: pstore: Using crash dump compression: deflate Aug 19 08:13:40.193185 kernel: pstore: Registered efi_pstore as persistent store backend Aug 19 08:13:40.193196 kernel: NET: Registered PF_INET6 protocol family Aug 19 08:13:40.193204 kernel: Segment Routing with IPv6 Aug 19 08:13:40.193213 kernel: In-situ OAM (IOAM) with IPv6 Aug 19 08:13:40.193221 kernel: NET: Registered PF_PACKET protocol family Aug 19 08:13:40.193229 kernel: Key type dns_resolver registered Aug 19 08:13:40.193238 kernel: IPI shorthand broadcast: enabled Aug 19 08:13:40.193246 kernel: sched_clock: Marking stable (3641005900, 105066300)->(4064860600, -318788400) Aug 19 08:13:40.193255 kernel: registered taskstats version 1 Aug 19 08:13:40.193263 kernel: Loading compiled-in X.509 certificates Aug 19 08:13:40.193274 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.41-flatcar: 93a065b103c00d4b81cc5822e4e7f9674e63afaf' Aug 19 08:13:40.193282 kernel: Demotion targets for Node 0: null Aug 19 08:13:40.193291 kernel: Key type .fscrypt registered Aug 19 08:13:40.193299 kernel: Key type fscrypt-provisioning registered Aug 19 08:13:40.193308 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 19 08:13:40.193316 kernel: ima: Allocated hash algorithm: sha1 Aug 19 08:13:40.193324 kernel: ima: No architecture policies found Aug 19 08:13:40.193333 kernel: clk: Disabling unused clocks Aug 19 08:13:40.193341 kernel: Warning: unable to open an initial console. Aug 19 08:13:40.193352 kernel: Freeing unused kernel image (initmem) memory: 54040K Aug 19 08:13:40.193361 kernel: Write protecting the kernel read-only data: 24576k Aug 19 08:13:40.193369 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Aug 19 08:13:40.193377 kernel: Run /init as init process Aug 19 08:13:40.193386 kernel: with arguments: Aug 19 08:13:40.193394 kernel: /init Aug 19 08:13:40.193402 kernel: with environment: Aug 19 08:13:40.193410 kernel: HOME=/ Aug 19 08:13:40.193418 kernel: TERM=linux Aug 19 08:13:40.193429 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 19 08:13:40.193439 systemd[1]: Successfully made /usr/ read-only. Aug 19 08:13:40.193450 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) Aug 19 08:13:40.193459 systemd[1]: Detected virtualization microsoft. Aug 19 08:13:40.193468 systemd[1]: Detected architecture x86-64. Aug 19 08:13:40.193476 systemd[1]: Running in initrd. Aug 19 08:13:40.193485 systemd[1]: No hostname configured, using default hostname. Aug 19 08:13:40.193497 systemd[1]: Hostname set to . Aug 19 08:13:40.193505 systemd[1]: Initializing machine ID from random generator. Aug 19 08:13:40.193514 systemd[1]: Queued start job for default target initrd.target. Aug 19 08:13:40.193523 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:13:40.193532 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:13:40.193541 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 19 08:13:40.193550 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 19 08:13:40.193559 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 19 08:13:40.193571 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 19 08:13:40.193581 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 19 08:13:40.193590 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 19 08:13:40.193599 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:13:40.193608 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:13:40.193617 systemd[1]: Reached target paths.target - Path Units. Aug 19 08:13:40.193626 systemd[1]: Reached target slices.target - Slice Units. Aug 19 08:13:40.193637 systemd[1]: Reached target swap.target - Swaps. Aug 19 08:13:40.193646 systemd[1]: Reached target timers.target - Timer Units. Aug 19 08:13:40.193667 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 19 08:13:40.193676 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 19 08:13:40.193686 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 19 08:13:40.193694 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 19 08:13:40.193703 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:13:40.193712 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 19 08:13:40.193721 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:13:40.193733 systemd[1]: Reached target sockets.target - Socket Units. Aug 19 08:13:40.193742 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 19 08:13:40.193751 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 19 08:13:40.193760 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 19 08:13:40.193769 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Aug 19 08:13:40.193778 systemd[1]: Starting systemd-fsck-usr.service... Aug 19 08:13:40.193787 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 19 08:13:40.193796 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 19 08:13:40.193807 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:40.193835 systemd-journald[232]: Collecting audit messages is disabled. Aug 19 08:13:40.193856 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 19 08:13:40.193870 systemd-journald[232]: Journal started Aug 19 08:13:40.193890 systemd-journald[232]: Runtime Journal (/run/log/journal/12f2e1e810e2404c93b1682c4e3edd00) is 8M, max 2.2G, 2.1G free. Aug 19 08:13:40.202486 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:13:40.202910 systemd[1]: Started systemd-journald.service - Journal Service. Aug 19 08:13:40.203022 systemd[1]: Finished systemd-fsck-usr.service. Aug 19 08:13:40.205207 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 19 08:13:40.206502 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 19 08:13:40.243267 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:40.245833 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 19 08:13:40.262261 systemd-modules-load[235]: Inserted module 'overlay' Aug 19 08:13:40.269487 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Aug 19 08:13:40.275768 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:13:40.278100 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:13:40.280026 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 19 08:13:40.286491 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 19 08:13:40.297167 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 19 08:13:40.310636 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 19 08:13:40.305490 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:13:40.323050 systemd-modules-load[235]: Inserted module 'br_netfilter' Aug 19 08:13:40.326512 kernel: Bridge firewalling registered Aug 19 08:13:40.331762 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 19 08:13:40.334077 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 19 08:13:40.354451 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:13:40.357401 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 19 08:13:40.370643 dracut-cmdline[265]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.420558 systemd-resolved[284]: Positive Trust Anchors: Aug 19 08:13:40.420572 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 19 08:13:40.420603 systemd-resolved[284]: 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 Aug 19 08:13:40.423094 systemd-resolved[284]: Defaulting to hostname 'linux'. Aug 19 08:13:40.424070 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 19 08:13:40.433206 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:13:40.528528 kernel: SCSI subsystem initialized Aug 19 08:13:40.539672 kernel: Loading iSCSI transport class v2.0-870. Aug 19 08:13:40.551674 kernel: iscsi: registered transport (tcp) Aug 19 08:13:40.574602 kernel: iscsi: registered transport (qla4xxx) Aug 19 08:13:40.574629 kernel: QLogic iSCSI HBA Driver Aug 19 08:13:40.593590 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 19 08:13:40.633237 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:13:40.635706 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 19 08:13:40.696682 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 19 08:13:40.698778 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 19 08:13:40.762666 kernel: raid6: avx2x4 gen() 30900 MB/s Aug 19 08:13:40.781671 kernel: raid6: avx2x2 gen() 31003 MB/s Aug 19 08:13:40.802679 kernel: raid6: avx2x1 gen() 26726 MB/s Aug 19 08:13:40.802703 kernel: raid6: using algorithm avx2x2 gen() 31003 MB/s Aug 19 08:13:40.825734 kernel: raid6: .... xor() 17110 MB/s, rmw enabled Aug 19 08:13:40.825763 kernel: raid6: using avx2x2 recovery algorithm Aug 19 08:13:40.848677 kernel: xor: automatically using best checksumming function avx Aug 19 08:13:41.015678 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 19 08:13:41.022379 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 19 08:13:41.031813 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:13:41.066065 systemd-udevd[485]: Using default interface naming scheme 'v255'. Aug 19 08:13:41.072412 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:13:41.074679 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 19 08:13:41.109902 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Aug 19 08:13:41.136909 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 19 08:13:41.138867 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 19 08:13:41.216954 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:13:41.219395 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 19 08:13:41.250669 kernel: cryptd: max_cpu_qlen set to 1000 Aug 19 08:13:41.262217 kernel: AES CTR mode by8 optimization enabled Aug 19 08:13:41.262256 kernel: hv_vmbus: Vmbus version:4.0 Aug 19 08:13:41.279100 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:41.296722 kernel: hv_vmbus: registering driver hyperv_keyboard Aug 19 08:13:41.296748 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 Aug 19 08:13:41.279165 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:41.311796 kernel: pps_core: LinuxPPS API ver. 1 registered Aug 19 08:13:41.311831 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Aug 19 08:13:41.297718 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:41.314809 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:41.336482 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 19 08:13:41.336504 kernel: PTP clock support registered Aug 19 08:13:41.336516 kernel: hv_vmbus: registering driver hv_pci Aug 19 08:13:41.336528 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Aug 19 08:13:41.319155 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:41.356741 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Aug 19 08:13:41.356915 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Aug 19 08:13:41.357084 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Aug 19 08:13:41.357221 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Aug 19 08:13:41.357717 kernel: hv_vmbus: registering driver hv_netvsc Aug 19 08:13:41.363825 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Aug 19 08:13:41.363478 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:41.384689 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Aug 19 08:13:41.384731 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 19 08:13:41.384751 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 19 08:13:41.384768 kernel: pci 0001:00:00.0: Enabling HDA controller Aug 19 08:13:41.363572 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:41.391637 kernel: hv_vmbus: registering driver hv_storvsc Aug 19 08:13:41.391665 kernel: hv_vmbus: registering driver hid_hyperv Aug 19 08:13:41.379801 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:42.164377 kernel: hv_utils: Registering HyperV Utility Driver Aug 19 08:13:42.164404 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Aug 19 08:13:42.164418 kernel: hv_vmbus: registering driver hv_utils Aug 19 08:13:42.164429 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Aug 19 08:13:42.164608 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Aug 19 08:13:42.164771 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Aug 19 08:13:42.164924 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Aug 19 08:13:42.165132 kernel: scsi host1: storvsc_host_t Aug 19 08:13:42.165474 kernel: hv_utils: Shutdown IC version 3.2 Aug 19 08:13:42.165493 kernel: scsi host0: storvsc_host_t Aug 19 08:13:42.165800 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Aug 19 08:13:42.165976 kernel: hv_utils: Heartbeat IC version 3.0 Aug 19 08:13:42.165991 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 19 08:13:42.166251 kernel: hv_utils: TimeSync IC version 4.0 Aug 19 08:13:42.166266 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 19 08:13:42.166415 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Aug 19 08:13:42.130123 systemd-resolved[284]: Clock change detected. Flushing caches. Aug 19 08:13:42.177977 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Aug 19 08:13:42.178162 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Aug 19 08:13:42.178302 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Aug 19 08:13:42.178438 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Aug 19 08:13:42.182950 kernel: sd 0:0:0:1: [sdb] Write Protect is off Aug 19 08:13:42.188137 kernel: sd 0:0:0:0: [sda] Write Protect is off Aug 19 08:13:42.188322 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Aug 19 08:13:42.188463 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Aug 19 08:13:42.190100 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 19 08:13:42.190259 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 19 08:13:42.195095 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#66 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.215843 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#67 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.216008 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#70 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.221578 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#75 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.230405 kernel: sdb: sdb1 Aug 19 08:13:42.230430 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Aug 19 08:13:42.232846 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:42.246803 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:42.246828 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Aug 19 08:13:42.254154 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Aug 19 08:13:42.254344 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 19 08:13:42.256102 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Aug 19 08:13:42.852980 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Aug 19 08:13:42.881127 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 19 08:13:43.031741 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Aug 19 08:13:43.183497 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Aug 19 08:13:43.185014 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Aug 19 08:13:43.196894 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 19 08:13:43.238100 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#59 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:43.250095 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:43.260096 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#12 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:43.284102 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:44.078933 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 19 08:13:44.082837 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 19 08:13:44.089920 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:13:44.099531 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 19 08:13:44.108779 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 19 08:13:44.152731 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 19 08:13:44.291372 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#195 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:44.306095 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:44.306358 disk-uuid[725]: The operation has completed successfully. Aug 19 08:13:44.353422 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 19 08:13:44.353529 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 19 08:13:44.412395 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 19 08:13:44.443626 sh[897]: Success Aug 19 08:13:44.483511 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 19 08:13:44.483544 kernel: device-mapper: uevent: version 1.0.3 Aug 19 08:13:44.488238 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Aug 19 08:13:44.501103 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Aug 19 08:13:44.724391 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 19 08:13:44.727867 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 19 08:13:44.755442 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 19 08:13:44.781151 kernel: BTRFS: device fsid 99050df3-5e04-4f37-acde-dec46aab7896 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (915) Aug 19 08:13:44.781176 kernel: BTRFS info (device dm-0): first mount of filesystem 99050df3-5e04-4f37-acde-dec46aab7896 Aug 19 08:13:44.781195 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:44.781207 kernel: BTRFS info (device dm-0): using free-space-tree Aug 19 08:13:45.714506 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 19 08:13:45.717148 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Aug 19 08:13:45.721979 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 19 08:13:45.729777 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 19 08:13:45.741204 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 19 08:13:45.783099 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (948) Aug 19 08:13:45.792588 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:45.792618 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:45.795707 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:45.835096 kernel: BTRFS info (device sda6): last unmount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:45.835929 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 19 08:13:45.844365 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 19 08:13:45.854611 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 19 08:13:45.857522 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 19 08:13:45.905694 systemd-networkd[1084]: lo: Link UP Aug 19 08:13:45.905705 systemd-networkd[1084]: lo: Gained carrier Aug 19 08:13:45.906717 systemd-networkd[1084]: Enumeration completed Aug 19 08:13:45.906954 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 19 08:13:45.907092 systemd-networkd[1084]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:13:45.907096 systemd-networkd[1084]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:13:45.907807 systemd-networkd[1084]: eth0: Link UP Aug 19 08:13:45.907970 systemd-networkd[1084]: eth0: Gained carrier Aug 19 08:13:45.907980 systemd-networkd[1084]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:13:45.912332 systemd[1]: Reached target network.target - Network. Aug 19 08:13:45.970120 systemd-networkd[1084]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:13:47.929195 systemd-networkd[1084]: eth0: Gained IPv6LL Aug 19 08:13:49.589927 ignition[1075]: Ignition 2.21.0 Aug 19 08:13:49.589942 ignition[1075]: Stage: fetch-offline Aug 19 08:13:49.590040 ignition[1075]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.590050 ignition[1075]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.590181 ignition[1075]: parsed url from cmdline: "" Aug 19 08:13:49.590186 ignition[1075]: no config URL provided Aug 19 08:13:49.590192 ignition[1075]: reading system config file "/usr/lib/ignition/user.ign" Aug 19 08:13:49.590201 ignition[1075]: no config at "/usr/lib/ignition/user.ign" Aug 19 08:13:49.590209 ignition[1075]: failed to fetch config: resource requires networking Aug 19 08:13:49.590597 ignition[1075]: Ignition finished successfully Aug 19 08:13:49.614377 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 19 08:13:49.623267 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 19 08:13:49.663586 ignition[1097]: Ignition 2.21.0 Aug 19 08:13:49.663599 ignition[1097]: Stage: fetch Aug 19 08:13:49.663859 ignition[1097]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.663871 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.664227 ignition[1097]: parsed url from cmdline: "" Aug 19 08:13:49.664233 ignition[1097]: no config URL provided Aug 19 08:13:49.664240 ignition[1097]: reading system config file "/usr/lib/ignition/user.ign" Aug 19 08:13:49.664252 ignition[1097]: no config at "/usr/lib/ignition/user.ign" Aug 19 08:13:49.664283 ignition[1097]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Aug 19 08:13:49.782832 ignition[1097]: GET result: OK Aug 19 08:13:49.782893 ignition[1097]: config has been read from IMDS userdata Aug 19 08:13:49.782907 ignition[1097]: parsing config with SHA512: 0313e6852e46b8609a7f9d111148fb57c55a76aca87baf0c5f39f03c3423576f21dfd72ef8690461ac831bcb5150b3b363728b240953687bc85c2f082b2d4453 Aug 19 08:13:49.786766 unknown[1097]: fetched base config from "system" Aug 19 08:13:49.786962 ignition[1097]: fetch: fetch complete Aug 19 08:13:49.786773 unknown[1097]: fetched base config from "system" Aug 19 08:13:49.786967 ignition[1097]: fetch: fetch passed Aug 19 08:13:49.786778 unknown[1097]: fetched user config from "azure" Aug 19 08:13:49.787007 ignition[1097]: Ignition finished successfully Aug 19 08:13:49.794728 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 19 08:13:49.797295 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 19 08:13:49.847687 ignition[1106]: Ignition 2.21.0 Aug 19 08:13:49.847746 ignition[1106]: Stage: kargs Aug 19 08:13:49.848519 ignition[1106]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.848541 ignition[1106]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.849199 ignition[1106]: kargs: kargs passed Aug 19 08:13:49.856158 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 19 08:13:49.849247 ignition[1106]: Ignition finished successfully Aug 19 08:13:49.864585 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 19 08:13:49.907586 ignition[1116]: Ignition 2.21.0 Aug 19 08:13:49.908257 ignition[1116]: Stage: disks Aug 19 08:13:49.908565 ignition[1116]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.911251 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 19 08:13:49.908578 ignition[1116]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.916104 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 19 08:13:49.909380 ignition[1116]: disks: disks passed Aug 19 08:13:49.923361 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 19 08:13:49.909440 ignition[1116]: Ignition finished successfully Aug 19 08:13:49.932618 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 19 08:13:49.940531 systemd[1]: Reached target sysinit.target - System Initialization. Aug 19 08:13:49.948699 systemd[1]: Reached target basic.target - Basic System. Aug 19 08:13:49.955652 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 19 08:13:50.063542 systemd-fsck[1128]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Aug 19 08:13:50.070484 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 19 08:13:50.081450 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 19 08:13:50.328105 kernel: EXT4-fs (sda9): mounted filesystem 41966107-04fa-426e-9830-6b4efa50e27b r/w with ordered data mode. Quota mode: none. Aug 19 08:13:50.329173 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 19 08:13:50.330426 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 19 08:13:50.355889 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 19 08:13:50.358191 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 19 08:13:50.381640 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 19 08:13:50.399287 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1143) Aug 19 08:13:50.383135 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 19 08:13:50.415132 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:50.415170 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:50.415185 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:50.383167 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 19 08:13:50.408520 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 19 08:13:50.416944 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 19 08:13:50.434744 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 19 08:13:51.898212 coreos-metadata[1145]: Aug 19 08:13:51.898 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 19 08:13:51.916295 coreos-metadata[1145]: Aug 19 08:13:51.916 INFO Fetch successful Aug 19 08:13:51.920153 coreos-metadata[1145]: Aug 19 08:13:51.916 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Aug 19 08:13:51.927994 coreos-metadata[1145]: Aug 19 08:13:51.924 INFO Fetch successful Aug 19 08:13:51.927994 coreos-metadata[1145]: Aug 19 08:13:51.924 INFO wrote hostname ci-4426.0.0-a-b7a1e26b00 to /sysroot/etc/hostname Aug 19 08:13:51.933220 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 19 08:13:52.298622 initrd-setup-root[1173]: cut: /sysroot/etc/passwd: No such file or directory Aug 19 08:13:52.421666 initrd-setup-root[1180]: cut: /sysroot/etc/group: No such file or directory Aug 19 08:13:52.492215 initrd-setup-root[1187]: cut: /sysroot/etc/shadow: No such file or directory Aug 19 08:13:52.523959 initrd-setup-root[1194]: cut: /sysroot/etc/gshadow: No such file or directory Aug 19 08:13:54.109045 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 19 08:13:54.121322 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 19 08:13:54.126347 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 19 08:13:54.157297 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 19 08:13:54.166015 kernel: BTRFS info (device sda6): last unmount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:54.180179 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 19 08:13:54.195531 ignition[1263]: INFO : Ignition 2.21.0 Aug 19 08:13:54.195531 ignition[1263]: INFO : Stage: mount Aug 19 08:13:54.202937 ignition[1263]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:54.202937 ignition[1263]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:54.202937 ignition[1263]: INFO : mount: mount passed Aug 19 08:13:54.202937 ignition[1263]: INFO : Ignition finished successfully Aug 19 08:13:54.208289 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 19 08:13:54.216834 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 19 08:13:54.249690 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 19 08:13:54.272448 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1279) Aug 19 08:13:54.272481 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:54.276493 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:54.279677 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:54.283459 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 19 08:13:54.318024 ignition[1296]: INFO : Ignition 2.21.0 Aug 19 08:13:54.318024 ignition[1296]: INFO : Stage: files Aug 19 08:13:54.324191 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:54.324191 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:54.324191 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Aug 19 08:13:54.337119 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 19 08:13:54.337119 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 19 08:13:54.373696 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 19 08:13:54.379179 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 19 08:13:54.384361 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 19 08:13:54.379666 unknown[1296]: wrote ssh authorized keys file for user: core Aug 19 08:13:54.424569 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 19 08:13:54.431955 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 19 08:13:54.431955 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 19 08:13:54.431955 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 19 08:13:54.431955 ignition[1296]: INFO : files: files passed Aug 19 08:13:54.431955 ignition[1296]: INFO : Ignition finished successfully Aug 19 08:13:54.440306 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 19 08:13:54.448205 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 19 08:13:54.459675 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 19 08:13:54.493488 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 19 08:13:54.493599 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 19 08:13:54.514876 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.514876 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.527181 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.528503 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 19 08:13:54.538362 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 19 08:13:54.548058 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 19 08:13:54.598003 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 19 08:13:54.598129 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 19 08:13:54.606890 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 19 08:13:54.616820 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 19 08:13:54.624839 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 19 08:13:54.625580 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 19 08:13:54.673229 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 19 08:13:54.684017 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 19 08:13:54.711186 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:13:54.712050 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:13:54.725445 systemd[1]: Stopped target timers.target - Timer Units. Aug 19 08:13:54.733487 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 19 08:13:54.733604 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 19 08:13:54.744272 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 19 08:13:54.754539 systemd[1]: Stopped target basic.target - Basic System. Aug 19 08:13:54.756076 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 19 08:13:54.756820 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 19 08:13:54.757600 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 19 08:13:54.758369 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Aug 19 08:13:54.760703 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 19 08:13:54.761400 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 19 08:13:54.762118 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 19 08:13:54.890412 ignition[1353]: INFO : Ignition 2.21.0 Aug 19 08:13:54.890412 ignition[1353]: INFO : Stage: umount Aug 19 08:13:54.890412 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:54.890412 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:54.890412 ignition[1353]: INFO : umount: umount passed Aug 19 08:13:54.890412 ignition[1353]: INFO : Ignition finished successfully Aug 19 08:13:54.762803 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 19 08:13:54.763864 systemd[1]: Stopped target swap.target - Swaps. Aug 19 08:13:54.764226 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 19 08:13:54.764342 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 19 08:13:54.765651 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:13:54.766364 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:13:54.767012 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 19 08:13:54.767139 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:13:54.767617 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 19 08:13:54.767723 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 19 08:13:54.769093 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 19 08:13:54.769209 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 19 08:13:54.769805 systemd[1]: ignition-files.service: Deactivated successfully. Aug 19 08:13:54.769911 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 19 08:13:54.770531 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 19 08:13:54.770638 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 19 08:13:54.771931 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 19 08:13:54.773250 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 19 08:13:54.773948 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 19 08:13:54.774066 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:13:54.774752 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 19 08:13:54.774858 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 19 08:13:54.778958 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 19 08:13:54.876455 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 19 08:13:54.890317 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 19 08:13:54.890464 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 19 08:13:54.892183 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 19 08:13:54.892236 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 19 08:13:54.897988 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 19 08:13:54.898041 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 19 08:13:54.907592 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 19 08:13:54.907642 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 19 08:13:54.915298 systemd[1]: Stopped target network.target - Network. Aug 19 08:13:54.923340 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 19 08:13:54.923396 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 19 08:13:54.931328 systemd[1]: Stopped target paths.target - Path Units. Aug 19 08:13:54.932727 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 19 08:13:54.945138 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:13:54.958628 systemd[1]: Stopped target slices.target - Slice Units. Aug 19 08:13:54.966640 systemd[1]: Stopped target sockets.target - Socket Units. Aug 19 08:13:54.974231 systemd[1]: iscsid.socket: Deactivated successfully. Aug 19 08:13:54.974280 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 19 08:13:54.983561 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 19 08:13:54.983608 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 19 08:13:54.985731 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 19 08:13:54.985789 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 19 08:13:55.000301 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 19 08:13:55.000347 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 19 08:13:55.008017 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 19 08:13:55.015499 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 19 08:13:55.024771 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 19 08:13:55.025441 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 19 08:13:55.025536 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 19 08:13:55.036446 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 19 08:13:55.036559 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 19 08:13:55.049430 systemd[1]: Stopped target network-pre.target - Preparation for Network. Aug 19 08:13:55.057389 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 19 08:13:55.057461 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:13:55.070228 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 19 08:13:55.077480 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 19 08:13:55.077534 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 19 08:13:55.086593 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 19 08:13:55.086640 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:13:55.095055 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 19 08:13:55.095119 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 19 08:13:55.106178 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 19 08:13:55.106236 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:13:55.119156 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:13:55.121665 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 19 08:13:55.121725 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 19 08:13:55.121765 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 19 08:13:55.121807 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:55.143791 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 19 08:13:55.143905 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 19 08:13:55.145675 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 19 08:13:55.145825 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:13:55.153328 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 19 08:13:55.153380 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 19 08:13:55.161171 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 19 08:13:55.161209 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:13:55.168536 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 19 08:13:55.168588 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 19 08:13:55.183774 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 19 08:13:55.183828 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 19 08:13:55.194867 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 19 08:13:55.194922 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 19 08:13:55.209265 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 19 08:13:55.216164 systemd[1]: systemd-network-generator.service: Deactivated successfully. Aug 19 08:13:55.216221 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:13:55.224828 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 19 08:13:55.224878 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:13:55.233652 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 19 08:13:55.233698 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:13:55.241755 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 19 08:13:55.241803 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:13:55.249512 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:55.249557 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:55.270149 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Aug 19 08:13:55.270206 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Aug 19 08:13:55.270246 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 19 08:13:55.270286 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:55.531941 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Aug 19 08:13:55.270651 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 19 08:13:55.270739 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 19 08:13:55.374251 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 19 08:13:55.374378 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 19 08:13:55.378034 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 19 08:13:55.385053 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 19 08:13:55.385121 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 19 08:13:55.393436 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 19 08:13:55.427045 systemd[1]: Switching root. Aug 19 08:13:55.565706 systemd-journald[232]: Journal stopped Aug 19 08:14:02.456885 kernel: SELinux: policy capability network_peer_controls=1 Aug 19 08:14:02.456915 kernel: SELinux: policy capability open_perms=1 Aug 19 08:14:02.456927 kernel: SELinux: policy capability extended_socket_class=1 Aug 19 08:14:02.456936 kernel: SELinux: policy capability always_check_network=0 Aug 19 08:14:02.456945 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 19 08:14:02.456954 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 19 08:14:02.456965 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 19 08:14:02.456976 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 19 08:14:02.456990 kernel: SELinux: policy capability userspace_initial_context=0 Aug 19 08:14:02.457000 kernel: audit: type=1403 audit(1755591236.695:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 19 08:14:02.457012 systemd[1]: Successfully loaded SELinux policy in 220.854ms. Aug 19 08:14:02.457024 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.554ms. Aug 19 08:14:02.457035 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) Aug 19 08:14:02.457046 systemd[1]: Detected virtualization microsoft. Aug 19 08:14:02.457060 systemd[1]: Detected architecture x86-64. Aug 19 08:14:02.457071 systemd[1]: Detected first boot. Aug 19 08:14:02.457093 systemd[1]: Hostname set to . Aug 19 08:14:02.457105 systemd[1]: Initializing machine ID from random generator. Aug 19 08:14:02.457116 zram_generator::config[1397]: No configuration found. Aug 19 08:14:02.457130 kernel: Guest personality initialized and is inactive Aug 19 08:14:02.457140 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Aug 19 08:14:02.457150 kernel: Initialized host personality Aug 19 08:14:02.457160 kernel: NET: Registered PF_VSOCK protocol family Aug 19 08:14:02.457170 systemd[1]: Populated /etc with preset unit settings. Aug 19 08:14:02.457182 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 19 08:14:02.457193 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 19 08:14:02.457206 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 19 08:14:02.457216 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 19 08:14:02.457227 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 19 08:14:02.457241 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 19 08:14:02.457253 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 19 08:14:02.457263 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 19 08:14:02.457275 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 19 08:14:02.457286 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 19 08:14:02.457299 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 19 08:14:02.457311 systemd[1]: Created slice user.slice - User and Session Slice. Aug 19 08:14:02.457321 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:14:02.457332 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:14:02.457343 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 19 08:14:02.457354 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 19 08:14:02.457369 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 19 08:14:02.457381 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 19 08:14:02.457395 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 19 08:14:02.457446 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:14:02.457461 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:14:02.457472 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 19 08:14:02.457484 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 19 08:14:02.457495 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 19 08:14:02.457506 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 19 08:14:02.457521 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:14:02.457537 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 19 08:14:02.457549 systemd[1]: Reached target slices.target - Slice Units. Aug 19 08:14:02.457560 systemd[1]: Reached target swap.target - Swaps. Aug 19 08:14:02.457571 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 19 08:14:02.457582 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 19 08:14:02.457597 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 19 08:14:02.457609 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:14:02.457620 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 19 08:14:02.457631 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:14:02.457642 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 19 08:14:02.457654 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 19 08:14:02.457665 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 19 08:14:02.457679 systemd[1]: Mounting media.mount - External Media Directory... Aug 19 08:14:02.457690 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:02.457702 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 19 08:14:02.457713 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 19 08:14:02.457724 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 19 08:14:02.457736 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). Aug 19 08:14:02.457747 systemd[1]: Reached target machines.target - Containers. Aug 19 08:14:02.457759 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 19 08:14:02.457770 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 19 08:14:02.457787 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 19 08:14:02.457799 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 19 08:14:02.457810 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 19 08:14:02.457822 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 19 08:14:02.457833 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 19 08:14:02.457844 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 19 08:14:02.457855 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 19 08:14:02.457867 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 19 08:14:02.457882 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 19 08:14:02.457893 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 19 08:14:02.457904 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 19 08:14:02.457916 systemd[1]: Stopped systemd-fsck-usr.service. Aug 19 08:14:02.457927 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). Aug 19 08:14:02.457938 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 19 08:14:02.457950 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 19 08:14:02.457961 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 19 08:14:02.457975 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 19 08:14:02.457986 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 19 08:14:02.457997 kernel: loop: module loaded Aug 19 08:14:02.458008 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 19 08:14:02.458019 systemd[1]: verity-setup.service: Deactivated successfully. Aug 19 08:14:02.458034 systemd[1]: Stopped verity-setup.service. Aug 19 08:14:02.458046 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:02.458057 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 19 08:14:02.458109 systemd-journald[1504]: Collecting audit messages is disabled. Aug 19 08:14:02.458136 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 19 08:14:02.458148 systemd-journald[1504]: Journal started Aug 19 08:14:02.458174 systemd-journald[1504]: Runtime Journal (/run/log/journal/926ecde3f29e494798b2d7401e8588a8) is 8M, max 2.2G, 2.1G free. Aug 19 08:14:01.492025 systemd[1]: Queued start job for default target multi-user.target. Aug 19 08:14:01.517510 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Aug 19 08:14:01.518009 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 19 08:14:02.466102 systemd[1]: Started systemd-journald.service - Journal Service. Aug 19 08:14:02.470773 systemd[1]: Mounted media.mount - External Media Directory. Aug 19 08:14:02.474790 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 19 08:14:02.479195 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 19 08:14:02.483684 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 19 08:14:02.487870 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 19 08:14:02.492770 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:14:02.497958 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 19 08:14:02.498155 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 19 08:14:02.502895 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 19 08:14:02.503096 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 19 08:14:02.508195 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 19 08:14:02.508384 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 19 08:14:02.512914 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 19 08:14:02.517578 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:14:02.524046 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 19 08:14:02.524246 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 19 08:14:02.529179 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 19 08:14:02.541617 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 19 08:14:02.547194 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 19 08:14:02.551639 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 19 08:14:02.551677 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 19 08:14:02.556609 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 19 08:14:02.567914 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 19 08:14:02.572220 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 19 08:14:02.578095 kernel: fuse: init (API version 7.41) Aug 19 08:14:02.596530 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 19 08:14:02.601579 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 19 08:14:02.606090 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 19 08:14:02.614895 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 19 08:14:02.619107 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 19 08:14:02.620008 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 19 08:14:02.624913 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 19 08:14:02.631073 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 19 08:14:02.637433 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 19 08:14:02.637635 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 19 08:14:02.642226 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 19 08:14:02.648160 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 19 08:14:02.655648 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 19 08:14:02.667662 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:14:02.673410 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 19 08:14:02.728131 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 19 08:14:02.733138 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 19 08:14:02.737139 systemd-journald[1504]: Time spent on flushing to /var/log/journal/926ecde3f29e494798b2d7401e8588a8 is 46.076ms for 952 entries. Aug 19 08:14:02.737139 systemd-journald[1504]: System Journal (/var/log/journal/926ecde3f29e494798b2d7401e8588a8) is 8M, max 2.6G, 2.6G free. Aug 19 08:14:04.769217 systemd-journald[1504]: Received client request to flush runtime journal. Aug 19 08:14:04.769295 kernel: loop0: detected capacity change from 0 to 29256 Aug 19 08:14:04.769330 kernel: ACPI: bus type drm_connector registered Aug 19 08:14:02.744198 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 19 08:14:02.766199 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:14:02.837074 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Aug 19 08:14:02.837103 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Aug 19 08:14:02.840943 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:14:02.847390 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 19 08:14:03.323594 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 19 08:14:03.323786 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 19 08:14:03.874178 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 19 08:14:03.879658 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 19 08:14:03.915051 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Aug 19 08:14:03.915066 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Aug 19 08:14:03.917856 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:14:04.770976 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 19 08:14:05.745145 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 19 08:14:05.971110 kernel: loop1: detected capacity change from 0 to 111000 Aug 19 08:14:06.168858 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 19 08:14:06.169612 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 19 08:14:07.548889 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 19 08:14:07.554236 kernel: loop2: detected capacity change from 0 to 128016 Aug 19 08:14:07.555959 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:14:07.599633 systemd-udevd[1564]: Using default interface naming scheme 'v255'. Aug 19 08:14:07.984345 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:14:07.993457 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 19 08:14:08.011296 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 19 08:14:08.048109 kernel: loop3: detected capacity change from 0 to 29256 Aug 19 08:14:08.062105 kernel: loop4: detected capacity change from 0 to 111000 Aug 19 08:14:08.066475 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 19 08:14:08.077102 kernel: hv_vmbus: registering driver hyperv_fb Aug 19 08:14:08.077164 kernel: loop5: detected capacity change from 0 to 128016 Aug 19 08:14:08.087950 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Aug 19 08:14:08.088012 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Aug 19 08:14:08.093607 kernel: Console: switching to colour dummy device 80x25 Aug 19 08:14:08.095112 kernel: Console: switching to colour frame buffer device 128x48 Aug 19 08:14:08.104353 kernel: mousedev: PS/2 mouse device common for all mice Aug 19 08:14:08.108709 (sd-merge)[1610]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Aug 19 08:14:08.111097 kernel: hv_vmbus: registering driver hv_balloon Aug 19 08:14:08.115101 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Aug 19 08:14:08.116136 (sd-merge)[1610]: Merged extensions into '/usr'. Aug 19 08:14:08.122718 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 19 08:14:08.130942 systemd[1]: Starting ensure-sysext.service... Aug 19 08:14:08.136429 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 19 08:14:08.168093 systemd[1]: Reload requested from client PID 1638 ('systemctl') (unit ensure-sysext.service)... Aug 19 08:14:08.168109 systemd[1]: Reloading... Aug 19 08:14:08.170108 kernel: EDAC MC: Ver: 3.0.0 Aug 19 08:14:08.208780 systemd-tmpfiles[1639]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Aug 19 08:14:08.208820 systemd-tmpfiles[1639]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Aug 19 08:14:08.209130 systemd-tmpfiles[1639]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 19 08:14:08.209449 systemd-tmpfiles[1639]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 19 08:14:08.210436 systemd-tmpfiles[1639]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 19 08:14:08.210759 systemd-tmpfiles[1639]: ACLs are not supported, ignoring. Aug 19 08:14:08.210835 systemd-tmpfiles[1639]: ACLs are not supported, ignoring. Aug 19 08:14:08.242412 systemd-tmpfiles[1639]: Detected autofs mount point /boot during canonicalization of boot. Aug 19 08:14:08.244591 systemd-tmpfiles[1639]: Skipping /boot Aug 19 08:14:08.263059 systemd-tmpfiles[1639]: Detected autofs mount point /boot during canonicalization of boot. Aug 19 08:14:08.263198 systemd-tmpfiles[1639]: Skipping /boot Aug 19 08:14:08.264107 zram_generator::config[1740]: No configuration found. Aug 19 08:14:08.334495 systemd-networkd[1588]: lo: Link UP Aug 19 08:14:08.334505 systemd-networkd[1588]: lo: Gained carrier Aug 19 08:14:08.335808 systemd-networkd[1588]: Enumeration completed Aug 19 08:14:08.336201 systemd-networkd[1588]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:08.336210 systemd-networkd[1588]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:14:08.336738 systemd-networkd[1588]: eth0: Link UP Aug 19 08:14:08.336747 systemd-networkd[1588]: eth0: Gained carrier Aug 19 08:14:08.336761 systemd-networkd[1588]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:08.380160 systemd-networkd[1588]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:14:08.522580 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 19 08:14:08.527585 systemd[1]: Reloading finished in 358 ms. Aug 19 08:14:08.567228 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 19 08:14:08.571957 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 19 08:14:08.606823 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:14:08.658300 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:08.659580 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 19 08:14:08.664597 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 19 08:14:08.669237 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 19 08:14:08.688859 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 19 08:14:08.693874 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 19 08:14:08.698658 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 19 08:14:08.703994 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 19 08:14:08.707862 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 19 08:14:08.708861 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 19 08:14:08.713315 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). Aug 19 08:14:08.714539 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 19 08:14:08.719956 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 19 08:14:08.726811 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 19 08:14:08.733225 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 19 08:14:08.737354 systemd[1]: Reached target time-set.target - System Time Set. Aug 19 08:14:08.742306 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 19 08:14:08.748273 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:14:08.749692 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:08.752491 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 19 08:14:08.757228 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 19 08:14:08.764351 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 19 08:14:08.764537 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 19 08:14:08.769196 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 19 08:14:08.769375 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 19 08:14:08.771973 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 19 08:14:08.772176 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 19 08:14:08.773291 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 19 08:14:08.773975 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 19 08:14:08.776732 systemd[1]: Finished ensure-sysext.service. Aug 19 08:14:08.784508 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 19 08:14:08.785295 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 19 08:14:08.785436 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 19 08:14:08.819620 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 19 08:14:08.825455 augenrules[1846]: No rules Aug 19 08:14:08.826473 systemd[1]: audit-rules.service: Deactivated successfully. Aug 19 08:14:08.826696 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 19 08:14:08.938116 systemd-resolved[1817]: Positive Trust Anchors: Aug 19 08:14:08.938133 systemd-resolved[1817]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 19 08:14:08.938167 systemd-resolved[1817]: 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 Aug 19 08:14:08.954572 systemd-resolved[1817]: Using system hostname 'ci-4426.0.0-a-b7a1e26b00'. Aug 19 08:14:08.956135 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 19 08:14:08.957669 systemd[1]: Reached target network.target - Network. Aug 19 08:14:08.958289 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:14:09.382694 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 19 08:14:09.384540 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). Aug 19 08:14:09.407385 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:14:10.329225 systemd-networkd[1588]: eth0: Gained IPv6LL Aug 19 08:14:10.331569 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 19 08:14:10.337013 systemd[1]: Reached target network-online.target - Network is Online. Aug 19 08:14:16.892660 ldconfig[1529]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 19 08:14:16.904573 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 19 08:14:16.911176 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 19 08:14:16.948685 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 19 08:14:16.953429 systemd[1]: Reached target sysinit.target - System Initialization. Aug 19 08:14:16.958000 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 19 08:14:16.963167 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 19 08:14:16.968247 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Aug 19 08:14:16.973225 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 19 08:14:16.977745 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 19 08:14:16.982758 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 19 08:14:16.987851 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 19 08:14:16.987899 systemd[1]: Reached target paths.target - Path Units. Aug 19 08:14:16.991618 systemd[1]: Reached target timers.target - Timer Units. Aug 19 08:14:16.996329 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 19 08:14:17.002050 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 19 08:14:17.008091 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 19 08:14:17.013287 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 19 08:14:17.018190 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 19 08:14:17.024273 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 19 08:14:17.028868 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 19 08:14:17.035436 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 19 08:14:17.040536 systemd[1]: Reached target sockets.target - Socket Units. Aug 19 08:14:17.044358 systemd[1]: Reached target basic.target - Basic System. Aug 19 08:14:17.048004 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 19 08:14:17.048037 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 19 08:14:17.050288 systemd[1]: Starting chronyd.service - NTP client/server... Aug 19 08:14:17.055196 systemd[1]: Starting containerd.service - containerd container runtime... Aug 19 08:14:17.071955 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 19 08:14:17.076881 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 19 08:14:17.081426 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 19 08:14:17.086943 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 19 08:14:17.093374 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 19 08:14:17.097273 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). Aug 19 08:14:17.098352 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Aug 19 08:14:17.102319 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Aug 19 08:14:17.103293 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Aug 19 08:14:17.107428 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Aug 19 08:14:17.108509 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 19 08:14:17.109901 jq[1870]: false Aug 19 08:14:17.114224 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 19 08:14:17.118969 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 19 08:14:17.125224 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 19 08:14:17.131761 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 19 08:14:17.138250 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 19 08:14:17.138701 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 19 08:14:17.139282 systemd[1]: Starting update-engine.service - Update Engine... Aug 19 08:14:17.139682 oslogin_cache_refresh[1872]: Refreshing passwd entry cache Aug 19 08:14:17.142412 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Refreshing passwd entry cache Aug 19 08:14:17.139797 KVP[1873]: KVP starting; pid is:1873 Aug 19 08:14:17.140576 chronyd[1862]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Aug 19 08:14:17.142118 KVP[1873]: KVP LIC Version: 3.1 Aug 19 08:14:17.145115 kernel: hv_utils: KVP IC version 4.0 Aug 19 08:14:17.145593 chronyd[1862]: Timezone right/UTC failed leap second check, ignoring Aug 19 08:14:17.145746 chronyd[1862]: Loaded seccomp filter (level 2) Aug 19 08:14:17.150616 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Failure getting users, quitting Aug 19 08:14:17.150616 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 19 08:14:17.150616 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Refreshing group entry cache Aug 19 08:14:17.145712 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 19 08:14:17.150168 oslogin_cache_refresh[1872]: Failure getting users, quitting Aug 19 08:14:17.150184 oslogin_cache_refresh[1872]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 19 08:14:17.150227 oslogin_cache_refresh[1872]: Refreshing group entry cache Aug 19 08:14:17.151335 systemd[1]: Started chronyd.service - NTP client/server. Aug 19 08:14:17.154721 jq[1889]: true Aug 19 08:14:17.155603 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Failure getting groups, quitting Aug 19 08:14:17.155603 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 19 08:14:17.155527 oslogin_cache_refresh[1872]: Failure getting groups, quitting Aug 19 08:14:17.155537 oslogin_cache_refresh[1872]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 19 08:14:17.156070 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 19 08:14:17.161293 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 19 08:14:17.161499 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 19 08:14:17.161786 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Aug 19 08:14:17.162040 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Aug 19 08:14:17.162462 extend-filesystems[1871]: Found /dev/sda6 Aug 19 08:14:17.172766 jq[1892]: true Aug 19 08:14:17.177320 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 19 08:14:17.177553 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 19 08:14:17.196530 systemd[1]: motdgen.service: Deactivated successfully. Aug 19 08:14:17.209370 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 19 08:14:17.209446 (ntainerd)[1916]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 19 08:14:17.232525 extend-filesystems[1871]: Found /dev/sda9 Aug 19 08:14:17.236761 extend-filesystems[1871]: Checking size of /dev/sda9 Aug 19 08:14:17.253901 bash[1914]: Updated "/home/core/.ssh/authorized_keys" Aug 19 08:14:17.255106 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 19 08:14:17.260871 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 19 08:14:17.275048 systemd-logind[1884]: New seat seat0. Aug 19 08:14:17.276364 systemd-logind[1884]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 19 08:14:17.276561 systemd[1]: Started systemd-logind.service - User Login Management. Aug 19 08:14:17.284942 extend-filesystems[1871]: Old size kept for /dev/sda9 Aug 19 08:14:17.285908 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 19 08:14:17.286163 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 19 08:14:17.316100 update_engine[1887]: I20250819 08:14:17.315871 1887 main.cc:92] Flatcar Update Engine starting Aug 19 08:14:17.403750 sshd_keygen[1888]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 19 08:14:17.413056 setup-nvidia[1875]: Downloading Flatcar Container Linux Developer Container for version: 4426.0.0 Aug 19 08:14:17.424865 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 19 08:14:17.430403 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 19 08:14:17.434805 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Aug 19 08:14:17.450416 systemd[1]: issuegen.service: Deactivated successfully. Aug 19 08:14:17.450617 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 19 08:14:17.456175 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 19 08:14:17.462359 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Aug 19 08:14:17.496984 dbus-daemon[1865]: [system] SELinux support is enabled Aug 19 08:14:17.497160 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 19 08:14:17.499438 update_engine[1887]: I20250819 08:14:17.499383 1887 update_check_scheduler.cc:74] Next update check in 10m51s Aug 19 08:14:17.503699 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). Aug 19 08:14:17.503734 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 19 08:14:17.504504 dbus-daemon[1865]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 19 08:14:17.508756 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). Aug 19 08:14:17.508773 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 19 08:14:17.513766 systemd[1]: Started update-engine.service - Update Engine. Aug 19 08:14:17.519287 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 19 08:14:17.538227 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 19 08:14:17.544797 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 19 08:14:17.549638 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 19 08:14:17.550901 coreos-metadata[1864]: Aug 19 08:14:17.550 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 19 08:14:17.553809 coreos-metadata[1864]: Aug 19 08:14:17.553 INFO Fetch successful Aug 19 08:14:17.554005 coreos-metadata[1864]: Aug 19 08:14:17.553 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Aug 19 08:14:17.554224 systemd[1]: Reached target getty.target - Login Prompts. Aug 19 08:14:17.558263 coreos-metadata[1864]: Aug 19 08:14:17.558 INFO Fetch successful Aug 19 08:14:17.558560 coreos-metadata[1864]: Aug 19 08:14:17.558 INFO Fetching http://168.63.129.16/machine/b6b549e6-2f7c-48dd-8ea2-3de88b77cd6b/b378e936%2D9e95%2D4801%2D9bff%2D9b2a609f90f7.%5Fci%2D4426.0.0%2Da%2Db7a1e26b00?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Aug 19 08:14:17.560143 coreos-metadata[1864]: Aug 19 08:14:17.560 INFO Fetch successful Aug 19 08:14:17.560253 coreos-metadata[1864]: Aug 19 08:14:17.560 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Aug 19 08:14:17.567523 coreos-metadata[1864]: Aug 19 08:14:17.567 INFO Fetch successful Aug 19 08:14:17.600872 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 19 08:14:17.605555 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 19 08:14:17.978738 locksmithd[2020]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 19 08:14:18.568774 setup-nvidia[2038]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 19 08:14:18.569096 setup-nvidia[2038]: Dload Upload Total Spent Left Speed Aug 19 08:14:18.829092 containerd[1916]: time="2025-08-19T08:14:18Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Aug 19 08:14:18.829710 containerd[1916]: time="2025-08-19T08:14:18.829677800Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Aug 19 08:14:18.838556 containerd[1916]: time="2025-08-19T08:14:18.838516500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.2µs" Aug 19 08:14:18.838556 containerd[1916]: time="2025-08-19T08:14:18.838543200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Aug 19 08:14:18.838639 containerd[1916]: time="2025-08-19T08:14:18.838560800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Aug 19 08:14:18.838729 containerd[1916]: time="2025-08-19T08:14:18.838701000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Aug 19 08:14:18.838729 containerd[1916]: time="2025-08-19T08:14:18.838722700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Aug 19 08:14:18.838794 containerd[1916]: time="2025-08-19T08:14:18.838745600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 19 08:14:18.838822 containerd[1916]: time="2025-08-19T08:14:18.838802100Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 19 08:14:18.838822 containerd[1916]: time="2025-08-19T08:14:18.838816600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839029 containerd[1916]: time="2025-08-19T08:14:18.839000100Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839029 containerd[1916]: time="2025-08-19T08:14:18.839020300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839106 containerd[1916]: time="2025-08-19T08:14:18.839032000Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839106 containerd[1916]: time="2025-08-19T08:14:18.839039500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839165 containerd[1916]: time="2025-08-19T08:14:18.839131000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839367 containerd[1916]: time="2025-08-19T08:14:18.839344200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839401 containerd[1916]: time="2025-08-19T08:14:18.839380200Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.839401 containerd[1916]: time="2025-08-19T08:14:18.839390500Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Aug 19 08:14:18.839447 containerd[1916]: time="2025-08-19T08:14:18.839420600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Aug 19 08:14:18.839649 containerd[1916]: time="2025-08-19T08:14:18.839625800Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Aug 19 08:14:18.839715 containerd[1916]: time="2025-08-19T08:14:18.839695300Z" level=info msg="metadata content store policy set" policy=shared Aug 19 08:14:18.856987 containerd[1916]: time="2025-08-19T08:14:18.856954300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Aug 19 08:14:18.857053 containerd[1916]: time="2025-08-19T08:14:18.857002400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Aug 19 08:14:18.857053 containerd[1916]: time="2025-08-19T08:14:18.857018000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Aug 19 08:14:18.857053 containerd[1916]: time="2025-08-19T08:14:18.857029100Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Aug 19 08:14:18.857053 containerd[1916]: time="2025-08-19T08:14:18.857042100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857053500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857065700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857096200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857109100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857118500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857127200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Aug 19 08:14:18.857166 containerd[1916]: time="2025-08-19T08:14:18.857138400Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Aug 19 08:14:18.857311 containerd[1916]: time="2025-08-19T08:14:18.857241600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Aug 19 08:14:18.857311 containerd[1916]: time="2025-08-19T08:14:18.857262200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Aug 19 08:14:18.857311 containerd[1916]: time="2025-08-19T08:14:18.857292500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Aug 19 08:14:18.857311 containerd[1916]: time="2025-08-19T08:14:18.857307300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857327700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857341100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857351800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857360800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857370800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857380000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Aug 19 08:14:18.857397 containerd[1916]: time="2025-08-19T08:14:18.857389400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Aug 19 08:14:18.857542 containerd[1916]: time="2025-08-19T08:14:18.857443300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Aug 19 08:14:18.857542 containerd[1916]: time="2025-08-19T08:14:18.857456700Z" level=info msg="Start snapshots syncer" Aug 19 08:14:18.857542 containerd[1916]: time="2025-08-19T08:14:18.857481300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Aug 19 08:14:18.857703 containerd[1916]: time="2025-08-19T08:14:18.857662700Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Aug 19 08:14:18.857821 containerd[1916]: time="2025-08-19T08:14:18.857733100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Aug 19 08:14:18.857821 containerd[1916]: time="2025-08-19T08:14:18.857809500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Aug 19 08:14:18.857934 containerd[1916]: time="2025-08-19T08:14:18.857910800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Aug 19 08:14:18.857965 containerd[1916]: time="2025-08-19T08:14:18.857936800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Aug 19 08:14:18.857965 containerd[1916]: time="2025-08-19T08:14:18.857947100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Aug 19 08:14:18.857965 containerd[1916]: time="2025-08-19T08:14:18.857958600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Aug 19 08:14:18.858028 containerd[1916]: time="2025-08-19T08:14:18.857968700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Aug 19 08:14:18.858028 containerd[1916]: time="2025-08-19T08:14:18.857977900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Aug 19 08:14:18.858028 containerd[1916]: time="2025-08-19T08:14:18.857987600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Aug 19 08:14:18.858028 containerd[1916]: time="2025-08-19T08:14:18.858005600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Aug 19 08:14:18.858028 containerd[1916]: time="2025-08-19T08:14:18.858015800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858038600Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858077100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858108100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858116500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858124900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858132200Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Aug 19 08:14:18.858144 containerd[1916]: time="2025-08-19T08:14:18.858140600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858150500Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858167300Z" level=info msg="runtime interface created" Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858172700Z" level=info msg="created NRI interface" Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858194300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858208500Z" level=info msg="Connect containerd service" Aug 19 08:14:18.858293 containerd[1916]: time="2025-08-19T08:14:18.858231700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 19 08:14:18.858924 containerd[1916]: time="2025-08-19T08:14:18.858894900Z" 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" Aug 19 08:14:19.822911 containerd[1916]: time="2025-08-19T08:14:19.822864200Z" level=info msg="Start subscribing containerd event" Aug 19 08:14:19.823016 containerd[1916]: time="2025-08-19T08:14:19.822921700Z" level=info msg="Start recovering state" Aug 19 08:14:19.823062 containerd[1916]: time="2025-08-19T08:14:19.823038400Z" level=info msg="Start event monitor" Aug 19 08:14:19.823062 containerd[1916]: time="2025-08-19T08:14:19.823059600Z" level=info msg="Start cni network conf syncer for default" Aug 19 08:14:19.823134 containerd[1916]: time="2025-08-19T08:14:19.823067300Z" level=info msg="Start streaming server" Aug 19 08:14:19.823134 containerd[1916]: time="2025-08-19T08:14:19.823077300Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Aug 19 08:14:19.823134 containerd[1916]: time="2025-08-19T08:14:19.823106200Z" level=info msg="runtime interface starting up..." Aug 19 08:14:19.823134 containerd[1916]: time="2025-08-19T08:14:19.823115100Z" level=info msg="starting plugins..." Aug 19 08:14:19.823232 containerd[1916]: time="2025-08-19T08:14:19.823127100Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 19 08:14:19.823385 containerd[1916]: time="2025-08-19T08:14:19.823363000Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 19 08:14:19.823485 containerd[1916]: time="2025-08-19T08:14:19.823132200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Aug 19 08:14:19.823732 containerd[1916]: time="2025-08-19T08:14:19.823700700Z" level=info msg="containerd successfully booted in 0.995186s" Aug 19 08:14:19.823926 systemd[1]: Started containerd.service - containerd container runtime. Aug 19 08:14:20.554406 waagent[2017]: 2025-08-19T08:14:20.554327Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Aug 19 08:14:20.560002 waagent[2017]: 2025-08-19T08:14:20.559950Z INFO Daemon Daemon OS: flatcar 4426.0.0 Aug 19 08:14:20.564413 waagent[2017]: 2025-08-19T08:14:20.564369Z INFO Daemon Daemon Python: 3.11.13 Aug 19 08:14:20.568752 waagent[2017]: 2025-08-19T08:14:20.568704Z INFO Daemon Daemon Run daemon Aug 19 08:14:20.572430 waagent[2017]: 2025-08-19T08:14:20.572382Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4426.0.0' Aug 19 08:14:20.579119 waagent[2017]: 2025-08-19T08:14:20.579059Z INFO Daemon Daemon Using waagent for provisioning Aug 19 08:14:20.583238 waagent[2017]: 2025-08-19T08:14:20.583196Z INFO Daemon Daemon Activate resource disk Aug 19 08:14:20.586937 waagent[2017]: 2025-08-19T08:14:20.586890Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Aug 19 08:14:20.594400 waagent[2017]: 2025-08-19T08:14:20.594354Z INFO Daemon Daemon Found device: sdb Aug 19 08:14:20.603430 waagent[2017]: 2025-08-19T08:14:20.603382Z INFO Daemon Daemon Examining partition table Aug 19 08:14:20.631201 waagent[2017]: 2025-08-19T08:14:20.631149Z INFO Daemon Daemon GPT not detected, determining filesystem Aug 19 08:14:20.669688 waagent[2017]: 2025-08-19T08:14:20.669634Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Aug 19 08:14:20.674321 waagent[2017]: 2025-08-19T08:14:20.674275Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Aug 19 08:14:21.144118 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#217 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:14:21.156160 kernel: sdb: sdb1 Aug 19 08:14:21.180703 waagent[2017]: 2025-08-19T08:14:21.180629Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Aug 19 08:14:21.199101 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#227 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:14:21.206044 waagent[2017]: 2025-08-19T08:14:21.205993Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Aug 19 08:14:21.206128 kernel: sdb: sdb1 Aug 19 08:14:23.364716 login[2022]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Aug 19 08:14:23.366601 login[2023]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 19 08:14:23.372958 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 19 08:14:23.373962 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 19 08:14:23.380339 systemd-logind[1884]: New session 1 of user core. Aug 19 08:14:23.402506 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 19 08:14:23.404877 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 19 08:14:23.427375 (systemd)[2127]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 19 08:14:23.429606 systemd-logind[1884]: New session c1 of user core. Aug 19 08:14:23.729899 systemd[2127]: Queued start job for default target default.target. Aug 19 08:14:23.753023 systemd[2127]: Created slice app.slice - User Application Slice. Aug 19 08:14:23.753054 systemd[2127]: Reached target paths.target - Paths. Aug 19 08:14:23.753118 systemd[2127]: Reached target timers.target - Timers. Aug 19 08:14:23.754315 systemd[2127]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 19 08:14:23.765720 systemd[2127]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:14:23.765840 systemd[2127]: Reached target sockets.target - Sockets. Aug 19 08:14:23.765895 systemd[2127]: Reached target basic.target - Basic System. Aug 19 08:14:23.765938 systemd[2127]: Reached target default.target - Main User Target. Aug 19 08:14:23.765969 systemd[2127]: Startup finished in 330ms. Aug 19 08:14:23.766005 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 19 08:14:23.767314 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 19 08:14:24.365185 login[2022]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 19 08:14:24.369648 systemd-logind[1884]: New session 2 of user core. Aug 19 08:14:24.388194 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 19 08:14:26.352518 waagent[2017]: 2025-08-19T08:14:26.352434Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Aug 19 08:14:26.458907 waagent[2017]: 2025-08-19T08:14:26.458837Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Aug 19 08:14:26.459100 kernel: EXT4-fs (sdb1): mounted filesystem c79f6ab5-8645-4f76-9c76-c64090333f0b r/w with ordered data mode. Quota mode: none. Aug 19 08:14:26.460995 waagent[2017]: 2025-08-19T08:14:26.460952Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 19 08:14:26.461988 waagent[2017]: 2025-08-19T08:14:26.461952Z INFO Daemon Daemon Running default provisioning handler Aug 19 08:14:26.469254 waagent[2017]: 2025-08-19T08:14:26.469206Z 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. Aug 19 08:14:26.470989 waagent[2017]: 2025-08-19T08:14:26.470946Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Aug 19 08:14:26.472107 waagent[2017]: 2025-08-19T08:14:26.472061Z INFO Daemon Daemon cloud-init is enabled: False Aug 19 08:14:26.472839 waagent[2017]: 2025-08-19T08:14:26.472807Z INFO Daemon Daemon Copying ovf-env.xml Aug 19 08:14:26.756208 waagent[2017]: 2025-08-19T08:14:26.756076Z INFO Daemon Daemon Successfully mounted dvd Aug 19 08:14:26.768792 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.768849Z INFO Daemon Daemon Detect protocol endpoint Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.770430Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.771112Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.771809Z INFO Daemon Daemon Test for route to 168.63.129.16 Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.773435Z INFO Daemon Daemon Route to 168.63.129.16 exists Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.774789Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.786538Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.788001Z INFO Daemon Daemon Wire protocol version:2012-11-30 Aug 19 08:14:26.805166 waagent[2017]: 2025-08-19T08:14:26.789287Z INFO Daemon Daemon Server preferred version:2015-04-05 Aug 19 08:14:26.982013 waagent[2017]: 2025-08-19T08:14:26.981934Z INFO Daemon Daemon Initializing goal state during protocol detection Aug 19 08:14:26.983626 waagent[2017]: 2025-08-19T08:14:26.983572Z INFO Daemon Daemon Forcing an update of the goal state. Aug 19 08:14:26.992784 waagent[2017]: 2025-08-19T08:14:26.992733Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 19 08:14:27.030975 waagent[2017]: 2025-08-19T08:14:27.030936Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Aug 19 08:14:27.032921 waagent[2017]: 2025-08-19T08:14:27.032847Z INFO Daemon Aug 19 08:14:27.033937 waagent[2017]: 2025-08-19T08:14:27.033891Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 29949694-333c-4baf-b143-2af51097afcc eTag: 14180699728785809365 source: Fabric] Aug 19 08:14:27.035639 waagent[2017]: 2025-08-19T08:14:27.035585Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Aug 19 08:14:27.036364 waagent[2017]: 2025-08-19T08:14:27.036323Z INFO Daemon Aug 19 08:14:27.036817 waagent[2017]: 2025-08-19T08:14:27.036783Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Aug 19 08:14:27.041579 waagent[2017]: 2025-08-19T08:14:27.041547Z INFO Daemon Daemon Downloading artifacts profile blob Aug 19 08:14:27.163093 waagent[2017]: 2025-08-19T08:14:27.163033Z INFO Daemon Downloaded certificate {'thumbprint': '3A52C959795DD4FDC742D5D30AF5F5ED2F187299', 'hasPrivateKey': True} Aug 19 08:14:27.165023 waagent[2017]: 2025-08-19T08:14:27.164968Z INFO Daemon Fetch goal state completed Aug 19 08:14:27.201383 waagent[2017]: 2025-08-19T08:14:27.201340Z INFO Daemon Daemon Starting provisioning Aug 19 08:14:27.202974 waagent[2017]: 2025-08-19T08:14:27.202917Z INFO Daemon Daemon Handle ovf-env.xml. Aug 19 08:14:27.204409 waagent[2017]: 2025-08-19T08:14:27.204361Z INFO Daemon Daemon Set hostname [ci-4426.0.0-a-b7a1e26b00] Aug 19 08:14:27.239950 waagent[2017]: 2025-08-19T08:14:27.239904Z INFO Daemon Daemon Publish hostname [ci-4426.0.0-a-b7a1e26b00] Aug 19 08:14:27.241721 waagent[2017]: 2025-08-19T08:14:27.241662Z INFO Daemon Daemon Examine /proc/net/route for primary interface Aug 19 08:14:27.243239 waagent[2017]: 2025-08-19T08:14:27.243194Z INFO Daemon Daemon Primary interface is [eth0] Aug 19 08:14:27.260108 systemd-networkd[1588]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:27.260118 systemd-networkd[1588]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:14:27.260144 systemd-networkd[1588]: eth0: DHCP lease lost Aug 19 08:14:27.260573 waagent[2017]: 2025-08-19T08:14:27.260525Z INFO Daemon Daemon Create user account if not exists Aug 19 08:14:27.265044 waagent[2017]: 2025-08-19T08:14:27.264992Z INFO Daemon Daemon User core already exists, skip useradd Aug 19 08:14:27.272228 waagent[2017]: 2025-08-19T08:14:27.266642Z INFO Daemon Daemon Configure sudoer Aug 19 08:14:27.272228 waagent[2017]: 2025-08-19T08:14:27.272111Z INFO Daemon Daemon Configure sshd Aug 19 08:14:27.277383 waagent[2017]: 2025-08-19T08:14:27.277341Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Aug 19 08:14:27.278907 waagent[2017]: 2025-08-19T08:14:27.278867Z INFO Daemon Daemon Deploy ssh public key. Aug 19 08:14:27.335143 systemd-networkd[1588]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:14:28.377130 waagent[2017]: 2025-08-19T08:14:28.377041Z INFO Daemon Daemon Provisioning complete Aug 19 08:14:28.387885 waagent[2017]: 2025-08-19T08:14:28.387845Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Aug 19 08:14:28.389442 waagent[2017]: 2025-08-19T08:14:28.389394Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Aug 19 08:14:28.390141 waagent[2017]: 2025-08-19T08:14:28.390104Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Aug 19 08:14:28.541717 waagent[2187]: 2025-08-19T08:14:28.541643Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Aug 19 08:14:28.542044 waagent[2187]: 2025-08-19T08:14:28.541776Z INFO ExtHandler ExtHandler OS: flatcar 4426.0.0 Aug 19 08:14:28.542044 waagent[2187]: 2025-08-19T08:14:28.541841Z INFO ExtHandler ExtHandler Python: 3.11.13 Aug 19 08:14:28.542044 waagent[2187]: 2025-08-19T08:14:28.541904Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Aug 19 08:14:28.667056 waagent[2187]: 2025-08-19T08:14:28.666922Z INFO ExtHandler ExtHandler Distro: flatcar-4426.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Aug 19 08:14:28.667213 waagent[2187]: 2025-08-19T08:14:28.667173Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:28.667293 waagent[2187]: 2025-08-19T08:14:28.667260Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:28.673949 waagent[2187]: 2025-08-19T08:14:28.673903Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 19 08:14:28.678758 waagent[2187]: 2025-08-19T08:14:28.678716Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Aug 19 08:14:28.679208 waagent[2187]: 2025-08-19T08:14:28.679168Z INFO ExtHandler Aug 19 08:14:28.679290 waagent[2187]: 2025-08-19T08:14:28.679257Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 9bc6cb56-492a-46f8-9682-e80f9456fbbf eTag: 14180699728785809365 source: Fabric] Aug 19 08:14:28.679604 waagent[2187]: 2025-08-19T08:14:28.679567Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Aug 19 08:14:28.680225 waagent[2187]: 2025-08-19T08:14:28.680186Z INFO ExtHandler Aug 19 08:14:28.680298 waagent[2187]: 2025-08-19T08:14:28.680266Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Aug 19 08:14:28.683813 waagent[2187]: 2025-08-19T08:14:28.683778Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Aug 19 08:14:28.744666 waagent[2187]: 2025-08-19T08:14:28.744604Z INFO ExtHandler Downloaded certificate {'thumbprint': '3A52C959795DD4FDC742D5D30AF5F5ED2F187299', 'hasPrivateKey': True} Aug 19 08:14:28.745205 waagent[2187]: 2025-08-19T08:14:28.745156Z INFO ExtHandler Fetch goal state completed Aug 19 08:14:28.756236 waagent[2187]: 2025-08-19T08:14:28.756191Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.1 11 Feb 2025 (Library: OpenSSL 3.4.1 11 Feb 2025) Aug 19 08:14:28.761036 waagent[2187]: 2025-08-19T08:14:28.760986Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2187 Aug 19 08:14:28.761188 waagent[2187]: 2025-08-19T08:14:28.761152Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Aug 19 08:14:28.761527 waagent[2187]: 2025-08-19T08:14:28.761490Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Aug 19 08:14:28.763088 waagent[2187]: 2025-08-19T08:14:28.763048Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4426.0.0', '', 'Flatcar Container Linux by Kinvolk'] Aug 19 08:14:28.763531 waagent[2187]: 2025-08-19T08:14:28.763493Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4426.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Aug 19 08:14:28.763678 waagent[2187]: 2025-08-19T08:14:28.763643Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Aug 19 08:14:28.764278 waagent[2187]: 2025-08-19T08:14:28.764241Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Aug 19 08:14:28.863560 waagent[2187]: 2025-08-19T08:14:28.863520Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Aug 19 08:14:28.863740 waagent[2187]: 2025-08-19T08:14:28.863705Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Aug 19 08:14:28.870034 waagent[2187]: 2025-08-19T08:14:28.870004Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Aug 19 08:14:28.876330 systemd[1]: Reload requested from client PID 2202 ('systemctl') (unit waagent.service)... Aug 19 08:14:28.876346 systemd[1]: Reloading... Aug 19 08:14:28.935105 zram_generator::config[2245]: No configuration found. Aug 19 08:14:29.243836 systemd[1]: Reloading finished in 367 ms. Aug 19 08:14:29.267456 waagent[2187]: 2025-08-19T08:14:29.267379Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Aug 19 08:14:29.267545 waagent[2187]: 2025-08-19T08:14:29.267523Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Aug 19 08:14:30.298167 waagent[2187]: 2025-08-19T08:14:30.298073Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Aug 19 08:14:30.298505 waagent[2187]: 2025-08-19T08:14:30.298422Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Aug 19 08:14:30.299201 waagent[2187]: 2025-08-19T08:14:30.299155Z INFO ExtHandler ExtHandler Starting env monitor service. Aug 19 08:14:30.299640 waagent[2187]: 2025-08-19T08:14:30.299601Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Aug 19 08:14:30.299728 waagent[2187]: 2025-08-19T08:14:30.299684Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:30.300058 waagent[2187]: 2025-08-19T08:14:30.300017Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:30.300250 waagent[2187]: 2025-08-19T08:14:30.300206Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:30.300423 waagent[2187]: 2025-08-19T08:14:30.300370Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Aug 19 08:14:30.300482 waagent[2187]: 2025-08-19T08:14:30.300442Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Aug 19 08:14:30.300550 waagent[2187]: 2025-08-19T08:14:30.300512Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:30.300855 waagent[2187]: 2025-08-19T08:14:30.300811Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Aug 19 08:14:30.301436 waagent[2187]: 2025-08-19T08:14:30.301378Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Aug 19 08:14:30.301608 waagent[2187]: 2025-08-19T08:14:30.301554Z INFO EnvHandler ExtHandler Configure routes Aug 19 08:14:30.301699 waagent[2187]: 2025-08-19T08:14:30.301655Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Aug 19 08:14:30.301991 waagent[2187]: 2025-08-19T08:14:30.301951Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Aug 19 08:14:30.301991 waagent[2187]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Aug 19 08:14:30.301991 waagent[2187]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Aug 19 08:14:30.301991 waagent[2187]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Aug 19 08:14:30.301991 waagent[2187]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.301991 waagent[2187]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.301991 waagent[2187]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.302158 waagent[2187]: 2025-08-19T08:14:30.302023Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Aug 19 08:14:30.302158 waagent[2187]: 2025-08-19T08:14:30.302089Z INFO EnvHandler ExtHandler Gateway:None Aug 19 08:14:30.302673 waagent[2187]: 2025-08-19T08:14:30.302638Z INFO EnvHandler ExtHandler Routes:None Aug 19 08:14:30.311075 waagent[2187]: 2025-08-19T08:14:30.311024Z INFO ExtHandler ExtHandler Aug 19 08:14:30.311170 waagent[2187]: 2025-08-19T08:14:30.311132Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: fb62046f-bae0-489f-abb2-9040c20f3d6d correlation e37800da-dd91-4bad-81d3-c284745ac338 created: 2025-08-19T08:12:34.377266Z] Aug 19 08:14:30.311510 waagent[2187]: 2025-08-19T08:14:30.311468Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Aug 19 08:14:30.312088 waagent[2187]: 2025-08-19T08:14:30.312049Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Aug 19 08:14:30.375456 waagent[2187]: 2025-08-19T08:14:30.375409Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Aug 19 08:14:30.375456 waagent[2187]: Try `iptables -h' or 'iptables --help' for more information.) Aug 19 08:14:30.375827 waagent[2187]: 2025-08-19T08:14:30.375789Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 7259BAD4-2984-41E3-89B0-EF518DD2D208;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Aug 19 08:14:30.409296 waagent[2187]: 2025-08-19T08:14:30.409246Z INFO MonitorHandler ExtHandler Network interfaces: Aug 19 08:14:30.409296 waagent[2187]: Executing ['ip', '-a', '-o', 'link']: Aug 19 08:14:30.409296 waagent[2187]: 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 Aug 19 08:14:30.409296 waagent[2187]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:4a:b5:5c brd ff:ff:ff:ff:ff:ff Aug 19 08:14:30.409296 waagent[2187]: Executing ['ip', '-4', '-a', '-o', 'address']: Aug 19 08:14:30.409296 waagent[2187]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Aug 19 08:14:30.409296 waagent[2187]: 2: eth0 inet 10.200.8.20/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Aug 19 08:14:30.409296 waagent[2187]: Executing ['ip', '-6', '-a', '-o', 'address']: Aug 19 08:14:30.409296 waagent[2187]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Aug 19 08:14:30.409296 waagent[2187]: 2: eth0 inet6 fe80::7eed:8dff:fe4a:b55c/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Aug 19 08:14:30.528303 waagent[2187]: 2025-08-19T08:14:30.528244Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Aug 19 08:14:30.528303 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.528303 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.528303 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.528303 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.528303 waagent[2187]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.528303 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.528303 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 19 08:14:30.528303 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 19 08:14:30.528303 waagent[2187]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 19 08:14:30.531925 waagent[2187]: 2025-08-19T08:14:30.531884Z INFO EnvHandler ExtHandler Current Firewall rules: Aug 19 08:14:30.531925 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.531925 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.531925 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.531925 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.531925 waagent[2187]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.531925 waagent[2187]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.531925 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 19 08:14:30.531925 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 19 08:14:30.531925 waagent[2187]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 19 08:14:30.532213 waagent[2187]: 2025-08-19T08:14:30.532176Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Aug 19 08:14:30.532363 waagent[2187]: 2025-08-19T08:14:30.532332Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Aug 19 08:14:40.850851 setup-nvidia[2038]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 476M 0 688k 0 0 533k 0 0:15:13 0:00:01 0:15:12 533k\u000d 4 476M 4 19.9M 0 0 8971k 0 0:00:54 0:00:02 0:00:52 8970k\u000d 9 476M 9 43.0M 0 0 13.1M 0 0:00:36 0:00:03 0:00:33 13.1M\u000d 13 476M 13 65.6M 0 0 15.4M 0 0:00:30 0:00:04 0:00:26 15.4M\u000d 18 476M 18 87.6M 0 0 16.7M 0 0:00:28 0:00:05 0:00:23 17.8M\u000d 23 476M 23 112M 0 0 18.0M 0 0:00:26 0:00:06 0:00:20 22.6M\u000d 28 476M 28 135M 0 0 18.7M 0 0:00:25 0:00:07 0:00:18 23.3M\u000d 32 476M 32 156M 0 0 19.0M 0 0:00:25 0:00:08 0:00:17 22.8M\u000d 37 476M 37 179M 0 0 19.4M 0 0:00:24 0:00:09 0:00:15 22.8M\u000d 42 476M 42 203M 0 0 19.9M 0 0:00:23 0:00:10 0:00:13 23.2M\u000d 48 476M 48 229M 0 0 20.4M 0 0:00:23 0:00:11 0:00:12 23.4M\u000d 51 476M 51 247M 0 0 20.1M 0 0:00:23 0:00:12 0:00:11 22.1M\u000d 54 476M 54 257M 0 0 19.4M 0 0:00:24 0:00:13 0:00:11 20.0M\u000d 58 476M 58 280M 0 0 19.6M 0 0:00:24 0:00:14 0:00:10 20.0M\u000d 63 476M 63 303M 0 0 19.8M 0 0:00:23 0:00:15 0:00:08 19.8M\u000d 68 476M 68 327M 0 0 20.1M 0 0:00:23 0:00:16 0:00:07 19.4M\u000d 73 476M 73 350M 0 0 20.3M 0 0:00:23 0:00:17 0:00:06 20.7M\u000d 78 476M 78 373M 0 0 20.4M 0 0:00:23 0:00:18 0:00:05 23.2M\u000d 83 476M 83 398M 0 0 20.6M 0 0:00:23 0:00:19 0:00:04 23.6M\u000d 88 476M 88 423M 0 0 20.8M 0 0:00:22 0:00:20 0:00:02 23.9M\u000d 94 476M 94 448M 0 0 21.0M 0 0:00:22 0:00:21 0:00:01 24.2M\u000d 99 476M 99 474M 0 0 21.2M 0 0:00:22 0:00:22 --:--:-- 24.5M\u000d100 476M 100 476M 0 0 21.3M 0 0:00:22 0:00:22 --:--:-- 25.4M Aug 19 08:14:40.924813 chronyd[1862]: Selected source PHC0 Aug 19 08:14:50.433843 setup-nvidia[1875]: Downloading NVIDIA 535.247.01 Driver Aug 19 08:14:50.438195 setup-nvidia[2345]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 19 08:14:50.438421 setup-nvidia[2345]: Dload Upload Total Spent Left Speed Aug 19 08:14:51.833306 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 19 08:14:51.836088 systemd[1]: Started sshd@0-10.200.8.20:22-10.200.16.10:48346.service - OpenSSH per-connection server daemon (10.200.16.10:48346). Aug 19 08:14:52.352439 setup-nvidia[2345]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 6 326M 6 20.8M 0 0 60.3M 0 0:00:05 --:--:-- 0:00:05 60.2M\u000d 61 326M 61 201M 0 0 149M 0 0:00:02 0:00:01 0:00:01 149M\u000d100 326M 100 326M 0 0 170M 0 0:00:01 0:00:01 --:--:-- 170M Aug 19 08:14:52.435624 setup-nvidia[1875]: Extract the NVIDIA Driver Installer 535.247.01 Aug 19 08:14:52.435624 setup-nvidia[1875]: /opt/nvidia/workdir/nvidia-workdir / Aug 19 08:14:52.476115 setup-nvidia[2352]: Creating directory NVIDIA-Linux-x86_64-535.247.01 Aug 19 08:14:52.626600 sshd[2347]: Accepted publickey for core from 10.200.16.10 port 48346 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:52.627856 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:52.632033 systemd-logind[1884]: New session 3 of user core. Aug 19 08:14:52.638192 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 19 08:14:52.722850 setup-nvidia[2352]: Verifying archive integrity... OK Aug 19 08:14:53.221391 systemd[1]: Started sshd@1-10.200.8.20:22-10.200.16.10:48356.service - OpenSSH per-connection server daemon (10.200.16.10:48356). Aug 19 08:14:53.505287 setup-nvidia[2352]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.247.01 Aug 19 08:14:53.912548 sshd[2378]: Accepted publickey for core from 10.200.16.10 port 48356 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:53.913900 sshd-session[2378]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:53.918177 systemd-logind[1884]: New session 4 of user core. Aug 19 08:14:53.934299 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 19 08:14:54.385400 sshd[2388]: Connection closed by 10.200.16.10 port 48356 Aug 19 08:14:54.385822 sshd-session[2378]: pam_unix(sshd:session): session closed for user core Aug 19 08:14:54.389572 systemd[1]: sshd@1-10.200.8.20:22-10.200.16.10:48356.service: Deactivated successfully. Aug 19 08:14:54.391165 systemd[1]: session-4.scope: Deactivated successfully. Aug 19 08:14:54.391875 systemd-logind[1884]: Session 4 logged out. Waiting for processes to exit. Aug 19 08:14:54.393001 systemd-logind[1884]: Removed session 4. Aug 19 08:14:54.513487 systemd[1]: Started sshd@2-10.200.8.20:22-10.200.16.10:48362.service - OpenSSH per-connection server daemon (10.200.16.10:48362). Aug 19 08:14:55.183719 sshd[2394]: Accepted publickey for core from 10.200.16.10 port 48362 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:55.185202 sshd-session[2394]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:55.190413 systemd-logind[1884]: New session 5 of user core. Aug 19 08:14:55.197264 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 19 08:14:55.264572 setup-nvidia[2384]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Aug 19 08:14:55.333754 setup-nvidia[1875]: / Aug 19 08:14:55.333971 setup-nvidia[1875]: Spawn system-nspawn container to install the NVIDIA drivers Aug 19 08:14:55.439236 sudo[2401]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4426.0.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.247.01 Aug 19 08:14:55.440955 sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 19 08:14:55.446487 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Aug 19 08:14:55.449049 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 19 08:14:55.475118 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 19 08:14:55.478668 systemd[1]: Starting user@0.service - User Manager for UID 0... Aug 19 08:14:55.496046 (systemd)[2404]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Aug 19 08:14:55.498200 systemd-logind[1884]: New session c3 of user root. Aug 19 08:14:55.655628 sshd[2397]: Connection closed by 10.200.16.10 port 48362 Aug 19 08:14:55.658840 systemd[1]: sshd@2-10.200.8.20:22-10.200.16.10:48362.service: Deactivated successfully. Aug 19 08:14:55.655978 sshd-session[2394]: pam_unix(sshd:session): session closed for user core Aug 19 08:14:55.660522 systemd[1]: session-5.scope: Deactivated successfully. Aug 19 08:14:55.661848 systemd-logind[1884]: Session 5 logged out. Waiting for processes to exit. Aug 19 08:14:55.662840 systemd-logind[1884]: Removed session 5. Aug 19 08:14:55.677903 systemd[2404]: Queued start job for default target default.target. Aug 19 08:14:55.697130 systemd[2404]: Created slice app.slice - User Application Slice. Aug 19 08:14:55.697159 systemd[2404]: Reached target paths.target - Paths. Aug 19 08:14:55.697203 systemd[2404]: Reached target timers.target - Timers. Aug 19 08:14:55.698293 systemd[2404]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 19 08:14:55.708469 systemd[2404]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:14:55.708526 systemd[2404]: Reached target sockets.target - Sockets. Aug 19 08:14:55.708575 systemd[2404]: Reached target basic.target - Basic System. Aug 19 08:14:55.708618 systemd[2404]: Reached target default.target - Main User Target. Aug 19 08:14:55.708649 systemd[2404]: Startup finished in 204ms. Aug 19 08:14:55.708676 systemd[1]: Started user@0.service - User Manager for UID 0. Aug 19 08:14:55.711734 systemd[1]: Started session-c2.scope - Session c2 of User root. Aug 19 08:14:55.786111 kernel: loop6: detected capacity change from 0 to 12644352 Aug 19 08:14:55.796100 kernel: loop6: p9 Aug 19 08:14:55.923468 setup-nvidia[2417]: ░ Spawning container flatcardevelopercontainer-4426.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4426.0.0.bin. Aug 19 08:14:56.269059 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Aug 19 08:14:57.727006 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Aug 19 08:14:57.727143 kernel: EXT4-fs (loop6p9): mounted filesystem 1c457d39-febc-4c4d-8be4-692431dfc416 ro without journal. Quota mode: none. Aug 19 08:15:02.649207 update_engine[1887]: I20250819 08:15:02.649125 1887 update_attempter.cc:509] Updating boot flags... Aug 19 08:15:05.767297 systemd[1]: Started sshd@3-10.200.8.20:22-10.200.16.10:50414.service - OpenSSH per-connection server daemon (10.200.16.10:50414). Aug 19 08:15:06.409928 sshd[2597]: Accepted publickey for core from 10.200.16.10 port 50414 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:06.411231 sshd-session[2597]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:06.415427 systemd-logind[1884]: New session 6 of user core. Aug 19 08:15:06.435203 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 19 08:15:06.866405 sshd[2602]: Connection closed by 10.200.16.10 port 50414 Aug 19 08:15:06.866767 sshd-session[2597]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:06.870453 systemd[1]: sshd@3-10.200.8.20:22-10.200.16.10:50414.service: Deactivated successfully. Aug 19 08:15:06.872059 systemd[1]: session-6.scope: Deactivated successfully. Aug 19 08:15:06.872779 systemd-logind[1884]: Session 6 logged out. Waiting for processes to exit. Aug 19 08:15:06.873667 systemd-logind[1884]: Removed session 6. Aug 19 08:15:06.982948 systemd[1]: Started sshd@4-10.200.8.20:22-10.200.16.10:50424.service - OpenSSH per-connection server daemon (10.200.16.10:50424). Aug 19 08:15:07.624095 sshd[2655]: Accepted publickey for core from 10.200.16.10 port 50424 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:07.625378 sshd-session[2655]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:07.629479 systemd-logind[1884]: New session 7 of user core. Aug 19 08:15:07.644194 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 19 08:15:08.075898 sshd[2747]: Connection closed by 10.200.16.10 port 50424 Aug 19 08:15:08.076248 sshd-session[2655]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:08.079877 systemd[1]: sshd@4-10.200.8.20:22-10.200.16.10:50424.service: Deactivated successfully. Aug 19 08:15:08.081469 systemd[1]: session-7.scope: Deactivated successfully. Aug 19 08:15:08.082165 systemd-logind[1884]: Session 7 logged out. Waiting for processes to exit. Aug 19 08:15:08.083103 systemd-logind[1884]: Removed session 7. Aug 19 08:15:18.193418 systemd[1]: Started sshd@5-10.200.8.20:22-10.200.16.10:60290.service - OpenSSH per-connection server daemon (10.200.16.10:60290). Aug 19 08:15:18.834488 sshd[3276]: Accepted publickey for core from 10.200.16.10 port 60290 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:18.835767 sshd-session[3276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:18.839911 systemd-logind[1884]: New session 8 of user core. Aug 19 08:15:18.848206 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 19 08:15:19.296214 sshd[3279]: Connection closed by 10.200.16.10 port 60290 Aug 19 08:15:19.296558 sshd-session[3276]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:19.299976 systemd[1]: sshd@5-10.200.8.20:22-10.200.16.10:60290.service: Deactivated successfully. Aug 19 08:15:19.301542 systemd[1]: session-8.scope: Deactivated successfully. Aug 19 08:15:19.302254 systemd-logind[1884]: Session 8 logged out. Waiting for processes to exit. Aug 19 08:15:19.303139 systemd-logind[1884]: Removed session 8. Aug 19 08:15:19.413434 systemd[1]: Started sshd@6-10.200.8.20:22-10.200.16.10:60292.service - OpenSSH per-connection server daemon (10.200.16.10:60292). Aug 19 08:15:20.055194 sshd[3285]: Accepted publickey for core from 10.200.16.10 port 60292 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:20.056431 sshd-session[3285]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:20.066859 systemd-logind[1884]: New session 9 of user core. Aug 19 08:15:20.086196 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 19 08:15:20.507388 sshd[3310]: Connection closed by 10.200.16.10 port 60292 Aug 19 08:15:20.507674 sshd-session[3285]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:20.511061 systemd[1]: sshd@6-10.200.8.20:22-10.200.16.10:60292.service: Deactivated successfully. Aug 19 08:15:20.512817 systemd[1]: session-9.scope: Deactivated successfully. Aug 19 08:15:20.513501 systemd-logind[1884]: Session 9 logged out. Waiting for processes to exit. Aug 19 08:15:20.514407 systemd-logind[1884]: Removed session 9. Aug 19 08:15:30.624432 systemd[1]: Started sshd@7-10.200.8.20:22-10.200.16.10:32864.service - OpenSSH per-connection server daemon (10.200.16.10:32864). Aug 19 08:15:31.265590 sshd[3435]: Accepted publickey for core from 10.200.16.10 port 32864 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:31.266866 sshd-session[3435]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:31.271804 systemd-logind[1884]: New session 10 of user core. Aug 19 08:15:31.287209 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 19 08:15:31.720165 sshd[3438]: Connection closed by 10.200.16.10 port 32864 Aug 19 08:15:31.720489 sshd-session[3435]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:31.724012 systemd[1]: sshd@7-10.200.8.20:22-10.200.16.10:32864.service: Deactivated successfully. Aug 19 08:15:31.725579 systemd[1]: session-10.scope: Deactivated successfully. Aug 19 08:15:31.726387 systemd-logind[1884]: Session 10 logged out. Waiting for processes to exit. Aug 19 08:15:31.727458 systemd-logind[1884]: Removed session 10. Aug 19 08:15:31.846273 systemd[1]: Started sshd@8-10.200.8.20:22-10.200.16.10:32880.service - OpenSSH per-connection server daemon (10.200.16.10:32880). Aug 19 08:15:32.492841 sshd[3445]: Accepted publickey for core from 10.200.16.10 port 32880 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:32.494249 sshd-session[3445]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:32.498352 systemd-logind[1884]: New session 11 of user core. Aug 19 08:15:32.512207 systemd[1]: Started session-11.scope - Session 11 of User core. Aug 19 08:15:32.948298 sshd[3473]: Connection closed by 10.200.16.10 port 32880 Aug 19 08:15:32.948655 sshd-session[3445]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:32.952260 systemd[1]: sshd@8-10.200.8.20:22-10.200.16.10:32880.service: Deactivated successfully. Aug 19 08:15:32.953848 systemd[1]: session-11.scope: Deactivated successfully. Aug 19 08:15:32.954625 systemd-logind[1884]: Session 11 logged out. Waiting for processes to exit. Aug 19 08:15:32.955581 systemd-logind[1884]: Removed session 11. Aug 19 08:15:43.068491 systemd[1]: Started sshd@9-10.200.8.20:22-10.200.16.10:47578.service - OpenSSH per-connection server daemon (10.200.16.10:47578). Aug 19 08:15:43.721161 sshd[7020]: Accepted publickey for core from 10.200.16.10 port 47578 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:43.722905 sshd-session[7020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:43.733701 systemd-logind[1884]: New session 12 of user core. Aug 19 08:15:43.737539 systemd[1]: Started session-12.scope - Session 12 of User core. Aug 19 08:15:44.175274 sshd[7227]: Connection closed by 10.200.16.10 port 47578 Aug 19 08:15:44.177455 sshd-session[7020]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:44.182975 systemd-logind[1884]: Session 12 logged out. Waiting for processes to exit. Aug 19 08:15:44.186560 systemd[1]: sshd@9-10.200.8.20:22-10.200.16.10:47578.service: Deactivated successfully. Aug 19 08:15:44.189009 systemd[1]: session-12.scope: Deactivated successfully. Aug 19 08:15:44.192844 systemd-logind[1884]: Removed session 12. Aug 19 08:15:44.295327 systemd[1]: Started sshd@10-10.200.8.20:22-10.200.16.10:47590.service - OpenSSH per-connection server daemon (10.200.16.10:47590). Aug 19 08:15:44.954913 sshd[7422]: Accepted publickey for core from 10.200.16.10 port 47590 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:44.956641 sshd-session[7422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:44.961604 systemd-logind[1884]: New session 13 of user core. Aug 19 08:15:44.970761 systemd[1]: Started session-13.scope - Session 13 of User core. Aug 19 08:15:45.409871 sshd[7704]: Connection closed by 10.200.16.10 port 47590 Aug 19 08:15:45.410679 sshd-session[7422]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:45.415816 systemd[1]: sshd@10-10.200.8.20:22-10.200.16.10:47590.service: Deactivated successfully. Aug 19 08:15:45.418537 systemd[1]: session-13.scope: Deactivated successfully. Aug 19 08:15:45.419831 systemd-logind[1884]: Session 13 logged out. Waiting for processes to exit. Aug 19 08:15:45.421280 systemd-logind[1884]: Removed session 13. Aug 19 08:15:55.530396 systemd[1]: Started sshd@11-10.200.8.20:22-10.200.16.10:48840.service - OpenSSH per-connection server daemon (10.200.16.10:48840). Aug 19 08:15:56.187605 sshd[11483]: Accepted publickey for core from 10.200.16.10 port 48840 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:56.189043 sshd-session[11483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:56.193242 systemd-logind[1884]: New session 14 of user core. Aug 19 08:15:56.208195 systemd[1]: Started session-14.scope - Session 14 of User core. Aug 19 08:15:56.652852 sshd[11606]: Connection closed by 10.200.16.10 port 48840 Aug 19 08:15:56.654270 sshd-session[11483]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:56.661040 systemd-logind[1884]: Session 14 logged out. Waiting for processes to exit. Aug 19 08:15:56.661920 systemd[1]: sshd@11-10.200.8.20:22-10.200.16.10:48840.service: Deactivated successfully. Aug 19 08:15:56.663865 systemd[1]: session-14.scope: Deactivated successfully. Aug 19 08:15:56.666640 systemd-logind[1884]: Removed session 14. Aug 19 08:15:56.770313 systemd[1]: Started sshd@12-10.200.8.20:22-10.200.16.10:48842.service - OpenSSH per-connection server daemon (10.200.16.10:48842). Aug 19 08:15:57.437101 sshd[11637]: Accepted publickey for core from 10.200.16.10 port 48842 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:57.438669 sshd-session[11637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:57.444592 systemd-logind[1884]: New session 15 of user core. Aug 19 08:15:57.451226 systemd[1]: Started session-15.scope - Session 15 of User core. Aug 19 08:15:57.896659 sshd[11659]: Connection closed by 10.200.16.10 port 48842 Aug 19 08:15:57.897077 sshd-session[11637]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:57.900126 systemd[1]: sshd@12-10.200.8.20:22-10.200.16.10:48842.service: Deactivated successfully. Aug 19 08:15:57.902049 systemd[1]: session-15.scope: Deactivated successfully. Aug 19 08:15:57.903408 systemd-logind[1884]: Session 15 logged out. Waiting for processes to exit. Aug 19 08:15:57.905652 systemd-logind[1884]: Removed session 15. Aug 19 08:16:08.015305 systemd[1]: Started sshd@13-10.200.8.20:22-10.200.16.10:33160.service - OpenSSH per-connection server daemon (10.200.16.10:33160). Aug 19 08:16:08.688094 sshd[12097]: Accepted publickey for core from 10.200.16.10 port 33160 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:08.689587 sshd-session[12097]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:08.699421 systemd-logind[1884]: New session 16 of user core. Aug 19 08:16:08.705238 systemd[1]: Started session-16.scope - Session 16 of User core. Aug 19 08:16:09.142166 sshd[12117]: Connection closed by 10.200.16.10 port 33160 Aug 19 08:16:09.142564 sshd-session[12097]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:09.146633 systemd[1]: sshd@13-10.200.8.20:22-10.200.16.10:33160.service: Deactivated successfully. Aug 19 08:16:09.148900 systemd[1]: session-16.scope: Deactivated successfully. Aug 19 08:16:09.151334 systemd-logind[1884]: Session 16 logged out. Waiting for processes to exit. Aug 19 08:16:09.152770 systemd-logind[1884]: Removed session 16. Aug 19 08:16:09.272879 systemd[1]: Started sshd@14-10.200.8.20:22-10.200.16.10:33172.service - OpenSSH per-connection server daemon (10.200.16.10:33172). Aug 19 08:16:09.929112 sshd[12136]: Accepted publickey for core from 10.200.16.10 port 33172 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:09.932004 sshd-session[12136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:09.938280 systemd-logind[1884]: New session 17 of user core. Aug 19 08:16:09.945169 systemd[1]: Started session-17.scope - Session 17 of User core. Aug 19 08:16:10.383071 sshd[12162]: Connection closed by 10.200.16.10 port 33172 Aug 19 08:16:10.383446 sshd-session[12136]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:10.386887 systemd-logind[1884]: Session 17 logged out. Waiting for processes to exit. Aug 19 08:16:10.387944 systemd[1]: sshd@14-10.200.8.20:22-10.200.16.10:33172.service: Deactivated successfully. Aug 19 08:16:10.390037 systemd[1]: session-17.scope: Deactivated successfully. Aug 19 08:16:10.393177 systemd-logind[1884]: Removed session 17. Aug 19 08:16:20.500824 systemd[1]: Started sshd@15-10.200.8.20:22-10.200.16.10:46394.service - OpenSSH per-connection server daemon (10.200.16.10:46394). Aug 19 08:16:21.159292 sshd[12522]: Accepted publickey for core from 10.200.16.10 port 46394 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:21.160754 sshd-session[12522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:21.168053 systemd-logind[1884]: New session 18 of user core. Aug 19 08:16:21.179269 systemd[1]: Started session-18.scope - Session 18 of User core. Aug 19 08:16:21.615506 sshd[12537]: Connection closed by 10.200.16.10 port 46394 Aug 19 08:16:21.617283 sshd-session[12522]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:21.620937 systemd[1]: sshd@15-10.200.8.20:22-10.200.16.10:46394.service: Deactivated successfully. Aug 19 08:16:21.622527 systemd[1]: session-18.scope: Deactivated successfully. Aug 19 08:16:21.625999 systemd-logind[1884]: Session 18 logged out. Waiting for processes to exit. Aug 19 08:16:21.627441 systemd-logind[1884]: Removed session 18. Aug 19 08:16:21.736446 systemd[1]: Started sshd@16-10.200.8.20:22-10.200.16.10:46410.service - OpenSSH per-connection server daemon (10.200.16.10:46410). Aug 19 08:16:22.393984 sshd[12555]: Accepted publickey for core from 10.200.16.10 port 46410 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:22.395462 sshd-session[12555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:22.401622 systemd-logind[1884]: New session 19 of user core. Aug 19 08:16:22.411794 systemd[1]: Started session-19.scope - Session 19 of User core. Aug 19 08:16:22.849208 sshd[12576]: Connection closed by 10.200.16.10 port 46410 Aug 19 08:16:22.853259 sshd-session[12555]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:22.858056 systemd[1]: sshd@16-10.200.8.20:22-10.200.16.10:46410.service: Deactivated successfully. Aug 19 08:16:22.859692 systemd[1]: session-19.scope: Deactivated successfully. Aug 19 08:16:22.862045 systemd-logind[1884]: Session 19 logged out. Waiting for processes to exit. Aug 19 08:16:22.863679 systemd-logind[1884]: Removed session 19. Aug 19 08:16:32.970438 systemd[1]: Started sshd@17-10.200.8.20:22-10.200.16.10:34894.service - OpenSSH per-connection server daemon (10.200.16.10:34894). Aug 19 08:16:33.610273 sshd[12807]: Accepted publickey for core from 10.200.16.10 port 34894 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:33.611604 sshd-session[12807]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:33.615772 systemd-logind[1884]: New session 20 of user core. Aug 19 08:16:33.630227 systemd[1]: Started session-20.scope - Session 20 of User core. Aug 19 08:16:34.063198 sshd[12810]: Connection closed by 10.200.16.10 port 34894 Aug 19 08:16:34.063574 sshd-session[12807]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:34.066935 systemd[1]: sshd@17-10.200.8.20:22-10.200.16.10:34894.service: Deactivated successfully. Aug 19 08:16:34.068604 systemd[1]: session-20.scope: Deactivated successfully. Aug 19 08:16:34.069795 systemd-logind[1884]: Session 20 logged out. Waiting for processes to exit. Aug 19 08:16:34.070866 systemd-logind[1884]: Removed session 20. Aug 19 08:16:34.187356 systemd[1]: Started sshd@18-10.200.8.20:22-10.200.16.10:34898.service - OpenSSH per-connection server daemon (10.200.16.10:34898). Aug 19 08:16:34.829597 sshd[12819]: Accepted publickey for core from 10.200.16.10 port 34898 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:34.830901 sshd-session[12819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:34.835033 systemd-logind[1884]: New session 21 of user core. Aug 19 08:16:34.846201 systemd[1]: Started session-21.scope - Session 21 of User core. Aug 19 08:16:35.282724 sshd[12851]: Connection closed by 10.200.16.10 port 34898 Aug 19 08:16:35.283066 sshd-session[12819]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:35.286525 systemd[1]: sshd@18-10.200.8.20:22-10.200.16.10:34898.service: Deactivated successfully. Aug 19 08:16:35.288108 systemd[1]: session-21.scope: Deactivated successfully. Aug 19 08:16:35.288778 systemd-logind[1884]: Session 21 logged out. Waiting for processes to exit. Aug 19 08:16:35.289670 systemd-logind[1884]: Removed session 21. Aug 19 08:16:36.757259 setup-nvidia[2417]: \u000d Aug 19 08:16:36.757259 setup-nvidia[2417]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Aug 19 08:16:36.757259 setup-nvidia[2417]: frequently when this kernel module was built against the wrong or Aug 19 08:16:36.757259 setup-nvidia[2417]: improperly configured kernel sources, with a version of gcc that differs Aug 19 08:16:36.757259 setup-nvidia[2417]: from the one used to build the target kernel, or if another driver, such Aug 19 08:16:36.757259 setup-nvidia[2417]: as nouveau, is present and prevents the NVIDIA kernel module from Aug 19 08:16:36.757259 setup-nvidia[2417]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Aug 19 08:16:36.757259 setup-nvidia[2417]: installed in this system is supported by this NVIDIA Linux graphics Aug 19 08:16:36.757259 setup-nvidia[2417]: driver release. Aug 19 08:16:36.757259 setup-nvidia[2417]: \u000d Aug 19 08:16:36.757259 setup-nvidia[2417]: Please see the log entries 'Kernel module load error' and 'Kernel Aug 19 08:16:36.757259 setup-nvidia[2417]: messages' at the end of the file Aug 19 08:16:36.757259 setup-nvidia[2417]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more Aug 19 08:16:36.757259 setup-nvidia[2417]: information. Aug 19 08:16:36.757259 setup-nvidia[2417]: \u000d Aug 19 08:16:36.771602 setup-nvidia[2417]: dmesg: read kernel buffer failed: Operation not permitted Aug 19 08:16:36.790049 setup-nvidia[2417]: \u000d Aug 19 08:16:36.790049 setup-nvidia[2417]: ERROR: Installation has failed. Please see the file Aug 19 08:16:36.790049 setup-nvidia[2417]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for Aug 19 08:16:36.790049 setup-nvidia[2417]: details. You may find suggestions on fixing installation problems in Aug 19 08:16:36.790049 setup-nvidia[2417]: the README available on the Linux driver download page at Aug 19 08:16:36.790049 setup-nvidia[2417]: www.nvidia.com. Aug 19 08:16:36.790049 setup-nvidia[2417]: \u000d Aug 19 08:16:36.790749 setup-nvidia[2417]: Last 50 lines of nvidia-installer.log: Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039045rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039042rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039035rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039091rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039087rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039086rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039088rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039032rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030603rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030602rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030606rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030605rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030604rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030607rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030609rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039399rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:36.791546 setup-nvidia[2417]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/Module.symvers Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.mod.o Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/.module-common.o Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.mod.o Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.mod.o Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.mod.o Aug 19 08:16:36.791546 setup-nvidia[2417]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.mod.o Aug 19 08:16:36.791546 setup-nvidia[2417]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Aug 19 08:16:36.791546 setup-nvidia[2417]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Aug 19 08:16:36.791546 setup-nvidia[2417]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko due to unavailability of vmlinux Aug 19 08:16:36.792184 setup-nvidia[2417]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko due to unavailability of vmlinux Aug 19 08:16:36.792184 setup-nvidia[2417]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko due to unavailability of vmlinux Aug 19 08:16:36.792184 setup-nvidia[2417]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko due to unavailability of vmlinux Aug 19 08:16:36.792184 setup-nvidia[2417]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Aug 19 08:16:36.792184 setup-nvidia[2417]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko due to unavailability of vmlinux Aug 19 08:16:36.792184 setup-nvidia[2417]: make[2]: Leaving directory '/usr/lib/modules/6.12.41-flatcar/build' Aug 19 08:16:36.792184 setup-nvidia[2417]: make[1]: Leaving directory '/usr/lib/modules/6.12.41-flatcar/source' Aug 19 08:16:36.792184 setup-nvidia[2417]: -> done. Aug 19 08:16:36.792184 setup-nvidia[2417]: -> Kernel module compilation complete. Aug 19 08:16:36.792184 setup-nvidia[2417]: -> Unable to determine if Secure Boot is enabled: No such file or directory Aug 19 08:16:36.792184 setup-nvidia[2417]: 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. Aug 19 08:16:36.792184 setup-nvidia[2417]: \u000d Aug 19 08:16:36.792184 setup-nvidia[2417]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more information. Aug 19 08:16:36.792184 setup-nvidia[2417]: -> Kernel module load error: Operation not permitted Aug 19 08:16:36.792184 setup-nvidia[2417]: -> Kernel messages: Aug 19 08:16:36.792184 setup-nvidia[2417]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/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. Aug 19 08:16:45.409124 systemd[1]: Started sshd@19-10.200.8.20:22-10.200.16.10:34460.service - OpenSSH per-connection server daemon (10.200.16.10:34460). Aug 19 08:16:46.049661 sshd[13005]: Accepted publickey for core from 10.200.16.10 port 34460 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:46.050931 sshd-session[13005]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:46.055293 systemd-logind[1884]: New session 22 of user core. Aug 19 08:16:46.070202 systemd[1]: Started session-22.scope - Session 22 of User core. Aug 19 08:16:46.500954 sshd[13008]: Connection closed by 10.200.16.10 port 34460 Aug 19 08:16:46.501232 sshd-session[13005]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:46.504677 systemd[1]: sshd@19-10.200.8.20:22-10.200.16.10:34460.service: Deactivated successfully. Aug 19 08:16:46.506547 systemd[1]: session-22.scope: Deactivated successfully. Aug 19 08:16:46.507394 systemd-logind[1884]: Session 22 logged out. Waiting for processes to exit. Aug 19 08:16:46.508298 systemd-logind[1884]: Removed session 22. Aug 19 08:16:46.620382 systemd[1]: Started sshd@20-10.200.8.20:22-10.200.16.10:34468.service - OpenSSH per-connection server daemon (10.200.16.10:34468). Aug 19 08:16:47.261747 sshd[13014]: Accepted publickey for core from 10.200.16.10 port 34468 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:47.263045 sshd-session[13014]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:47.267263 systemd-logind[1884]: New session 23 of user core. Aug 19 08:16:47.280206 systemd[1]: Started session-23.scope - Session 23 of User core. Aug 19 08:16:47.715257 sshd[13017]: Connection closed by 10.200.16.10 port 34468 Aug 19 08:16:47.715595 sshd-session[13014]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:47.718917 systemd[1]: sshd@20-10.200.8.20:22-10.200.16.10:34468.service: Deactivated successfully. Aug 19 08:16:47.720440 systemd[1]: session-23.scope: Deactivated successfully. Aug 19 08:16:47.721134 systemd-logind[1884]: Session 23 logged out. Waiting for processes to exit. Aug 19 08:16:47.721991 systemd-logind[1884]: Removed session 23. Aug 19 08:16:57.837438 systemd[1]: Started sshd@21-10.200.8.20:22-10.200.16.10:35686.service - OpenSSH per-connection server daemon (10.200.16.10:35686). Aug 19 08:16:58.478179 kernel: EXT4-fs (loop6p9): unmounting filesystem 1c457d39-febc-4c4d-8be4-692431dfc416. Aug 19 08:16:58.478768 setup-nvidia[2417]: Container flatcardevelopercontainer-4426.0.0.bin exited successfully. Aug 19 08:16:58.481021 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4426.0.0.bin.mount: Deactivated successfully. Aug 19 08:16:58.486291 sshd[13024]: Accepted publickey for core from 10.200.16.10 port 35686 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:58.487558 sshd-session[13024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:58.491475 systemd-logind[1884]: New session 24 of user core. Aug 19 08:16:58.511210 systemd[1]: Started session-24.scope - Session 24 of User core. Aug 19 08:16:58.531846 sudo[2401]: pam_unix(sudo:session): session closed for user root Aug 19 08:16:58.533682 systemd[1]: session-c2.scope: Deactivated successfully. Aug 19 08:16:58.533932 systemd[1]: session-c2.scope: Consumed 5min 7.876s CPU time, 888.3M memory peak. Aug 19 08:16:58.937235 sshd[13027]: Connection closed by 10.200.16.10 port 35686 Aug 19 08:16:58.937620 sshd-session[13024]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:58.941304 systemd[1]: sshd@21-10.200.8.20:22-10.200.16.10:35686.service: Deactivated successfully. Aug 19 08:16:58.942910 systemd[1]: session-24.scope: Deactivated successfully. Aug 19 08:16:58.943615 systemd-logind[1884]: Session 24 logged out. Waiting for processes to exit. Aug 19 08:16:58.944530 systemd-logind[1884]: Removed session 24. Aug 19 08:16:58.955594 setup-nvidia[1875]: /opt/nvidia / Aug 19 08:16:58.956630 setup-nvidia[1875]: / Aug 19 08:16:58.966106 kernel: IPMI message handler: version 39.2 Aug 19 08:16:58.969100 kernel: ipmi device interface Aug 19 08:16:58.981775 setup-nvidia[1875]: /opt/nvidia/current/usr/lib/modules/6.12.41-flatcar/video / Aug 19 08:16:59.039065 kernel: nvidia: loading out-of-tree module taints kernel. Aug 19 08:16:59.039209 kernel: nvidia: module license 'NVIDIA' taints kernel. Aug 19 08:16:59.039233 kernel: Disabling lock debugging due to kernel taint Aug 19 08:16:59.046025 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Aug 19 08:16:59.046076 kernel: nvidia: module license taints kernel. Aug 19 08:16:59.054615 systemd[1]: Started sshd@22-10.200.8.20:22-10.200.16.10:35694.service - OpenSSH per-connection server daemon (10.200.16.10:35694). Aug 19 08:16:59.432115 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Aug 19 08:16:59.699137 sshd[13058]: Accepted publickey for core from 10.200.16.10 port 35694 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:59.700351 sshd-session[13058]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:59.704524 systemd-logind[1884]: New session 25 of user core. Aug 19 08:16:59.826105 kernel: Aug 19 08:16:59.826229 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Aug 19 08:16:59.837317 systemd[1]: Started session-25.scope - Session 25 of User core. Aug 19 08:17:00.036102 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.247.01 Wed Mar 26 11:50:32 UTC 2025 Aug 19 08:17:00.057096 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.247.01 Wed Mar 26 06:12:31 UTC 2025 Aug 19 08:17:00.078119 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Aug 19 08:17:00.126107 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Aug 19 08:17:00.126224 setup-nvidia[1875]: / Aug 19 08:17:00.141536 setup-nvidia[13072]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 19 08:17:00.199903 sshd[13063]: Connection closed by 10.200.16.10 port 35694 Aug 19 08:17:00.200268 sshd-session[13058]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:00.203724 systemd[1]: sshd@22-10.200.8.20:22-10.200.16.10:35694.service: Deactivated successfully. Aug 19 08:17:00.205341 systemd[1]: session-25.scope: Deactivated successfully. Aug 19 08:17:00.206066 systemd-logind[1884]: Session 25 logged out. Waiting for processes to exit. Aug 19 08:17:00.206952 systemd-logind[1884]: Removed session 25. Aug 19 08:17:01.943941 setup-nvidia[13092]: Tue Aug 19 08:17:01 2025 Aug 19 08:17:01.943941 setup-nvidia[13092]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:01.943941 setup-nvidia[13092]: | NVIDIA-SMI 535.247.01 Driver Version: 535.247.01 CUDA Version: 12.2 | Aug 19 08:17:01.943941 setup-nvidia[13092]: |-----------------------------------------+----------------------+----------------------+ Aug 19 08:17:01.943941 setup-nvidia[13092]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Aug 19 08:17:01.943941 setup-nvidia[13092]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Aug 19 08:17:01.943941 setup-nvidia[13092]: | | | MIG M. | Aug 19 08:17:01.943941 setup-nvidia[13092]: |=========================================+======================+======================| Aug 19 08:17:01.947453 setup-nvidia[13092]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Aug 19 08:17:01.947453 setup-nvidia[13092]: | N/A 27C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Aug 19 08:17:01.947453 setup-nvidia[13092]: | | | N/A | Aug 19 08:17:01.947453 setup-nvidia[13092]: +-----------------------------------------+----------------------+----------------------+ Aug 19 08:17:01.947453 setup-nvidia[13092]: Aug 19 08:17:01.947453 setup-nvidia[13092]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:01.947453 setup-nvidia[13092]: | Processes: | Aug 19 08:17:01.947453 setup-nvidia[13092]: | GPU GI CI PID Type Process name GPU Memory | Aug 19 08:17:01.947453 setup-nvidia[13092]: | ID ID Usage | Aug 19 08:17:01.947453 setup-nvidia[13092]: |=======================================================================================| Aug 19 08:17:01.947732 setup-nvidia[13092]: | No running processes found | Aug 19 08:17:01.947732 setup-nvidia[13092]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:02.316344 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 19 08:17:02.318411 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 19 08:17:02.324648 systemd[1]: Startup finished in 971ms (firmware) + 40.834s (loader) + 3.759s (kernel) + 16.066s (initrd) + 3min 5.849s (userspace) = 4min 7.482s. Aug 19 08:17:08.763781 systemd[1]: Stopping user@0.service - User Manager for UID 0... Aug 19 08:17:08.765875 systemd[2404]: Activating special unit exit.target... Aug 19 08:17:08.765961 systemd[2404]: Stopped target default.target - Main User Target. Aug 19 08:17:08.766002 systemd[2404]: Stopped target basic.target - Basic System. Aug 19 08:17:08.766032 systemd[2404]: Stopped target paths.target - Paths. Aug 19 08:17:08.766057 systemd[2404]: Stopped target sockets.target - Sockets. Aug 19 08:17:08.766098 systemd[2404]: Stopped target timers.target - Timers. Aug 19 08:17:08.766448 systemd[2404]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:17:08.766659 systemd[2404]: Removed slice app.slice - User Application Slice. Aug 19 08:17:08.766689 systemd[2404]: Reached target shutdown.target - Shutdown. Aug 19 08:17:08.766834 systemd[2404]: Finished systemd-exit.service - Exit the Session. Aug 19 08:17:08.766867 systemd[2404]: Reached target exit.target - Exit the Session. Aug 19 08:17:08.778972 (sd-pam)[2405]: pam_unix(systemd-user:session): session closed for user root Aug 19 08:17:08.780232 systemd[1]: user@0.service: Deactivated successfully. Aug 19 08:17:08.780709 systemd[1]: Stopped user@0.service - User Manager for UID 0. Aug 19 08:17:08.783248 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 19 08:17:08.808596 systemd[1]: run-user-0.mount: Deactivated successfully. Aug 19 08:17:08.809565 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Aug 19 08:17:08.809767 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 19 08:17:08.811351 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Aug 19 08:17:08.811443 systemd[1]: user-0.slice: Consumed 5min 8.146s CPU time, 894.5M memory peak. Aug 19 08:17:08.811864 systemd-logind[1884]: Removed session c3. Aug 19 08:17:10.333588 systemd[1]: Started sshd@23-10.200.8.20:22-10.200.16.10:36276.service - OpenSSH per-connection server daemon (10.200.16.10:36276). Aug 19 08:17:10.989919 sshd[13103]: Accepted publickey for core from 10.200.16.10 port 36276 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:10.991239 sshd-session[13103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:10.995514 systemd-logind[1884]: New session 26 of user core. Aug 19 08:17:11.005203 systemd[1]: Started session-26.scope - Session 26 of User core. Aug 19 08:17:11.441240 sshd[13106]: Connection closed by 10.200.16.10 port 36276 Aug 19 08:17:11.441627 sshd-session[13103]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:11.445222 systemd[1]: sshd@23-10.200.8.20:22-10.200.16.10:36276.service: Deactivated successfully. Aug 19 08:17:11.446806 systemd[1]: session-26.scope: Deactivated successfully. Aug 19 08:17:11.447491 systemd-logind[1884]: Session 26 logged out. Waiting for processes to exit. Aug 19 08:17:11.448462 systemd-logind[1884]: Removed session 26. Aug 19 08:17:11.557281 systemd[1]: Started sshd@24-10.200.8.20:22-10.200.16.10:36286.service - OpenSSH per-connection server daemon (10.200.16.10:36286). Aug 19 08:17:12.198006 sshd[13112]: Accepted publickey for core from 10.200.16.10 port 36286 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:12.200865 sshd-session[13112]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:12.204946 systemd-logind[1884]: New session 27 of user core. Aug 19 08:17:12.219201 systemd[1]: Started session-27.scope - Session 27 of User core. Aug 19 08:17:12.646341 sshd[13115]: Connection closed by 10.200.16.10 port 36286 Aug 19 08:17:12.646664 sshd-session[13112]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:12.650128 systemd[1]: sshd@24-10.200.8.20:22-10.200.16.10:36286.service: Deactivated successfully. Aug 19 08:17:12.651689 systemd[1]: session-27.scope: Deactivated successfully. Aug 19 08:17:12.652369 systemd-logind[1884]: Session 27 logged out. Waiting for processes to exit. Aug 19 08:17:12.653324 systemd-logind[1884]: Removed session 27. Aug 19 08:17:12.763109 systemd[1]: Started sshd@25-10.200.8.20:22-10.200.16.10:36292.service - OpenSSH per-connection server daemon (10.200.16.10:36292). Aug 19 08:17:13.425578 sshd[13121]: Accepted publickey for core from 10.200.16.10 port 36292 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:13.426875 sshd-session[13121]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:13.431115 systemd-logind[1884]: New session 28 of user core. Aug 19 08:17:13.441196 systemd[1]: Started session-28.scope - Session 28 of User core. Aug 19 08:17:13.883217 sshd[13124]: Connection closed by 10.200.16.10 port 36292 Aug 19 08:17:13.883570 sshd-session[13121]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:13.886883 systemd[1]: sshd@25-10.200.8.20:22-10.200.16.10:36292.service: Deactivated successfully. Aug 19 08:17:13.888550 systemd[1]: session-28.scope: Deactivated successfully. Aug 19 08:17:13.889255 systemd-logind[1884]: Session 28 logged out. Waiting for processes to exit. Aug 19 08:17:13.890156 systemd-logind[1884]: Removed session 28. Aug 19 08:17:14.002257 systemd[1]: Started sshd@26-10.200.8.20:22-10.200.16.10:36294.service - OpenSSH per-connection server daemon (10.200.16.10:36294). Aug 19 08:17:14.643069 sshd[13130]: Accepted publickey for core from 10.200.16.10 port 36294 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:14.644335 sshd-session[13130]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:14.648348 systemd-logind[1884]: New session 29 of user core. Aug 19 08:17:14.657201 systemd[1]: Started session-29.scope - Session 29 of User core. Aug 19 08:17:14.993992 sudo[13137]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Aug 19 08:17:14.994375 sudo[13137]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 19 08:17:15.029782 sudo[13137]: pam_unix(sudo:session): session closed for user root Aug 19 08:17:15.133457 sshd[13135]: Connection closed by 10.200.16.10 port 36294 Aug 19 08:17:15.133865 sshd-session[13130]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:15.137539 systemd[1]: sshd@26-10.200.8.20:22-10.200.16.10:36294.service: Deactivated successfully. Aug 19 08:17:15.139191 systemd[1]: session-29.scope: Deactivated successfully. Aug 19 08:17:15.139875 systemd-logind[1884]: Session 29 logged out. Waiting for processes to exit. Aug 19 08:17:15.140811 systemd-logind[1884]: Removed session 29. Aug 19 08:17:15.257683 systemd[1]: Started sshd@27-10.200.8.20:22-10.200.16.10:36310.service - OpenSSH per-connection server daemon (10.200.16.10:36310). Aug 19 08:17:15.916557 sshd[13143]: Accepted publickey for core from 10.200.16.10 port 36310 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:15.917893 sshd-session[13143]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:15.922187 systemd-logind[1884]: New session 30 of user core. Aug 19 08:17:15.936196 systemd[1]: Started session-30.scope - Session 30 of User core. Aug 19 08:17:16.268386 sudo[13148]: 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 Aug 19 08:17:16.268689 sudo[13148]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 19 08:17:16.276827 sudo[13148]: pam_unix(sudo:session): session closed for user root Aug 19 08:17:16.282445 sudo[13147]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Aug 19 08:17:16.282733 sudo[13147]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 19 08:17:16.291882 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 19 08:17:16.335062 augenrules[13170]: No rules Aug 19 08:17:16.336260 systemd[1]: audit-rules.service: Deactivated successfully. Aug 19 08:17:16.336490 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 19 08:17:16.337228 sudo[13147]: pam_unix(sudo:session): session closed for user root Aug 19 08:17:16.441614 sshd[13146]: Connection closed by 10.200.16.10 port 36310 Aug 19 08:17:16.441946 sshd-session[13143]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:16.444727 systemd[1]: sshd@27-10.200.8.20:22-10.200.16.10:36310.service: Deactivated successfully. Aug 19 08:17:16.446229 systemd[1]: session-30.scope: Deactivated successfully. Aug 19 08:17:16.447470 systemd-logind[1884]: Session 30 logged out. Waiting for processes to exit. Aug 19 08:17:16.448419 systemd-logind[1884]: Removed session 30. Aug 19 08:17:16.558072 systemd[1]: Started sshd@28-10.200.8.20:22-10.200.16.10:36318.service - OpenSSH per-connection server daemon (10.200.16.10:36318). Aug 19 08:17:17.219435 sshd[13179]: Accepted publickey for core from 10.200.16.10 port 36318 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:17.220728 sshd-session[13179]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:17.224851 systemd-logind[1884]: New session 31 of user core. Aug 19 08:17:17.235208 systemd[1]: Started session-31.scope - Session 31 of User core. Aug 19 08:17:17.570807 sudo[13183]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Aug 19 08:17:17.571134 sudo[13183]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 19 08:17:17.574385 sudo[13183]: pam_unix(sudo:session): session closed for user root Aug 19 08:17:17.684026 sshd[13182]: Connection closed by 10.200.16.10 port 36318 Aug 19 08:17:17.684366 sshd-session[13179]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:17.687561 systemd[1]: sshd@28-10.200.8.20:22-10.200.16.10:36318.service: Deactivated successfully. Aug 19 08:17:17.689226 systemd[1]: session-31.scope: Deactivated successfully. Aug 19 08:17:17.689887 systemd-logind[1884]: Session 31 logged out. Waiting for processes to exit. Aug 19 08:17:17.690825 systemd-logind[1884]: Removed session 31. Aug 19 08:17:17.803160 systemd[1]: Started sshd@29-10.200.8.20:22-10.200.16.10:36322.service - OpenSSH per-connection server daemon (10.200.16.10:36322). Aug 19 08:17:18.465197 sshd[13189]: Accepted publickey for core from 10.200.16.10 port 36322 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:18.466479 sshd-session[13189]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:18.470645 systemd-logind[1884]: New session 32 of user core. Aug 19 08:17:18.480226 systemd[1]: Started session-32.scope - Session 32 of User core. Aug 19 08:17:18.815387 sudo[13193]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Aug 19 08:17:18.815686 sudo[13193]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 19 08:17:22.236369 sudo[13193]: pam_unix(sudo:session): session closed for user root Aug 19 08:17:22.339964 sshd[13192]: Connection closed by 10.200.16.10 port 36322 Aug 19 08:17:22.340268 sshd-session[13189]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:22.343941 systemd[1]: sshd@29-10.200.8.20:22-10.200.16.10:36322.service: Deactivated successfully. Aug 19 08:17:22.345545 systemd[1]: session-32.scope: Deactivated successfully. Aug 19 08:17:22.346237 systemd-logind[1884]: Session 32 logged out. Waiting for processes to exit. Aug 19 08:17:22.347202 systemd-logind[1884]: Removed session 32. Aug 19 08:17:22.466065 systemd[1]: Started sshd@30-10.200.8.20:22-10.200.16.10:52878.service - OpenSSH per-connection server daemon (10.200.16.10:52878). Aug 19 08:17:23.108550 sshd[13199]: Accepted publickey for core from 10.200.16.10 port 52878 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:23.109815 sshd-session[13199]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:23.113888 systemd-logind[1884]: New session 33 of user core. Aug 19 08:17:23.122203 systemd[1]: Started session-33.scope - Session 33 of User core. Aug 19 08:17:23.652259 sshd[13202]: Connection closed by 10.200.16.10 port 52878 Aug 19 08:17:23.652633 sshd-session[13199]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:23.656239 systemd[1]: sshd@30-10.200.8.20:22-10.200.16.10:52878.service: Deactivated successfully. Aug 19 08:17:23.657801 systemd[1]: session-33.scope: Deactivated successfully. Aug 19 08:17:23.658481 systemd-logind[1884]: Session 33 logged out. Waiting for processes to exit. Aug 19 08:17:23.659428 systemd-logind[1884]: Removed session 33. Aug 19 08:17:23.772144 systemd[1]: Started sshd@31-10.200.8.20:22-10.200.16.10:52890.service - OpenSSH per-connection server daemon (10.200.16.10:52890). Aug 19 08:17:24.434451 sshd[13217]: Accepted publickey for core from 10.200.16.10 port 52890 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:24.435733 sshd-session[13217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:24.439839 systemd-logind[1884]: New session 34 of user core. Aug 19 08:17:24.452203 systemd[1]: Started session-34.scope - Session 34 of User core. Aug 19 08:17:25.592001 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 19 08:17:25.607452 (dockerd)[13241]: 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 Aug 19 08:17:27.078852 dockerd[13241]: time="2025-08-19T08:17:27.078789898Z" level=info msg="Starting up" Aug 19 08:17:27.079504 dockerd[13241]: time="2025-08-19T08:17:27.079467857Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Aug 19 08:17:27.093710 dockerd[13241]: time="2025-08-19T08:17:27.093676003Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Aug 19 08:17:27.743945 dockerd[13241]: time="2025-08-19T08:17:27.743854506Z" level=info msg="Loading containers: start." Aug 19 08:17:27.758104 kernel: Initializing XFRM netlink socket Aug 19 08:17:28.017142 systemd-networkd[1588]: docker0: Link UP Aug 19 08:17:28.032775 dockerd[13241]: time="2025-08-19T08:17:28.032696537Z" level=info msg="Loading containers: done." Aug 19 08:17:28.054484 dockerd[13241]: time="2025-08-19T08:17:28.054437030Z" 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 Aug 19 08:17:28.054605 dockerd[13241]: time="2025-08-19T08:17:28.054510626Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Aug 19 08:17:28.054605 dockerd[13241]: time="2025-08-19T08:17:28.054589521Z" level=info msg="Initializing buildkit" Aug 19 08:17:28.105526 dockerd[13241]: time="2025-08-19T08:17:28.105493960Z" level=info msg="Completed buildkit initialization" Aug 19 08:17:28.111408 dockerd[13241]: time="2025-08-19T08:17:28.111378306Z" level=info msg="Daemon has completed initialization" Aug 19 08:17:28.111485 dockerd[13241]: time="2025-08-19T08:17:28.111427003Z" level=info msg="API listen on /run/docker.sock" Aug 19 08:17:28.111548 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 19 08:17:29.497484 systemd[1]: var-lib-docker-overlay2-70b964921574187aa8450703874b7dc5fecee7b4fa05dde7e3fcec28d7ce97cd\x2dinit-merged.mount: Deactivated successfully. Aug 19 08:17:29.627632 containerd[1916]: time="2025-08-19T08:17:29.627585508Z" level=info msg="connecting to shim 585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde" address="unix:///run/containerd/s/b05e1cf6a8867f27455f511d46c77aa2565c6da7f79f20af5468916fb5c1590c" namespace=moby protocol=ttrpc version=3 Aug 19 08:17:29.654405 systemd[1]: Started docker-585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde.scope - libcontainer container 585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde. Aug 19 08:17:29.700325 kernel: docker0: port 1(veth6e599c6) entered blocking state Aug 19 08:17:29.700407 kernel: docker0: port 1(veth6e599c6) entered disabled state Aug 19 08:17:29.700429 kernel: veth6e599c6: entered allmulticast mode Aug 19 08:17:29.705303 kernel: veth6e599c6: entered promiscuous mode Aug 19 08:17:29.705526 systemd-networkd[1588]: veth6e599c6: Link UP Aug 19 08:17:29.737101 kernel: eth0: renamed from vethce135e1 Aug 19 08:17:29.744182 kernel: docker0: port 1(veth6e599c6) entered blocking state Aug 19 08:17:29.744237 kernel: docker0: port 1(veth6e599c6) entered forwarding state Aug 19 08:17:29.744591 systemd-networkd[1588]: veth6e599c6: Gained carrier Aug 19 08:17:29.744977 systemd-networkd[1588]: docker0: Gained carrier Aug 19 08:17:31.097239 systemd-networkd[1588]: veth6e599c6: Gained IPv6LL Aug 19 08:17:31.609228 systemd-networkd[1588]: docker0: Gained IPv6LL Aug 19 08:17:33.027507 systemd[1]: docker-585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde.scope: Deactivated successfully. Aug 19 08:17:33.039743 dockerd[13241]: time="2025-08-19T08:17:33.039643658Z" level=info msg="ignoring event" container=585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Aug 19 08:17:33.040026 containerd[1916]: time="2025-08-19T08:17:33.039803250Z" level=info msg="shim disconnected" id=585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde namespace=moby Aug 19 08:17:33.040026 containerd[1916]: time="2025-08-19T08:17:33.039844448Z" level=warning msg="cleaning up after shim disconnected" id=585d08ca8805cabe77ac50c40c8e7db8fde45582bd6b84b95742c1b9855b7cde namespace=moby Aug 19 08:17:33.040026 containerd[1916]: time="2025-08-19T08:17:33.039852447Z" level=info msg="cleaning up dead shim" namespace=moby Aug 19 08:17:33.072109 kernel: docker0: port 1(veth6e599c6) entered disabled state Aug 19 08:17:33.072230 kernel: vethce135e1: renamed from eth0 Aug 19 08:17:33.072104 systemd-networkd[1588]: veth6e599c6: Lost carrier Aug 19 08:17:33.090239 systemd-networkd[1588]: veth6e599c6: Link DOWN Aug 19 08:17:33.096922 kernel: docker0: port 1(veth6e599c6) entered disabled state Aug 19 08:17:33.096969 kernel: veth6e599c6 (unregistering): left allmulticast mode Aug 19 08:17:33.100363 kernel: veth6e599c6 (unregistering): left promiscuous mode Aug 19 08:17:33.103780 kernel: docker0: port 1(veth6e599c6) entered disabled state Aug 19 08:17:33.124104 systemd[1]: run-docker-netns-34195049e4ca.mount: Deactivated successfully. Aug 19 08:17:33.128434 systemd[1]: var-lib-docker-overlay2-70b964921574187aa8450703874b7dc5fecee7b4fa05dde7e3fcec28d7ce97cd-merged.mount: Deactivated successfully. Aug 19 08:17:33.282513 sshd[13220]: Connection closed by 10.200.16.10 port 52890 Aug 19 08:17:33.282864 sshd-session[13217]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:33.286492 systemd[1]: sshd@31-10.200.8.20:22-10.200.16.10:52890.service: Deactivated successfully. Aug 19 08:17:33.288061 systemd[1]: session-34.scope: Deactivated successfully. Aug 19 08:17:33.288752 systemd-logind[1884]: Session 34 logged out. Waiting for processes to exit. Aug 19 08:17:33.289768 systemd-logind[1884]: Removed session 34. Aug 19 08:17:33.399218 systemd[1]: Started sshd@32-10.200.8.20:22-10.200.16.10:54136.service - OpenSSH per-connection server daemon (10.200.16.10:54136). Aug 19 08:17:34.038696 sshd[13533]: Accepted publickey for core from 10.200.16.10 port 54136 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:34.039952 sshd-session[13533]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:34.044165 systemd-logind[1884]: New session 35 of user core. Aug 19 08:17:34.060430 systemd[1]: Started session-35.scope - Session 35 of User core. Aug 19 08:17:34.105324 systemd-networkd[1588]: docker0: Lost carrier Aug 19 08:17:34.441808 systemd[1]: var-lib-docker-overlay2-7def8d4e098d83dbfa212d3078d5fedea90e1f735bf7288261369b8e448e5501\x2dinit-merged.mount: Deactivated successfully. Aug 19 08:17:34.547347 containerd[1916]: time="2025-08-19T08:17:34.547303729Z" level=info msg="connecting to shim 705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7" address="unix:///run/containerd/s/e711a32eb63bb9940c96369eed89ad6754cc639f7f668edaddd4819a7dd1dc76" namespace=moby protocol=ttrpc version=3 Aug 19 08:17:34.574215 systemd[1]: Started docker-705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7.scope - libcontainer container 705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7. Aug 19 08:17:34.616881 kernel: docker0: port 1(veth84a64f9) entered blocking state Aug 19 08:17:34.616965 kernel: docker0: port 1(veth84a64f9) entered disabled state Aug 19 08:17:34.619572 kernel: veth84a64f9: entered allmulticast mode Aug 19 08:17:34.622316 kernel: veth84a64f9: entered promiscuous mode Aug 19 08:17:34.623124 systemd-networkd[1588]: veth84a64f9: Link UP Aug 19 08:17:34.648104 kernel: eth0: renamed from vethb1207e8 Aug 19 08:17:34.654654 kernel: docker0: port 1(veth84a64f9) entered blocking state Aug 19 08:17:34.654700 kernel: docker0: port 1(veth84a64f9) entered forwarding state Aug 19 08:17:34.655030 systemd-networkd[1588]: veth84a64f9: Gained carrier Aug 19 08:17:34.655266 systemd-networkd[1588]: docker0: Gained carrier Aug 19 08:17:34.690829 systemd[1]: docker-705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7.scope: Deactivated successfully. Aug 19 08:17:34.710757 dockerd[13241]: time="2025-08-19T08:17:34.710118565Z" level=info msg="ignoring event" container=705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Aug 19 08:17:34.711061 containerd[1916]: time="2025-08-19T08:17:34.710141864Z" level=info msg="shim disconnected" id=705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7 namespace=moby Aug 19 08:17:34.711061 containerd[1916]: time="2025-08-19T08:17:34.710171662Z" level=warning msg="cleaning up after shim disconnected" id=705dbd0c969c7417f91a9760f4a103abe79e0427980385d0c5bd956e224840a7 namespace=moby Aug 19 08:17:34.711061 containerd[1916]: time="2025-08-19T08:17:34.710179562Z" level=info msg="cleaning up dead shim" namespace=moby Aug 19 08:17:34.738100 kernel: docker0: port 1(veth84a64f9) entered disabled state Aug 19 08:17:34.738162 kernel: vethb1207e8: renamed from eth0 Aug 19 08:17:34.738133 systemd-networkd[1588]: veth84a64f9: Lost carrier Aug 19 08:17:34.760728 systemd-networkd[1588]: veth84a64f9: Link DOWN Aug 19 08:17:34.764094 kernel: docker0: port 1(veth84a64f9) entered disabled state Aug 19 08:17:34.770714 kernel: veth84a64f9 (unregistering): left allmulticast mode Aug 19 08:17:34.770764 kernel: veth84a64f9 (unregistering): left promiscuous mode Aug 19 08:17:34.773998 kernel: docker0: port 1(veth84a64f9) entered disabled state Aug 19 08:17:34.925237 sshd[13536]: Connection closed by 10.200.16.10 port 54136 Aug 19 08:17:34.925590 sshd-session[13533]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:34.928437 systemd[1]: sshd@32-10.200.8.20:22-10.200.16.10:54136.service: Deactivated successfully. Aug 19 08:17:34.930046 systemd[1]: session-35.scope: Deactivated successfully. Aug 19 08:17:34.931223 systemd-logind[1884]: Session 35 logged out. Waiting for processes to exit. Aug 19 08:17:34.932267 systemd-logind[1884]: Removed session 35. Aug 19 08:17:35.042246 systemd[1]: Started sshd@33-10.200.8.20:22-10.200.16.10:54148.service - OpenSSH per-connection server daemon (10.200.16.10:54148). Aug 19 08:17:35.113301 systemd-networkd[1588]: docker0: Lost carrier Aug 19 08:17:35.439073 systemd[1]: var-lib-docker-overlay2-7def8d4e098d83dbfa212d3078d5fedea90e1f735bf7288261369b8e448e5501-merged.mount: Deactivated successfully. Aug 19 08:17:35.710684 sshd[13639]: Accepted publickey for core from 10.200.16.10 port 54148 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:35.711912 sshd-session[13639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:35.715913 systemd-logind[1884]: New session 36 of user core. Aug 19 08:17:35.729212 systemd[1]: Started session-36.scope - Session 36 of User core. Aug 19 08:17:36.129437 ntpdate[13652]: ntpdate 4.2.8p17@1.4004-o Mon Aug 18 21:32:25 UTC 2025 (1) Aug 19 08:17:42.972599 ntpdate[13652]: adjust time server 193.1.8.106 offset -0.009098 sec Aug 19 08:17:43.075264 sshd[13642]: Connection closed by 10.200.16.10 port 54148 Aug 19 08:17:43.075655 sshd-session[13639]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:43.079198 systemd[1]: sshd@33-10.200.8.20:22-10.200.16.10:54148.service: Deactivated successfully. Aug 19 08:17:43.080791 systemd[1]: session-36.scope: Deactivated successfully. Aug 19 08:17:43.081530 systemd-logind[1884]: Session 36 logged out. Waiting for processes to exit. Aug 19 08:17:43.082460 systemd-logind[1884]: Removed session 36.