Sep 9 01:30:48.240995 kernel: Linux version 6.12.109-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 15.3.0 p8) 15.3.0, GNU ld (Gentoo 2.46.0 p1) 2.46.0) #1 SMP PREEMPT_DYNAMIC Tue Sep 8 22:30:55 -00 2026 Sep 9 01:30:48.241012 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=8b01db594d78ebb1caee7895d3f2ad3faf1dafe1120cbd21e7a5a04ef4e9e018 Sep 9 01:30:48.241020 kernel: BIOS-provided physical RAM map: Sep 9 01:30:48.241024 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 9 01:30:48.241030 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 9 01:30:48.241035 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000044fdfff] usable Sep 9 01:30:48.241041 kernel: BIOS-e820: [mem 0x00000000044fe000-0x00000000048fdfff] reserved Sep 9 01:30:48.241045 kernel: BIOS-e820: [mem 0x00000000048fe000-0x000000003ff1efff] usable Sep 9 01:30:48.241050 kernel: BIOS-e820: [mem 0x000000003ff1f000-0x000000003ffc8fff] reserved Sep 9 01:30:48.241054 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 9 01:30:48.241058 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 9 01:30:48.241062 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 9 01:30:48.241066 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable Sep 9 01:30:48.241073 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 9 01:30:48.241079 kernel: NX (Execute Disable) protection: active Sep 9 01:30:48.241083 kernel: APIC: Static calls initialized Sep 9 01:30:48.241088 kernel: efi: EFI v2.7 by Microsoft Sep 9 01:30:48.241095 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff88000 SMBIOS 3.0=0x3ff86000 MEMATTR=0x3ea69018 RNG=0x3ffd2018 Sep 9 01:30:48.241100 kernel: random: crng init done Sep 9 01:30:48.241104 kernel: secureboot: Secure boot disabled Sep 9 01:30:48.241109 kernel: SMBIOS 3.1.0 present. Sep 9 01:30:48.241113 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 04/22/2026 Sep 9 01:30:48.241118 kernel: DMI: Memory slots populated: 2/2 Sep 9 01:30:48.241122 kernel: Hypervisor detected: Microsoft Hyper-V Sep 9 01:30:48.241126 kernel: Hyper-V: privilege flags low 0xae7f, high 0x3b8030, hints 0x9e4e24, misc 0xe0bed7b2 Sep 9 01:30:48.241131 kernel: Hyper-V: Nested features: 0x3e0101 Sep 9 01:30:48.241135 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 9 01:30:48.241139 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 9 01:30:48.241146 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 01:30:48.241151 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 01:30:48.241156 kernel: tsc: Detected 2300.001 MHz processor Sep 9 01:30:48.241160 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 01:30:48.241167 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 01:30:48.241173 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x10000000000 Sep 9 01:30:48.241178 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 9 01:30:48.241184 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 01:30:48.241191 kernel: e820: update [mem 0x48000000-0xffffffff] usable ==> reserved Sep 9 01:30:48.241196 kernel: last_pfn = 0x40000 max_arch_pfn = 0x10000000000 Sep 9 01:30:48.241201 kernel: Using GB pages for direct mapping Sep 9 01:30:48.241205 kernel: ACPI: Early table checksum verification disabled Sep 9 01:30:48.241213 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 9 01:30:48.241220 kernel: ACPI: XSDT 0x000000003FFF90E8 00005C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241225 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241230 kernel: ACPI: DSDT 0x000000003FFD6000 01E22B (v02 MSFTVM DSDT01 00000001 INTL 20230628) Sep 9 01:30:48.241235 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 9 01:30:48.241240 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241244 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241252 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241256 kernel: ACPI: APIC 0x000000003FFD5000 000058 (v05 HVLITE HVLITETB 00000000 MSHV 00000000) Sep 9 01:30:48.241261 kernel: ACPI: SRAT 0x000000003FFD4000 0000A0 (v03 HVLITE HVLITETB 00000000 MSHV 00000000) Sep 9 01:30:48.241266 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 01:30:48.241271 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 9 01:30:48.241275 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff422a] Sep 9 01:30:48.241280 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 9 01:30:48.241287 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 9 01:30:48.241292 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 9 01:30:48.241297 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 9 01:30:48.241302 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5057] Sep 9 01:30:48.241306 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd409f] Sep 9 01:30:48.241311 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 9 01:30:48.241316 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Sep 9 01:30:48.241323 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] Sep 9 01:30:48.241328 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00001000-0x2bfffffff] Sep 9 01:30:48.241333 kernel: NODE_DATA(0) allocated [mem 0x2bfff8dc0-0x2bfffffff] Sep 9 01:30:48.241338 kernel: Zone ranges: Sep 9 01:30:48.241343 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 01:30:48.241347 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 9 01:30:48.241352 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] Sep 9 01:30:48.241359 kernel: Device empty Sep 9 01:30:48.241364 kernel: Movable zone start for each node Sep 9 01:30:48.241369 kernel: Early memory node ranges Sep 9 01:30:48.241374 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 9 01:30:48.241378 kernel: node 0: [mem 0x0000000000100000-0x00000000044fdfff] Sep 9 01:30:48.241383 kernel: node 0: [mem 0x00000000048fe000-0x000000003ff1efff] Sep 9 01:30:48.241388 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 9 01:30:48.241393 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] Sep 9 01:30:48.241400 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] Sep 9 01:30:48.241405 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 01:30:48.241409 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 9 01:30:48.241414 kernel: On node 0, zone DMA32: 1024 pages in unavailable ranges Sep 9 01:30:48.241419 kernel: On node 0, zone DMA32: 224 pages in unavailable ranges Sep 9 01:30:48.241424 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 9 01:30:48.241428 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 9 01:30:48.241435 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 9 01:30:48.241440 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 01:30:48.241445 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 9 01:30:48.241450 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 9 01:30:48.241455 kernel: TSC deadline timer available Sep 9 01:30:48.241459 kernel: CPU topo: Max. logical packages: 1 Sep 9 01:30:48.241464 kernel: CPU topo: Max. logical dies: 1 Sep 9 01:30:48.241471 kernel: CPU topo: Max. dies per package: 1 Sep 9 01:30:48.241476 kernel: CPU topo: Max. threads per core: 2 Sep 9 01:30:48.241480 kernel: CPU topo: Num. cores per package: 1 Sep 9 01:30:48.241485 kernel: CPU topo: Num. threads per package: 2 Sep 9 01:30:48.241489 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Sep 9 01:30:48.241494 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 9 01:30:48.241499 kernel: Booting paravirtualized kernel on Hyper-V Sep 9 01:30:48.241505 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 01:30:48.241509 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 9 01:30:48.241514 kernel: percpu: Embedded 60 pages/cpu s208472 r8192 d29096 u1048576 Sep 9 01:30:48.241519 kernel: pcpu-alloc: s208472 r8192 d29096 u1048576 alloc=1*2097152 Sep 9 01:30:48.241523 kernel: pcpu-alloc: [0] 0 1 Sep 9 01:30:48.241528 kernel: Hyper-V: PV spinlocks enabled Sep 9 01:30:48.241533 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 01:30:48.241539 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=8b01db594d78ebb1caee7895d3f2ad3faf1dafe1120cbd21e7a5a04ef4e9e018 Sep 9 01:30:48.241544 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Sep 9 01:30:48.241549 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 01:30:48.241554 kernel: Fallback order for Node 0: 0 Sep 9 01:30:48.241558 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2095807 Sep 9 01:30:48.241563 kernel: Policy zone: Normal Sep 9 01:30:48.241568 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 01:30:48.241573 kernel: software IO TLB: area num 2. Sep 9 01:30:48.241578 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 9 01:30:48.241583 kernel: ftrace: allocating 41466 entries in 164 pages Sep 9 01:30:48.241587 kernel: ftrace: allocated 164 pages with 3 groups Sep 9 01:30:48.241592 kernel: Dynamic Preempt: voluntary Sep 9 01:30:48.241597 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 01:30:48.241604 kernel: rcu: RCU event tracing is enabled. Sep 9 01:30:48.241614 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 9 01:30:48.241619 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 01:30:48.241625 kernel: Rude variant of Tasks RCU enabled. Sep 9 01:30:48.241630 kernel: Tracing variant of Tasks RCU enabled. Sep 9 01:30:48.241635 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 01:30:48.241640 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 9 01:30:48.241645 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 01:30:48.241650 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 01:30:48.241655 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 01:30:48.241661 kernel: Using NULL legacy PIC Sep 9 01:30:48.241666 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 Sep 9 01:30:48.241671 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 01:30:48.241676 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 01:30:48.241683 kernel: Console: colour dummy device 80x25 Sep 9 01:30:48.241687 kernel: printk: legacy console [tty1] enabled Sep 9 01:30:48.241692 kernel: printk: legacy console [ttyS0] enabled Sep 9 01:30:48.241697 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 9 01:30:48.241702 kernel: ACPI: Core revision 20240827 Sep 9 01:30:48.241707 kernel: Failed to register legacy timer interrupt Sep 9 01:30:48.241712 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 01:30:48.241718 kernel: x2apic enabled Sep 9 01:30:48.241723 kernel: APIC: Switched APIC routing to: physical x2apic Sep 9 01:30:48.241728 kernel: Hyper-V: Host Build 10.0.26102.1438-1-0 Sep 9 01:30:48.241733 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 9 01:30:48.241738 kernel: Hyper-V: Disabling IBT because of Hyper-V bug Sep 9 01:30:48.241743 kernel: Hyper-V: Using IPI hypercalls Sep 9 01:30:48.241748 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 9 01:30:48.241754 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 9 01:30:48.241759 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 9 01:30:48.241765 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 9 01:30:48.241770 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 9 01:30:48.241775 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 9 01:30:48.241780 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212735f0517, max_idle_ns: 440795237604 ns Sep 9 01:30:48.241785 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4600.00 BogoMIPS (lpj=2300001) Sep 9 01:30:48.241791 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 9 01:30:48.241796 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 9 01:30:48.241801 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 9 01:30:48.241805 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 01:30:48.241810 kernel: Spectre V2 : Mitigation: Retpolines Sep 9 01:30:48.241815 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 9 01:30:48.241820 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Sep 9 01:30:48.241824 kernel: RETBleed: Vulnerable Sep 9 01:30:48.241829 kernel: Speculative Store Bypass: Vulnerable Sep 9 01:30:48.241835 kernel: active return thunk: its_return_thunk Sep 9 01:30:48.241840 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 9 01:30:48.241844 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 01:30:48.241849 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 01:30:48.241854 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 01:30:48.241858 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 9 01:30:48.241863 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 9 01:30:48.241868 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 9 01:30:48.241887 kernel: x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' Sep 9 01:30:48.241895 kernel: x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config' Sep 9 01:30:48.241902 kernel: x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data' Sep 9 01:30:48.241907 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 01:30:48.241911 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 9 01:30:48.241916 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 9 01:30:48.241921 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 9 01:30:48.241926 kernel: x86/fpu: xstate_offset[11]: 2432, xstate_sizes[11]: 16 Sep 9 01:30:48.241930 kernel: x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]: 64 Sep 9 01:30:48.241935 kernel: x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192 Sep 9 01:30:48.241940 kernel: x86/fpu: Enabled xstate features 0x608e7, context size is 10752 bytes, using 'compacted' format. Sep 9 01:30:48.241945 kernel: Freeing SMP alternatives memory: 36K Sep 9 01:30:48.241949 kernel: pid_max: default: 32768 minimum: 301 Sep 9 01:30:48.241955 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 01:30:48.241960 kernel: landlock: Up and running. Sep 9 01:30:48.241965 kernel: SELinux: Initializing. Sep 9 01:30:48.241969 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 9 01:30:48.241974 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 9 01:30:48.241979 kernel: smpboot: CPU0: Intel INTEL(R) XEON(R) PLATINUM 8573C (family: 0x6, model: 0xcf, stepping: 0x2) Sep 9 01:30:48.241984 kernel: Performance Events: unsupported p6 CPU model 207 no PMU driver, software events only. Sep 9 01:30:48.241989 kernel: signal: max sigframe size: 11952 Sep 9 01:30:48.241994 kernel: rcu: Hierarchical SRCU implementation. Sep 9 01:30:48.242000 kernel: rcu: Max phase no-delay instances is 400. Sep 9 01:30:48.242005 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 01:30:48.242010 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 9 01:30:48.242015 kernel: smp: Bringing up secondary CPUs ... Sep 9 01:30:48.242020 kernel: smpboot: x86: Booting SMP configuration: Sep 9 01:30:48.242025 kernel: .... node #0, CPUs: #1 Sep 9 01:30:48.242030 kernel: smp: Brought up 1 node, 2 CPUs Sep 9 01:30:48.242035 kernel: smpboot: Total of 2 processors activated (9200.00 BogoMIPS) Sep 9 01:30:48.242042 kernel: Memory: 8058424K/8383228K available (14336K kernel code, 2475K rwdata, 32660K rodata, 16148K init, 1892K bss, 318544K reserved, 0K cma-reserved) Sep 9 01:30:48.242047 kernel: devtmpfs: initialized Sep 9 01:30:48.242052 kernel: x86/mm: Memory block size: 128MB Sep 9 01:30:48.242057 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 9 01:30:48.242062 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 9 01:30:48.242067 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 01:30:48.242072 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 01:30:48.242078 kernel: audit: initializing netlink subsys (disabled) Sep 9 01:30:48.242083 kernel: audit: type=2000 audit(1788917442.085:1): state=initialized audit_enabled=0 res=1 Sep 9 01:30:48.242088 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 01:30:48.242093 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 01:30:48.242098 kernel: cpuidle: using governor menu Sep 9 01:30:48.242103 kernel: efi: Freeing EFI boot services memory: 34940K Sep 9 01:30:48.242108 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 01:30:48.242114 kernel: dca service started, version 1.12.1 Sep 9 01:30:48.242119 kernel: e820: reserve RAM buffer [mem 0x044fe000-0x07ffffff] Sep 9 01:30:48.242124 kernel: e820: reserve RAM buffer [mem 0x3ff1f000-0x3fffffff] Sep 9 01:30:48.242128 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 9 01:30:48.242133 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 01:30:48.242138 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 01:30:48.242143 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 01:30:48.242150 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 01:30:48.242155 kernel: ACPI: Added _OSI(Module Device) Sep 9 01:30:48.242159 kernel: ACPI: Added _OSI(Processor Device) Sep 9 01:30:48.242164 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 01:30:48.242169 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 01:30:48.242174 kernel: ACPI: Interpreter enabled Sep 9 01:30:48.242179 kernel: ACPI: PM: (supports S0 S5) Sep 9 01:30:48.242185 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 01:30:48.242190 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 01:30:48.242195 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 9 01:30:48.242200 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 9 01:30:48.242205 kernel: iommu: Default domain type: Translated Sep 9 01:30:48.242210 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 9 01:30:48.242215 kernel: efivars: Registered efivars operations Sep 9 01:30:48.242221 kernel: PCI: Using ACPI for IRQ routing Sep 9 01:30:48.242226 kernel: PCI: System does not support PCI Sep 9 01:30:48.242231 kernel: vgaarb: loaded Sep 9 01:30:48.242236 kernel: clocksource: Switched to clocksource tsc-early Sep 9 01:30:48.242241 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 01:30:48.242246 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 01:30:48.242251 kernel: pnp: PnP ACPI init Sep 9 01:30:48.242257 kernel: pnp: PnP ACPI: found 3 devices Sep 9 01:30:48.242262 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 01:30:48.242267 kernel: NET: Registered PF_INET protocol family Sep 9 01:30:48.242272 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 01:30:48.242277 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Sep 9 01:30:48.242282 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 01:30:48.242287 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 01:30:48.242293 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 01:30:48.242298 kernel: TCP: Hash tables configured (established 65536 bind 65536) Sep 9 01:30:48.242303 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Sep 9 01:30:48.242308 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Sep 9 01:30:48.242313 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 01:30:48.242318 kernel: NET: Registered PF_XDP protocol family Sep 9 01:30:48.242323 kernel: PCI: CLS 0 bytes, default 64 Sep 9 01:30:48.242328 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 9 01:30:48.242334 kernel: software IO TLB: mapped [mem 0x000000003a96d000-0x000000003e96d000] (64MB) Sep 9 01:30:48.242339 kernel: RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer Sep 9 01:30:48.242344 kernel: RAPL PMU: hw unit of domain psys 2^-0 Joules Sep 9 01:30:48.242349 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212735f0517, max_idle_ns: 440795237604 ns Sep 9 01:30:48.242354 kernel: clocksource: Switched to clocksource tsc Sep 9 01:30:48.242359 kernel: Initialise system trusted keyrings Sep 9 01:30:48.242365 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Sep 9 01:30:48.242370 kernel: Key type asymmetric registered Sep 9 01:30:48.242375 kernel: Asymmetric key parser 'x509' registered Sep 9 01:30:48.242380 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 9 01:30:48.242385 kernel: io scheduler mq-deadline registered Sep 9 01:30:48.242390 kernel: io scheduler kyber registered Sep 9 01:30:48.242395 kernel: io scheduler bfq registered Sep 9 01:30:48.242400 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 01:30:48.242406 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 01:30:48.242411 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 01:30:48.242416 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 9 01:30:48.242421 kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 01:30:48.242426 kernel: ACPI: bus type drm_connector registered Sep 9 01:30:48.242431 kernel: i8042: PNP: No PS/2 controller found. Sep 9 01:30:48.242555 kernel: rtc_cmos 00:02: registered as rtc0 Sep 9 01:30:48.242633 kernel: rtc_cmos 00:02: setting system clock to 2026-09-09T01:30:44 UTC (1788917444) Sep 9 01:30:48.242705 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 9 01:30:48.242712 kernel: intel_pstate: Intel P-state driver initializing Sep 9 01:30:48.242717 kernel: efifb: probing for efifb Sep 9 01:30:48.242722 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 9 01:30:48.242727 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 9 01:30:48.242734 kernel: efifb: scrolling: redraw Sep 9 01:30:48.242739 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 9 01:30:48.242744 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 01:30:48.242749 kernel: fb0: EFI VGA frame buffer device Sep 9 01:30:48.242754 kernel: pstore: Using crash dump compression: deflate Sep 9 01:30:48.242759 kernel: pstore: Registered efi_pstore as persistent store backend Sep 9 01:30:48.242764 kernel: NET: Registered PF_INET6 protocol family Sep 9 01:30:48.242770 kernel: Segment Routing with IPv6 Sep 9 01:30:48.242775 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 01:30:48.242780 kernel: NET: Registered PF_PACKET protocol family Sep 9 01:30:48.242785 kernel: Key type dns_resolver registered Sep 9 01:30:48.242790 kernel: IPI shorthand broadcast: enabled Sep 9 01:30:48.242795 kernel: sched_clock: Marking stable (1755004124, 87994881)->(2138162032, -295163027) Sep 9 01:30:48.242800 kernel: registered taskstats version 1 Sep 9 01:30:48.242807 kernel: Loading compiled-in X.509 certificates Sep 9 01:30:48.242812 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.109-flatcar: 3200f9f68afc35408551543aa206c667cb82085c' Sep 9 01:30:48.242817 kernel: Demotion targets for Node 0: null Sep 9 01:30:48.242822 kernel: Key type .fscrypt registered Sep 9 01:30:48.242827 kernel: Key type fscrypt-provisioning registered Sep 9 01:30:48.242832 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 01:30:48.242837 kernel: ima: Allocated hash algorithm: sha1 Sep 9 01:30:48.242843 kernel: ima: No architecture policies found Sep 9 01:30:48.242848 kernel: clk: Disabling unused clocks Sep 9 01:30:48.242853 kernel: Freeing unused kernel image (initmem) memory: 16148K Sep 9 01:30:48.242858 kernel: Write protecting the kernel read-only data: 47104k Sep 9 01:30:48.242863 kernel: Freeing unused kernel image (rodata/data gap) memory: 108K Sep 9 01:30:48.242868 kernel: Run /init as init process Sep 9 01:30:48.242897 kernel: with arguments: Sep 9 01:30:48.242904 kernel: /init Sep 9 01:30:48.242909 kernel: with environment: Sep 9 01:30:48.242914 kernel: HOME=/ Sep 9 01:30:48.242919 kernel: TERM=linux Sep 9 01:30:48.242924 kernel: hv_vmbus: hv_mmio=[mem 0x40000000-0xfed3ffff],[mem 0xfc0000000-0xfffffffff] fb=[mem 0x40000000-0x47ffffff] Sep 9 01:30:48.242929 kernel: hv_vmbus: Vmbus version:5.3 Sep 9 01:30:48.242934 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 9 01:30:48.242939 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 9 01:30:48.242946 kernel: PTP clock support registered Sep 9 01:30:48.242951 kernel: hv_utils: Registering HyperV Utility Driver Sep 9 01:30:48.242956 kernel: SCSI subsystem initialized Sep 9 01:30:48.242961 kernel: hv_vmbus: registering driver hv_utils Sep 9 01:30:48.242966 kernel: hv_utils: Shutdown IC version 3.2 Sep 9 01:30:48.242971 kernel: hv_utils: Heartbeat IC version 3.0 Sep 9 01:30:48.242977 kernel: hv_utils: TimeSync IC version 4.0 Sep 9 01:30:48.242983 kernel: hv_vmbus: registering driver hv_pci Sep 9 01:30:48.243096 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI VMBus probing: Using version 0x10004 Sep 9 01:30:48.243183 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI host bridge to bus c05b:00 Sep 9 01:30:48.243286 kernel: pci_bus c05b:00: root bus resource [mem 0xfc0000000-0xfc007ffff window] Sep 9 01:30:48.243375 kernel: pci_bus c05b:00: No busn resource found for root bus, will use [bus 00-ff] Sep 9 01:30:48.243487 kernel: pci c05b:00:00.0: [1414:00a9] type 00 class 0x010802 PCIe Endpoint Sep 9 01:30:48.243584 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit] Sep 9 01:30:48.243672 kernel: pci_bus c05b:00: busn_res: [bus 00-ff] end is updated to 00 Sep 9 01:30:48.243769 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit]: assigned Sep 9 01:30:48.243775 kernel: hv_vmbus: registering driver hv_storvsc Sep 9 01:30:48.243886 kernel: scsi host0: storvsc_host_t Sep 9 01:30:48.244001 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Sep 9 01:30:48.244008 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 01:30:48.244013 kernel: hv_vmbus: registering driver hid_hyperv Sep 9 01:30:48.244019 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Sep 9 01:30:48.244104 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 9 01:30:48.244112 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 9 01:30:48.244118 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Sep 9 01:30:48.244211 kernel: nvme nvme0: pci function c05b:00:00.0 Sep 9 01:30:48.244309 kernel: nvme c05b:00:00.0: enabling device (0000 -> 0002) Sep 9 01:30:48.244390 kernel: nvme nvme0: 2/0/0 default/read/poll queues Sep 9 01:30:48.244397 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Sep 9 01:30:48.244492 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 9 01:30:48.244499 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 9 01:30:48.244592 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 9 01:30:48.244598 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 01:30:48.244614 kernel: device-mapper: uevent: version 1.0.3 Sep 9 01:30:48.244620 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 01:30:48.244627 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Sep 9 01:30:48.244632 kernel: raid6: avx512x4 gen() 46778 MB/s Sep 9 01:30:48.244637 kernel: raid6: avx512x2 gen() 46889 MB/s Sep 9 01:30:48.244642 kernel: raid6: avx512x1 gen() 30007 MB/s Sep 9 01:30:48.244648 kernel: raid6: avx2x4 gen() 43480 MB/s Sep 9 01:30:48.244653 kernel: raid6: avx2x2 gen() 44718 MB/s Sep 9 01:30:48.244658 kernel: raid6: avx2x1 gen() 31422 MB/s Sep 9 01:30:48.244663 kernel: raid6: using algorithm avx512x2 gen() 46889 MB/s Sep 9 01:30:48.244669 kernel: raid6: .... xor() 37283 MB/s, rmw enabled Sep 9 01:30:48.244675 kernel: raid6: using avx512x2 recovery algorithm Sep 9 01:30:48.244680 kernel: xor: automatically using best checksumming function avx Sep 9 01:30:48.244685 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 01:30:48.244691 kernel: BTRFS: device fsid 7e197cdc-c9a9-48e3-8c0e-7f77f05de8af devid 1 transid 46 /dev/mapper/usr (254:0) scanned by mount (1060) Sep 9 01:30:48.244696 kernel: BTRFS info (device dm-0): first mount of filesystem 7e197cdc-c9a9-48e3-8c0e-7f77f05de8af Sep 9 01:30:48.244701 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 9 01:30:48.244708 kernel: BTRFS info (device dm-0 state E): enabling ssd optimizations Sep 9 01:30:48.244713 kernel: BTRFS info (device dm-0 state E): disabling log replay at mount time Sep 9 01:30:48.244718 kernel: BTRFS info (device dm-0 state E): enabling free space tree Sep 9 01:30:48.244724 kernel: loop: module loaded Sep 9 01:30:48.244729 kernel: loop0: detected capacity change from 0 to 109608 Sep 9 01:30:48.244734 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 01:30:48.244740 systemd[1]: Successfully made /usr/ read-only. Sep 9 01:30:48.244749 systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) Sep 9 01:30:48.244755 systemd[1]: Detected virtualization microsoft. Sep 9 01:30:48.244760 systemd[1]: Detected architecture x86-64. Sep 9 01:30:48.244766 systemd[1]: Running in initrd. Sep 9 01:30:48.244771 systemd[1]: Initializing machine ID from random generator. Sep 9 01:30:48.244778 systemd[1]: No hostname configured, using default hostname. Sep 9 01:30:48.244783 systemd[1]: Hostname set to . Sep 9 01:30:48.244789 (gene[1093]: '/usr/lib/systemd/system-generators/dracut-crypt-generator' failed with exit status 2. Sep 9 01:30:48.244795 systemd[1]: Queued start job for default target initrd.target. Sep 9 01:30:48.244801 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 01:30:48.244808 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 01:30:48.244814 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 01:30:48.244820 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 01:30:48.244825 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 01:30:48.244831 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 01:30:48.244838 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 01:30:48.244844 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 01:30:48.244849 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 01:30:48.244855 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 01:30:48.244860 systemd[1]: Reached target paths.target - Path Units. Sep 9 01:30:48.244866 systemd[1]: Reached target slices.target - Slice Units. Sep 9 01:30:48.244887 systemd[1]: Reached target swap.target - Swaps. Sep 9 01:30:48.244893 systemd[1]: Reached target timers.target - Timer Units. Sep 9 01:30:48.244899 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 01:30:48.244904 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 01:30:48.244910 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 01:30:48.244915 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Sep 9 01:30:48.244921 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 01:30:48.244928 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 01:30:48.244934 systemd[1]: Listening on systemd-networkd-resolve-hook.socket - Network Management Resolve Hook Socket. Sep 9 01:30:48.244939 systemd[1]: Listening on systemd-networkd.socket - Network Management Netlink Socket. Sep 9 01:30:48.244945 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 01:30:48.244951 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 01:30:48.244956 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 01:30:48.244962 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments skipped, no trigger condition checks were met. Sep 9 01:30:48.244969 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 01:30:48.244974 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 01:30:48.244980 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 01:30:48.244986 systemd[1]: systemd-battery-check.service - Early Battery Level Check skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/class/power_supply Sep 9 01:30:48.244992 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 01:30:48.244999 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 01:30:48.245004 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:30:48.245010 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 01:30:48.245015 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 01:30:48.245021 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 01:30:48.245042 systemd-journald[1184]: Collecting audit messages is enabled. Sep 9 01:30:48.245057 systemd-journald[1184]: Journal started Sep 9 01:30:48.245072 systemd-journald[1184]: Runtime Journal (/run/log/journal/b6f448781e994c9c87637e47af8f09ca) is 8M, max 158.5M, 150.5M free. Sep 9 01:30:48.245000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.249394 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 01:30:48.249413 kernel: audit: type=1130 audit(1788917448.245:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.251004 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 01:30:48.290605 systemd-modules-load[1189]: Using 2 probe threads Sep 9 01:30:48.310690 systemd-tmpfiles[1195]: /usr/lib/tmpfiles.d/systemd.conf:30: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 01:30:48.311950 systemd-tmpfiles[1195]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 01:30:48.312861 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 01:30:48.311000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.318004 kernel: audit: type=1130 audit(1788917448.311:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.318129 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 01:30:48.333073 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 01:30:48.335102 systemd-vconsole-setup[1190]: Configuration of first virtual console was skipped, ignoring remaining ones. Sep 9 01:30:48.331000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.339886 kernel: audit: type=1130 audit(1788917448.331:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.341066 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:30:48.341000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.346002 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 01:30:48.352970 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 01:30:48.353000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.358687 kernel: audit: type=1130 audit(1788917448.341:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.358714 kernel: audit: type=1130 audit(1788917448.353:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.404008 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 01:30:48.406000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.413994 kernel: audit: type=1130 audit(1788917448.406:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.411985 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 01:30:48.419894 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 01:30:48.421910 kernel: Bridge firewalling registered Sep 9 01:30:48.422156 systemd-modules-load[1189]: Inserted module 'br_netfilter' Sep 9 01:30:48.423217 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 01:30:48.426000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.433516 kernel: audit: type=1130 audit(1788917448.426:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.432200 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 01:30:48.438535 dracut-cmdline[1213]: dracut-111 Sep 9 01:30:48.442347 dracut-cmdline[1213]: Using kernel command line parameters: 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=8b01db594d78ebb1caee7895d3f2ad3faf1dafe1120cbd21e7a5a04ef4e9e018 Sep 9 01:30:48.454636 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 01:30:48.454000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.457000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.462513 kernel: audit: type=1130 audit(1788917448.454:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.454944 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 01:30:48.465194 kernel: audit: type=1131 audit(1788917448.457:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.464142 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 01:30:48.461000 audit: BPF prog-id=5 op=LOAD Sep 9 01:30:48.469995 kernel: audit: type=1334 audit(1788917448.461:11): prog-id=5 op=LOAD Sep 9 01:30:48.535541 systemd-resolved[1247]: Positive Trust Anchors: Sep 9 01:30:48.537043 systemd-resolved[1247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 01:30:48.537047 systemd-resolved[1247]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Sep 9 01:30:48.537080 systemd-resolved[1247]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 01:30:48.604932 kernel: Loading iSCSI transport class v2.0-870. Sep 9 01:30:48.630810 systemd-resolved[1247]: Defaulting to hostname 'linux'. Sep 9 01:30:48.652723 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 01:30:48.654000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.656012 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 01:30:48.680893 kernel: iscsi: registered transport (tcp) Sep 9 01:30:48.728956 kernel: iscsi: registered transport (qla4xxx) Sep 9 01:30:48.728996 kernel: QLogic iSCSI HBA Driver Sep 9 01:30:48.777532 systemd[1]: Starting systemd-network-generator.service - Generate Network Units from Kernel Command Line... Sep 9 01:30:48.791706 systemd[1]: Finished systemd-network-generator.service - Generate Network Units from Kernel Command Line. Sep 9 01:30:48.793000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.796594 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 01:30:48.830789 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 01:30:48.830000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.834191 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 01:30:48.841996 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 01:30:48.863805 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 01:30:48.865000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.865000 audit: BPF prog-id=6 op=LOAD Sep 9 01:30:48.865000 audit: BPF prog-id=7 op=LOAD Sep 9 01:30:48.867997 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 01:30:48.926669 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 01:30:48.929000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.968511 systemd-udevd[1461]: Using default interface naming scheme 'v260'. Sep 9 01:30:48.982147 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 01:30:48.983000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:48.990816 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 01:30:48.990000 audit: BPF prog-id=8 op=LOAD Sep 9 01:30:48.994598 systemd[1]: Starting systemd-networkd.service - Network Management... Sep 9 01:30:49.020643 dracut-pre-trigger[1571]: rd.md=0: removing MD RAID activation Sep 9 01:30:49.036495 systemd-networkd[1572]: Failed to open nftables netlink socket. IPMasquerade= and NFTSet= settings will not be applied. Ignoring: Protocol not supported Sep 9 01:30:49.044214 systemd-networkd[1572]: lo: Link UP Sep 9 01:30:49.044224 systemd-networkd[1572]: lo: Gained carrier Sep 9 01:30:49.044731 systemd[1]: Started systemd-networkd.service - Network Management. Sep 9 01:30:49.048000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.050973 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 01:30:49.050000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.052570 systemd[1]: Reached target network.target - Network. Sep 9 01:30:49.054997 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 01:30:49.109796 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 01:30:49.112000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.115989 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 01:30:49.211895 kernel: hv_vmbus: registering driver hv_netvsc Sep 9 01:30:49.211931 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#109 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Sep 9 01:30:49.213140 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 01:30:49.214000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.213459 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:30:49.216423 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:30:49.235207 kernel: hv_netvsc f8615163-0000-1000-2000-70a8a52fcd3d (unnamed net_device) (uninitialized): VF slot 1 added Sep 9 01:30:49.235139 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:30:49.251096 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 01:30:49.251262 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:30:49.252900 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 01:30:49.254000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.254000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.255970 systemd-networkd[1572]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 9 01:30:49.255976 systemd-networkd[1572]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 01:30:49.257739 systemd-networkd[1572]: eth0: Link UP Sep 9 01:30:49.258106 systemd-networkd[1572]: eth0: Gained carrier Sep 9 01:30:49.258117 systemd-networkd[1572]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 9 01:30:49.265549 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:30:49.280935 systemd-networkd[1572]: eth0: DHCPv4 address 10.0.0.8/24, gateway 10.0.0.1 acquired from 168.63.129.16 Sep 9 01:30:49.305953 systemd-vconsole-setup[1651]: Configuration of first virtual console was skipped, ignoring remaining ones. Sep 9 01:30:49.308000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.306999 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:30:49.315211 kernel: AES CTR mode by8 optimization enabled Sep 9 01:30:49.440899 kernel: nvme nvme0: using unchecked data buffer Sep 9 01:30:49.545607 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - MSFT NVMe Accelerator v1.0 USR-A. Sep 9 01:30:49.550500 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 01:30:49.670601 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - MSFT NVMe Accelerator v1.0 EFI-SYSTEM. Sep 9 01:30:49.682491 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. Sep 9 01:30:49.691778 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - MSFT NVMe Accelerator v1.0 ROOT. Sep 9 01:30:49.704680 systemd[1]: Mounting oem.mount - /oem... Sep 9 01:30:49.785908 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1759) Sep 9 01:30:49.792890 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem b560603b-762b-48b5-acad-ee47f15907af Sep 9 01:30:49.794894 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Sep 9 01:30:49.833435 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Sep 9 01:30:49.833473 kernel: BTRFS info (device nvme0n1p6): turning on async discard Sep 9 01:30:49.833533 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Sep 9 01:30:49.834701 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 01:30:49.836000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:49.839664 systemd[1]: Mounted oem.mount - /oem. Sep 9 01:30:49.846667 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 01:30:49.851591 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 01:30:49.853183 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 01:30:49.858016 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 01:30:49.862458 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 01:30:49.884619 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 01:30:49.882000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:50.252824 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI VMBus probing: Using version 0x10004 Sep 9 01:30:50.253095 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI host bridge to bus 7870:00 Sep 9 01:30:50.255417 kernel: pci_bus 7870:00: root bus resource [mem 0xfc2000000-0xfc4007fff window] Sep 9 01:30:50.256952 kernel: pci_bus 7870:00: No busn resource found for root bus, will use [bus 00-ff] Sep 9 01:30:50.261058 kernel: pci 7870:00:00.0: [1414:00ba] type 00 class 0x020000 PCIe Endpoint Sep 9 01:30:50.265890 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref] Sep 9 01:30:50.269959 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref] Sep 9 01:30:50.272083 kernel: pci 7870:00:00.0: enabling Extended Tags Sep 9 01:30:50.290186 kernel: pci_bus 7870:00: busn_res: [bus 00-ff] end is updated to 00 Sep 9 01:30:50.290424 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref]: assigned Sep 9 01:30:50.294894 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref]: assigned Sep 9 01:30:50.332846 kernel: mana 7870:00:00.0: enabling device (0000 -> 0002) Sep 9 01:30:50.343594 kernel: mana 7870:00:00.0: Microsoft Azure Network Adapter protocol version: 0.1.1 Sep 9 01:30:50.343832 kernel: hv_netvsc f8615163-0000-1000-2000-70a8a52fcd3d eth0: VF registering: eth1 Sep 9 01:30:50.345514 kernel: mana 7870:00:00.0 eth1: joined to eth0 Sep 9 01:30:50.349771 systemd-networkd[1572]: eth1: Interface name change detected, renamed to enP30832s1. Sep 9 01:30:50.353249 kernel: mana 7870:00:00.0 enP30832s1: renamed from eth1 Sep 9 01:30:50.447890 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 Sep 9 01:30:50.449892 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Sep 9 01:30:50.450222 kernel: hv_netvsc f8615163-0000-1000-2000-70a8a52fcd3d eth0: Data path switched to VF: enP30832s1 Sep 9 01:30:50.451907 systemd-networkd[1572]: enP30832s1: Link UP Sep 9 01:30:50.453028 systemd-networkd[1572]: enP30832s1: Gained carrier Sep 9 01:30:50.486233 systemd-networkd[1572]: eth0: Gained IPv6LL Sep 9 01:30:50.846472 disk-uuid[1752]: Warning: The kernel is still using the old partition table. Sep 9 01:30:50.846472 disk-uuid[1752]: The new table will be used at the next reboot or after you Sep 9 01:30:50.846472 disk-uuid[1752]: run partprobe(8) or kpartx(8) Sep 9 01:30:50.846472 disk-uuid[1752]: The operation has completed successfully. Sep 9 01:30:50.853713 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 01:30:50.854000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:50.854000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:50.853837 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 01:30:50.859582 systemd[1]: Mounting boot.mount - Boot partition... Sep 9 01:30:50.915440 systemd[1]: Mounted boot.mount - Boot partition. Sep 9 01:30:50.918894 ignition[1794]: Ignition 2.24.0 Sep 9 01:30:50.918902 ignition[1794]: Stage: fetch-offline Sep 9 01:30:50.920904 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 01:30:50.919094 ignition[1794]: no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:50.919102 ignition[1794]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:50.919188 ignition[1794]: parsed url from cmdline: "" Sep 9 01:30:50.919190 ignition[1794]: no config URL provided Sep 9 01:30:50.919195 ignition[1794]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 01:30:50.919208 ignition[1794]: no config at "/usr/lib/ignition/user.ign" Sep 9 01:30:50.919213 ignition[1794]: failed to fetch config: resource requires networking Sep 9 01:30:50.919350 ignition[1794]: Ignition finished successfully Sep 9 01:30:50.940000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:50.943011 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 9 01:30:50.962753 ignition[1827]: Ignition 2.24.0 Sep 9 01:30:50.962766 ignition[1827]: Stage: fetch Sep 9 01:30:50.962979 ignition[1827]: no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:50.962986 ignition[1827]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:50.963058 ignition[1827]: parsed url from cmdline: "" Sep 9 01:30:50.963061 ignition[1827]: no config URL provided Sep 9 01:30:50.963066 ignition[1827]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 01:30:50.963074 ignition[1827]: no config at "/usr/lib/ignition/user.ign" Sep 9 01:30:50.963092 ignition[1827]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 9 01:30:51.023090 ignition[1827]: GET result: OK Sep 9 01:30:51.023136 ignition[1827]: config has been read from IMDS userdata Sep 9 01:30:51.023147 ignition[1827]: parsing config with SHA512: 5cb3771611f841b2ec8d3e4784b93bbd2a00cd9cba360db1cc0626f27f312b667724ef3adaed985e792b5024f7cdeb7a6d76ba10c13b67a66ebcafbc77c2fd79 Sep 9 01:30:51.028396 unknown[1827]: fetched base config from "system" Sep 9 01:30:51.028406 unknown[1827]: fetched base config from "system" Sep 9 01:30:51.032000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.028622 ignition[1827]: fetch: fetch complete Sep 9 01:30:51.028413 unknown[1827]: fetched user config from "azure" Sep 9 01:30:51.028626 ignition[1827]: fetch: fetch passed Sep 9 01:30:51.030931 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 9 01:30:51.028659 ignition[1827]: Ignition finished successfully Sep 9 01:30:51.034664 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 01:30:51.054208 ignition[1833]: Ignition 2.24.0 Sep 9 01:30:51.054219 ignition[1833]: Stage: kargs Sep 9 01:30:51.056267 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 01:30:51.054397 ignition[1833]: no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:51.054403 ignition[1833]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:51.054870 ignition[1833]: kargs: kargs passed Sep 9 01:30:51.054926 ignition[1833]: Ignition finished successfully Sep 9 01:30:51.063000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.065651 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 01:30:51.097283 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 01:30:51.097000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.097000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.097374 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 01:30:51.102000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.098694 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 9 01:30:51.098766 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 9 01:30:51.099051 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 01:30:51.099133 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 01:30:51.106030 systemd[1]: Unmounting oem.mount - /oem... Sep 9 01:30:51.125960 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem b560603b-762b-48b5-acad-ee47f15907af Sep 9 01:30:51.126094 systemd[1]: oem.mount: Deactivated successfully. Sep 9 01:30:51.126452 systemd[1]: Unmounted oem.mount - /oem. Sep 9 01:30:51.144278 ignition[1843]: Ignition 2.24.0 Sep 9 01:30:51.144288 ignition[1843]: Stage: disks Sep 9 01:30:51.147000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.145827 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 01:30:51.144462 ignition[1843]: no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:51.149262 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 01:30:51.144468 ignition[1843]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:51.152956 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 01:30:51.144966 ignition[1843]: disks: disks passed Sep 9 01:30:51.156938 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 01:30:51.144995 ignition[1843]: Ignition finished successfully Sep 9 01:30:51.158982 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 01:30:51.161921 systemd[1]: Reached target basic.target - Basic System. Sep 9 01:30:51.167000 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 01:30:51.301105 systemd-fsck[1852]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Sep 9 01:30:51.305605 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 01:30:51.307000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:51.310093 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 01:30:51.624887 kernel: EXT4-fs (nvme0n1p9): mounted filesystem cf90976e-5b98-45ff-add6-9743e1ca0149 r/w with ordered data mode. Quota mode: none. Sep 9 01:30:51.625971 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 01:30:51.627649 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 01:30:51.641911 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 01:30:51.644262 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 9 01:30:51.649897 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition skipped, unmet condition check ConditionPathIsReadWrite=!/sysroot Sep 9 01:30:51.649943 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 01:30:51.661752 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 01:30:51.664711 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 01:30:52.037027 systemd-tmpfiles[1882]: /sysroot/usr/lib/tmpfiles.d/base_image_var.conf:1: Duplicate line for path "/sysroot/var", ignoring. Sep 9 01:30:52.037066 systemd-tmpfiles[1882]: /sysroot/usr/lib/tmpfiles.d/base_image_var.conf:7: Duplicate line for path "/sysroot/var/empty", ignoring. Sep 9 01:30:52.037175 systemd-tmpfiles[1882]: /sysroot/usr/lib/tmpfiles.d/base_image_var.conf:28: Duplicate line for path "/sysroot/var/log", ignoring. Sep 9 01:30:52.247691 coreos-metadata[1862]: Sep 09 01:30:52.247 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 9 01:30:52.256260 coreos-metadata[1862]: Sep 09 01:30:52.256 INFO Fetch successful Sep 9 01:30:52.257509 coreos-metadata[1862]: Sep 09 01:30:52.257 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 9 01:30:52.268468 coreos-metadata[1862]: Sep 09 01:30:52.268 INFO Fetch successful Sep 9 01:30:52.270361 coreos-metadata[1862]: Sep 09 01:30:52.269 INFO wrote hostname ci-4790.0.0-n-8255c4c8e5 to /sysroot/etc/hostname Sep 9 01:30:52.271021 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 01:30:52.273000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.173894 kernel: loop1: detected capacity change from 0 to 44472 Sep 9 01:30:53.209899 kernel: loop1: p1 p2 p3 Sep 9 01:30:53.582915 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:53.582965 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:53.585765 kernel: device-mapper: table: 254:1: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:53.586958 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:53.587424 systemd-confext[1941]: device-mapper: reload ioctl on loop1p1-14-verity (254:1) failed: Invalid argument Sep 9 01:30:53.627937 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:53.701894 kernel: erofs: (device dm-1): mounted with root inode @ nid 40. Sep 9 01:30:53.718886 kernel: loop2: detected capacity change from 0 to 44472 Sep 9 01:30:53.727928 kernel: loop2: p1 p2 p3 Sep 9 01:30:53.734959 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:53.739201 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:53.739236 kernel: device-mapper: table: 254:1: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:53.742426 (sd-merge)[1952]: device-mapper: reload ioctl on loop2p1-18-verity (254:1) failed: Invalid argument Sep 9 01:30:53.748500 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:53.748517 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:53.766838 (sd-merge)[1952]: Using extensions '00-flatcar-default.raw'. Sep 9 01:30:53.767608 (sd-merge)[1952]: Merged extensions into '/sysroot/etc'. Sep 9 01:30:53.771991 kernel: erofs: (device dm-1): mounted with root inode @ nid 40. Sep 9 01:30:53.772769 initrd-setup-root[1959]: /etc 00-flatcar-default Wed 2026-09-09 01:30:48 UTC Sep 9 01:30:53.774545 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 01:30:53.775000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.783912 kernel: kauditd_printk_skb: 29 callbacks suppressed Sep 9 01:30:53.781202 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 01:30:53.785269 kernel: audit: type=1130 audit(1788917453.775:41): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.815694 ignition[1963]: INFO : Ignition 2.24.0 Sep 9 01:30:53.815694 ignition[1963]: INFO : Stage: mount Sep 9 01:30:53.818000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.823949 kernel: audit: type=1130 audit(1788917453.818:42): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.823968 ignition[1963]: INFO : no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:53.823968 ignition[1963]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:53.823968 ignition[1963]: INFO : mount: mount passed Sep 9 01:30:53.823968 ignition[1963]: INFO : Ignition finished successfully Sep 9 01:30:53.817692 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 01:30:53.822676 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 01:30:53.841024 systemd[1]: Mounting oem.mount - /oem... Sep 9 01:30:53.857888 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1971) Sep 9 01:30:53.857919 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem b560603b-762b-48b5-acad-ee47f15907af Sep 9 01:30:53.860034 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Sep 9 01:30:53.865212 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Sep 9 01:30:53.865252 kernel: BTRFS info (device nvme0n1p6): turning on async discard Sep 9 01:30:53.866424 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Sep 9 01:30:53.868341 systemd[1]: Mounted oem.mount - /oem. Sep 9 01:30:53.871017 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 01:30:53.885037 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 01:30:53.906787 ignition[1990]: INFO : Ignition 2.24.0 Sep 9 01:30:53.906787 ignition[1990]: INFO : Stage: files Sep 9 01:30:53.910128 ignition[1990]: INFO : no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:53.910128 ignition[1990]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:53.910128 ignition[1990]: DEBUG : files: compiled without relabeling support, skipping Sep 9 01:30:53.910128 ignition[1990]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 01:30:53.910128 ignition[1990]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 01:30:53.910128 ignition[1990]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 01:30:53.910128 ignition[1990]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 01:30:53.910000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.924339 kernel: audit: type=1130 audit(1788917453.910:43): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.924358 ignition[1990]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/var/lib/nftables/rules-save" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/var/lib/nftables/rules-save" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: op(5): [started] processing unit "nftables-load.service" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: op(5): [finished] processing unit "nftables-load.service" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: op(6): [started] setting preset to enabled for "nftables-load.service" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: op(6): [finished] setting preset to enabled for "nftables-load.service" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 01:30:53.924358 ignition[1990]: INFO : files: files passed Sep 9 01:30:53.924358 ignition[1990]: INFO : Ignition finished successfully Sep 9 01:30:53.947000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.947000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.910152 unknown[1990]: wrote ssh authorized keys file for user: core Sep 9 01:30:53.970004 kernel: audit: type=1130 audit(1788917453.947:44): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.912045 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 01:30:53.971928 kernel: audit: type=1131 audit(1788917453.947:45): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:53.918005 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 01:30:53.922121 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 01:30:53.937438 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 01:30:53.979568 initrd-setup-root-after-ignition[2020]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 01:30:53.979568 initrd-setup-root-after-ignition[2020]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 01:30:53.937532 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 01:30:53.986046 kernel: loop3: detected capacity change from 0 to 44472 Sep 9 01:30:53.986081 initrd-setup-root-after-ignition[2026]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 01:30:53.988683 kernel: loop3: p1 p2 p3 Sep 9 01:30:54.001230 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.001265 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:54.004898 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:54.005018 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:54.005439 systemd-confext[2028]: device-mapper: reload ioctl on loop3p1-21-verity (254:2) failed: Invalid argument Sep 9 01:30:54.011646 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.024917 kernel: erofs: (device dm-2): mounted with root inode @ nid 40. Sep 9 01:30:54.045891 kernel: loop4: detected capacity change from 0 to 44472 Sep 9 01:30:54.052915 kernel: loop4: p1 p2 p3 Sep 9 01:30:54.059959 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.059989 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:54.061337 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:54.062344 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:54.062465 (sd-merge)[2036]: device-mapper: reload ioctl on loop4p1-25-verity (254:2) failed: Invalid argument Sep 9 01:30:54.071008 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.084693 (sd-merge)[2036]: Skipping extension refresh because no change was found, use --always-refresh=yes to always do a refresh. Sep 9 01:30:54.087105 kernel: erofs: (device dm-2): mounted with root inode @ nid 40. Sep 9 01:30:54.231900 kernel: loop4: detected capacity change from 0 to 137288 Sep 9 01:30:54.270901 kernel: loop4: p1 p2 p3 Sep 9 01:30:54.526906 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.530505 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:54.530537 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:54.530549 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:54.531784 systemd-sysext[2044]: device-mapper: reload ioctl on loop4p1-29-verity (254:2) failed: Invalid argument Sep 9 01:30:54.538894 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:54.674924 kernel: erofs: (device dm-2): mounted with root inode @ nid 39. Sep 9 01:30:54.789911 kernel: loop5: detected capacity change from 0 to 183704 Sep 9 01:30:54.887952 kernel: loop5: p1 p2 p3 Sep 9 01:30:55.195335 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:55.195385 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:55.196398 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:55.197888 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:55.198381 systemd-sysext[2044]: device-mapper: reload ioctl on loop5p1-33-verity (254:2) failed: Invalid argument Sep 9 01:30:55.204897 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:55.294916 kernel: erofs: (device dm-2): mounted with root inode @ nid 39. Sep 9 01:30:55.334891 kernel: loop6: detected capacity change from 0 to 404728 Sep 9 01:30:55.405970 kernel: loop6: p1 p2 p3 Sep 9 01:30:55.677469 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:55.677381 systemd-sysext[2044]: device-mapper: reload ioctl on loop6p1-37-verity (254:2) failed: Invalid argument Sep 9 01:30:55.679840 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:55.679856 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:55.679864 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:55.681897 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:55.874906 kernel: erofs: (device dm-2): mounted with root inode @ nid 39. Sep 9 01:30:55.971914 kernel: loop7: detected capacity change from 0 to 137288 Sep 9 01:30:55.979895 kernel: loop7: p1 p2 p3 Sep 9 01:30:55.989945 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:55.989983 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:55.991155 kernel: device-mapper: table: 254:2: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:55.993135 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:55.993189 (sd-merge)[2067]: device-mapper: reload ioctl on loop7p1-41-verity (254:2) failed: Invalid argument Sep 9 01:30:56.000895 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:56.019885 kernel: erofs: (device dm-2): mounted with root inode @ nid 39. Sep 9 01:30:56.021904 kernel: loop1: detected capacity change from 0 to 183704 Sep 9 01:30:56.030904 kernel: loop1: p1 p2 p3 Sep 9 01:30:56.040205 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:56.040237 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:56.045387 kernel: device-mapper: table: 254:3: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:56.045418 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:56.045450 (sd-merge)[2067]: device-mapper: reload ioctl on loop1p1-45-verity (254:3) failed: Invalid argument Sep 9 01:30:56.050885 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:56.072892 kernel: erofs: (device dm-3): mounted with root inode @ nid 39. Sep 9 01:30:56.075889 kernel: loop3: detected capacity change from 0 to 404728 Sep 9 01:30:56.083928 kernel: loop3: p1 p2 p3 Sep 9 01:30:56.090076 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:56.090109 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:30:56.090122 kernel: device-mapper: table: 254:4: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:30:56.092277 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:30:56.092303 (sd-merge)[2067]: device-mapper: reload ioctl on loop3p1-49-verity (254:4) failed: Invalid argument Sep 9 01:30:56.096911 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:30:56.126890 kernel: erofs: (device dm-4): mounted with root inode @ nid 39. Sep 9 01:30:56.127594 (sd-merge)[2067]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure-4790.0.0+nightly-20260908-2100.raw'. Sep 9 01:30:56.130018 (sd-merge)[2067]: Merged extensions into '/sysroot/usr'. Sep 9 01:30:56.132991 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 01:30:56.135000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.138896 kernel: audit: type=1130 audit(1788917456.135:46): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.138998 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 01:30:56.152267 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 01:30:56.153424 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 01:30:56.155000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.155000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.162989 kernel: audit: type=1130 audit(1788917456.155:47): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.157857 systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. Sep 9 01:30:56.163240 kernel: audit: type=1131 audit(1788917456.155:48): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.158610 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 01:30:56.168585 systemd[1]: Starting dracut-mount.service - dracut mount hook... Sep 9 01:30:56.410528 systemd[1]: Finished dracut-mount.service - dracut mount hook. Sep 9 01:30:56.411000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.416889 kernel: audit: type=1130 audit(1788917456.411:49): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.416950 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 01:30:56.436636 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 01:30:56.434000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.443179 kernel: audit: type=1130 audit(1788917456.434:50): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.440995 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 01:30:56.459081 systemd[1]: Stopped target basic.target - Basic System. Sep 9 01:30:56.460928 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 01:30:56.465055 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 01:30:56.469047 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 01:30:56.471987 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 01:30:56.473511 systemd[1]: Stopped target paths.target - Path Units. Sep 9 01:30:56.478016 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 01:30:56.480559 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 01:30:56.483008 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 01:30:56.485468 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 01:30:56.491698 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 01:30:56.494999 systemd[1]: Stopped target local-fs-pre.target - Preparation for Local File Systems. Sep 9 01:30:56.495310 systemd[1]: Stopped target swap.target - Swaps. Sep 9 01:30:56.499215 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 01:30:56.503777 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 01:30:56.504804 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 01:30:56.507000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.506988 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 01:30:56.516000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.507055 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 01:30:56.507760 systemd[1]: systemd-coredump.socket: Deactivated successfully. Sep 9 01:30:56.520000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.507824 systemd[1]: Closed systemd-coredump.socket - Process Core Dump Socket. Sep 9 01:30:56.508160 systemd[1]: systemd-journald-audit.socket: Deactivated successfully. Sep 9 01:30:56.508231 systemd[1]: Closed systemd-journald-audit.socket - Journal Audit Socket. Sep 9 01:30:56.508462 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 01:30:56.508553 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 01:30:56.508830 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 01:30:56.509073 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 01:30:56.509337 systemd[1]: dracut-mount.service: Deactivated successfully. Sep 9 01:30:56.509410 systemd[1]: Stopped dracut-mount.service - dracut mount hook. Sep 9 01:30:56.518192 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 01:30:56.518275 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 01:30:56.540000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.542000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.542000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.521350 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 01:30:56.526207 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 01:30:56.529456 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 01:30:56.538089 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 01:30:56.541367 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 01:30:56.548000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.541591 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 01:30:56.542270 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 01:30:56.542383 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 01:30:56.542747 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 01:30:56.542855 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 01:30:56.543136 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 01:30:56.543234 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 01:30:56.543577 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 9 01:30:56.543665 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 01:30:56.551938 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 01:30:56.571696 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 01:30:56.573819 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 01:30:56.576000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.578933 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 01:30:56.580971 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 01:30:56.582000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.585985 ignition[2109]: INFO : Ignition 2.24.0 Sep 9 01:30:56.585985 ignition[2109]: INFO : Stage: umount Sep 9 01:30:56.585985 ignition[2109]: INFO : no config dir at "/usr/lib/ignition/base.d" Sep 9 01:30:56.585985 ignition[2109]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 01:30:56.585985 ignition[2109]: INFO : umount: umount passed Sep 9 01:30:56.585985 ignition[2109]: INFO : Ignition finished successfully Sep 9 01:30:56.584130 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 01:30:56.584259 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 01:30:56.594000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.596557 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 01:30:56.596653 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 01:30:56.599000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.602684 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 01:30:56.603032 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 01:30:56.606000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.610064 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 01:30:56.612000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.612000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.611306 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 01:30:56.614822 systemd[1]: Stopped target network.target - Network. Sep 9 01:30:56.620000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.621000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup-pre comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.621000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.617941 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 01:30:56.617983 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 01:30:56.634000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.621979 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 01:30:56.622008 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 01:30:56.623128 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 01:30:56.623148 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 01:30:56.623659 systemd[1]: Stopping systemd-networkd.service - Network Management... Sep 9 01:30:56.624218 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 01:30:56.634694 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 01:30:56.634794 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 01:30:56.638391 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 01:30:56.638494 systemd[1]: Stopped systemd-networkd.service - Network Management. Sep 9 01:30:56.647000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.649000 audit: BPF prog-id=5 op=UNLOAD Sep 9 01:30:56.649000 audit: BPF prog-id=8 op=UNLOAD Sep 9 01:30:56.651508 systemd[1]: systemd-networkd-resolve-hook.socket: Deactivated successfully. Sep 9 01:30:56.651567 systemd[1]: Closed systemd-networkd-resolve-hook.socket - Network Management Resolve Hook Socket. Sep 9 01:30:56.655753 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 01:30:56.656720 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 01:30:56.658576 systemd[1]: Closed systemd-networkd.socket - Network Management Netlink Socket. Sep 9 01:30:56.662702 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 01:30:56.668390 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 01:30:56.668432 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 01:30:56.672000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.675170 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 01:30:56.691904 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 01:30:56.696077 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 01:30:56.698000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.700835 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 01:30:56.701044 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 01:30:56.703394 kernel: hv_netvsc f8615163-0000-1000-2000-70a8a52fcd3d eth0: Data path switched from VF: enP30832s1 Sep 9 01:30:56.705888 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Sep 9 01:30:56.710613 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 01:30:56.710652 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 01:30:56.713000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.714153 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 01:30:56.714181 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 01:30:56.716000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.717373 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 01:30:56.717401 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 01:30:56.719000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.721475 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 01:30:56.725095 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 01:30:56.725254 systemd[1]: Stopped systemd-network-generator.service - Generate Network Units from Kernel Command Line. Sep 9 01:30:56.726546 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 01:30:56.726577 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 01:30:56.726734 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 9 01:30:56.726755 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 01:30:56.726790 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 01:30:56.726809 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 01:30:56.727008 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 01:30:56.727027 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:30:56.727620 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 01:30:56.727703 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 01:30:56.725000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.725000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.725000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.725000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.726000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.726000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.757782 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 01:30:56.758333 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 01:30:56.760000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.760000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:30:56.762316 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 01:30:56.767367 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 01:30:56.778984 systemd[1]: Switching root. Sep 9 01:30:56.849342 systemd-journald[1184]: Journal stopped Sep 9 01:31:02.582128 systemd-journald[1184]: Received SIGTERM from PID 1 (systemd). Sep 9 01:31:02.582163 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 01:31:02.582175 kernel: SELinux: policy capability open_perms=1 Sep 9 01:31:02.582183 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 01:31:02.582193 kernel: SELinux: policy capability always_check_network=0 Sep 9 01:31:02.582201 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 01:31:02.582211 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 01:31:02.582220 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 01:31:02.582227 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 01:31:02.582235 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 01:31:02.582245 systemd[1]: Successfully loaded SELinux policy in 176.358ms. Sep 9 01:31:02.582256 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.160ms. Sep 9 01:31:02.582266 systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) Sep 9 01:31:02.582276 systemd[1]: Detected virtualization microsoft. Sep 9 01:31:02.582288 systemd[1]: Detected architecture x86-64. Sep 9 01:31:02.582296 systemd[1]: Detected first boot. Sep 9 01:31:02.582305 systemd[1]: Initializing machine ID from random generator. Sep 9 01:31:02.582314 systemd[1]: Hostname set to . Sep 9 01:31:02.582323 kernel: kauditd_printk_skb: 36 callbacks suppressed Sep 9 01:31:02.582334 kernel: audit: type=1334 audit(1788917459.436:87): prog-id=9 op=LOAD Sep 9 01:31:02.582343 kernel: audit: type=1334 audit(1788917459.436:88): prog-id=9 op=UNLOAD Sep 9 01:31:02.582352 zram_generator::config[2150]: No configuration found. Sep 9 01:31:02.582362 kernel: Guest personality initialized and is inactive Sep 9 01:31:02.582370 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Sep 9 01:31:02.582378 kernel: Initialized host personality Sep 9 01:31:02.582388 kernel: NET: Registered PF_VSOCK protocol family Sep 9 01:31:02.582398 systemd[1]: Applying preset policy. Sep 9 01:31:02.582412 systemd[1]: Created symlink '/etc/systemd/system/basic.target.wants/nftables-load.service' → '/usr/lib/systemd/system/nftables-load.service'. Sep 9 01:31:02.582422 systemd[1]: Created symlink '/etc/systemd/system/timers.target.wants/google-oslogin-cache.timer' → '/usr/lib/systemd/system/google-oslogin-cache.timer'. Sep 9 01:31:02.582431 systemd[1]: Populated /etc with preset unit settings. Sep 9 01:31:02.582439 kernel: audit: type=1334 audit(1788917461.903:89): prog-id=10 op=LOAD Sep 9 01:31:02.582447 kernel: audit: type=1334 audit(1788917461.903:90): prog-id=2 op=UNLOAD Sep 9 01:31:02.582457 kernel: audit: type=1334 audit(1788917461.903:91): prog-id=11 op=LOAD Sep 9 01:31:02.582466 kernel: audit: type=1334 audit(1788917461.903:92): prog-id=12 op=LOAD Sep 9 01:31:02.582475 kernel: audit: type=1334 audit(1788917461.903:93): prog-id=3 op=UNLOAD Sep 9 01:31:02.582483 kernel: audit: type=1334 audit(1788917461.903:94): prog-id=4 op=UNLOAD Sep 9 01:31:02.582491 kernel: audit: type=1334 audit(1788917461.904:95): prog-id=13 op=LOAD Sep 9 01:31:02.582499 kernel: audit: type=1334 audit(1788917461.904:96): prog-id=10 op=UNLOAD Sep 9 01:31:02.582510 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 01:31:02.582519 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 01:31:02.582532 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 01:31:02.582543 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 01:31:02.582553 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 01:31:02.582563 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 01:31:02.582574 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 01:31:02.582583 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 01:31:02.582593 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 01:31:02.582604 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 01:31:02.582614 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 01:31:02.582624 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 01:31:02.582633 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 01:31:02.582644 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 01:31:02.582653 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 01:31:02.582663 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 01:31:02.582674 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 01:31:02.582684 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 9 01:31:02.582693 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 9 01:31:02.582704 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 01:31:02.582713 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 01:31:02.582723 systemd[1]: Reached target imports.target - Image Downloads. Sep 9 01:31:02.582733 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 01:31:02.582743 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 01:31:02.582752 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 01:31:02.582762 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 01:31:02.582774 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 01:31:02.582784 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 01:31:02.582794 systemd[1]: Reached target remote-integritysetup.target - Remote Integrity Protected Volumes. Sep 9 01:31:02.582806 systemd[1]: Reached target remote-veritysetup.target - Remote Verity Protected Volumes. Sep 9 01:31:02.582816 systemd[1]: Reached target slices.target - Slice Units. Sep 9 01:31:02.582825 systemd[1]: Reached target swap.target - Swaps. Sep 9 01:31:02.582834 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 01:31:02.582844 systemd[1]: Listening on systemd-ask-password.socket - Query the User Interactively for a Password. Sep 9 01:31:02.582853 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 01:31:02.582863 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 01:31:02.582891 systemd[1]: Listening on systemd-factory-reset.socket - Factory Reset Management. Sep 9 01:31:02.582901 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Sep 9 01:31:02.582911 systemd[1]: Listening on systemd-mountfsd.socket - DDI File System Mounter Socket. Sep 9 01:31:02.582921 systemd[1]: Listening on systemd-mute-console.socket - Console Output Muting Service Socket. Sep 9 01:31:02.582931 systemd[1]: Listening on systemd-networkd-varlink-metrics.socket - Network Management Metrics Varlink Socket. Sep 9 01:31:02.582941 systemd[1]: Listening on systemd-networkd-varlink.socket - Network Management Varlink Socket. Sep 9 01:31:02.582950 systemd[1]: Listening on systemd-networkd.socket - Network Management Netlink Socket. Sep 9 01:31:02.582961 systemd[1]: Listening on systemd-nsresourced.socket - Namespace Resource Manager Socket. Sep 9 01:31:02.582970 systemd[1]: Listening on systemd-oomd.socket - Userspace Out-Of-Memory (OOM) Killer Socket. Sep 9 01:31:02.582979 systemd[1]: Listening on systemd-repart.socket - Disk Repartitioning Service Socket. Sep 9 01:31:02.582989 systemd[1]: Listening on systemd-resolved-monitor.socket - Resolve Monitor Varlink Socket. Sep 9 01:31:02.583000 systemd[1]: Listening on systemd-resolved-varlink.socket - Resolve Service Varlink Socket. Sep 9 01:31:02.583010 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 01:31:02.583021 systemd[1]: Listening on systemd-udevd-varlink.socket - udev Varlink Socket. Sep 9 01:31:02.583029 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 01:31:02.583039 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 01:31:02.583049 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 01:31:02.583059 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 01:31:02.583069 systemd[1]: proc-xen.mount - /proc/xen skipped, unmet condition check ConditionVirtualization=xen Sep 9 01:31:02.583078 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 01:31:02.583089 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 01:31:02.583099 systemd[1]: tmp.mount: x-systemd.graceful-option=usrquota specified, but option is not available, suppressing. Sep 9 01:31:02.583109 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 01:31:02.583119 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 01:31:02.583129 systemd[1]: ignition-delete-config.service - Ignition (delete config) skipped, no trigger condition checks were met. Sep 9 01:31:02.583139 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 01:31:02.583148 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 01:31:02.583159 systemd[1]: modprobe@drm.service - Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm Sep 9 01:31:02.583168 systemd[1]: modprobe@efi_pstore.service - Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore Sep 9 01:31:02.583179 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 01:31:02.583189 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf skipped, unmet condition check ConditionPathExists=!/etc/nsswitch.conf Sep 9 01:31:02.583199 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 01:31:02.583209 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 01:31:02.583220 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 Sep 9 01:31:02.583229 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 01:31:02.583239 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 01:31:02.583249 systemd[1]: Starting systemd-network-generator.service - Generate Network Units from Kernel Command Line... Sep 9 01:31:02.583259 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 01:31:02.583268 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 01:31:02.583277 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 01:31:02.583288 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 01:31:02.583298 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer skipped, unmet condition check ConditionVirtualization=xen Sep 9 01:31:02.583308 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 01:31:02.583332 systemd-journald[2226]: Collecting audit messages is enabled. Sep 9 01:31:02.583360 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 01:31:02.583377 systemd-journald[2226]: Journal started Sep 9 01:31:02.583399 systemd-journald[2226]: Runtime Journal (/run/log/journal/e789104066ff420c92a02e00856d7598) is 8M, max 158.5M, 150.5M free. Sep 9 01:31:02.140000 audit[1]: EVENT_LISTENER pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Sep 9 01:31:02.501000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.505000 audit: BPF prog-id=15 op=UNLOAD Sep 9 01:31:02.505000 audit: BPF prog-id=14 op=UNLOAD Sep 9 01:31:02.505000 audit: BPF prog-id=16 op=LOAD Sep 9 01:31:02.505000 audit: BPF prog-id=17 op=LOAD Sep 9 01:31:02.505000 audit: BPF prog-id=18 op=LOAD Sep 9 01:31:02.578000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Sep 9 01:31:02.578000 audit[2226]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=5 a1=7ffd7a690270 a2=4000 a3=0 items=0 ppid=1 pid=2226 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:02.578000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Sep 9 01:31:01.894812 systemd[1]: Queued start job for default target multi-user.target. Sep 9 01:31:01.906271 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Sep 9 01:31:01.906573 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 01:31:02.555075 systemd-modules-load[2230]: Using 2 probe threads Sep 9 01:31:02.590000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.592554 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 01:31:02.595010 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 01:31:02.596051 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 01:31:02.600059 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 01:31:02.602254 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 01:31:02.604229 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 01:31:02.605895 kernel: fuse: init (API version 7.41) Sep 9 01:31:02.604000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.606623 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 01:31:02.606755 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 01:31:02.608000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.608000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.610131 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 01:31:02.610286 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 01:31:02.609000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.609000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.611828 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 01:31:02.613000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.615239 systemd[1]: Finished systemd-network-generator.service - Generate Network Units from Kernel Command Line. Sep 9 01:31:02.616000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.618909 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 01:31:02.618000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.629948 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 01:31:02.630000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-load-credentials comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.635042 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 01:31:02.642017 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 01:31:02.644166 systemd[1]: remount-root.service - Remount Root File System skipped, unmet condition check ConditionPathIsReadWrite=!/ Sep 9 01:31:02.648527 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 01:31:02.657001 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 01:31:02.659481 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore Sep 9 01:31:02.663062 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 01:31:02.667212 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 01:31:02.673534 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 01:31:02.678371 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 01:31:02.680958 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 01:31:02.692136 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 01:31:02.694432 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 01:31:02.692000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.701978 systemd-journald[2226]: Time spent on flushing to /var/log/journal/e789104066ff420c92a02e00856d7598 is 22.983ms for 1190 entries. Sep 9 01:31:02.701978 systemd-journald[2226]: System Journal (/var/log/journal/e789104066ff420c92a02e00856d7598) is 8M, max 2.2G, 2.2G free. Sep 9 01:31:02.742000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.747309 systemd-journald[2226]: Received client request to flush runtime journal. Sep 9 01:31:02.743215 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 01:31:02.747763 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 01:31:02.747000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.777912 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 01:31:02.777000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.795018 systemd-tmpfiles[2267]: ACLs are not supported, ignoring. Sep 9 01:31:02.795035 systemd-tmpfiles[2267]: ACLs are not supported, ignoring. Sep 9 01:31:02.796861 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 01:31:02.796000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.978647 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 01:31:02.980000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:02.982864 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 01:31:03.115951 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 01:31:03.116000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.121024 systemd[1]: Starting systemd-journalctl.socket - Journal Log Access Socket... Sep 9 01:31:03.125000 audit: BPF prog-id=19 op=LOAD Sep 9 01:31:03.125000 audit: BPF prog-id=20 op=LOAD Sep 9 01:31:03.125000 audit: BPF prog-id=21 op=LOAD Sep 9 01:31:03.129000 audit: BPF prog-id=22 op=LOAD Sep 9 01:31:03.128635 systemd[1]: Starting systemd-oomd.service - Userspace Out-Of-Memory (OOM) Killer... Sep 9 01:31:03.133207 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 01:31:03.137065 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 01:31:03.139908 systemd[1]: Listening on systemd-journalctl.socket - Journal Log Access Socket. Sep 9 01:31:03.154044 systemd[1]: Starting modprobe@tun.service - Load Kernel Module tun... Sep 9 01:31:03.156000 audit: BPF prog-id=23 op=LOAD Sep 9 01:31:03.156000 audit: BPF prog-id=24 op=LOAD Sep 9 01:31:03.156000 audit: BPF prog-id=25 op=LOAD Sep 9 01:31:03.160044 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 01:31:03.166121 systemd-tmpfiles[2296]: ACLs are not supported, ignoring. Sep 9 01:31:03.166137 systemd-tmpfiles[2296]: ACLs are not supported, ignoring. Sep 9 01:31:03.171066 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 01:31:03.170000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.173785 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 01:31:03.176319 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) skipped, unmet condition check ConditionPathExists=/var/lib/machines.raw Sep 9 01:31:03.176341 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 01:31:03.178120 systemd[1]: Reached target machines.target - Virtual Machines and Containers. Sep 9 01:31:03.195062 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Sep 9 01:31:03.197170 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 01:31:03.198654 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats skipped, no trigger condition checks were met. Sep 9 01:31:03.199349 systemd[1]: Starting systemd-confext.service - Merge System Configuration Images into /etc/... Sep 9 01:31:03.208025 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 01:31:03.210498 systemd[1]: systemd-repart.service - Repartition Root Disk skipped, no trigger condition checks were met. Sep 9 01:31:03.212661 systemd[1]: Starting systemd-userdb-load-credentials.service - Load JSON user/group Records from Credentials... Sep 9 01:31:03.216485 systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 2298 ((systemd-userd)) Sep 9 01:31:03.218027 systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Sep 9 01:31:03.250894 kernel: tun: Universal TUN/TAP device driver, 1.6 Sep 9 01:31:03.252274 systemd[1]: modprobe@tun.service: Deactivated successfully. Sep 9 01:31:03.253000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@tun comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.253000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@tun comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.252546 systemd[1]: Finished modprobe@tun.service - Load Kernel Module tun. Sep 9 01:31:03.257091 systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Sep 9 01:31:03.260000 audit: BPF prog-id=26 op=LOAD Sep 9 01:31:03.261000 audit: BPF prog-id=27 op=LOAD Sep 9 01:31:03.261000 audit: BPF prog-id=28 op=LOAD Sep 9 01:31:03.264188 systemd[1]: Starting systemd-nsresourced.service - Namespace Resource Manager... Sep 9 01:31:03.266752 systemd[1]: Finished systemd-userdb-load-credentials.service - Load JSON user/group Records from Credentials. Sep 9 01:31:03.268000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdb-load-credentials comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.275917 kernel: loop4: detected capacity change from 0 to 44472 Sep 9 01:31:03.284272 kernel: loop4: p1 p2 p3 Sep 9 01:31:03.304803 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 01:31:03.307208 systemd-nsresourced[2310]: Not setting up BPF subsystem, as functionality has been disabled at compile time. Sep 9 01:31:03.305000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.309088 systemd[1]: Started systemd-nsresourced.service - Namespace Resource Manager. Sep 9 01:31:03.310000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-nsresourced comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.312109 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 01:31:03.311000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.344751 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:03.348285 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:03.348350 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:03.349436 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:03.350045 systemd-confext[2306]: device-mapper: reload ioctl on loop4p1-53-verity (254:5) failed: Invalid argument Sep 9 01:31:03.353912 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:03.403943 systemd-oomd[2294]: No swap; memory pressure usage will be degraded Sep 9 01:31:03.404589 systemd[1]: Started systemd-oomd.service - Userspace Out-Of-Memory (OOM) Killer. Sep 9 01:31:03.404000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-oomd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.423907 systemd-resolved[2295]: Positive Trust Anchors: Sep 9 01:31:03.423919 systemd-resolved[2295]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 01:31:03.423922 systemd-resolved[2295]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Sep 9 01:31:03.423952 systemd-resolved[2295]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 01:31:03.470590 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 01:31:03.470000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.470000 audit: BPF prog-id=7 op=UNLOAD Sep 9 01:31:03.470000 audit: BPF prog-id=6 op=UNLOAD Sep 9 01:31:03.470000 audit: BPF prog-id=29 op=LOAD Sep 9 01:31:03.470000 audit: BPF prog-id=30 op=LOAD Sep 9 01:31:03.473301 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 01:31:03.567022 systemd-resolved[2295]: Using system hostname 'ci-4790.0.0-n-8255c4c8e5'. Sep 9 01:31:03.568130 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 01:31:03.569000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.571013 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 01:31:03.696090 systemd-udevd[2330]: Using default interface naming scheme 'v260'. Sep 9 01:31:03.868357 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 01:31:03.871000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:03.951965 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 9 01:31:04.000439 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 01:31:04.012891 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#78 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Sep 9 01:31:04.016893 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 01:31:04.049892 kernel: hv_vmbus: registering driver hv_balloon Sep 9 01:31:04.051890 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 9 01:31:04.066058 kernel: hv_vmbus: registering driver hyperv_fb Sep 9 01:31:04.069138 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 9 01:31:04.069199 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 9 01:31:04.070896 kernel: Console: switching to colour dummy device 80x25 Sep 9 01:31:04.076232 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 01:31:04.080542 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 9 01:31:04.121150 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:31:04.285085 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. Sep 9 01:31:04.286113 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 01:31:04.319177 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 01:31:04.319442 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:31:04.319000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.319000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.322469 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 01:31:04.339000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.341474 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 01:31:04.351047 kernel: kvm_intel: Using Hyper-V Enlightened VMCS Sep 9 01:31:04.352891 kernel: cpu_based_exec_ctrl unsupported with eVMCS: 0x20000 Sep 9 01:31:04.357897 kernel: erofs: (device dm-5): mounted with root inode @ nid 40. Sep 9 01:31:04.376926 kernel: loop4: detected capacity change from 0 to 44472 Sep 9 01:31:04.387901 kernel: loop4: p1 p2 p3 Sep 9 01:31:04.392014 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.392058 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.393251 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.393274 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.393321 (sd-merge)[2412]: device-mapper: reload ioctl on loop4p1-58-verity (254:5) failed: Invalid argument Sep 9 01:31:04.396895 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.413821 (sd-merge)[2412]: Skipping extension refresh because no change was found, use --always-refresh=yes to always do a refresh. Sep 9 01:31:04.413923 kernel: erofs: (device dm-5): mounted with root inode @ nid 40. Sep 9 01:31:04.416280 systemd[1]: Finished systemd-confext.service - Merge System Configuration Images into /etc/. Sep 9 01:31:04.414000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-confext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.418921 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 01:31:04.428926 kernel: loop4: detected capacity change from 0 to 404728 Sep 9 01:31:04.439891 kernel: loop4: p1 p2 p3 Sep 9 01:31:04.447409 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.447450 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.448400 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.449004 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.449053 systemd-sysext[2421]: device-mapper: reload ioctl on loop4p1-62-verity (254:5) failed: Invalid argument Sep 9 01:31:04.452893 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.470921 kernel: erofs: (device dm-5): mounted with root inode @ nid 39. Sep 9 01:31:04.488092 kernel: loop4: detected capacity change from 0 to 183704 Sep 9 01:31:04.492890 kernel: loop4: p1 p2 p3 Sep 9 01:31:04.498837 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.500091 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.500122 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.501053 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.501113 systemd-sysext[2421]: device-mapper: reload ioctl on loop4p1-67-verity (254:5) failed: Invalid argument Sep 9 01:31:04.504892 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.523909 kernel: erofs: (device dm-5): mounted with root inode @ nid 39. Sep 9 01:31:04.543891 kernel: loop4: detected capacity change from 0 to 137288 Sep 9 01:31:04.548921 kernel: loop4: p1 p2 p3 Sep 9 01:31:04.555392 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.555423 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.556466 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.557378 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.557438 systemd-sysext[2421]: device-mapper: reload ioctl on loop4p1-72-verity (254:5) failed: Invalid argument Sep 9 01:31:04.559901 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.576895 kernel: erofs: (device dm-5): mounted with root inode @ nid 39. Sep 9 01:31:04.591888 kernel: loop4: detected capacity change from 0 to 404728 Sep 9 01:31:04.603240 kernel: loop4: p1 p2 p3 Sep 9 01:31:04.605763 systemd-vconsole-setup[2406]: Configuration of first virtual console was skipped, ignoring remaining ones. Sep 9 01:31:04.606336 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 01:31:04.607000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.611933 kernel: kauditd_printk_skb: 64 callbacks suppressed Sep 9 01:31:04.611963 kernel: audit: type=1130 audit(1788917464.607:159): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.616415 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.616461 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.618832 kernel: device-mapper: table: 254:5: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.620499 (sd-merge)[2443]: device-mapper: reload ioctl on loop4p1-77-verity (254:5) failed: Invalid argument Sep 9 01:31:04.620896 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.623905 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.644923 kernel: erofs: (device dm-5): mounted with root inode @ nid 39. Sep 9 01:31:04.647917 kernel: loop5: detected capacity change from 0 to 183704 Sep 9 01:31:04.656894 kernel: loop5: p1 p2 p3 Sep 9 01:31:04.662896 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.667497 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.667967 kernel: device-mapper: table: 254:6: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.667987 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.669350 (sd-merge)[2443]: device-mapper: reload ioctl on loop5p1-81-verity (254:6) failed: Invalid argument Sep 9 01:31:04.673466 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.690916 kernel: erofs: (device dm-6): mounted with root inode @ nid 39. Sep 9 01:31:04.692906 kernel: loop6: detected capacity change from 0 to 137288 Sep 9 01:31:04.700888 kernel: loop6: p1 p2 p3 Sep 9 01:31:04.704451 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.704504 kernel: device-mapper: verity: Unrecognized verity feature request: root_hash_sig_key_desc Sep 9 01:31:04.705369 kernel: device-mapper: table: 254:7: verity: Unrecognized verity feature request (-EINVAL) Sep 9 01:31:04.706230 kernel: device-mapper: ioctl: error adding target to table Sep 9 01:31:04.706529 (sd-merge)[2443]: device-mapper: reload ioctl on loop6p1-85-verity (254:7) failed: Invalid argument Sep 9 01:31:04.710192 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 9 01:31:04.727842 (sd-merge)[2443]: Skipping extension refresh because no change was found, use --always-refresh=yes to always do a refresh. Sep 9 01:31:04.728162 kernel: erofs: (device dm-7): mounted with root inode @ nid 39. Sep 9 01:31:04.730490 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 01:31:04.731000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.736675 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 01:31:04.736976 kernel: audit: type=1130 audit(1788917464.731:160): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.755143 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/base_image_var.conf:29: Duplicate line for path "/var/log/audit", ignoring. Sep 9 01:31:04.756256 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/base_image_var_late.conf:44: Duplicate line for path "/var/log/audit", ignoring. Sep 9 01:31:04.756524 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/baselayout.conf:15: Duplicate line for path "/var", ignoring. Sep 9 01:31:04.756530 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/baselayout.conf:16: Duplicate line for path "/var/empty", ignoring. Sep 9 01:31:04.756547 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/baselayout.conf:21: Duplicate line for path "/var/log", ignoring. Sep 9 01:31:04.756669 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/dbus.conf:5: Duplicate line for path "/var/lib/dbus", ignoring. Sep 9 01:31:04.756978 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/nfs-utils.conf:2: Duplicate line for path "/var/lib/nfs/v4recovery", ignoring. Sep 9 01:31:04.757765 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 01:31:04.757836 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 01:31:04.758078 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 01:31:04.759171 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/tpm2-tss-fapi.conf:2: Duplicate line for path "/var/lib/tpm2-tss/system/keystore", ignoring. Sep 9 01:31:04.759252 systemd-tmpfiles[2467]: ACLs are not supported, ignoring. Sep 9 01:31:04.759334 systemd-tmpfiles[2467]: ACLs are not supported, ignoring. Sep 9 01:31:04.760139 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/var.conf:10: Duplicate line for path "/var", ignoring. Sep 9 01:31:04.760225 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 01:31:04.760330 systemd-tmpfiles[2467]: /usr/lib/tmpfiles.d/var.conf:21: Duplicate line for path "/var/lib", ignoring. Sep 9 01:31:04.762412 systemd-tmpfiles[2467]: Detected autofs mount point '/boot' during canonicalization of 'boot'. Sep 9 01:31:04.762474 systemd-tmpfiles[2467]: Skipping /boot Sep 9 01:31:04.766990 systemd-tmpfiles[2467]: Detected autofs mount point '/boot' during canonicalization of 'boot'. Sep 9 01:31:04.767001 systemd-tmpfiles[2467]: Skipping /boot Sep 9 01:31:04.772437 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 01:31:04.773000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.778000 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 01:31:04.780999 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 01:31:04.781342 kernel: audit: type=1130 audit(1788917464.773:161): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.784451 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 01:31:04.788685 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 01:31:04.792138 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 01:31:04.802000 audit[2473]: AUDIT1127 pid=2473 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.807914 kernel: audit: type=1127 audit(1788917464.802:162): pid=2473 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.808444 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 01:31:04.808000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.813899 kernel: audit: type=1130 audit(1788917464.808:163): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.898820 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 01:31:04.898000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:04.903951 kernel: audit: type=1130 audit(1788917464.898:164): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:05.004000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Sep 9 01:31:05.006911 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 01:31:05.007240 augenrules[2497]: No rules Sep 9 01:31:05.007098 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 01:31:05.004000 audit[2497]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffd63fedc20 a2=420 a3=0 items=0 ppid=2469 pid=2497 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/bin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:05.014764 kernel: audit: type=1305 audit(1788917465.004:165): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Sep 9 01:31:05.014789 kernel: audit: type=1300 audit(1788917465.004:165): arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffd63fedc20 a2=420 a3=0 items=0 ppid=2469 pid=2497 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/bin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:05.004000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Sep 9 01:31:05.019895 kernel: audit: type=1327 audit(1788917465.004:165): proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Sep 9 01:31:05.146666 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 01:31:05.148300 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt skipped, unmet condition check ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt Sep 9 01:31:11.151991 ldconfig[2471]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 01:31:11.165231 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 01:31:11.168002 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 01:31:11.181804 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 01:31:11.183411 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 01:31:11.185985 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 01:31:11.188969 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 01:31:11.191933 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 9 01:31:11.193439 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 01:31:11.195978 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 01:31:11.198935 systemd[1]: Started systemd-sysupdate-reboot.timer - Reboot Automatically After System Update. Sep 9 01:31:11.201981 systemd[1]: Started systemd-sysupdate.timer - Automatic System Update. Sep 9 01:31:11.203235 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 01:31:11.205913 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer skipped, unmet condition check ConditionPathExists=/usr/.noupdate Sep 9 01:31:11.205952 systemd[1]: Reached target paths.target - Path Units. Sep 9 01:31:11.208924 systemd[1]: Reached target timers.target - Timer Units. Sep 9 01:31:11.227963 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 01:31:11.231125 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 01:31:11.233615 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 01:31:11.238151 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 01:31:11.241196 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 01:31:11.244381 systemd[1]: Listening on systemd-logind-varlink.socket - User Login Management Varlink Socket. Sep 9 01:31:11.248130 systemd[1]: Listening on systemd-machined.socket - Virtual Machine and Container Registration Service Socket. Sep 9 01:31:11.250235 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 01:31:11.252343 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 01:31:11.253535 systemd[1]: Reached target basic.target - Basic System. Sep 9 01:31:11.255969 systemd[1]: addon-config@oem.service - Configure Addon /oem skipped, no trigger condition checks were met. Sep 9 01:31:11.256000 systemd[1]: addon-run@oem.service - Run Addon /oem skipped, no trigger condition checks were met. Sep 9 01:31:11.257387 systemd[1]: Starting chronyd.service - NTP client/server... Sep 9 01:31:11.260785 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 01:31:11.267517 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 01:31:11.274346 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 01:31:11.281398 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 01:31:11.284979 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS skipped, unmet condition check ConditionPathExists=/oem/bin/flatcar-setup-environment Sep 9 01:31:11.289893 jq[2515]: false Sep 9 01:31:11.288309 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 9 01:31:11.290251 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon skipped, unmet condition check ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio Sep 9 01:31:11.297082 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 9 01:31:11.301049 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon skipped, unmet condition check ConditionPathExists=/dev/vmbus/hv_vss Sep 9 01:31:11.303007 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 01:31:11.305777 systemd[1]: Starting nftables-load.service - Load nftables firewall rules... Sep 9 01:31:11.311663 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 01:31:11.318065 KVP[2518]: KVP starting; pid is:2518 Sep 9 01:31:11.319106 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 01:31:11.328941 kernel: hv_utils: KVP IC version 4.0 Sep 9 01:31:11.329141 KVP[2518]: KVP LIC Version: 3.1 Sep 9 01:31:11.329428 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 01:31:11.331430 systemd[1]: tcsd.service - TCG Core Services Daemon skipped, unmet condition check ConditionPathExists=/dev/tpm0 Sep 9 01:31:11.332351 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 01:31:11.335621 chronyd[2512]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Sep 9 01:31:11.336054 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 01:31:11.341531 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Refreshing passwd entry cache Sep 9 01:31:11.340981 oslogin_cache_refresh[2517]: Refreshing passwd entry cache Sep 9 01:31:11.342618 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Failure getting users, quitting Sep 9 01:31:11.342618 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 01:31:11.342592 oslogin_cache_refresh[2517]: Failure getting users, quitting Sep 9 01:31:11.342605 oslogin_cache_refresh[2517]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 01:31:11.342742 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Refreshing group entry cache Sep 9 01:31:11.342693 oslogin_cache_refresh[2517]: Refreshing group entry cache Sep 9 01:31:11.345403 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Failure getting groups, quitting Sep 9 01:31:11.345403 google_oslogin_nss_cache[2517]: oslogin_cache_refresh[2517]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 01:31:11.345304 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 01:31:11.343088 oslogin_cache_refresh[2517]: Failure getting groups, quitting Sep 9 01:31:11.343094 oslogin_cache_refresh[2517]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 01:31:11.347633 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 01:31:11.347811 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 01:31:11.348018 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 9 01:31:11.348174 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 9 01:31:11.350446 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 01:31:11.351348 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 01:31:11.360514 motdgen[2542]: /oem/oem-release: line 2: Azure: command not found Sep 9 01:31:11.363940 jq[2532]: true Sep 9 01:31:11.364154 extend-filesystems[2516]: Found /dev/nvme0n1p6 Sep 9 01:31:11.372383 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 01:31:11.372806 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 01:31:11.375490 chronyd[2512]: Timezone right/UTC failed leap second check, ignoring Sep 9 01:31:11.375736 systemd[1]: Started chronyd.service - NTP client/server. Sep 9 01:31:11.375613 chronyd[2512]: Loaded seccomp filter (level 2) Sep 9 01:31:11.381561 extend-filesystems[2516]: Found /dev/nvme0n1p9 Sep 9 01:31:11.385763 jq[2547]: true Sep 9 01:31:11.386909 extend-filesystems[2516]: Checking size of /dev/nvme0n1p9 Sep 9 01:31:11.413023 update_engine[2531]: I20260909 01:31:11.412940 2531 main.cc:92] Flatcar Update Engine starting Sep 9 01:31:11.416685 extend-filesystems[2516]: Resized partition /dev/nvme0n1p9 Sep 9 01:31:11.435496 extend-filesystems[2582]: resize2fs 1.47.4 (6-Mar-2025) Sep 9 01:31:11.447906 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 6359552 to 6376955 blocks Sep 9 01:31:11.453265 systemd[1]: nftables-load.service: Deactivated successfully. Sep 9 01:31:11.453579 systemd[1]: Finished nftables-load.service - Load nftables firewall rules. Sep 9 01:31:11.457250 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 01:31:11.486781 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 6376955 Sep 9 01:31:11.459550 systemd[1]: Listening on systemd-networkd-resolve-hook.socket - Network Management Resolve Hook Socket. Sep 9 01:31:11.465209 systemd[1]: Starting systemd-networkd.service - Network Management... Sep 9 01:31:11.472595 systemd-logind[2527]: New seat seat0. Sep 9 01:31:11.487347 systemd-logind[2527]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Sep 9 01:31:11.487986 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 01:31:11.499435 extend-filesystems[2582]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Sep 9 01:31:11.499435 extend-filesystems[2582]: old_desc_blocks = 4, new_desc_blocks = 4 Sep 9 01:31:11.499435 extend-filesystems[2582]: The filesystem on /dev/nvme0n1p9 is now 6376955 (4k) blocks long. Sep 9 01:31:11.509240 extend-filesystems[2516]: Resized filesystem in /dev/nvme0n1p9 Sep 9 01:31:11.508752 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 01:31:11.509541 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 01:31:11.513730 bash[2578]: Updated "/home/core/.ssh/authorized_keys" Sep 9 01:31:11.513569 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 01:31:11.516596 systemd[1]: sshkeys.service skipped, no trigger condition checks were met. Sep 9 01:31:11.533289 dbus-daemon[2513]: [system] SELinux support is enabled Sep 9 01:31:11.533818 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 01:31:11.537276 systemd[1]: system-cloudinit@usr-share-coreos-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/coreos/cloud-config.yml skipped, unmet condition check ConditionFileNotEmpty=/usr/share/coreos/cloud-config.yml Sep 9 01:31:11.537925 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 01:31:11.540293 update_engine[2531]: I20260909 01:31:11.540263 2531 update_check_scheduler.cc:74] Next update check in 8m59s Sep 9 01:31:11.541983 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline skipped, unmet condition check ConditionKernelCommandLine=cloud-config-url Sep 9 01:31:11.542002 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 01:31:11.551735 dbus-daemon[2513]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 9 01:31:11.551800 systemd[1]: Started update-engine.service - Update Engine. Sep 9 01:31:11.558201 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 01:31:11.610535 systemd-networkd[2586]: lo: Link UP Sep 9 01:31:11.611900 systemd-networkd[2586]: lo: Gained carrier Sep 9 01:31:11.614238 systemd[1]: Started systemd-networkd.service - Network Management. Sep 9 01:31:11.614354 systemd-networkd[2586]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 9 01:31:11.614404 systemd-networkd[2586]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 01:31:11.617463 systemd[1]: Reached target network.target - Network. Sep 9 01:31:11.617908 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 Sep 9 01:31:11.620976 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 01:31:11.625340 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 01:31:11.629000 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Online... Sep 9 01:31:11.636894 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Sep 9 01:31:11.647894 kernel: hv_netvsc f8615163-0000-1000-2000-70a8a52fcd3d eth0: Data path switched to VF: enP30832s1 Sep 9 01:31:11.654517 systemd-networkd[2586]: enP30832s1: Link UP Sep 9 01:31:11.654606 systemd-networkd[2586]: eth0: Link UP Sep 9 01:31:11.654614 systemd-networkd[2586]: eth0: Gained carrier Sep 9 01:31:11.654627 systemd-networkd[2586]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 9 01:31:11.663222 systemd-networkd[2586]: enP30832s1: Gained carrier Sep 9 01:31:11.668978 systemd-networkd[2586]: eth0: DHCPv4 address 10.0.0.8/24, gateway 10.0.0.1 acquired from 168.63.129.16 Sep 9 01:31:11.707082 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 01:31:11.732330 sshd_keygen[2556]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 MLDSA44-ED25519 Sep 9 01:31:11.750257 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 01:31:11.752424 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 01:31:11.768500 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 01:31:11.768654 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 01:31:11.772396 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 01:31:11.787050 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 01:31:11.793242 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 01:31:11.797293 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 9 01:31:11.800175 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 01:31:11.856709 locksmithd[2609]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 01:31:13.525036 systemd-networkd[2586]: eth0: Gained IPv6LL Sep 9 01:31:13.527018 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Online. Sep 9 01:31:13.529408 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 01:31:13.533259 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 01:31:13.545050 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 9 01:31:13.573107 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 9 01:31:13.617841 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 01:31:13.781275 containerd[2627]: time="2026-09-09T01:31:13Z" 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 Sep 9 01:31:13.783333 containerd[2627]: time="2026-09-09T01:31:13.783304715Z" level=info msg="starting containerd" revision=e53c7c1516c3b2bff98eb76f1f4117477e6f4e66 version=v2.2.5 Sep 9 01:31:13.842835 containerd[2627]: time="2026-09-09T01:31:13.842808276Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.896µs" Sep 9 01:31:13.842835 containerd[2627]: time="2026-09-09T01:31:13.842832144Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 01:31:13.842927 containerd[2627]: time="2026-09-09T01:31:13.842864826Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 01:31:13.842927 containerd[2627]: time="2026-09-09T01:31:13.842885736Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 01:31:13.843018 containerd[2627]: time="2026-09-09T01:31:13.843005762Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 01:31:13.843038 containerd[2627]: time="2026-09-09T01:31:13.843020568Z" level=info msg="loading plugin" id=io.containerd.mount-handler.v1.erofs type=io.containerd.mount-handler.v1 Sep 9 01:31:13.843038 containerd[2627]: time="2026-09-09T01:31:13.843030754Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843081 containerd[2627]: time="2026-09-09T01:31:13.843066197Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843104 containerd[2627]: time="2026-09-09T01:31:13.843079864Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843248 containerd[2627]: time="2026-09-09T01:31:13.843232071Z" 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 Sep 9 01:31:13.843271 containerd[2627]: time="2026-09-09T01:31:13.843246190Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843271 containerd[2627]: time="2026-09-09T01:31:13.843254912Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843271 containerd[2627]: time="2026-09-09T01:31:13.843261614Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843404 containerd[2627]: time="2026-09-09T01:31:13.843391520Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843469 containerd[2627]: time="2026-09-09T01:31:13.843459014Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843584 containerd[2627]: time="2026-09-09T01:31:13.843573328Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 01:31:13.843606 containerd[2627]: time="2026-09-09T01:31:13.843595696Z" 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 Sep 9 01:31:13.843624 containerd[2627]: time="2026-09-09T01:31:13.843604124Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 01:31:13.843826 containerd[2627]: time="2026-09-09T01:31:13.843814640Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 01:31:13.846015 containerd[2627]: time="2026-09-09T01:31:13.845856006Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 01:31:13.846015 containerd[2627]: time="2026-09-09T01:31:13.845967453Z" level=info msg="metadata content store policy set" policy=shared Sep 9 01:31:13.863134 containerd[2627]: time="2026-09-09T01:31:13.863105240Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 01:31:13.863201 containerd[2627]: time="2026-09-09T01:31:13.863146702Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 01:31:13.863201 containerd[2627]: time="2026-09-09T01:31:13.863169535Z" level=info msg="built-in NRI default validator is disabled" Sep 9 01:31:13.863201 containerd[2627]: time="2026-09-09T01:31:13.863175651Z" level=info msg="runtime interface created" Sep 9 01:31:13.863201 containerd[2627]: time="2026-09-09T01:31:13.863179811Z" level=info msg="created NRI interface" Sep 9 01:31:13.863201 containerd[2627]: time="2026-09-09T01:31:13.863187706Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Sep 9 01:31:13.895656 containerd[2627]: time="2026-09-09T01:31:13.895629373Z" level=info msg="loading plugin" id=io.containerd.mount-manager.v1.bolt type=io.containerd.mount-manager.v1 Sep 9 01:31:13.895784 containerd[2627]: time="2026-09-09T01:31:13.895766156Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 01:31:13.895823 containerd[2627]: time="2026-09-09T01:31:13.895788560Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 01:31:13.895823 containerd[2627]: time="2026-09-09T01:31:13.895800887Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 01:31:13.895823 containerd[2627]: time="2026-09-09T01:31:13.895813633Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895822393Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895832715Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895843451Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895853964Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895863754Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 01:31:13.895894 containerd[2627]: time="2026-09-09T01:31:13.895883502Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 01:31:13.896014 containerd[2627]: time="2026-09-09T01:31:13.895898224Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 01:31:13.896014 containerd[2627]: time="2026-09-09T01:31:13.895996444Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 01:31:13.896014 containerd[2627]: time="2026-09-09T01:31:13.896011805Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 01:31:13.896078 containerd[2627]: time="2026-09-09T01:31:13.896022215Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 01:31:13.896078 containerd[2627]: time="2026-09-09T01:31:13.896038422Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 01:31:13.896078 containerd[2627]: time="2026-09-09T01:31:13.896048621Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 01:31:13.896078 containerd[2627]: time="2026-09-09T01:31:13.896058961Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 01:31:13.896078 containerd[2627]: time="2026-09-09T01:31:13.896068927Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896078227Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896088417Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.mounts type=io.containerd.grpc.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896097465Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896108771Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896117570Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 01:31:13.896165 containerd[2627]: time="2026-09-09T01:31:13.896137887Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 01:31:13.896286 containerd[2627]: time="2026-09-09T01:31:13.896178379Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 01:31:13.896286 containerd[2627]: time="2026-09-09T01:31:13.896189361Z" level=info msg="Start snapshots syncer" Sep 9 01:31:13.896286 containerd[2627]: time="2026-09-09T01:31:13.896200836Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 01:31:13.896900 containerd[2627]: time="2026-09-09T01:31:13.896781483Z" 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,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":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\"}" Sep 9 01:31:13.896900 containerd[2627]: time="2026-09-09T01:31:13.896838411Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 01:31:13.897053 containerd[2627]: time="2026-09-09T01:31:13.896952199Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897080554Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897101209Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897113652Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897123548Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897133167Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897141958Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897149799Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897160642Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897167961Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897188464Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897200112Z" 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 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897207585Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897215256Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 01:31:13.898266 containerd[2627]: time="2026-09-09T01:31:13.897222325Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 01:31:13.898603 containerd[2627]: time="2026-09-09T01:31:13.897231011Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 01:31:13.898603 containerd[2627]: time="2026-09-09T01:31:13.897239562Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 01:31:13.898603 containerd[2627]: time="2026-09-09T01:31:13.897249070Z" level=info msg="Connect containerd service" Sep 9 01:31:13.898603 containerd[2627]: time="2026-09-09T01:31:13.897270984Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 01:31:13.898603 containerd[2627]: time="2026-09-09T01:31:13.898167706Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 01:31:14.723207 containerd[2627]: time="2026-09-09T01:31:14.723140922Z" level=info msg="Start subscribing containerd event" Sep 9 01:31:14.723331 containerd[2627]: time="2026-09-09T01:31:14.723220147Z" level=info msg="Start recovering state" Sep 9 01:31:14.723404 containerd[2627]: time="2026-09-09T01:31:14.723387832Z" level=info msg="Start event monitor" Sep 9 01:31:14.723432 containerd[2627]: time="2026-09-09T01:31:14.723407919Z" level=info msg="Start cni network conf syncer for default" Sep 9 01:31:14.723432 containerd[2627]: time="2026-09-09T01:31:14.723415406Z" level=info msg="Start streaming server" Sep 9 01:31:14.723470 containerd[2627]: time="2026-09-09T01:31:14.723439389Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 01:31:14.723470 containerd[2627]: time="2026-09-09T01:31:14.723446251Z" level=info msg="runtime interface starting up..." Sep 9 01:31:14.723470 containerd[2627]: time="2026-09-09T01:31:14.723454212Z" level=info msg="starting plugins..." Sep 9 01:31:14.723470 containerd[2627]: time="2026-09-09T01:31:14.723465771Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 01:31:14.723933 containerd[2627]: time="2026-09-09T01:31:14.723814701Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 01:31:14.723933 containerd[2627]: time="2026-09-09T01:31:14.723856419Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 01:31:14.724428 containerd[2627]: time="2026-09-09T01:31:14.724327226Z" level=info msg="containerd successfully booted in 0.943279s" Sep 9 01:31:14.725017 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 01:31:14.727325 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 01:31:14.729467 systemd[1]: Startup finished in 3.793s (kernel) + 11.106s (initrd) + 17.409s (userspace) = 32.309s. Sep 9 01:31:15.138856 login[2646]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 01:31:15.149077 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 01:31:15.150006 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 01:31:15.153135 systemd-logind[2527]: New session '1' of user 'core' with class 'user-light' and type 'tty'. Sep 9 01:31:15.186137 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 01:31:15.189113 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 01:31:15.413828 login[2647]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 01:31:15.418171 systemd-logind[2527]: New session '2' of user 'core' with class 'user-light' and type 'tty'. Sep 9 01:31:15.425228 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 01:31:16.781139 waagent[2664]: 2026-09-09T01:31:16.781071Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 9 01:31:16.782592 waagent[2664]: 2026-09-09T01:31:16.782553Z INFO Daemon Daemon OS: flatcar 4790.0.0 Sep 9 01:31:16.783610 waagent[2664]: 2026-09-09T01:31:16.783584Z INFO Daemon Daemon Python: 3.12.13 Sep 9 01:31:16.785191 waagent[2664]: 2026-09-09T01:31:16.784589Z INFO Daemon Daemon Run daemon Sep 9 01:31:16.786192 waagent[2664]: 2026-09-09T01:31:16.786150Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4790.0.0' Sep 9 01:31:16.788072 waagent[2664]: 2026-09-09T01:31:16.788040Z INFO Daemon Daemon Using waagent for provisioning Sep 9 01:31:16.789285 waagent[2664]: 2026-09-09T01:31:16.789254Z INFO Daemon Daemon Activate resource disk Sep 9 01:31:16.790498 waagent[2664]: 2026-09-09T01:31:16.790472Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 9 01:31:16.793083 waagent[2664]: 2026-09-09T01:31:16.793050Z INFO Daemon Daemon Found device: None Sep 9 01:31:16.793978 waagent[2664]: 2026-09-09T01:31:16.793948Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Sep 9 01:31:16.795639 waagent[2664]: 2026-09-09T01:31:16.795581Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Sep 9 01:31:16.798047 waagent[2664]: 2026-09-09T01:31:16.798011Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 01:31:16.799224 waagent[2664]: 2026-09-09T01:31:16.799196Z INFO Daemon Daemon Running default provisioning handler Sep 9 01:31:16.805001 waagent[2664]: 2026-09-09T01:31:16.804666Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Sep 9 01:31:16.806894 waagent[2664]: 2026-09-09T01:31:16.806816Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 9 01:31:16.807003 waagent[2664]: 2026-09-09T01:31:16.806979Z INFO Daemon Daemon cloud-init is enabled: False Sep 9 01:31:16.814575 waagent[2664]: 2026-09-09T01:31:16.807047Z INFO Daemon Daemon Copying ovf-env.xml Sep 9 01:31:16.897177 waagent[2664]: 2026-09-09T01:31:16.897131Z INFO Daemon Daemon Successfully mounted dvd Sep 9 01:31:17.000802 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 9 01:31:17.002523 waagent[2664]: 2026-09-09T01:31:17.002466Z INFO Daemon Daemon Detect protocol endpoint Sep 9 01:31:17.003771 waagent[2664]: 2026-09-09T01:31:17.003704Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 01:31:17.005185 waagent[2664]: 2026-09-09T01:31:17.005157Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 9 01:31:17.005434 waagent[2664]: 2026-09-09T01:31:17.005412Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 9 01:31:17.007781 waagent[2664]: 2026-09-09T01:31:17.007756Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 9 01:31:17.008993 waagent[2664]: 2026-09-09T01:31:17.008967Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 9 01:31:17.036787 waagent[2664]: 2026-09-09T01:31:17.035859Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 9 01:31:17.036787 waagent[2664]: 2026-09-09T01:31:17.036196Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 9 01:31:17.036787 waagent[2664]: 2026-09-09T01:31:17.036550Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 9 01:31:17.208225 waagent[2664]: 2026-09-09T01:31:17.208179Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 9 01:31:17.209572 waagent[2664]: 2026-09-09T01:31:17.209538Z INFO Daemon Daemon Forcing an update of the goal state. Sep 9 01:31:17.216136 waagent[2664]: 2026-09-09T01:31:17.216109Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 01:31:17.228936 waagent[2664]: 2026-09-09T01:31:17.228910Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.185 Sep 9 01:31:17.230190 waagent[2664]: 2026-09-09T01:31:17.230158Z INFO Daemon Sep 9 01:31:17.230884 waagent[2664]: 2026-09-09T01:31:17.230850Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 9550e763-eac8-438c-a75b-3ba733e9d2b1 eTag: 2619313520164111798 source: Fabric] Sep 9 01:31:17.233079 waagent[2664]: 2026-09-09T01:31:17.233054Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 9 01:31:17.234545 waagent[2664]: 2026-09-09T01:31:17.234522Z INFO Daemon Sep 9 01:31:17.235169 waagent[2664]: 2026-09-09T01:31:17.234761Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 9 01:31:17.240424 waagent[2664]: 2026-09-09T01:31:17.240402Z INFO Daemon Daemon Downloading artifacts profile blob Sep 9 01:31:17.342275 waagent[2664]: 2026-09-09T01:31:17.342205Z INFO Daemon Downloaded certificate {'thumbprint': 'A66D07F847036A0D245F266841C81365FE23D025', 'hasPrivateKey': True} Sep 9 01:31:17.344271 waagent[2664]: 2026-09-09T01:31:17.342614Z INFO Daemon Fetch goal state completed Sep 9 01:31:17.357414 waagent[2664]: 2026-09-09T01:31:17.357372Z INFO Daemon Daemon Starting provisioning Sep 9 01:31:17.359077 waagent[2664]: 2026-09-09T01:31:17.357542Z INFO Daemon Daemon Handle ovf-env.xml. Sep 9 01:31:17.359077 waagent[2664]: 2026-09-09T01:31:17.357752Z INFO Daemon Daemon Set hostname [ci-4790.0.0-n-8255c4c8e5] Sep 9 01:31:17.374557 waagent[2664]: 2026-09-09T01:31:17.374520Z INFO Daemon Daemon Publish hostname [ci-4790.0.0-n-8255c4c8e5] Sep 9 01:31:17.375304 waagent[2664]: 2026-09-09T01:31:17.374803Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 9 01:31:17.375717 waagent[2664]: 2026-09-09T01:31:17.375429Z INFO Daemon Daemon Primary interface is [eth0] Sep 9 01:31:17.381695 systemd-networkd[2586]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 9 01:31:17.381703 systemd-networkd[2586]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Sep 9 01:31:17.381725 systemd-networkd[2586]: eth0: DHCP lease lost Sep 9 01:31:17.397869 waagent[2664]: 2026-09-09T01:31:17.397827Z INFO Daemon Daemon Create user account if not exists Sep 9 01:31:17.406493 waagent[2664]: 2026-09-09T01:31:17.398049Z INFO Daemon Daemon User core already exists, skip useradd Sep 9 01:31:17.406493 waagent[2664]: 2026-09-09T01:31:17.398185Z INFO Daemon Daemon Configure sudoer Sep 9 01:31:17.406493 waagent[2664]: 2026-09-09T01:31:17.398638Z INFO Daemon Daemon Configure sshd Sep 9 01:31:17.406493 waagent[2664]: 2026-09-09T01:31:17.398851Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 9 01:31:17.406493 waagent[2664]: 2026-09-09T01:31:17.399003Z INFO Daemon Daemon Deploy ssh public key. Sep 9 01:31:17.412930 systemd-networkd[2586]: eth0: DHCPv4 address 10.0.0.8/24, gateway 10.0.0.1 acquired from 168.63.129.16 Sep 9 01:31:18.499584 waagent[2664]: 2026-09-09T01:31:18.499546Z INFO Daemon Daemon Provisioning complete Sep 9 01:31:18.506864 waagent[2664]: 2026-09-09T01:31:18.506832Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 9 01:31:18.507970 waagent[2664]: 2026-09-09T01:31:18.507888Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 9 01:31:18.509611 waagent[2664]: 2026-09-09T01:31:18.509581Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 9 01:31:18.608456 waagent[2751]: 2026-09-09T01:31:18.608390Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 9 01:31:18.608741 waagent[2751]: 2026-09-09T01:31:18.608487Z INFO ExtHandler ExtHandler OS: flatcar 4790.0.0 Sep 9 01:31:18.608741 waagent[2751]: 2026-09-09T01:31:18.608534Z INFO ExtHandler ExtHandler Python: 3.12.13 Sep 9 01:31:18.608741 waagent[2751]: 2026-09-09T01:31:18.608573Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 9 01:31:18.647080 waagent[2751]: 2026-09-09T01:31:18.647033Z INFO ExtHandler ExtHandler Distro: flatcar-4790.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.12.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 9 01:31:18.647209 waagent[2751]: 2026-09-09T01:31:18.647185Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 01:31:18.647277 waagent[2751]: 2026-09-09T01:31:18.647246Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 01:31:18.657103 waagent[2751]: 2026-09-09T01:31:18.657058Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 01:31:18.668774 waagent[2751]: 2026-09-09T01:31:18.668744Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.185 Sep 9 01:31:18.669115 waagent[2751]: 2026-09-09T01:31:18.669089Z INFO ExtHandler Sep 9 01:31:18.669166 waagent[2751]: 2026-09-09T01:31:18.669147Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 99301e48-2305-4af1-8a15-2a98efc976e9 eTag: 2619313520164111798 source: Fabric] Sep 9 01:31:18.669355 waagent[2751]: 2026-09-09T01:31:18.669332Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 9 01:31:18.669670 waagent[2751]: 2026-09-09T01:31:18.669648Z INFO ExtHandler Sep 9 01:31:18.669708 waagent[2751]: 2026-09-09T01:31:18.669692Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 9 01:31:18.673722 waagent[2751]: 2026-09-09T01:31:18.673696Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 9 01:31:18.745841 waagent[2751]: 2026-09-09T01:31:18.745796Z INFO ExtHandler Downloaded certificate {'thumbprint': 'A66D07F847036A0D245F266841C81365FE23D025', 'hasPrivateKey': True} Sep 9 01:31:18.746162 waagent[2751]: 2026-09-09T01:31:18.746132Z INFO ExtHandler Fetch goal state completed Sep 9 01:31:18.754670 waagent[2751]: 2026-09-09T01:31:18.754602Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.5.7 9 Jun 2026 (Library: OpenSSL 3.5.7 9 Jun 2026) Sep 9 01:31:18.757395 waagent[2751]: 2026-09-09T01:31:18.757354Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2751 Sep 9 01:31:18.757496 waagent[2751]: 2026-09-09T01:31:18.757474Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 9 01:31:18.757723 waagent[2751]: 2026-09-09T01:31:18.757701Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 9 01:31:18.758666 waagent[2751]: 2026-09-09T01:31:18.758633Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4790.0.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 9 01:31:18.758935 waagent[2751]: 2026-09-09T01:31:18.758913Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4790.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 9 01:31:18.759032 waagent[2751]: 2026-09-09T01:31:18.759014Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 9 01:31:18.759381 waagent[2751]: 2026-09-09T01:31:18.759360Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 9 01:31:18.877820 waagent[2751]: 2026-09-09T01:31:18.877797Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 9 01:31:18.877973 waagent[2751]: 2026-09-09T01:31:18.877953Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 9 01:31:18.881889 waagent[2751]: 2026-09-09T01:31:18.881835Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 9 01:31:18.886278 systemd[1]: Reload requested from client PID 2766 ('systemctl') (unit waagent.service)... Sep 9 01:31:18.886293 systemd[1]: Reloading... Sep 9 01:31:18.940904 zram_generator::config[2810]: No configuration found. Sep 9 01:31:19.032926 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#77 cmd 0x4a status: scsi 0x0 srb 0x20 hv 0xc0000001 Sep 9 01:31:19.165629 systemd[1]: Reloading finished in 279 ms. Sep 9 01:31:19.186425 waagent[2751]: 2026-09-09T01:31:19.185090Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 9 01:31:19.186425 waagent[2751]: 2026-09-09T01:31:19.185194Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 9 01:31:19.498438 waagent[2751]: 2026-09-09T01:31:19.498348Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 9 01:31:19.498627 waagent[2751]: 2026-09-09T01:31:19.498603Z 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] Sep 9 01:31:19.499216 waagent[2751]: 2026-09-09T01:31:19.499181Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 9 01:31:19.499510 waagent[2751]: 2026-09-09T01:31:19.499473Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 9 01:31:19.499588 waagent[2751]: 2026-09-09T01:31:19.499555Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 01:31:19.500007 waagent[2751]: 2026-09-09T01:31:19.499979Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 9 01:31:19.500169 waagent[2751]: 2026-09-09T01:31:19.500051Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 01:31:19.500169 waagent[2751]: 2026-09-09T01:31:19.500128Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 01:31:19.500335 waagent[2751]: 2026-09-09T01:31:19.500318Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 9 01:31:19.500393 waagent[2751]: 2026-09-09T01:31:19.500372Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 01:31:19.500550 waagent[2751]: 2026-09-09T01:31:19.500531Z INFO EnvHandler ExtHandler Configure routes Sep 9 01:31:19.500618 waagent[2751]: 2026-09-09T01:31:19.500584Z INFO EnvHandler ExtHandler Gateway:None Sep 9 01:31:19.500674 waagent[2751]: 2026-09-09T01:31:19.500645Z INFO EnvHandler ExtHandler Routes:None Sep 9 01:31:19.501246 waagent[2751]: 2026-09-09T01:31:19.501224Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 9 01:31:19.502896 waagent[2751]: 2026-09-09T01:31:19.502019Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 9 01:31:19.502896 waagent[2751]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 9 01:31:19.502896 waagent[2751]: eth0 00000000 0100000A 0003 0 0 1024 00000000 0 0 0 Sep 9 01:31:19.502896 waagent[2751]: eth0 0000000A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 9 01:31:19.502896 waagent[2751]: eth0 0100000A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 9 01:31:19.502896 waagent[2751]: eth0 10813FA8 0100000A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 01:31:19.502896 waagent[2751]: eth0 FEA9FEA9 0100000A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 01:31:19.502896 waagent[2751]: 2026-09-09T01:31:19.502194Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 9 01:31:19.502896 waagent[2751]: 2026-09-09T01:31:19.502253Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 9 01:31:19.504148 waagent[2751]: 2026-09-09T01:31:19.504119Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 9 01:31:19.516079 waagent[2751]: 2026-09-09T01:31:19.516051Z INFO ExtHandler ExtHandler Sep 9 01:31:19.516146 waagent[2751]: 2026-09-09T01:31:19.516111Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 815219f7-8e3d-432b-9627-3baa37843b12 correlation a14f0a8d-e8d5-42cc-9c12-7c09fca56ae4 created: 2026-09-09T01:30:21.457153Z] Sep 9 01:31:19.516361 waagent[2751]: 2026-09-09T01:31:19.516337Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 9 01:31:19.516718 waagent[2751]: 2026-09-09T01:31:19.516696Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Sep 9 01:31:19.546384 waagent[2751]: 2026-09-09T01:31:19.546350Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 61642252-450C-4B6C-A2D4-A927B220A1BA;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 9 01:31:19.558642 waagent[2751]: 2026-09-09T01:31:19.558598Z INFO MonitorHandler ExtHandler Network interfaces: Sep 9 01:31:19.558642 waagent[2751]: Executing ['ip', '-a', '-o', 'link']: Sep 9 01:31:19.558642 waagent[2751]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Sep 9 01:31:19.558642 waagent[2751]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 70:a8:a5:2f:cd:3d brd ff:ff:ff:ff:ff:ff\ alias Network Device\ altname enx70a8a52fcd3d Sep 9 01:31:19.558642 waagent[2751]: 3: enP30832s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 70:a8:a5:2f:cd:3d brd ff:ff:ff:ff:ff:ff\ altname enP30832p0s0\ altname enp0s0\ altname ens1 Sep 9 01:31:19.558642 waagent[2751]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 9 01:31:19.558642 waagent[2751]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 9 01:31:19.558642 waagent[2751]: 2: eth0 inet 10.0.0.8/24 metric 1024 brd 10.0.0.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 9 01:31:19.558642 waagent[2751]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 9 01:31:19.558642 waagent[2751]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 9 01:31:19.558642 waagent[2751]: 2: eth0 inet6 fe80::72a8:a5ff:fe2f:cd3d/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 9 01:31:19.589279 waagent[2751]: 2026-09-09T01:31:19.589236Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 9 01:31:19.589279 waagent[2751]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.589279 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.589279 waagent[2751]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.589279 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.589279 waagent[2751]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.589279 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.589279 waagent[2751]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 01:31:19.589279 waagent[2751]: 5 468 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 01:31:19.589279 waagent[2751]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 01:31:19.591672 waagent[2751]: 2026-09-09T01:31:19.591630Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 9 01:31:19.591672 waagent[2751]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.591672 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.591672 waagent[2751]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.591672 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.591672 waagent[2751]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 01:31:19.591672 waagent[2751]: pkts bytes target prot opt in out source destination Sep 9 01:31:19.591672 waagent[2751]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 01:31:19.591672 waagent[2751]: 5 468 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 01:31:19.591672 waagent[2751]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 01:31:35.155110 chronyd[2512]: Selected source PHC0 Sep 9 01:31:37.576109 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 01:31:37.577347 systemd[1]: Started sshd@0-1-10.0.0.8:22-20.101.212.173:54346.service - OpenSSH per-connection server daemon (20.101.212.173:54346). Sep 9 01:31:37.829736 sshd[2908]: Accepted publickey for core from 20.101.212.173 port 54346 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:37.830625 sshd-session[2908]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:37.840695 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 01:31:37.844953 systemd-logind[2527]: New session '3' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:37.848984 (systemd)[2912]: pam_unix(systemd-user:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:37.850804 systemd-logind[2527]: New session '4' of user 'core' with class 'manager-early' and type 'unspecified'. Sep 9 01:31:38.024192 systemd[2912]: Queued start job for default target default.target. Sep 9 01:31:38.030434 systemd[2912]: Created slice app.slice - User Application Slice. Sep 9 01:31:38.030463 systemd[2912]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of User's Temporary Directories. Sep 9 01:31:38.030479 systemd[2912]: Reached target machines.target - Virtual Machines and Containers. Sep 9 01:31:38.030514 systemd[2912]: Reached target paths.target - Paths. Sep 9 01:31:38.030538 systemd[2912]: Reached target timers.target - Timers. Sep 9 01:31:38.031347 systemd[2912]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 01:31:38.032776 systemd[2912]: Listening on systemd-ask-password.socket - Query the User Interactively for a Password. Sep 9 01:31:38.032923 systemd[2912]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Sep 9 01:31:38.033772 systemd[2912]: Listening on systemd-journalctl.socket - Journal Log Access Socket. Sep 9 01:31:38.033995 systemd[2912]: Listening on systemd-machined.socket - Virtual Machine and Container Registration Service Socket. Sep 9 01:31:38.034541 systemd[2912]: Starting systemd-tmpfiles-setup.service - Create User Files and Directories... Sep 9 01:31:38.041125 systemd[2912]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 01:31:38.041962 systemd[2912]: Reached target sockets.target - Sockets. Sep 9 01:31:38.044016 systemd[2912]: Finished systemd-tmpfiles-setup.service - Create User Files and Directories. Sep 9 01:31:38.044199 systemd[2912]: Reached target basic.target - Basic System. Sep 9 01:31:38.044291 systemd[2912]: Reached target default.target - Main User Target. Sep 9 01:31:38.044358 systemd[2912]: Startup finished in 189ms. Sep 9 01:31:38.044399 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 01:31:38.048048 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 01:31:38.113735 systemd[1]: Started sshd@1-2-10.0.0.8:22-20.101.212.173:54360.service - OpenSSH per-connection server daemon (20.101.212.173:54360). Sep 9 01:31:38.224009 sshd[2926]: Accepted publickey for core from 20.101.212.173 port 54360 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:38.224867 sshd-session[2926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:38.228556 systemd-logind[2527]: New session '5' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:38.231021 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 01:31:38.269641 sshd[2930]: Connection closed by 20.101.212.173 port 54360 Sep 9 01:31:38.270784 sshd-session[2926]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:38.272246 systemd[1]: sshd@1-2-10.0.0.8:22-20.101.212.173:54360.service: Deactivated successfully. Sep 9 01:31:38.273415 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 01:31:38.274449 systemd-logind[2527]: Session 5 logged out. Waiting for processes to exit. Sep 9 01:31:38.275535 systemd-logind[2527]: Removed session 5. Sep 9 01:31:38.289696 systemd[1]: Started sshd@2-3-10.0.0.8:22-20.101.212.173:53486.service - OpenSSH per-connection server daemon (20.101.212.173:53486). Sep 9 01:31:38.396390 sshd[2936]: Accepted publickey for core from 20.101.212.173 port 53486 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:38.397387 sshd-session[2936]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:38.400974 systemd-logind[2527]: New session '6' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:38.409035 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 01:31:38.441181 sshd[2940]: Connection closed by 20.101.212.173 port 53486 Sep 9 01:31:38.441987 sshd-session[2936]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:38.443752 systemd[1]: sshd@2-3-10.0.0.8:22-20.101.212.173:53486.service: Deactivated successfully. Sep 9 01:31:38.444806 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 01:31:38.445384 systemd-logind[2527]: Session 6 logged out. Waiting for processes to exit. Sep 9 01:31:38.446312 systemd-logind[2527]: Removed session 6. Sep 9 01:31:38.467677 systemd[1]: Started sshd@3-4097-10.0.0.8:22-20.101.212.173:53488.service - OpenSSH per-connection server daemon (20.101.212.173:53488). Sep 9 01:31:38.575093 sshd[2947]: Accepted publickey for core from 20.101.212.173 port 53488 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:38.575907 sshd-session[2947]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:38.578808 systemd-logind[2527]: New session '7' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:38.584995 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 01:31:38.620477 sshd[2951]: Connection closed by 20.101.212.173 port 53488 Sep 9 01:31:38.620996 sshd-session[2947]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:38.623132 systemd[1]: sshd@3-4097-10.0.0.8:22-20.101.212.173:53488.service: Deactivated successfully. Sep 9 01:31:38.624185 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 01:31:38.625180 systemd-logind[2527]: Session 7 logged out. Waiting for processes to exit. Sep 9 01:31:38.625801 systemd-logind[2527]: Removed session 7. Sep 9 01:31:38.653631 systemd[1]: Started sshd@4-4-10.0.0.8:22-20.101.212.173:53500.service - OpenSSH per-connection server daemon (20.101.212.173:53500). Sep 9 01:31:38.760131 sshd[2957]: Accepted publickey for core from 20.101.212.173 port 53500 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:38.760843 sshd-session[2957]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:38.764367 systemd-logind[2527]: New session '8' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:38.774012 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 01:31:39.001678 sudo[2962]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 9 01:31:39.001902 sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 01:31:39.013513 sudo[2962]: pam_unix(sudo:session): session closed for user root Sep 9 01:31:39.028169 sshd[2961]: Connection closed by 20.101.212.173 port 53500 Sep 9 01:31:39.029311 sshd-session[2957]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:39.031205 systemd[1]: sshd@4-4-10.0.0.8:22-20.101.212.173:53500.service: Deactivated successfully. Sep 9 01:31:39.032308 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 01:31:39.032982 systemd-logind[2527]: Session 8 logged out. Waiting for processes to exit. Sep 9 01:31:39.033950 systemd-logind[2527]: Removed session 8. Sep 9 01:31:39.053708 systemd[1]: Started sshd@5-5-10.0.0.8:22-20.101.212.173:53504.service - OpenSSH per-connection server daemon (20.101.212.173:53504). Sep 9 01:31:39.159492 sshd[2969]: Accepted publickey for core from 20.101.212.173 port 53504 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:39.160346 sshd-session[2969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:39.163920 systemd-logind[2527]: New session '9' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:39.166007 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 9 01:31:39.192563 sudo[2975]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 9 01:31:39.192757 sudo[2975]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 01:31:39.193833 sudo[2975]: pam_unix(sudo:session): session closed for user root Sep 9 01:31:39.197839 sudo[2974]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 9 01:31:39.198058 sudo[2974]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 01:31:39.203114 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 01:31:39.247000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Sep 9 01:31:39.247000 audit[2999]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffd7e6b44f0 a2=420 a3=0 items=0 ppid=2980 pid=2999 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/bin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:39.258046 kernel: audit: type=1305 audit(1788917499.247:166): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Sep 9 01:31:39.258101 kernel: audit: type=1300 audit(1788917499.247:166): arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffd7e6b44f0 a2=420 a3=0 items=0 ppid=2980 pid=2999 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/bin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:39.258230 augenrules[2999]: No rules Sep 9 01:31:39.247000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Sep 9 01:31:39.259805 kernel: audit: type=1327 audit(1788917499.247:166): proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Sep 9 01:31:39.259408 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 01:31:39.259563 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 01:31:39.259000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.260271 sudo[2974]: pam_unix(sudo:session): session closed for user root Sep 9 01:31:39.259000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.264185 kernel: audit: type=1130 audit(1788917499.259:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.264212 kernel: audit: type=1131 audit(1788917499.259:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.259000 audit[2974]: AUDIT1106 pid=2974 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_umask,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.266813 kernel: audit: type=1106 audit(1788917499.259:169): pid=2974 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_umask,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.259000 audit[2974]: AUDIT1104 pid=2974 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.268966 kernel: audit: type=1104 audit(1788917499.259:170): pid=2974 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.280922 sshd[2973]: Connection closed by 20.101.212.173 port 53504 Sep 9 01:31:39.281969 sshd-session[2969]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:39.281000 audit[2969]: AUDIT1106 pid=2969 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_namespace,pam_keyinit,pam_limits,pam_env,pam_umask,pam_unix,pam_systemd,pam_lastlog,pam_mail acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.287971 kernel: audit: type=1106 audit(1788917499.281:171): pid=2969 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_namespace,pam_keyinit,pam_limits,pam_env,pam_umask,pam_unix,pam_systemd,pam_lastlog,pam_mail acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.284118 systemd[1]: sshd@5-5-10.0.0.8:22-20.101.212.173:53504.service: Deactivated successfully. Sep 9 01:31:39.285552 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 01:31:39.281000 audit[2969]: AUDIT1104 pid=2969 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.288250 systemd-logind[2527]: Session 9 logged out. Waiting for processes to exit. Sep 9 01:31:39.288828 systemd-logind[2527]: Removed session 9. Sep 9 01:31:39.281000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-5-10.0.0.8:22-20.101.212.173:53504 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.295167 kernel: audit: type=1104 audit(1788917499.281:172): pid=2969 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.295199 kernel: audit: type=1131 audit(1788917499.281:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-5-10.0.0.8:22-20.101.212.173:53504 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.303000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-6-10.0.0.8:22-20.101.212.173:53518 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.304721 systemd[1]: Started sshd@6-6-10.0.0.8:22-20.101.212.173:53518.service - OpenSSH per-connection server daemon (20.101.212.173:53518). Sep 9 01:31:39.408000 audit[3008]: AUDIT1101 pid=3008 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_time,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.409526 sshd[3008]: Accepted publickey for core from 20.101.212.173 port 53518 ssh2: RSA SHA256:DHl1hUxJU9E/jYlbwneRthndozW7c9qspn9/duOHSAk Sep 9 01:31:39.409000 audit[3008]: AUDIT1103 pid=3008 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.409000 audit[3008]: SYSCALL arch=c000003e syscall=1 success=yes exit=3 a0=8 a1=7ffce8a12d30 a2=3 a3=0 items=0 ppid=1 pid=3008 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=10 comm="sshd-session" exe="/usr/lib64/misc/sshd-session" subj=system_u:system_r:kernel_t:s0 key=(null) Sep 9 01:31:39.409000 audit: PROCTITLE proctitle=737368642D73657373696F6E3A20636F7265205B707269765D Sep 9 01:31:39.410443 sshd-session[3008]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 01:31:39.413366 systemd-logind[2527]: New session '10' of user 'core' with class 'user' and type 'tty'. Sep 9 01:31:39.421012 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 9 01:31:39.421000 audit[3008]: AUDIT1105 pid=3008 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_namespace,pam_keyinit,pam_limits,pam_env,pam_umask,pam_unix,pam_systemd,pam_lastlog,pam_mail acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.422000 audit[3012]: AUDIT1103 pid=3012 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.442000 audit[3014]: AUDIT1101 pid=3014 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.443690 sudo[3014]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/nft --json list ruleset Sep 9 01:31:39.442000 audit[3014]: AUDIT1110 pid=3014 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.443000 audit[3014]: AUDIT1105 pid=3014 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_umask,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.443911 sudo[3014]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 01:31:39.474000 audit[3014]: AUDIT1106 pid=3014 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_umask,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.475810 sudo[3014]: pam_unix(sudo:session): session closed for user root Sep 9 01:31:39.475000 audit[3014]: AUDIT1104 pid=3014 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.490563 sshd[3012]: Connection closed by 20.101.212.173 port 53518 Sep 9 01:31:39.491714 sshd-session[3008]: pam_unix(sshd:session): session closed for user core Sep 9 01:31:39.491000 audit[3008]: AUDIT1106 pid=3008 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_namespace,pam_keyinit,pam_limits,pam_env,pam_umask,pam_unix,pam_systemd,pam_lastlog,pam_mail acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.491000 audit[3008]: AUDIT1104 pid=3008 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/lib64/misc/sshd-session" hostname=20.101.212.173 addr=20.101.212.173 terminal=ssh res=success' Sep 9 01:31:39.493347 systemd[1]: sshd@6-6-10.0.0.8:22-20.101.212.173:53518.service: Deactivated successfully. Sep 9 01:31:39.494468 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 01:31:39.492000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-6-10.0.0.8:22-20.101.212.173:53518 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 9 01:31:39.495083 systemd-logind[2527]: Session 10 logged out. Waiting for processes to exit. Sep 9 01:31:39.496040 systemd-logind[2527]: Removed session 10.