Mar 17 18:47:37.030496 kernel: Linux version 5.15.179-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP Mon Mar 17 17:12:34 -00 2025 Mar 17 18:47:37.030520 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=249ccd113f901380672c0d31e18f792e8e0344094c0e39eedc449f039418b31a Mar 17 18:47:37.030531 kernel: BIOS-provided physical RAM map: Mar 17 18:47:37.030537 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 17 18:47:37.030545 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Mar 17 18:47:37.030552 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Mar 17 18:47:37.030561 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Mar 17 18:47:37.030573 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Mar 17 18:47:37.030578 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Mar 17 18:47:37.030585 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Mar 17 18:47:37.030593 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable Mar 17 18:47:37.030598 kernel: printk: bootconsole [earlyser0] enabled Mar 17 18:47:37.030606 kernel: NX (Execute Disable) protection: active Mar 17 18:47:37.030613 kernel: efi: EFI v2.70 by Microsoft Mar 17 18:47:37.030635 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5c8a98 RNG=0x3ffd1018 Mar 17 18:47:37.030643 kernel: random: crng init done Mar 17 18:47:37.030650 kernel: SMBIOS 3.1.0 present. Mar 17 18:47:37.030660 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Mar 17 18:47:37.030670 kernel: Hypervisor detected: Microsoft Hyper-V Mar 17 18:47:37.030680 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x64e24, misc 0xbed7b2 Mar 17 18:47:37.030691 kernel: Hyper-V Host Build:20348-10.0-1-0.1799 Mar 17 18:47:37.030702 kernel: Hyper-V: Nested features: 0x1e0101 Mar 17 18:47:37.030716 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Mar 17 18:47:37.030737 kernel: Hyper-V: Using hypercall for remote TLB flush Mar 17 18:47:37.030748 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Mar 17 18:47:37.030760 kernel: tsc: Marking TSC unstable due to running on Hyper-V Mar 17 18:47:37.030772 kernel: tsc: Detected 2593.904 MHz processor Mar 17 18:47:37.030783 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 17 18:47:37.030795 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 17 18:47:37.030807 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x400000000 Mar 17 18:47:37.030819 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 17 18:47:37.030831 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Mar 17 18:47:37.030845 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Mar 17 18:47:37.030857 kernel: Using GB pages for direct mapping Mar 17 18:47:37.030869 kernel: Secure boot disabled Mar 17 18:47:37.030881 kernel: ACPI: Early table checksum verification disabled Mar 17 18:47:37.030893 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Mar 17 18:47:37.030905 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.030917 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.030930 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Mar 17 18:47:37.030949 kernel: ACPI: FACS 0x000000003FFFE000 000040 Mar 17 18:47:37.030962 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.030975 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.030988 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.031000 kernel: ACPI: APIC 0x000000003FFD5000 000058 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.031012 kernel: ACPI: SRAT 0x000000003FFD4000 0002D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.031027 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.031040 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:47:37.031053 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Mar 17 18:47:37.031066 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Mar 17 18:47:37.031079 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Mar 17 18:47:37.031092 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Mar 17 18:47:37.031105 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Mar 17 18:47:37.031117 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Mar 17 18:47:37.031133 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5057] Mar 17 18:47:37.031146 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd42cf] Mar 17 18:47:37.031159 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Mar 17 18:47:37.031172 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Mar 17 18:47:37.031185 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Mar 17 18:47:37.031198 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Mar 17 18:47:37.031210 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Mar 17 18:47:37.031223 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] hotplug Mar 17 18:47:37.031236 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2c0000000-0xfdfffffff] hotplug Mar 17 18:47:37.031251 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Mar 17 18:47:37.031264 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Mar 17 18:47:37.031277 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Mar 17 18:47:37.031290 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Mar 17 18:47:37.031303 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Mar 17 18:47:37.031316 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Mar 17 18:47:37.031329 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Mar 17 18:47:37.031341 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Mar 17 18:47:37.031354 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Mar 17 18:47:37.031370 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000000-0x1ffffffffffff] hotplug Mar 17 18:47:37.031382 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x2000000000000-0x3ffffffffffff] hotplug Mar 17 18:47:37.031396 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4000000000000-0x7ffffffffffff] hotplug Mar 17 18:47:37.031408 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x8000000000000-0xfffffffffffff] hotplug Mar 17 18:47:37.031421 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00000000-0x2bfffffff] Mar 17 18:47:37.031434 kernel: NODE_DATA(0) allocated [mem 0x2bfffa000-0x2bfffffff] Mar 17 18:47:37.031447 kernel: Zone ranges: Mar 17 18:47:37.031460 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 17 18:47:37.031473 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Mar 17 18:47:37.031488 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] Mar 17 18:47:37.031501 kernel: Movable zone start for each node Mar 17 18:47:37.031514 kernel: Early memory node ranges Mar 17 18:47:37.031527 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 17 18:47:37.031539 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Mar 17 18:47:37.031552 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Mar 17 18:47:37.031565 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] Mar 17 18:47:37.031578 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] Mar 17 18:47:37.031591 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 17 18:47:37.031606 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 17 18:47:37.034282 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Mar 17 18:47:37.034299 kernel: ACPI: PM-Timer IO Port: 0x408 Mar 17 18:47:37.034306 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Mar 17 18:47:37.034313 kernel: IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 Mar 17 18:47:37.034320 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 17 18:47:37.034327 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 17 18:47:37.034334 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Mar 17 18:47:37.034341 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Mar 17 18:47:37.034355 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Mar 17 18:47:37.034362 kernel: Booting paravirtualized kernel on Hyper-V Mar 17 18:47:37.034369 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 17 18:47:37.034376 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1 Mar 17 18:47:37.034383 kernel: percpu: Embedded 56 pages/cpu s188696 r8192 d32488 u1048576 Mar 17 18:47:37.034390 kernel: pcpu-alloc: s188696 r8192 d32488 u1048576 alloc=1*2097152 Mar 17 18:47:37.034396 kernel: pcpu-alloc: [0] 0 1 Mar 17 18:47:37.034403 kernel: Hyper-V: PV spinlocks enabled Mar 17 18:47:37.034410 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 17 18:47:37.034419 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2062618 Mar 17 18:47:37.034426 kernel: Policy zone: Normal Mar 17 18:47:37.034434 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=249ccd113f901380672c0d31e18f792e8e0344094c0e39eedc449f039418b31a Mar 17 18:47:37.034441 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 18:47:37.034448 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Mar 17 18:47:37.034455 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 18:47:37.034465 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:47:37.034472 kernel: Memory: 8079144K/8387460K available (12294K kernel code, 2278K rwdata, 13724K rodata, 47472K init, 4108K bss, 308056K reserved, 0K cma-reserved) Mar 17 18:47:37.034481 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 17 18:47:37.034491 kernel: ftrace: allocating 34580 entries in 136 pages Mar 17 18:47:37.034505 kernel: ftrace: allocated 136 pages with 2 groups Mar 17 18:47:37.034514 kernel: rcu: Hierarchical RCU implementation. Mar 17 18:47:37.034525 kernel: rcu: RCU event tracing is enabled. Mar 17 18:47:37.034533 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Mar 17 18:47:37.034542 kernel: Rude variant of Tasks RCU enabled. Mar 17 18:47:37.034551 kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:47:37.034560 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 18:47:37.034569 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 17 18:47:37.034578 kernel: Using NULL legacy PIC Mar 17 18:47:37.034588 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 Mar 17 18:47:37.034598 kernel: Console: colour dummy device 80x25 Mar 17 18:47:37.034605 kernel: printk: console [tty1] enabled Mar 17 18:47:37.034612 kernel: printk: console [ttyS0] enabled Mar 17 18:47:37.034637 kernel: printk: bootconsole [earlyser0] disabled Mar 17 18:47:37.034649 kernel: ACPI: Core revision 20210730 Mar 17 18:47:37.034657 kernel: Failed to register legacy timer interrupt Mar 17 18:47:37.034666 kernel: APIC: Switch to symmetric I/O mode setup Mar 17 18:47:37.034675 kernel: Hyper-V: Using IPI hypercalls Mar 17 18:47:37.034682 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.80 BogoMIPS (lpj=2593904) Mar 17 18:47:37.034690 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Mar 17 18:47:37.034700 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Mar 17 18:47:37.034708 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 17 18:47:37.034717 kernel: Spectre V2 : Mitigation: Retpolines Mar 17 18:47:37.034724 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 17 18:47:37.034733 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 17 18:47:37.034744 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Mar 17 18:47:37.034751 kernel: RETBleed: Vulnerable Mar 17 18:47:37.034761 kernel: Speculative Store Bypass: Vulnerable Mar 17 18:47:37.034769 kernel: TAA: Vulnerable: Clear CPU buffers attempted, no microcode Mar 17 18:47:37.034776 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Mar 17 18:47:37.034785 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 17 18:47:37.034793 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 17 18:47:37.034802 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 17 18:47:37.034810 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Mar 17 18:47:37.034819 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Mar 17 18:47:37.034830 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Mar 17 18:47:37.034837 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 17 18:47:37.034848 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Mar 17 18:47:37.034855 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Mar 17 18:47:37.034862 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Mar 17 18:47:37.034872 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Mar 17 18:47:37.034880 kernel: Freeing SMP alternatives memory: 32K Mar 17 18:47:37.034890 kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:47:37.034897 kernel: LSM: Security Framework initializing Mar 17 18:47:37.034904 kernel: SELinux: Initializing. Mar 17 18:47:37.034913 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 17 18:47:37.034924 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 17 18:47:37.034931 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8272CL CPU @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x7) Mar 17 18:47:37.034938 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Mar 17 18:47:37.034946 kernel: signal: max sigframe size: 3632 Mar 17 18:47:37.034956 kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:47:37.034964 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Mar 17 18:47:37.034971 kernel: smp: Bringing up secondary CPUs ... Mar 17 18:47:37.034982 kernel: x86: Booting SMP configuration: Mar 17 18:47:37.034989 kernel: .... node #0, CPUs: #1 Mar 17 18:47:37.034999 kernel: TAA CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/tsx_async_abort.html for more details. Mar 17 18:47:37.035009 kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Mar 17 18:47:37.035017 kernel: smp: Brought up 1 node, 2 CPUs Mar 17 18:47:37.035024 kernel: smpboot: Max logical packages: 1 Mar 17 18:47:37.035031 kernel: smpboot: Total of 2 processors activated (10375.61 BogoMIPS) Mar 17 18:47:37.035042 kernel: devtmpfs: initialized Mar 17 18:47:37.035049 kernel: x86/mm: Memory block size: 128MB Mar 17 18:47:37.035056 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Mar 17 18:47:37.035064 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 18:47:37.035076 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 17 18:47:37.035083 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:47:37.035090 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 18:47:37.035099 kernel: audit: initializing netlink subsys (disabled) Mar 17 18:47:37.035108 kernel: audit: type=2000 audit(1742237256.024:1): state=initialized audit_enabled=0 res=1 Mar 17 18:47:37.035115 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:47:37.035123 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 17 18:47:37.035133 kernel: cpuidle: using governor menu Mar 17 18:47:37.035140 kernel: ACPI: bus type PCI registered Mar 17 18:47:37.035149 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:47:37.035156 kernel: dca service started, version 1.12.1 Mar 17 18:47:37.035167 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 17 18:47:37.035174 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 18:47:37.035184 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:47:37.035192 kernel: ACPI: Added _OSI(Module Device) Mar 17 18:47:37.035199 kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:47:37.035209 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:47:37.035217 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:47:37.035229 kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:47:37.035236 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:47:37.035243 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:47:37.035253 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 18:47:37.035261 kernel: ACPI: Interpreter enabled Mar 17 18:47:37.035271 kernel: ACPI: PM: (supports S0 S5) Mar 17 18:47:37.035278 kernel: ACPI: Using IOAPIC for interrupt routing Mar 17 18:47:37.035287 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 17 18:47:37.035296 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Mar 17 18:47:37.035307 kernel: iommu: Default domain type: Translated Mar 17 18:47:37.035316 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 17 18:47:37.035323 kernel: vgaarb: loaded Mar 17 18:47:37.035332 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 18:47:37.035340 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 18:47:37.035349 kernel: PTP clock support registered Mar 17 18:47:37.035358 kernel: Registered efivars operations Mar 17 18:47:37.035365 kernel: PCI: Using ACPI for IRQ routing Mar 17 18:47:37.035374 kernel: PCI: System does not support PCI Mar 17 18:47:37.035384 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Mar 17 18:47:37.035394 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:47:37.035402 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:47:37.035409 kernel: pnp: PnP ACPI init Mar 17 18:47:37.035420 kernel: pnp: PnP ACPI: found 3 devices Mar 17 18:47:37.035427 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 17 18:47:37.035434 kernel: NET: Registered PF_INET protocol family Mar 17 18:47:37.035442 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Mar 17 18:47:37.035451 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Mar 17 18:47:37.035461 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 18:47:37.035469 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 18:47:37.035476 kernel: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) Mar 17 18:47:37.035486 kernel: TCP: Hash tables configured (established 65536 bind 65536) Mar 17 18:47:37.035494 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Mar 17 18:47:37.035504 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Mar 17 18:47:37.035511 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 18:47:37.035518 kernel: NET: Registered PF_XDP protocol family Mar 17 18:47:37.035529 kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:47:37.035539 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Mar 17 18:47:37.035548 kernel: software IO TLB: mapped [mem 0x000000003a8ad000-0x000000003e8ad000] (64MB) Mar 17 18:47:37.035556 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Mar 17 18:47:37.035563 kernel: Initialise system trusted keyrings Mar 17 18:47:37.035573 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Mar 17 18:47:37.035580 kernel: Key type asymmetric registered Mar 17 18:47:37.035590 kernel: Asymmetric key parser 'x509' registered Mar 17 18:47:37.035597 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 17 18:47:37.035604 kernel: io scheduler mq-deadline registered Mar 17 18:47:37.035617 kernel: io scheduler kyber registered Mar 17 18:47:37.035633 kernel: io scheduler bfq registered Mar 17 18:47:37.035641 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 17 18:47:37.035648 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 18:47:37.035658 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 17 18:47:37.035666 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Mar 17 18:47:37.035676 kernel: i8042: PNP: No PS/2 controller found. Mar 17 18:47:37.035803 kernel: rtc_cmos 00:02: registered as rtc0 Mar 17 18:47:37.035892 kernel: rtc_cmos 00:02: setting system clock to 2025-03-17T18:47:36 UTC (1742237256) Mar 17 18:47:37.035974 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Mar 17 18:47:37.035985 kernel: intel_pstate: CPU model not supported Mar 17 18:47:37.035992 kernel: efifb: probing for efifb Mar 17 18:47:37.036002 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 18:47:37.036010 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 18:47:37.036020 kernel: efifb: scrolling: redraw Mar 17 18:47:37.036028 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 18:47:37.036036 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:47:37.036047 kernel: fb0: EFI VGA frame buffer device Mar 17 18:47:37.036056 kernel: pstore: Registered efi as persistent store backend Mar 17 18:47:37.036065 kernel: NET: Registered PF_INET6 protocol family Mar 17 18:47:37.036072 kernel: Segment Routing with IPv6 Mar 17 18:47:37.036080 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 18:47:37.036089 kernel: NET: Registered PF_PACKET protocol family Mar 17 18:47:37.036098 kernel: Key type dns_resolver registered Mar 17 18:47:37.036107 kernel: IPI shorthand broadcast: enabled Mar 17 18:47:37.036114 kernel: sched_clock: Marking stable (780462900, 23762100)->(1008847200, -204622200) Mar 17 18:47:37.036125 kernel: registered taskstats version 1 Mar 17 18:47:37.036133 kernel: Loading compiled-in X.509 certificates Mar 17 18:47:37.036143 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.179-flatcar: d5b956bbabb2d386c0246a969032c0de9eaa8220' Mar 17 18:47:37.036151 kernel: Key type .fscrypt registered Mar 17 18:47:37.036158 kernel: Key type fscrypt-provisioning registered Mar 17 18:47:37.036166 kernel: pstore: Using crash dump compression: deflate Mar 17 18:47:37.036175 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 18:47:37.036183 kernel: ima: Allocated hash algorithm: sha1 Mar 17 18:47:37.036195 kernel: ima: No architecture policies found Mar 17 18:47:37.036202 kernel: clk: Disabling unused clocks Mar 17 18:47:37.036211 kernel: Freeing unused kernel image (initmem) memory: 47472K Mar 17 18:47:37.036219 kernel: Write protecting the kernel read-only data: 28672k Mar 17 18:47:37.036229 kernel: Freeing unused kernel image (text/rodata gap) memory: 2040K Mar 17 18:47:37.036237 kernel: Freeing unused kernel image (rodata/data gap) memory: 612K Mar 17 18:47:37.036244 kernel: Run /init as init process Mar 17 18:47:37.036253 kernel: with arguments: Mar 17 18:47:37.036261 kernel: /init Mar 17 18:47:37.036273 kernel: with environment: Mar 17 18:47:37.036280 kernel: HOME=/ Mar 17 18:47:37.036287 kernel: TERM=linux Mar 17 18:47:37.036296 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 18:47:37.036306 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Mar 17 18:47:37.036318 systemd[1]: Detected virtualization microsoft. Mar 17 18:47:37.036326 systemd[1]: Detected architecture x86-64. Mar 17 18:47:37.036334 systemd[1]: Running in initrd. Mar 17 18:47:37.036345 systemd[1]: No hostname configured, using default hostname. Mar 17 18:47:37.036354 systemd[1]: Hostname set to . Mar 17 18:47:37.036364 systemd[1]: Initializing machine ID from random generator. Mar 17 18:47:37.036372 systemd[1]: Queued start job for default target initrd.target. Mar 17 18:47:37.036380 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:47:37.036387 systemd[1]: Reached target cryptsetup.target. Mar 17 18:47:37.036398 systemd[1]: Reached target paths.target. Mar 17 18:47:37.036405 systemd[1]: Reached target slices.target. Mar 17 18:47:37.036416 systemd[1]: Reached target swap.target. Mar 17 18:47:37.036425 systemd[1]: Reached target timers.target. Mar 17 18:47:37.036435 systemd[1]: Listening on iscsid.socket. Mar 17 18:47:37.036444 systemd[1]: Listening on iscsiuio.socket. Mar 17 18:47:37.036453 systemd[1]: Listening on systemd-journald-audit.socket. Mar 17 18:47:37.036463 systemd[1]: Listening on systemd-journald-dev-log.socket. Mar 17 18:47:37.036470 systemd[1]: Listening on systemd-journald.socket. Mar 17 18:47:37.036480 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:47:37.036491 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:47:37.036500 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:47:37.036509 systemd[1]: Reached target sockets.target. Mar 17 18:47:37.036517 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:47:37.036525 systemd[1]: Finished network-cleanup.service. Mar 17 18:47:37.036535 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 18:47:37.036544 systemd[1]: Starting systemd-journald.service... Mar 17 18:47:37.036554 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:47:37.036561 systemd[1]: Starting systemd-resolved.service... Mar 17 18:47:37.036574 systemd[1]: Starting systemd-vconsole-setup.service... Mar 17 18:47:37.036581 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:47:37.036596 systemd-journald[183]: Journal started Mar 17 18:47:37.036646 systemd-journald[183]: Runtime Journal (/run/log/journal/acfd1781c52d4a799f1e879f79f0020d) is 8.0M, max 159.0M, 151.0M free. Mar 17 18:47:37.022491 systemd-modules-load[184]: Inserted module 'overlay' Mar 17 18:47:37.051000 audit[1]: SERVICE_START 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' Mar 17 18:47:37.067766 kernel: audit: type=1130 audit(1742237257.051:2): 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' Mar 17 18:47:37.067801 systemd[1]: Started systemd-journald.service. Mar 17 18:47:37.083633 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 18:47:37.081266 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 18:47:37.086100 systemd[1]: Finished systemd-vconsole-setup.service. Mar 17 18:47:37.080000 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' Mar 17 18:47:37.103961 systemd[1]: Starting dracut-cmdline-ask.service... Mar 17 18:47:37.106448 kernel: audit: type=1130 audit(1742237257.080:3): 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' Mar 17 18:47:37.114159 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:47:37.116832 kernel: Bridge firewalling registered Mar 17 18:47:37.120003 systemd-modules-load[184]: Inserted module 'br_netfilter' Mar 17 18:47:37.136352 systemd[1]: Finished dracut-cmdline-ask.service. Mar 17 18:47:37.191534 kernel: audit: type=1130 audit(1742237257.085:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:37.191562 kernel: audit: type=1130 audit(1742237257.090: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' Mar 17 18:47:37.191586 kernel: audit: type=1130 audit(1742237257.138:6): 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' Mar 17 18:47:37.191604 kernel: audit: type=1130 audit(1742237257.141:7): 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' Mar 17 18:47:37.085000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:37.090000 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' Mar 17 18:47:37.138000 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' Mar 17 18:47:37.141000 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' Mar 17 18:47:37.138160 systemd-resolved[185]: Positive Trust Anchors: Mar 17 18:47:37.195985 dracut-cmdline[202]: dracut-dracut-053 Mar 17 18:47:37.138168 systemd-resolved[185]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:47:37.200019 dracut-cmdline[202]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=249ccd113f901380672c0d31e18f792e8e0344094c0e39eedc449f039418b31a Mar 17 18:47:37.138200 systemd-resolved[185]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 17 18:47:37.139000 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:47:37.142128 systemd[1]: Starting dracut-cmdline.service... Mar 17 18:47:37.177243 systemd-resolved[185]: Defaulting to hostname 'linux'. Mar 17 18:47:37.239380 kernel: SCSI subsystem initialized Mar 17 18:47:37.241820 systemd[1]: Started systemd-resolved.service. Mar 17 18:47:37.243000 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' Mar 17 18:47:37.244415 systemd[1]: Reached target nss-lookup.target. Mar 17 18:47:37.263224 kernel: audit: type=1130 audit(1742237257.243:8): 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' Mar 17 18:47:37.275352 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 18:47:37.275398 kernel: device-mapper: uevent: version 1.0.3 Mar 17 18:47:37.280915 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Mar 17 18:47:37.284994 systemd-modules-load[184]: Inserted module 'dm_multipath' Mar 17 18:47:37.286836 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:47:37.293252 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:47:37.312702 kernel: audit: type=1130 audit(1742237257.290:9): 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' Mar 17 18:47:37.312734 kernel: Loading iSCSI transport class v2.0-870. Mar 17 18:47:37.290000 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' Mar 17 18:47:37.322000 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' Mar 17 18:47:37.320658 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:47:37.337058 kernel: audit: type=1130 audit(1742237257.322: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' Mar 17 18:47:37.347635 kernel: iscsi: registered transport (tcp) Mar 17 18:47:37.375352 kernel: iscsi: registered transport (qla4xxx) Mar 17 18:47:37.375400 kernel: QLogic iSCSI HBA Driver Mar 17 18:47:37.404391 systemd[1]: Finished dracut-cmdline.service. Mar 17 18:47:37.409000 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' Mar 17 18:47:37.410383 systemd[1]: Starting dracut-pre-udev.service... Mar 17 18:47:37.460638 kernel: raid6: avx512x4 gen() 18366 MB/s Mar 17 18:47:37.480635 kernel: raid6: avx512x4 xor() 8310 MB/s Mar 17 18:47:37.500632 kernel: raid6: avx512x2 gen() 18543 MB/s Mar 17 18:47:37.521637 kernel: raid6: avx512x2 xor() 29868 MB/s Mar 17 18:47:37.541631 kernel: raid6: avx512x1 gen() 18581 MB/s Mar 17 18:47:37.561636 kernel: raid6: avx512x1 xor() 26914 MB/s Mar 17 18:47:37.582635 kernel: raid6: avx2x4 gen() 18594 MB/s Mar 17 18:47:37.602635 kernel: raid6: avx2x4 xor() 7887 MB/s Mar 17 18:47:37.622630 kernel: raid6: avx2x2 gen() 18518 MB/s Mar 17 18:47:37.643636 kernel: raid6: avx2x2 xor() 22131 MB/s Mar 17 18:47:37.663632 kernel: raid6: avx2x1 gen() 14182 MB/s Mar 17 18:47:37.683632 kernel: raid6: avx2x1 xor() 19306 MB/s Mar 17 18:47:37.704634 kernel: raid6: sse2x4 gen() 11658 MB/s Mar 17 18:47:37.724633 kernel: raid6: sse2x4 xor() 7179 MB/s Mar 17 18:47:37.744633 kernel: raid6: sse2x2 gen() 12868 MB/s Mar 17 18:47:37.765641 kernel: raid6: sse2x2 xor() 7694 MB/s Mar 17 18:47:37.785630 kernel: raid6: sse2x1 gen() 11633 MB/s Mar 17 18:47:37.809558 kernel: raid6: sse2x1 xor() 5926 MB/s Mar 17 18:47:37.809584 kernel: raid6: using algorithm avx2x4 gen() 18594 MB/s Mar 17 18:47:37.809594 kernel: raid6: .... xor() 7887 MB/s, rmw enabled Mar 17 18:47:37.816496 kernel: raid6: using avx512x2 recovery algorithm Mar 17 18:47:37.831647 kernel: xor: automatically using best checksumming function avx Mar 17 18:47:37.927644 kernel: Btrfs loaded, crc32c=crc32c-intel, zoned=no, fsverity=no Mar 17 18:47:37.936115 systemd[1]: Finished dracut-pre-udev.service. Mar 17 18:47:37.937000 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' Mar 17 18:47:37.939000 audit: BPF prog-id=7 op=LOAD Mar 17 18:47:37.939000 audit: BPF prog-id=8 op=LOAD Mar 17 18:47:37.940740 systemd[1]: Starting systemd-udevd.service... Mar 17 18:47:37.955571 systemd-udevd[385]: Using default interface naming scheme 'v252'. Mar 17 18:47:37.962411 systemd[1]: Started systemd-udevd.service. Mar 17 18:47:37.967000 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' Mar 17 18:47:37.968824 systemd[1]: Starting dracut-pre-trigger.service... Mar 17 18:47:37.983036 dracut-pre-trigger[394]: rd.md=0: removing MD RAID activation Mar 17 18:47:38.010184 systemd[1]: Finished dracut-pre-trigger.service. Mar 17 18:47:38.012000 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' Mar 17 18:47:38.013574 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:47:38.053000 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' Mar 17 18:47:38.051730 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:47:38.109638 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:47:38.113639 kernel: hv_vmbus: Vmbus version:5.2 Mar 17 18:47:38.128661 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 18:47:38.142674 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 18:47:38.158636 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Mar 17 18:47:38.165655 kernel: AVX2 version of gcm_enc/dec engaged. Mar 17 18:47:38.175399 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:47:38.175433 kernel: AES CTR mode by8 optimization enabled Mar 17 18:47:38.175452 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 18:47:38.179641 kernel: scsi host0: storvsc_host_t Mar 17 18:47:38.186653 kernel: scsi host1: storvsc_host_t Mar 17 18:47:38.186701 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 18:47:38.195634 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 18:47:38.203646 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 18:47:38.216313 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 17 18:47:38.216352 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 18:47:38.241367 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 18:47:38.249886 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 18:47:38.249914 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 18:47:38.267611 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 17 18:47:38.267807 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 18:47:38.267984 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 18:47:38.268141 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 17 18:47:38.268301 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 18:47:38.268466 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:47:38.268492 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 18:47:38.313643 kernel: hv_netvsc 7c1e521f-cf9e-7c1e-521f-cf9e7c1e521f eth0: VF slot 1 added Mar 17 18:47:38.322635 kernel: hv_vmbus: registering driver hv_pci Mar 17 18:47:38.328637 kernel: hv_pci dbda7b45-eaf8-4fb9-8bfe-eb6e79330740: PCI VMBus probing: Using version 0x10004 Mar 17 18:47:38.408222 kernel: hv_pci dbda7b45-eaf8-4fb9-8bfe-eb6e79330740: PCI host bridge to bus eaf8:00 Mar 17 18:47:38.408402 kernel: pci_bus eaf8:00: root bus resource [mem 0xfe0000000-0xfe00fffff window] Mar 17 18:47:38.408591 kernel: pci_bus eaf8:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 18:47:38.408760 kernel: pci eaf8:00:02.0: [15b3:1016] type 00 class 0x020000 Mar 17 18:47:38.408938 kernel: pci eaf8:00:02.0: reg 0x10: [mem 0xfe0000000-0xfe00fffff 64bit pref] Mar 17 18:47:38.409096 kernel: pci eaf8:00:02.0: enabling Extended Tags Mar 17 18:47:38.409257 kernel: pci eaf8:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at eaf8:00:02.0 (capable of 63.008 Gb/s with 8.0 GT/s PCIe x8 link) Mar 17 18:47:38.409414 kernel: pci_bus eaf8:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 18:47:38.409566 kernel: pci eaf8:00:02.0: BAR 0: assigned [mem 0xfe0000000-0xfe00fffff 64bit pref] Mar 17 18:47:38.500648 kernel: mlx5_core eaf8:00:02.0: firmware version: 14.30.5000 Mar 17 18:47:38.751602 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (435) Mar 17 18:47:38.751638 kernel: mlx5_core eaf8:00:02.0: MLX5E: StrdRq(0) RqSz(1024) StrdSz(256) RxCqeCmprss(0) Mar 17 18:47:38.751824 kernel: mlx5_core eaf8:00:02.0: Supported tc offload range - chains: 1, prios: 1 Mar 17 18:47:38.751949 kernel: mlx5_core eaf8:00:02.0: mlx5e_tc_post_act_init:40:(pid 191): firmware level support is missing Mar 17 18:47:38.752049 kernel: hv_netvsc 7c1e521f-cf9e-7c1e-521f-cf9e7c1e521f eth0: VF registering: eth1 Mar 17 18:47:38.752144 kernel: mlx5_core eaf8:00:02.0 eth1: joined to eth0 Mar 17 18:47:38.587474 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Mar 17 18:47:38.649288 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:47:38.763131 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Mar 17 18:47:38.768716 kernel: mlx5_core eaf8:00:02.0 enP60152s1: renamed from eth1 Mar 17 18:47:38.793342 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Mar 17 18:47:38.796639 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Mar 17 18:47:38.798287 systemd[1]: Starting disk-uuid.service... Mar 17 18:47:38.813667 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:47:38.820642 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:47:39.829280 disk-uuid[555]: The operation has completed successfully. Mar 17 18:47:39.832227 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:47:39.893870 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 18:47:39.895000 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' Mar 17 18:47:39.895000 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' Mar 17 18:47:39.893968 systemd[1]: Finished disk-uuid.service. Mar 17 18:47:39.909547 systemd[1]: Starting verity-setup.service... Mar 17 18:47:39.944664 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Mar 17 18:47:40.124116 systemd[1]: Found device dev-mapper-usr.device. Mar 17 18:47:40.129063 systemd[1]: Finished verity-setup.service. Mar 17 18:47:40.133000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:40.133871 systemd[1]: Mounting sysusr-usr.mount... Mar 17 18:47:40.207408 systemd[1]: Mounted sysusr-usr.mount. Mar 17 18:47:40.211361 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Mar 17 18:47:40.211481 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Mar 17 18:47:40.215988 systemd[1]: Starting ignition-setup.service... Mar 17 18:47:40.219155 systemd[1]: Starting parse-ip-for-networkd.service... Mar 17 18:47:40.237639 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 18:47:40.237675 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:47:40.237693 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:47:40.290243 systemd[1]: Finished parse-ip-for-networkd.service. Mar 17 18:47:40.292000 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' Mar 17 18:47:40.295000 audit: BPF prog-id=9 op=LOAD Mar 17 18:47:40.295779 systemd[1]: Starting systemd-networkd.service... Mar 17 18:47:40.319648 systemd-networkd[793]: lo: Link UP Mar 17 18:47:40.323000 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' Mar 17 18:47:40.319657 systemd-networkd[793]: lo: Gained carrier Mar 17 18:47:40.320493 systemd-networkd[793]: Enumeration completed Mar 17 18:47:40.320564 systemd[1]: Started systemd-networkd.service. Mar 17 18:47:40.324305 systemd[1]: Reached target network.target. Mar 17 18:47:40.326179 systemd-networkd[793]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:47:40.332637 systemd[1]: Starting iscsiuio.service... Mar 17 18:47:40.340607 systemd[1]: Started iscsiuio.service. Mar 17 18:47:40.343000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:40.345398 systemd[1]: Starting iscsid.service... Mar 17 18:47:40.352895 iscsid[800]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:47:40.352895 iscsid[800]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Mar 17 18:47:40.352895 iscsid[800]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Mar 17 18:47:40.352895 iscsid[800]: If using hardware iscsi like qla4xxx this message can be ignored. Mar 17 18:47:40.352895 iscsid[800]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:47:40.352895 iscsid[800]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Mar 17 18:47:40.357000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:40.382000 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' Mar 17 18:47:40.353145 systemd[1]: Started iscsid.service. Mar 17 18:47:40.359341 systemd[1]: Starting dracut-initqueue.service... Mar 17 18:47:40.402954 kernel: mlx5_core eaf8:00:02.0 enP60152s1: Link up Mar 17 18:47:40.375650 systemd[1]: Finished dracut-initqueue.service. Mar 17 18:47:40.382925 systemd[1]: Reached target remote-fs-pre.target. Mar 17 18:47:40.393596 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:47:40.398678 systemd[1]: Reached target remote-fs.target. Mar 17 18:47:40.403772 systemd[1]: Starting dracut-pre-mount.service... Mar 17 18:47:40.415307 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 18:47:40.420055 systemd[1]: Finished dracut-pre-mount.service. Mar 17 18:47:40.423000 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' Mar 17 18:47:40.439877 kernel: hv_netvsc 7c1e521f-cf9e-7c1e-521f-cf9e7c1e521f eth0: Data path switched to VF: enP60152s1 Mar 17 18:47:40.440079 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:47:40.440466 systemd-networkd[793]: enP60152s1: Link UP Mar 17 18:47:40.441778 systemd-networkd[793]: eth0: Link UP Mar 17 18:47:40.442735 systemd-networkd[793]: eth0: Gained carrier Mar 17 18:47:40.450783 systemd-networkd[793]: enP60152s1: Gained carrier Mar 17 18:47:40.478702 systemd-networkd[793]: eth0: DHCPv4 address 10.200.8.38/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 17 18:47:40.505367 systemd[1]: Finished ignition-setup.service. Mar 17 18:47:40.507000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:40.508859 systemd[1]: Starting ignition-fetch-offline.service... Mar 17 18:47:42.365846 systemd-networkd[793]: eth0: Gained IPv6LL Mar 17 18:47:43.443075 ignition[820]: Ignition 2.14.0 Mar 17 18:47:43.443087 ignition[820]: Stage: fetch-offline Mar 17 18:47:43.443163 ignition[820]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:43.443206 ignition[820]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:43.502496 ignition[820]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:43.504431 ignition[820]: parsed url from cmdline: "" Mar 17 18:47:43.504438 ignition[820]: no config URL provided Mar 17 18:47:43.504448 ignition[820]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:47:43.504464 ignition[820]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:47:43.504472 ignition[820]: failed to fetch config: resource requires networking Mar 17 18:47:43.504674 ignition[820]: Ignition finished successfully Mar 17 18:47:43.518548 systemd[1]: Finished ignition-fetch-offline.service. Mar 17 18:47:43.521000 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' Mar 17 18:47:43.522206 systemd[1]: Starting ignition-fetch.service... Mar 17 18:47:43.544268 kernel: kauditd_printk_skb: 18 callbacks suppressed Mar 17 18:47:43.544291 kernel: audit: type=1130 audit(1742237263.521:29): 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' Mar 17 18:47:43.536507 ignition[826]: Ignition 2.14.0 Mar 17 18:47:43.536513 ignition[826]: Stage: fetch Mar 17 18:47:43.536607 ignition[826]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:43.536647 ignition[826]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:43.581828 ignition[826]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:43.582672 ignition[826]: parsed url from cmdline: "" Mar 17 18:47:43.582679 ignition[826]: no config URL provided Mar 17 18:47:43.582686 ignition[826]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:47:43.582698 ignition[826]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:47:43.582742 ignition[826]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 18:47:43.647635 ignition[826]: GET result: OK Mar 17 18:47:43.647733 ignition[826]: config has been read from IMDS userdata Mar 17 18:47:43.647773 ignition[826]: parsing config with SHA512: 71487a248009a1126160ee6d35a1a7555d0e6cc4a5972077cc9a010fbbebed14323e203f22b8054a8ffd47860a28738b94d3422d3b86bfa635acc011d8ba8a12 Mar 17 18:47:43.651202 unknown[826]: fetched base config from "system" Mar 17 18:47:43.651725 ignition[826]: fetch: fetch complete Mar 17 18:47:43.658000 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' Mar 17 18:47:43.651209 unknown[826]: fetched base config from "system" Mar 17 18:47:43.675145 kernel: audit: type=1130 audit(1742237263.658:30): 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' Mar 17 18:47:43.651729 ignition[826]: fetch: fetch passed Mar 17 18:47:43.651216 unknown[826]: fetched user config from "azure" Mar 17 18:47:43.651770 ignition[826]: Ignition finished successfully Mar 17 18:47:43.653226 systemd[1]: Finished ignition-fetch.service. Mar 17 18:47:43.659574 systemd[1]: Starting ignition-kargs.service... Mar 17 18:47:43.682701 ignition[832]: Ignition 2.14.0 Mar 17 18:47:43.682720 ignition[832]: Stage: kargs Mar 17 18:47:43.682843 ignition[832]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:43.682879 ignition[832]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:43.692784 systemd[1]: Finished ignition-kargs.service. Mar 17 18:47:43.694000 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' Mar 17 18:47:43.689465 ignition[832]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:43.713041 kernel: audit: type=1130 audit(1742237263.694:31): 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' Mar 17 18:47:43.695748 systemd[1]: Starting ignition-disks.service... Mar 17 18:47:43.690607 ignition[832]: kargs: kargs passed Mar 17 18:47:43.690660 ignition[832]: Ignition finished successfully Mar 17 18:47:43.724819 ignition[838]: Ignition 2.14.0 Mar 17 18:47:43.724828 ignition[838]: Stage: disks Mar 17 18:47:43.724955 ignition[838]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:43.724982 ignition[838]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:43.732876 ignition[838]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:43.738283 ignition[838]: disks: disks passed Mar 17 18:47:43.738344 ignition[838]: Ignition finished successfully Mar 17 18:47:43.741314 systemd[1]: Finished ignition-disks.service. Mar 17 18:47:43.744000 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' Mar 17 18:47:43.744774 systemd[1]: Reached target initrd-root-device.target. Mar 17 18:47:43.767410 kernel: audit: type=1130 audit(1742237263.744:32): 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' Mar 17 18:47:43.759043 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:47:43.763414 systemd[1]: Reached target local-fs.target. Mar 17 18:47:43.765444 systemd[1]: Reached target sysinit.target. Mar 17 18:47:43.767417 systemd[1]: Reached target basic.target. Mar 17 18:47:43.770191 systemd[1]: Starting systemd-fsck-root.service... Mar 17 18:47:43.908869 systemd-fsck[846]: ROOT: clean, 623/7326000 files, 481078/7359488 blocks Mar 17 18:47:43.921000 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' Mar 17 18:47:43.918880 systemd[1]: Finished systemd-fsck-root.service. Mar 17 18:47:43.938189 kernel: audit: type=1130 audit(1742237263.921:33): 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' Mar 17 18:47:43.922218 systemd[1]: Mounting sysroot.mount... Mar 17 18:47:43.950639 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Mar 17 18:47:43.950602 systemd[1]: Mounted sysroot.mount. Mar 17 18:47:43.954470 systemd[1]: Reached target initrd-root-fs.target. Mar 17 18:47:43.981271 systemd[1]: Mounting sysroot-usr.mount... Mar 17 18:47:43.987525 systemd[1]: Starting flatcar-metadata-hostname.service... Mar 17 18:47:43.992586 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 18:47:43.992638 systemd[1]: Reached target ignition-diskful.target. Mar 17 18:47:44.002857 systemd[1]: Mounted sysroot-usr.mount. Mar 17 18:47:44.044752 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:47:44.048861 systemd[1]: Starting initrd-setup-root.service... Mar 17 18:47:44.065640 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (857) Mar 17 18:47:44.065680 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 18:47:44.073853 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:47:44.073880 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:47:44.074241 initrd-setup-root[862]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 18:47:44.083091 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:47:44.090357 initrd-setup-root[888]: cut: /sysroot/etc/group: No such file or directory Mar 17 18:47:44.110681 initrd-setup-root[896]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 18:47:44.115594 initrd-setup-root[904]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 18:47:44.502036 systemd[1]: Finished initrd-setup-root.service. Mar 17 18:47:44.506000 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' Mar 17 18:47:44.508448 systemd[1]: Starting ignition-mount.service... Mar 17 18:47:44.527052 kernel: audit: type=1130 audit(1742237264.506:34): 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' Mar 17 18:47:44.527392 systemd[1]: Starting sysroot-boot.service... Mar 17 18:47:44.532903 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Mar 17 18:47:44.536170 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Mar 17 18:47:44.551021 systemd[1]: Finished sysroot-boot.service. Mar 17 18:47:44.554000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:44.568383 ignition[923]: INFO : Ignition 2.14.0 Mar 17 18:47:44.568383 ignition[923]: INFO : Stage: mount Mar 17 18:47:44.568383 ignition[923]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:44.568383 ignition[923]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:44.584476 kernel: audit: type=1130 audit(1742237264.554:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:44.582380 systemd[1]: Finished ignition-mount.service. Mar 17 18:47:44.586506 ignition[923]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:44.586506 ignition[923]: INFO : mount: mount passed Mar 17 18:47:44.586506 ignition[923]: INFO : Ignition finished successfully Mar 17 18:47:44.603641 kernel: audit: type=1130 audit(1742237264.585:36): 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' Mar 17 18:47:44.585000 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' Mar 17 18:47:45.252445 coreos-metadata[856]: Mar 17 18:47:45.252 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 18:47:45.266005 coreos-metadata[856]: Mar 17 18:47:45.265 INFO Fetch successful Mar 17 18:47:45.299191 coreos-metadata[856]: Mar 17 18:47:45.299 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 18:47:45.316061 coreos-metadata[856]: Mar 17 18:47:45.315 INFO Fetch successful Mar 17 18:47:45.329181 coreos-metadata[856]: Mar 17 18:47:45.329 INFO wrote hostname ci-3510.3.7-a-a0f6600ba5 to /sysroot/etc/hostname Mar 17 18:47:45.335000 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' Mar 17 18:47:45.330908 systemd[1]: Finished flatcar-metadata-hostname.service. Mar 17 18:47:45.351874 kernel: audit: type=1130 audit(1742237265.335:37): 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' Mar 17 18:47:45.337150 systemd[1]: Starting ignition-files.service... Mar 17 18:47:45.358592 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:47:45.372637 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (935) Mar 17 18:47:45.381808 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 18:47:45.381843 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:47:45.381857 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:47:45.390048 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:47:45.402549 ignition[954]: INFO : Ignition 2.14.0 Mar 17 18:47:45.402549 ignition[954]: INFO : Stage: files Mar 17 18:47:45.407758 ignition[954]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:45.407758 ignition[954]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:45.407758 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:45.422392 ignition[954]: DEBUG : files: compiled without relabeling support, skipping Mar 17 18:47:45.426525 ignition[954]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 18:47:45.426525 ignition[954]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 18:47:45.466698 ignition[954]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 18:47:45.470691 ignition[954]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 18:47:45.484246 unknown[954]: wrote ssh authorized keys file for user: core Mar 17 18:47:45.487361 ignition[954]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 18:47:45.487361 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/opt/helm-v3.13.2-linux-amd64.tar.gz" Mar 17 18:47:45.487361 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): GET https://get.helm.sh/helm-v3.13.2-linux-amd64.tar.gz: attempt #1 Mar 17 18:47:45.585595 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): GET result: OK Mar 17 18:47:45.721223 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/opt/helm-v3.13.2-linux-amd64.tar.gz" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/home/core/nginx.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/home/core/nginx.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/home/core/nfs-pod.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/home/core/nfs-pod.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/home/core/nfs-pvc.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/home/core/nfs-pvc.yaml" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(8): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(8): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(9): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Mar 17 18:47:45.727368 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(9): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): op(b): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1289086589" Mar 17 18:47:45.789394 ignition[954]: CRITICAL : files: createFilesystemsFiles: createFiles: op(a): op(b): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1289086589": device or resource busy Mar 17 18:47:45.789394 ignition[954]: ERROR : files: createFilesystemsFiles: createFiles: op(a): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1289086589", trying btrfs: device or resource busy Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): op(c): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1289086589" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): op(c): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1289086589" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): op(d): [started] unmounting "/mnt/oem1289086589" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): op(d): [finished] unmounting "/mnt/oem1289086589" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(a): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:47:45.789394 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): op(f): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1301164834" Mar 17 18:47:45.789394 ignition[954]: CRITICAL : files: createFilesystemsFiles: createFiles: op(e): op(f): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1301164834": device or resource busy Mar 17 18:47:45.747651 systemd[1]: mnt-oem1289086589.mount: Deactivated successfully. Mar 17 18:47:45.865191 ignition[954]: ERROR : files: createFilesystemsFiles: createFiles: op(e): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1301164834", trying btrfs: device or resource busy Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): op(10): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1301164834" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): op(10): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1301164834" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): op(11): [started] unmounting "/mnt/oem1301164834" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): op(11): [finished] unmounting "/mnt/oem1301164834" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(e): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(12): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Mar 17 18:47:45.865191 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(12): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 Mar 17 18:47:46.283067 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(12): GET result: OK Mar 17 18:47:46.684453 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(12): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Mar 17 18:47:46.684453 ignition[954]: INFO : files: op(13): [started] processing unit "nvidia.service" Mar 17 18:47:46.684453 ignition[954]: INFO : files: op(13): [finished] processing unit "nvidia.service" Mar 17 18:47:46.684453 ignition[954]: INFO : files: op(14): [started] processing unit "waagent.service" Mar 17 18:47:46.684453 ignition[954]: INFO : files: op(14): [finished] processing unit "waagent.service" Mar 17 18:47:46.684453 ignition[954]: INFO : files: op(15): [started] processing unit "prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(15): op(16): [started] writing unit "prepare-helm.service" at "/sysroot/etc/systemd/system/prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(15): op(16): [finished] writing unit "prepare-helm.service" at "/sysroot/etc/systemd/system/prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(15): [finished] processing unit "prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(17): [started] setting preset to enabled for "nvidia.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(17): [finished] setting preset to enabled for "nvidia.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(18): [started] setting preset to enabled for "waagent.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(18): [finished] setting preset to enabled for "waagent.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(19): [started] setting preset to enabled for "prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: op(19): [finished] setting preset to enabled for "prepare-helm.service" Mar 17 18:47:46.710324 ignition[954]: INFO : files: createResultFile: createFiles: op(1a): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:47:46.710324 ignition[954]: INFO : files: createResultFile: createFiles: op(1a): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:47:46.710324 ignition[954]: INFO : files: files passed Mar 17 18:47:46.710324 ignition[954]: INFO : Ignition finished successfully Mar 17 18:47:46.734476 kernel: audit: type=1130 audit(1742237266.710:38): 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' Mar 17 18:47:46.710000 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' Mar 17 18:47:46.705779 systemd[1]: Finished ignition-files.service. Mar 17 18:47:46.711557 systemd[1]: Starting initrd-setup-root-after-ignition.service... Mar 17 18:47:46.784000 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' Mar 17 18:47:46.784000 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' Mar 17 18:47:46.735120 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Mar 17 18:47:46.790211 initrd-setup-root-after-ignition[979]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 18:47:46.772559 systemd[1]: Starting ignition-quench.service... Mar 17 18:47:46.779985 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 18:47:46.798000 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' Mar 17 18:47:46.780101 systemd[1]: Finished ignition-quench.service. Mar 17 18:47:46.790319 systemd[1]: Finished initrd-setup-root-after-ignition.service. Mar 17 18:47:46.798973 systemd[1]: Reached target ignition-complete.target. Mar 17 18:47:46.804378 systemd[1]: Starting initrd-parse-etc.service... Mar 17 18:47:46.820855 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 18:47:46.820952 systemd[1]: Finished initrd-parse-etc.service. Mar 17 18:47:46.827000 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' Mar 17 18:47:46.827000 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' Mar 17 18:47:46.828083 systemd[1]: Reached target initrd-fs.target. Mar 17 18:47:46.832202 systemd[1]: Reached target initrd.target. Mar 17 18:47:46.836033 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Mar 17 18:47:46.840043 systemd[1]: Starting dracut-pre-pivot.service... Mar 17 18:47:46.854136 systemd[1]: Finished dracut-pre-pivot.service. Mar 17 18:47:46.853000 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' Mar 17 18:47:46.857132 systemd[1]: Starting initrd-cleanup.service... Mar 17 18:47:46.868538 systemd[1]: Stopped target nss-lookup.target. Mar 17 18:47:46.873291 systemd[1]: Stopped target remote-cryptsetup.target. Mar 17 18:47:46.878033 systemd[1]: Stopped target timers.target. Mar 17 18:47:46.882233 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 18:47:46.884824 systemd[1]: Stopped dracut-pre-pivot.service. Mar 17 18:47:46.888000 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' Mar 17 18:47:46.889125 systemd[1]: Stopped target initrd.target. Mar 17 18:47:46.893758 systemd[1]: Stopped target basic.target. Mar 17 18:47:46.895756 systemd[1]: Stopped target ignition-complete.target. Mar 17 18:47:46.899953 systemd[1]: Stopped target ignition-diskful.target. Mar 17 18:47:46.904460 systemd[1]: Stopped target initrd-root-device.target. Mar 17 18:47:46.909084 systemd[1]: Stopped target remote-fs.target. Mar 17 18:47:46.913588 systemd[1]: Stopped target remote-fs-pre.target. Mar 17 18:47:46.915899 systemd[1]: Stopped target sysinit.target. Mar 17 18:47:46.919907 systemd[1]: Stopped target local-fs.target. Mar 17 18:47:46.924023 systemd[1]: Stopped target local-fs-pre.target. Mar 17 18:47:46.928049 systemd[1]: Stopped target swap.target. Mar 17 18:47:46.935000 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' Mar 17 18:47:46.931813 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 18:47:46.942000 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' Mar 17 18:47:46.931940 systemd[1]: Stopped dracut-pre-mount.service. Mar 17 18:47:46.946000 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' Mar 17 18:47:46.936035 systemd[1]: Stopped target cryptsetup.target. Mar 17 18:47:46.940292 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 18:47:46.954000 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' Mar 17 18:47:46.959000 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' Mar 17 18:47:46.940427 systemd[1]: Stopped dracut-initqueue.service. Mar 17 18:47:46.945137 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 18:47:46.968000 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' Mar 17 18:47:46.971000 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' Mar 17 18:47:46.945281 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Mar 17 18:47:46.949782 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 18:47:46.983315 ignition[992]: INFO : Ignition 2.14.0 Mar 17 18:47:46.983315 ignition[992]: INFO : Stage: umount Mar 17 18:47:46.983315 ignition[992]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:47:46.983315 ignition[992]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:47:46.989000 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' Mar 17 18:47:46.989000 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' Mar 17 18:47:46.949938 systemd[1]: Stopped ignition-files.service. Mar 17 18:47:47.001167 ignition[992]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:47:47.001167 ignition[992]: INFO : umount: umount passed Mar 17 18:47:47.001167 ignition[992]: INFO : Ignition finished successfully Mar 17 18:47:47.006000 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' Mar 17 18:47:47.008000 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' Mar 17 18:47:47.012000 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' Mar 17 18:47:46.954408 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 18:47:46.954527 systemd[1]: Stopped flatcar-metadata-hostname.service. Mar 17 18:47:46.960254 systemd[1]: Stopping ignition-mount.service... Mar 17 18:47:47.023000 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' Mar 17 18:47:46.964418 systemd[1]: Stopping sysroot-boot.service... Mar 17 18:47:47.030000 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' Mar 17 18:47:46.966540 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 18:47:46.966737 systemd[1]: Stopped systemd-udev-trigger.service. Mar 17 18:47:46.969538 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 18:47:46.969702 systemd[1]: Stopped dracut-pre-trigger.service. Mar 17 18:47:46.975064 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 18:47:46.975140 systemd[1]: Finished initrd-cleanup.service. Mar 17 18:47:47.058000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:46.999699 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 18:47:46.999840 systemd[1]: Stopped ignition-mount.service. Mar 17 18:47:47.006564 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 18:47:47.006608 systemd[1]: Stopped ignition-disks.service. Mar 17 18:47:47.074000 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' Mar 17 18:47:47.010493 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 18:47:47.079000 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' Mar 17 18:47:47.010528 systemd[1]: Stopped ignition-kargs.service. Mar 17 18:47:47.012617 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 18:47:47.012688 systemd[1]: Stopped ignition-fetch.service. Mar 17 18:47:47.092000 audit: BPF prog-id=6 op=UNLOAD Mar 17 18:47:47.094000 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' Mar 17 18:47:47.023988 systemd[1]: Stopped target network.target. Mar 17 18:47:47.098000 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' Mar 17 18:47:47.027947 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 18:47:47.028007 systemd[1]: Stopped ignition-fetch-offline.service. Mar 17 18:47:47.030350 systemd[1]: Stopped target paths.target. Mar 17 18:47:47.034388 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 18:47:47.039667 systemd[1]: Stopped systemd-ask-password-console.path. Mar 17 18:47:47.042138 systemd[1]: Stopped target slices.target. Mar 17 18:47:47.046582 systemd[1]: Stopped target sockets.target. Mar 17 18:47:47.048661 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 18:47:47.048692 systemd[1]: Closed iscsid.socket. Mar 17 18:47:47.052424 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 18:47:47.052453 systemd[1]: Closed iscsiuio.socket. Mar 17 18:47:47.057191 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 18:47:47.057235 systemd[1]: Stopped ignition-setup.service. Mar 17 18:47:47.061089 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:47:47.066266 systemd[1]: Stopping systemd-resolved.service... Mar 17 18:47:47.070672 systemd-networkd[793]: eth0: DHCPv6 lease lost Mar 17 18:47:47.104000 audit: BPF prog-id=9 op=UNLOAD Mar 17 18:47:47.072852 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 18:47:47.073333 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:47:47.073428 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:47:47.077147 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 18:47:47.077227 systemd[1]: Stopped systemd-resolved.service. Mar 17 18:47:47.082124 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 18:47:47.082155 systemd[1]: Closed systemd-networkd.socket. Mar 17 18:47:47.086928 systemd[1]: Stopping network-cleanup.service... Mar 17 18:47:47.090511 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 18:47:47.090571 systemd[1]: Stopped parse-ip-for-networkd.service. Mar 17 18:47:47.094859 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 18:47:47.094912 systemd[1]: Stopped systemd-sysctl.service. Mar 17 18:47:47.099133 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 18:47:47.099185 systemd[1]: Stopped systemd-modules-load.service. Mar 17 18:47:47.163000 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' Mar 17 18:47:47.164364 systemd[1]: Stopping systemd-udevd.service... Mar 17 18:47:47.167702 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 18:47:47.174088 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 18:47:47.174208 systemd[1]: Stopped systemd-udevd.service. Mar 17 18:47:47.179000 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' Mar 17 18:47:47.181344 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 18:47:47.181401 systemd[1]: Closed systemd-udevd-control.socket. Mar 17 18:47:47.186357 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 18:47:47.195000 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' Mar 17 18:47:47.186402 systemd[1]: Closed systemd-udevd-kernel.socket. Mar 17 18:47:47.199000 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' Mar 17 18:47:47.190476 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 18:47:47.202000 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' Mar 17 18:47:47.190534 systemd[1]: Stopped dracut-pre-udev.service. Mar 17 18:47:47.195458 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 18:47:47.212000 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' Mar 17 18:47:47.195508 systemd[1]: Stopped dracut-cmdline.service. Mar 17 18:47:47.217000 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' Mar 17 18:47:47.226000 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' Mar 17 18:47:47.226638 kernel: hv_netvsc 7c1e521f-cf9e-7c1e-521f-cf9e7c1e521f eth0: Data path switched from VF: enP60152s1 Mar 17 18:47:47.199588 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 18:47:47.199671 systemd[1]: Stopped dracut-cmdline-ask.service. Mar 17 18:47:47.205377 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Mar 17 18:47:47.209706 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 18:47:47.209768 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Mar 17 18:47:47.212481 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 18:47:47.212537 systemd[1]: Stopped kmod-static-nodes.service. Mar 17 18:47:47.217324 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 18:47:47.217377 systemd[1]: Stopped systemd-vconsole-setup.service. Mar 17 18:47:47.248709 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 17 18:47:47.252974 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 18:47:47.255691 systemd[1]: Stopped network-cleanup.service. Mar 17 18:47:47.259000 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' Mar 17 18:47:47.260013 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 18:47:47.262739 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Mar 17 18:47:47.267000 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' Mar 17 18:47:47.267000 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' Mar 17 18:47:47.558458 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 18:47:47.558605 systemd[1]: Stopped sysroot-boot.service. Mar 17 18:47:47.563000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:47.564573 systemd[1]: Reached target initrd-switch-root.target. Mar 17 18:47:47.570000 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' Mar 17 18:47:47.568741 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 18:47:47.568805 systemd[1]: Stopped initrd-setup-root.service. Mar 17 18:47:47.571986 systemd[1]: Starting initrd-switch-root.service... Mar 17 18:47:47.585813 systemd[1]: Switching root. Mar 17 18:47:47.614045 systemd-journald[183]: Received SIGTERM from PID 1 (systemd). Mar 17 18:47:47.613000 audit[796]: ANOM_ABEND auid=4294967295 uid=0 gid=0 ses=4294967295 subj=kernel pid=796 comm="iscsiuio" exe=2F7573722F7362696E2F697363736975696F202864656C6574656429 sig=6 res=1 Mar 17 18:47:47.614153 iscsiuio[796]: libgcc_s.so.1 must be installed for pthread_cancel to work Mar 17 18:47:47.617290 iscsid[800]: iscsid shutting down. Mar 17 18:47:47.619133 systemd-journald[183]: Journal stopped Mar 17 18:47:58.323567 kernel: SELinux: Class mctp_socket not defined in policy. Mar 17 18:47:58.323592 kernel: SELinux: Class anon_inode not defined in policy. Mar 17 18:47:58.323605 kernel: SELinux: the above unknown classes and permissions will be allowed Mar 17 18:47:58.323614 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 18:47:58.323632 kernel: SELinux: policy capability open_perms=1 Mar 17 18:47:58.323641 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 18:47:58.323653 kernel: SELinux: policy capability always_check_network=0 Mar 17 18:47:58.323664 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 18:47:58.323674 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 18:47:58.323682 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 18:47:58.323694 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 18:47:58.323703 kernel: kauditd_printk_skb: 42 callbacks suppressed Mar 17 18:47:58.323714 kernel: audit: type=1403 audit(1742237269.072:81): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:47:58.323724 systemd[1]: Successfully loaded SELinux policy in 237.255ms. Mar 17 18:47:58.323740 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.965ms. Mar 17 18:47:58.323752 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Mar 17 18:47:58.323763 systemd[1]: Detected virtualization microsoft. Mar 17 18:47:58.323773 systemd[1]: Detected architecture x86-64. Mar 17 18:47:58.323784 systemd[1]: Detected first boot. Mar 17 18:47:58.323799 systemd[1]: Hostname set to . Mar 17 18:47:58.323808 systemd[1]: Initializing machine ID from random generator. Mar 17 18:47:58.323819 kernel: audit: type=1400 audit(1742237269.594:82): avc: denied { integrity } for pid=1 comm="systemd" lockdown_reason="/dev/mem,kmem,port" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Mar 17 18:47:58.323830 kernel: audit: type=1400 audit(1742237269.631:83): avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:47:58.323842 kernel: audit: type=1400 audit(1742237269.631:84): avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:47:58.323851 kernel: audit: type=1334 audit(1742237269.644:85): prog-id=10 op=LOAD Mar 17 18:47:58.323865 kernel: audit: type=1334 audit(1742237269.644:86): prog-id=10 op=UNLOAD Mar 17 18:47:58.323874 kernel: audit: type=1334 audit(1742237269.656:87): prog-id=11 op=LOAD Mar 17 18:47:58.323885 kernel: audit: type=1334 audit(1742237269.656:88): prog-id=11 op=UNLOAD Mar 17 18:47:58.323894 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Mar 17 18:47:58.323906 kernel: audit: type=1400 audit(1742237270.873:89): avc: denied { associate } for pid=1027 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Mar 17 18:47:58.323916 kernel: audit: type=1300 audit(1742237270.873:89): arch=c000003e syscall=188 success=yes exit=0 a0=c0001078cc a1=c00002ae58 a2=c000029100 a3=32 items=0 ppid=1010 pid=1027 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:58.323928 systemd[1]: Populated /etc with preset unit settings. Mar 17 18:47:58.323940 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:47:58.323953 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:47:58.323964 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 18:47:58.323975 kernel: kauditd_printk_skb: 7 callbacks suppressed Mar 17 18:47:58.323984 kernel: audit: type=1334 audit(1742237277.805:91): prog-id=12 op=LOAD Mar 17 18:47:58.323995 kernel: audit: type=1334 audit(1742237277.805:92): prog-id=3 op=UNLOAD Mar 17 18:47:58.324004 kernel: audit: type=1334 audit(1742237277.810:93): prog-id=13 op=LOAD Mar 17 18:47:58.324019 kernel: audit: type=1334 audit(1742237277.815:94): prog-id=14 op=LOAD Mar 17 18:47:58.324029 kernel: audit: type=1334 audit(1742237277.815:95): prog-id=4 op=UNLOAD Mar 17 18:47:58.324041 kernel: audit: type=1334 audit(1742237277.815:96): prog-id=5 op=UNLOAD Mar 17 18:47:58.324052 kernel: audit: type=1334 audit(1742237277.820:97): prog-id=15 op=LOAD Mar 17 18:47:58.324063 kernel: audit: type=1334 audit(1742237277.820:98): prog-id=12 op=UNLOAD Mar 17 18:47:58.324072 kernel: audit: type=1334 audit(1742237277.835:99): prog-id=16 op=LOAD Mar 17 18:47:58.324083 kernel: audit: type=1334 audit(1742237277.840:100): prog-id=17 op=LOAD Mar 17 18:47:58.324094 systemd[1]: iscsiuio.service: Main process exited, code=killed, status=6/ABRT Mar 17 18:47:58.324105 systemd[1]: iscsiuio.service: Failed with result 'signal'. Mar 17 18:47:58.324118 systemd[1]: Stopped iscsiuio.service. Mar 17 18:47:58.324129 systemd[1]: iscsid.service: Deactivated successfully. Mar 17 18:47:58.324142 systemd[1]: Stopped iscsid.service. Mar 17 18:47:58.324151 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 18:47:58.324163 systemd[1]: Stopped initrd-switch-root.service. Mar 17 18:47:58.324173 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 18:47:58.324186 systemd[1]: Created slice system-addon\x2dconfig.slice. Mar 17 18:47:58.324196 systemd[1]: Created slice system-addon\x2drun.slice. Mar 17 18:47:58.324210 systemd[1]: Created slice system-getty.slice. Mar 17 18:47:58.324220 systemd[1]: Created slice system-modprobe.slice. Mar 17 18:47:58.324232 systemd[1]: Created slice system-serial\x2dgetty.slice. Mar 17 18:47:58.324241 systemd[1]: Created slice system-system\x2dcloudinit.slice. Mar 17 18:47:58.324255 systemd[1]: Created slice system-systemd\x2dfsck.slice. Mar 17 18:47:58.324266 systemd[1]: Created slice user.slice. Mar 17 18:47:58.324278 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:47:58.324288 systemd[1]: Started systemd-ask-password-wall.path. Mar 17 18:47:58.324300 systemd[1]: Set up automount boot.automount. Mar 17 18:47:58.324314 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Mar 17 18:47:58.324324 systemd[1]: Stopped target initrd-switch-root.target. Mar 17 18:47:58.324336 systemd[1]: Stopped target initrd-fs.target. Mar 17 18:47:58.324347 systemd[1]: Stopped target initrd-root-fs.target. Mar 17 18:47:58.324359 systemd[1]: Reached target integritysetup.target. Mar 17 18:47:58.324369 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:47:58.324382 systemd[1]: Reached target remote-fs.target. Mar 17 18:47:58.324392 systemd[1]: Reached target slices.target. Mar 17 18:47:58.324406 systemd[1]: Reached target swap.target. Mar 17 18:47:58.324416 systemd[1]: Reached target torcx.target. Mar 17 18:47:58.324428 systemd[1]: Reached target veritysetup.target. Mar 17 18:47:58.324440 systemd[1]: Listening on systemd-coredump.socket. Mar 17 18:47:58.324453 systemd[1]: Listening on systemd-initctl.socket. Mar 17 18:47:58.324464 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:47:58.324476 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:47:58.324489 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:47:58.324500 systemd[1]: Listening on systemd-userdbd.socket. Mar 17 18:47:58.324513 systemd[1]: Mounting dev-hugepages.mount... Mar 17 18:47:58.324524 systemd[1]: Mounting dev-mqueue.mount... Mar 17 18:47:58.324536 systemd[1]: Mounting media.mount... Mar 17 18:47:58.324546 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:47:58.324560 systemd[1]: Mounting sys-kernel-debug.mount... Mar 17 18:47:58.324573 systemd[1]: Mounting sys-kernel-tracing.mount... Mar 17 18:47:58.324583 systemd[1]: Mounting tmp.mount... Mar 17 18:47:58.324596 systemd[1]: Starting flatcar-tmpfiles.service... Mar 17 18:47:58.324606 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:47:58.324625 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:47:58.324637 systemd[1]: Starting modprobe@configfs.service... Mar 17 18:47:58.324648 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:47:58.324661 systemd[1]: Starting modprobe@drm.service... Mar 17 18:47:58.324673 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:47:58.324686 systemd[1]: Starting modprobe@fuse.service... Mar 17 18:47:58.324696 systemd[1]: Starting modprobe@loop.service... Mar 17 18:47:58.324709 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 18:47:58.324719 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 18:47:58.324731 systemd[1]: Stopped systemd-fsck-root.service. Mar 17 18:47:58.324743 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 18:47:58.324754 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 18:47:58.324765 kernel: loop: module loaded Mar 17 18:47:58.324778 systemd[1]: Stopped systemd-journald.service. Mar 17 18:47:58.324791 systemd[1]: Starting systemd-journald.service... Mar 17 18:47:58.324800 kernel: fuse: init (API version 7.34) Mar 17 18:47:58.324812 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:47:58.324823 systemd[1]: Starting systemd-network-generator.service... Mar 17 18:47:58.324835 systemd[1]: Starting systemd-remount-fs.service... Mar 17 18:47:58.324844 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:47:58.324857 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 18:47:58.324868 systemd[1]: Stopped verity-setup.service. Mar 17 18:47:58.324881 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:47:58.324896 systemd-journald[1127]: Journal started Mar 17 18:47:58.324938 systemd-journald[1127]: Runtime Journal (/run/log/journal/1589e35ff6874c43a164dcb7fcb49176) is 8.0M, max 159.0M, 151.0M free. Mar 17 18:47:49.072000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:47:49.594000 audit[1]: AVC avc: denied { integrity } for pid=1 comm="systemd" lockdown_reason="/dev/mem,kmem,port" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Mar 17 18:47:49.631000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:47:49.631000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:47:49.644000 audit: BPF prog-id=10 op=LOAD Mar 17 18:47:49.644000 audit: BPF prog-id=10 op=UNLOAD Mar 17 18:47:49.656000 audit: BPF prog-id=11 op=LOAD Mar 17 18:47:49.656000 audit: BPF prog-id=11 op=UNLOAD Mar 17 18:47:50.873000 audit[1027]: AVC avc: denied { associate } for pid=1027 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Mar 17 18:47:50.873000 audit[1027]: SYSCALL arch=c000003e syscall=188 success=yes exit=0 a0=c0001078cc a1=c00002ae58 a2=c000029100 a3=32 items=0 ppid=1010 pid=1027 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:50.873000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:47:50.881000 audit[1027]: AVC avc: denied { associate } for pid=1027 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Mar 17 18:47:50.881000 audit[1027]: SYSCALL arch=c000003e syscall=258 success=yes exit=0 a0=ffffffffffffff9c a1=c0001079a5 a2=1ed a3=0 items=2 ppid=1010 pid=1027 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:50.881000 audit: CWD cwd="/" Mar 17 18:47:50.881000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:50.881000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:50.881000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:47:57.805000 audit: BPF prog-id=12 op=LOAD Mar 17 18:47:57.805000 audit: BPF prog-id=3 op=UNLOAD Mar 17 18:47:57.810000 audit: BPF prog-id=13 op=LOAD Mar 17 18:47:57.815000 audit: BPF prog-id=14 op=LOAD Mar 17 18:47:57.815000 audit: BPF prog-id=4 op=UNLOAD Mar 17 18:47:57.815000 audit: BPF prog-id=5 op=UNLOAD Mar 17 18:47:57.820000 audit: BPF prog-id=15 op=LOAD Mar 17 18:47:57.820000 audit: BPF prog-id=12 op=UNLOAD Mar 17 18:47:57.835000 audit: BPF prog-id=16 op=LOAD Mar 17 18:47:57.840000 audit: BPF prog-id=17 op=LOAD Mar 17 18:47:57.840000 audit: BPF prog-id=13 op=UNLOAD Mar 17 18:47:57.840000 audit: BPF prog-id=14 op=UNLOAD Mar 17 18:47:57.845000 audit[1]: SERVICE_STOP 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' Mar 17 18:47:57.866000 audit: BPF prog-id=15 op=UNLOAD Mar 17 18:47:57.876000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed' Mar 17 18:47:57.886000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.897000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.897000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.223000 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' Mar 17 18:47:58.237000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.243000 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' Mar 17 18:47:58.243000 audit[1]: SERVICE_STOP 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' Mar 17 18:47:58.244000 audit: BPF prog-id=18 op=LOAD Mar 17 18:47:58.244000 audit: BPF prog-id=19 op=LOAD Mar 17 18:47:58.244000 audit: BPF prog-id=20 op=LOAD Mar 17 18:47:58.244000 audit: BPF prog-id=16 op=UNLOAD Mar 17 18:47:58.244000 audit: BPF prog-id=17 op=UNLOAD Mar 17 18:47:58.320000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Mar 17 18:47:58.320000 audit[1127]: SYSCALL arch=c000003e syscall=46 success=yes exit=60 a0=6 a1=7ffd2503b330 a2=4000 a3=7ffd2503b3cc items=0 ppid=1 pid=1127 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) Mar 17 18:47:58.320000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Mar 17 18:47:58.320000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:50.810756 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:47:48.813741 systemd-coredump[1009]: Failed to connect to coredump service: No such file or directory Mar 17 18:47:50.823674 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:47:57.805001 systemd[1]: Queued start job for default target multi-user.target. Mar 17 18:47:50.823699 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:47:57.805015 systemd[1]: Unnecessary job was removed for dev-sda6.device. Mar 17 18:47:50.823745 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Mar 17 18:47:57.845737 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 18:47:50.823757 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="skipped missing lower profile" missing profile=oem Mar 17 18:47:50.823808 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Mar 17 18:47:50.823830 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Mar 17 18:47:50.824086 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Mar 17 18:47:50.824133 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:47:50.824148 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:47:50.863128 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Mar 17 18:47:50.863192 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Mar 17 18:47:50.863218 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Mar 17 18:47:50.863236 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Mar 17 18:47:50.863266 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Mar 17 18:47:50.863284 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:50Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Mar 17 18:47:56.866784 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:47:56.867123 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:47:56.867434 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:47:56.867800 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:47:56.867875 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Mar 17 18:47:56.867944 /usr/lib/systemd/system-generators/torcx-generator[1027]: time="2025-03-17T18:47:56Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Mar 17 18:47:58.336249 systemd[1]: Started systemd-journald.service. Mar 17 18:47:58.336000 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' Mar 17 18:47:58.337083 systemd[1]: Mounted dev-hugepages.mount. Mar 17 18:47:58.339392 systemd[1]: Mounted dev-mqueue.mount. Mar 17 18:47:58.341521 systemd[1]: Mounted media.mount. Mar 17 18:47:58.343543 systemd[1]: Mounted sys-kernel-debug.mount. Mar 17 18:47:58.345989 systemd[1]: Mounted sys-kernel-tracing.mount. Mar 17 18:47:58.348390 systemd[1]: Mounted tmp.mount. Mar 17 18:47:58.350441 systemd[1]: Finished flatcar-tmpfiles.service. Mar 17 18:47:58.352000 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' Mar 17 18:47:58.352847 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:47:58.355000 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' Mar 17 18:47:58.355325 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 18:47:58.355471 systemd[1]: Finished modprobe@configfs.service. Mar 17 18:47:58.357000 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' Mar 17 18:47:58.357000 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' Mar 17 18:47:58.358007 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:47:58.358194 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:47:58.360000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.360000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.360758 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:47:58.360920 systemd[1]: Finished modprobe@drm.service. Mar 17 18:47:58.362000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.362000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.363344 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:47:58.363493 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:47:58.365000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.365000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.366095 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 18:47:58.366431 systemd[1]: Finished modprobe@fuse.service. Mar 17 18:47:58.368000 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' Mar 17 18:47:58.368000 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' Mar 17 18:47:58.369085 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:47:58.369303 systemd[1]: Finished modprobe@loop.service. Mar 17 18:47:58.371000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.371000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:58.373033 systemd[1]: Finished systemd-network-generator.service. Mar 17 18:47:58.375000 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' Mar 17 18:47:58.376043 systemd[1]: Finished systemd-remount-fs.service. Mar 17 18:47:58.378000 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' Mar 17 18:47:58.378977 systemd[1]: Reached target network-pre.target. Mar 17 18:47:58.382638 systemd[1]: Mounting sys-fs-fuse-connections.mount... Mar 17 18:47:58.386550 systemd[1]: Mounting sys-kernel-config.mount... Mar 17 18:47:58.388589 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 18:47:58.404511 systemd[1]: Starting systemd-hwdb-update.service... Mar 17 18:47:58.408358 systemd[1]: Starting systemd-journal-flush.service... Mar 17 18:47:58.411800 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:47:58.413266 systemd[1]: Starting systemd-random-seed.service... Mar 17 18:47:58.415834 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:47:58.417667 systemd[1]: Starting systemd-sysusers.service... Mar 17 18:47:58.421956 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:47:58.424000 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' Mar 17 18:47:58.426580 systemd[1]: Mounted sys-fs-fuse-connections.mount. Mar 17 18:47:58.429800 systemd[1]: Mounted sys-kernel-config.mount. Mar 17 18:47:58.433795 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:47:58.448000 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' Mar 17 18:47:58.445750 systemd[1]: Finished systemd-random-seed.service. Mar 17 18:47:58.448897 systemd[1]: Reached target first-boot-complete.target. Mar 17 18:47:58.476976 systemd-journald[1127]: Time spent on flushing to /var/log/journal/1589e35ff6874c43a164dcb7fcb49176 is 28.174ms for 1160 entries. Mar 17 18:47:58.476976 systemd-journald[1127]: System Journal (/var/log/journal/1589e35ff6874c43a164dcb7fcb49176) is 8.0M, max 2.6G, 2.6G free. Mar 17 18:47:58.559793 systemd-journald[1127]: Received client request to flush runtime journal. Mar 17 18:47:58.485000 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' Mar 17 18:47:58.529000 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' Mar 17 18:47:58.477074 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:47:58.486498 systemd[1]: Starting systemd-udev-settle.service... Mar 17 18:47:58.561005 udevadm[1150]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 18:47:58.527429 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:47:58.560781 systemd[1]: Finished systemd-journal-flush.service. Mar 17 18:47:58.563000 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' Mar 17 18:47:58.939551 systemd[1]: Finished systemd-sysusers.service. Mar 17 18:47:58.942000 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' Mar 17 18:47:58.944299 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:47:59.212482 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:47:59.214000 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' Mar 17 18:47:59.375146 systemd[1]: Finished systemd-hwdb-update.service. Mar 17 18:47:59.377000 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' Mar 17 18:47:59.378000 audit: BPF prog-id=21 op=LOAD Mar 17 18:47:59.378000 audit: BPF prog-id=22 op=LOAD Mar 17 18:47:59.378000 audit: BPF prog-id=7 op=UNLOAD Mar 17 18:47:59.378000 audit: BPF prog-id=8 op=UNLOAD Mar 17 18:47:59.379846 systemd[1]: Starting systemd-udevd.service... Mar 17 18:47:59.397158 systemd-udevd[1155]: Using default interface naming scheme 'v252'. Mar 17 18:47:59.530026 systemd[1]: Started systemd-udevd.service. Mar 17 18:47:59.535000 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' Mar 17 18:47:59.537000 audit: BPF prog-id=23 op=LOAD Mar 17 18:47:59.540867 systemd[1]: Starting systemd-networkd.service... Mar 17 18:47:59.577056 systemd[1]: Condition check resulted in dev-ttyS0.device being skipped. Mar 17 18:47:59.618000 audit: BPF prog-id=24 op=LOAD Mar 17 18:47:59.618000 audit: BPF prog-id=25 op=LOAD Mar 17 18:47:59.618000 audit: BPF prog-id=26 op=LOAD Mar 17 18:47:59.620160 systemd[1]: Starting systemd-userdbd.service... Mar 17 18:47:59.637681 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 18:47:59.628000 audit[1162]: AVC avc: denied { confidentiality } for pid=1162 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Mar 17 18:47:59.664829 systemd[1]: Started systemd-userdbd.service. Mar 17 18:47:59.672463 kernel: hv_vmbus: registering driver hv_balloon Mar 17 18:47:59.671000 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' Mar 17 18:47:59.684639 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 18:47:59.719187 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 18:47:59.719270 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 18:47:59.724866 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 18:47:59.730903 kernel: Console: switching to colour dummy device 80x25 Mar 17 18:47:59.739143 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:47:59.628000 audit[1162]: SYSCALL arch=c000003e syscall=175 success=yes exit=0 a0=55652caa09d0 a1=f884 a2=7fa47711abc5 a3=5 items=12 ppid=1155 pid=1162 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:59.755610 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 18:47:59.755689 kernel: hv_vmbus: registering driver hv_utils Mar 17 18:47:59.628000 audit: CWD cwd="/" Mar 17 18:47:59.628000 audit: PATH item=0 name=(null) inode=1237 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=1 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=2 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=3 name=(null) inode=15384 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=4 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=5 name=(null) inode=15385 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=6 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=7 name=(null) inode=15386 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=8 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=9 name=(null) inode=15387 dev=00:0b mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=10 name=(null) inode=15383 dev=00:0b mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PATH item=11 name=(null) inode=15388 dev=00:0b mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:47:59.628000 audit: PROCTITLE proctitle="(udev-worker)" Mar 17 18:47:59.773483 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 18:47:59.773540 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 18:47:59.773557 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 18:48:00.976386 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:48:01.053871 kernel: KVM: vmx: using Hyper-V Enlightened VMCS Mar 17 18:48:01.096276 systemd[1]: Finished systemd-udev-settle.service. Mar 17 18:48:01.098000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:01.100188 systemd[1]: Starting lvm2-activation-early.service... Mar 17 18:48:01.127469 systemd-networkd[1169]: lo: Link UP Mar 17 18:48:01.127481 systemd-networkd[1169]: lo: Gained carrier Mar 17 18:48:01.128064 systemd-networkd[1169]: Enumeration completed Mar 17 18:48:01.128171 systemd[1]: Started systemd-networkd.service. Mar 17 18:48:01.130000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:01.132259 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:48:01.153797 systemd-networkd[1169]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:48:01.207875 kernel: mlx5_core eaf8:00:02.0 enP60152s1: Link up Mar 17 18:48:01.230563 kernel: hv_netvsc 7c1e521f-cf9e-7c1e-521f-cf9e7c1e521f eth0: Data path switched to VF: enP60152s1 Mar 17 18:48:01.230385 systemd-networkd[1169]: enP60152s1: Link UP Mar 17 18:48:01.230518 systemd-networkd[1169]: eth0: Link UP Mar 17 18:48:01.230523 systemd-networkd[1169]: eth0: Gained carrier Mar 17 18:48:01.237164 systemd-networkd[1169]: enP60152s1: Gained carrier Mar 17 18:48:01.263967 systemd-networkd[1169]: eth0: DHCPv4 address 10.200.8.38/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 17 18:48:01.379180 lvm[1231]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:48:01.406918 systemd[1]: Finished lvm2-activation-early.service. Mar 17 18:48:01.409000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:01.410054 systemd[1]: Reached target cryptsetup.target. Mar 17 18:48:01.413616 systemd[1]: Starting lvm2-activation.service... Mar 17 18:48:01.419639 lvm[1233]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:48:01.442955 systemd[1]: Finished lvm2-activation.service. Mar 17 18:48:01.445000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:01.445960 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:48:01.448590 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 17 18:48:01.448625 systemd[1]: Reached target local-fs.target. Mar 17 18:48:01.451042 systemd[1]: Reached target machines.target. Mar 17 18:48:01.454593 systemd[1]: Starting ldconfig.service... Mar 17 18:48:01.456788 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:48:01.456899 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:01.458018 systemd[1]: Starting systemd-boot-update.service... Mar 17 18:48:01.461395 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Mar 17 18:48:01.465685 systemd[1]: Starting systemd-machine-id-commit.service... Mar 17 18:48:01.469459 systemd[1]: Starting systemd-sysext.service... Mar 17 18:48:01.487327 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1235 (bootctl) Mar 17 18:48:01.488648 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Mar 17 18:48:01.849881 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Mar 17 18:48:01.849000 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' Mar 17 18:48:01.871966 systemd[1]: Unmounting usr-share-oem.mount... Mar 17 18:48:01.918586 systemd[1]: usr-share-oem.mount: Deactivated successfully. Mar 17 18:48:01.918839 systemd[1]: Unmounted usr-share-oem.mount. Mar 17 18:48:01.965142 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 18:48:01.965763 systemd[1]: Finished systemd-machine-id-commit.service. Mar 17 18:48:01.967000 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' Mar 17 18:48:01.984884 kernel: loop0: detected capacity change from 0 to 205544 Mar 17 18:48:02.041074 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 18:48:02.055873 kernel: loop1: detected capacity change from 0 to 205544 Mar 17 18:48:02.059513 (sd-sysext)[1247]: Using extensions 'kubernetes'. Mar 17 18:48:02.059963 (sd-sysext)[1247]: Merged extensions into '/usr'. Mar 17 18:48:02.075658 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.077386 systemd[1]: Mounting usr-share-oem.mount... Mar 17 18:48:02.080466 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.082263 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:48:02.085902 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:48:02.089277 systemd[1]: Starting modprobe@loop.service... Mar 17 18:48:02.091670 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.091912 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:02.092131 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.095152 systemd[1]: Mounted usr-share-oem.mount. Mar 17 18:48:02.097000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.097000 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' Mar 17 18:48:02.096686 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:48:02.096822 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:48:02.098566 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:48:02.098683 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:48:02.099150 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:48:02.099253 systemd[1]: Finished modprobe@loop.service. Mar 17 18:48:02.100004 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:48:02.100101 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.101147 systemd[1]: Finished systemd-sysext.service. Mar 17 18:48:02.104182 systemd[1]: Starting ensure-sysext.service... Mar 17 18:48:02.106339 systemd[1]: Starting systemd-tmpfiles-setup.service... Mar 17 18:48:02.117217 systemd[1]: Reloading. Mar 17 18:48:02.135782 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Mar 17 18:48:02.146919 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 18:48:02.160024 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 18:48:02.179747 /usr/lib/systemd/system-generators/torcx-generator[1274]: time="2025-03-17T18:48:02Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:48:02.179786 /usr/lib/systemd/system-generators/torcx-generator[1274]: time="2025-03-17T18:48:02Z" level=info msg="torcx already run" Mar 17 18:48:02.275173 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:48:02.275193 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:48:02.291837 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 18:48:02.356000 audit: BPF prog-id=27 op=LOAD Mar 17 18:48:02.356000 audit: BPF prog-id=18 op=UNLOAD Mar 17 18:48:02.356000 audit: BPF prog-id=28 op=LOAD Mar 17 18:48:02.356000 audit: BPF prog-id=29 op=LOAD Mar 17 18:48:02.356000 audit: BPF prog-id=19 op=UNLOAD Mar 17 18:48:02.356000 audit: BPF prog-id=20 op=UNLOAD Mar 17 18:48:02.357000 audit: BPF prog-id=30 op=LOAD Mar 17 18:48:02.357000 audit: BPF prog-id=24 op=UNLOAD Mar 17 18:48:02.357000 audit: BPF prog-id=31 op=LOAD Mar 17 18:48:02.357000 audit: BPF prog-id=32 op=LOAD Mar 17 18:48:02.357000 audit: BPF prog-id=25 op=UNLOAD Mar 17 18:48:02.357000 audit: BPF prog-id=26 op=UNLOAD Mar 17 18:48:02.358000 audit: BPF prog-id=33 op=LOAD Mar 17 18:48:02.358000 audit: BPF prog-id=34 op=LOAD Mar 17 18:48:02.358000 audit: BPF prog-id=21 op=UNLOAD Mar 17 18:48:02.358000 audit: BPF prog-id=22 op=UNLOAD Mar 17 18:48:02.359000 audit: BPF prog-id=35 op=LOAD Mar 17 18:48:02.359000 audit: BPF prog-id=23 op=UNLOAD Mar 17 18:48:02.373825 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.374143 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.375669 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:48:02.378884 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:48:02.383060 systemd[1]: Starting modprobe@loop.service... Mar 17 18:48:02.384451 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.384742 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:02.385028 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.386972 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:48:02.387274 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:48:02.388000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.388000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.388000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.388000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.389000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.389000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.389123 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:48:02.389233 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:48:02.390021 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:48:02.390214 systemd[1]: Finished modprobe@loop.service. Mar 17 18:48:02.393255 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.393502 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.395149 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:48:02.397732 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:48:02.402024 systemd[1]: Starting modprobe@loop.service... Mar 17 18:48:02.403689 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.403901 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:02.404078 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.405662 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:48:02.405812 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:48:02.406000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.406000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.409000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.409000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.407496 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:48:02.407602 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:48:02.411102 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:48:02.411254 systemd[1]: Finished modprobe@loop.service. Mar 17 18:48:02.412000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.412000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.414116 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:48:02.414263 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.417977 systemd[1]: proc-xen.mount was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.418386 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.419823 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:48:02.425584 systemd[1]: Starting modprobe@drm.service... Mar 17 18:48:02.428023 systemd-fsck[1243]: fsck.fat 4.2 (2021-01-31) Mar 17 18:48:02.428023 systemd-fsck[1243]: /dev/sda1: 789 files, 119299/258078 clusters Mar 17 18:48:02.429815 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:48:02.434068 systemd[1]: Starting modprobe@loop.service... Mar 17 18:48:02.438586 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.438758 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:02.440889 systemd[1]: xenserver-pv-version.service was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 18:48:02.441972 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Mar 17 18:48:02.444000 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-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.446176 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:48:02.446343 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:48:02.448000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.448000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.449252 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:48:02.449394 systemd[1]: Finished modprobe@drm.service. Mar 17 18:48:02.451000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.451000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.452182 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:48:02.452322 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:48:02.454000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.454000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.455248 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:48:02.455386 systemd[1]: Finished modprobe@loop.service. Mar 17 18:48:02.456000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.456000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.462496 systemd[1]: Finished ensure-sysext.service. Mar 17 18:48:02.464000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ensure-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.466974 systemd[1]: Mounting boot.mount... Mar 17 18:48:02.468932 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:48:02.468985 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:48:02.475332 systemd[1]: Mounted boot.mount. Mar 17 18:48:02.488414 systemd[1]: Finished systemd-boot-update.service. Mar 17 18:48:02.490000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.760178 systemd[1]: Finished systemd-tmpfiles-setup.service. Mar 17 18:48:02.762000 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' Mar 17 18:48:02.765514 systemd[1]: Starting audit-rules.service... Mar 17 18:48:02.769550 systemd[1]: Starting clean-ca-certificates.service... Mar 17 18:48:02.773371 systemd[1]: Starting systemd-journal-catalog-update.service... Mar 17 18:48:02.776000 audit: BPF prog-id=36 op=LOAD Mar 17 18:48:02.778205 systemd[1]: Starting systemd-resolved.service... Mar 17 18:48:02.780000 audit: BPF prog-id=37 op=LOAD Mar 17 18:48:02.783030 systemd[1]: Starting systemd-timesyncd.service... Mar 17 18:48:02.786612 systemd[1]: Starting systemd-update-utmp.service... Mar 17 18:48:02.806000 audit[1356]: SYSTEM_BOOT pid=1356 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' Mar 17 18:48:02.812044 systemd[1]: Finished systemd-update-utmp.service. Mar 17 18:48:02.814000 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' Mar 17 18:48:02.828000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.826842 systemd[1]: Finished clean-ca-certificates.service. Mar 17 18:48:02.829718 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:48:02.908151 systemd[1]: Started systemd-timesyncd.service. Mar 17 18:48:02.910792 systemd[1]: Reached target time-set.target. Mar 17 18:48:02.910000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:02.918367 systemd[1]: Finished systemd-journal-catalog-update.service. Mar 17 18:48:02.921000 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' Mar 17 18:48:02.956177 systemd-resolved[1354]: Positive Trust Anchors: Mar 17 18:48:02.956199 systemd-resolved[1354]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:48:02.956237 systemd-resolved[1354]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 17 18:48:02.997893 systemd-resolved[1354]: Using system hostname 'ci-3510.3.7-a-a0f6600ba5'. Mar 17 18:48:02.999514 systemd[1]: Started systemd-resolved.service. Mar 17 18:48:03.002000 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' Mar 17 18:48:03.003010 systemd[1]: Reached target network.target. Mar 17 18:48:03.005620 systemd[1]: Reached target nss-lookup.target. Mar 17 18:48:03.021000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Mar 17 18:48:03.021000 audit[1372]: SYSCALL arch=c000003e syscall=44 success=yes exit=1056 a0=3 a1=7ffd26af79d0 a2=420 a3=0 items=0 ppid=1351 pid=1372 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/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:48:03.021000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Mar 17 18:48:03.022443 augenrules[1372]: No rules Mar 17 18:48:03.022912 systemd[1]: Finished audit-rules.service. Mar 17 18:48:03.069247 systemd-timesyncd[1355]: Contacted time server 193.1.8.106:123 (0.flatcar.pool.ntp.org). Mar 17 18:48:03.069334 systemd-timesyncd[1355]: Initial clock synchronization to Mon 2025-03-17 18:48:03.069056 UTC. Mar 17 18:48:03.246162 systemd-networkd[1169]: eth0: Gained IPv6LL Mar 17 18:48:03.248548 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:48:03.251963 systemd[1]: Reached target network-online.target. Mar 17 18:48:07.505106 ldconfig[1234]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 18:48:07.516799 systemd[1]: Finished ldconfig.service. Mar 17 18:48:07.521845 systemd[1]: Starting systemd-update-done.service... Mar 17 18:48:07.530565 systemd[1]: Finished systemd-update-done.service. Mar 17 18:48:07.533522 systemd[1]: Reached target sysinit.target. Mar 17 18:48:07.535794 systemd[1]: Started motdgen.path. Mar 17 18:48:07.537832 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Mar 17 18:48:07.541198 systemd[1]: Started logrotate.timer. Mar 17 18:48:07.543471 systemd[1]: Started mdadm.timer. Mar 17 18:48:07.545575 systemd[1]: Started systemd-tmpfiles-clean.timer. Mar 17 18:48:07.547891 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 18:48:07.547926 systemd[1]: Reached target paths.target. Mar 17 18:48:07.549899 systemd[1]: Reached target timers.target. Mar 17 18:48:07.552216 systemd[1]: Listening on dbus.socket. Mar 17 18:48:07.555157 systemd[1]: Starting docker.socket... Mar 17 18:48:07.571152 systemd[1]: Listening on sshd.socket. Mar 17 18:48:07.573532 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:07.574029 systemd[1]: Listening on docker.socket. Mar 17 18:48:07.576078 systemd[1]: Reached target sockets.target. Mar 17 18:48:07.578207 systemd[1]: Reached target basic.target. Mar 17 18:48:07.580182 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:48:07.580216 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:48:07.581198 systemd[1]: Starting containerd.service... Mar 17 18:48:07.584756 systemd[1]: Starting dbus.service... Mar 17 18:48:07.587535 systemd[1]: Starting enable-oem-cloudinit.service... Mar 17 18:48:07.590908 systemd[1]: Starting extend-filesystems.service... Mar 17 18:48:07.593169 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Mar 17 18:48:07.594614 systemd[1]: Starting kubelet.service... Mar 17 18:48:07.598172 systemd[1]: Starting motdgen.service... Mar 17 18:48:07.601783 systemd[1]: Started nvidia.service. Mar 17 18:48:07.605635 systemd[1]: Starting prepare-helm.service... Mar 17 18:48:07.610936 systemd[1]: Starting ssh-key-proc-cmdline.service... Mar 17 18:48:07.614520 systemd[1]: Starting sshd-keygen.service... Mar 17 18:48:07.619541 systemd[1]: Starting systemd-logind.service... Mar 17 18:48:07.624078 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:48:07.624184 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 18:48:07.624732 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 18:48:07.625611 systemd[1]: Starting update-engine.service... Mar 17 18:48:07.630129 systemd[1]: Starting update-ssh-keys-after-ignition.service... Mar 17 18:48:07.636672 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 18:48:07.636924 systemd[1]: Finished ssh-key-proc-cmdline.service. Mar 17 18:48:07.658001 jq[1382]: false Mar 17 18:48:07.658527 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 18:48:07.660226 jq[1398]: true Mar 17 18:48:07.658753 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Mar 17 18:48:07.668874 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 18:48:07.669088 systemd[1]: Finished motdgen.service. Mar 17 18:48:07.683259 jq[1407]: true Mar 17 18:48:07.701077 extend-filesystems[1383]: Found loop1 Mar 17 18:48:07.704585 extend-filesystems[1383]: Found sda Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda1 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda2 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda3 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found usr Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda4 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda6 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda7 Mar 17 18:48:07.707311 extend-filesystems[1383]: Found sda9 Mar 17 18:48:07.707311 extend-filesystems[1383]: Checking size of /dev/sda9 Mar 17 18:48:07.786820 extend-filesystems[1383]: Old size kept for /dev/sda9 Mar 17 18:48:07.790018 extend-filesystems[1383]: Found sr0 Mar 17 18:48:07.794794 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 18:48:07.795022 systemd[1]: Finished extend-filesystems.service. Mar 17 18:48:07.806297 env[1404]: time="2025-03-17T18:48:07.806253372Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Mar 17 18:48:07.841135 tar[1402]: linux-amd64/helm Mar 17 18:48:07.875691 dbus-daemon[1381]: [system] SELinux support is enabled Mar 17 18:48:07.875895 systemd[1]: Started dbus.service. Mar 17 18:48:07.880893 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Mar 17 18:48:07.880935 systemd[1]: Reached target system-config.target. Mar 17 18:48:07.883703 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 17 18:48:07.883732 systemd[1]: Reached target user-config.target. Mar 17 18:48:07.891441 systemd-logind[1393]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 18:48:07.892667 systemd-logind[1393]: New seat seat0. Mar 17 18:48:07.897135 systemd[1]: Started systemd-logind.service. Mar 17 18:48:07.900558 bash[1434]: Updated "/home/core/.ssh/authorized_keys" Mar 17 18:48:07.901304 systemd[1]: Finished update-ssh-keys-after-ignition.service. Mar 17 18:48:07.927042 env[1404]: time="2025-03-17T18:48:07.926996407Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 18:48:07.928015 env[1404]: time="2025-03-17T18:48:07.927992102Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.929788 env[1404]: time="2025-03-17T18:48:07.929753694Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.179-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:48:07.935260 env[1404]: time="2025-03-17T18:48:07.935234168Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.935616 env[1404]: time="2025-03-17T18:48:07.935589967Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:48:07.935713 env[1404]: time="2025-03-17T18:48:07.935697566Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.935788 env[1404]: time="2025-03-17T18:48:07.935773066Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Mar 17 18:48:07.935864 env[1404]: time="2025-03-17T18:48:07.935836665Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.936024 env[1404]: time="2025-03-17T18:48:07.936006865Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.936331 env[1404]: time="2025-03-17T18:48:07.936311963Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:48:07.936580 env[1404]: time="2025-03-17T18:48:07.936558962Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:48:07.936653 env[1404]: time="2025-03-17T18:48:07.936637862Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 18:48:07.936758 env[1404]: time="2025-03-17T18:48:07.936746061Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Mar 17 18:48:07.936810 env[1404]: time="2025-03-17T18:48:07.936802061Z" level=info msg="metadata content store policy set" policy=shared Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951451792Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951487892Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951505692Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951541492Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951562092Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951579992Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951597992Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951616892Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951635291Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951658991Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951677991Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951694891Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 18:48:07.951874 env[1404]: time="2025-03-17T18:48:07.951803091Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.952472888Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.952895286Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.952945385Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.952964485Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953040085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953059085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953126585Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953143584Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953171584Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953188984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953205284Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953220784Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953250184Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953418483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.954439 env[1404]: time="2025-03-17T18:48:07.953438483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953455383Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953486683Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953506883Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953524983Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953559682Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Mar 17 18:48:07.955023 env[1404]: time="2025-03-17T18:48:07.953600082Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 18:48:07.955239 env[1404]: time="2025-03-17T18:48:07.953939281Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} 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}" Mar 17 18:48:07.955239 env[1404]: time="2025-03-17T18:48:07.954022380Z" level=info msg="Connect containerd service" Mar 17 18:48:07.955239 env[1404]: time="2025-03-17T18:48:07.954065580Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.955782372Z" 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" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.955884972Z" level=info msg="Start subscribing containerd event" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.955938271Z" level=info msg="Start recovering state" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956004471Z" level=info msg="Start event monitor" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956017771Z" level=info msg="Start snapshots syncer" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956029671Z" level=info msg="Start cni network conf syncer for default" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956039871Z" level=info msg="Start streaming server" Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956517969Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.956596668Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 18:48:07.981433 env[1404]: time="2025-03-17T18:48:07.971105400Z" level=info msg="containerd successfully booted in 0.167032s" Mar 17 18:48:07.956786 systemd[1]: Started containerd.service. Mar 17 18:48:08.009808 systemd[1]: nvidia.service: Deactivated successfully. Mar 17 18:48:08.378701 update_engine[1396]: I0317 18:48:08.378244 1396 main.cc:92] Flatcar Update Engine starting Mar 17 18:48:08.435578 systemd[1]: Started update-engine.service. Mar 17 18:48:08.441036 systemd[1]: Started locksmithd.service. Mar 17 18:48:08.444728 update_engine[1396]: I0317 18:48:08.444590 1396 update_check_scheduler.cc:74] Next update check in 9m32s Mar 17 18:48:08.518238 tar[1402]: linux-amd64/LICENSE Mar 17 18:48:08.518456 tar[1402]: linux-amd64/README.md Mar 17 18:48:08.525462 systemd[1]: Finished prepare-helm.service. Mar 17 18:48:08.919739 systemd[1]: Started kubelet.service. Mar 17 18:48:09.251710 sshd_keygen[1397]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 18:48:09.281937 systemd[1]: Finished sshd-keygen.service. Mar 17 18:48:09.286178 systemd[1]: Starting issuegen.service... Mar 17 18:48:09.290356 systemd[1]: Started waagent.service. Mar 17 18:48:09.301400 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 18:48:09.301573 systemd[1]: Finished issuegen.service. Mar 17 18:48:09.305332 systemd[1]: Starting systemd-user-sessions.service... Mar 17 18:48:09.327297 systemd[1]: Finished systemd-user-sessions.service. Mar 17 18:48:09.331797 systemd[1]: Started getty@tty1.service. Mar 17 18:48:09.335990 systemd[1]: Started serial-getty@ttyS0.service. Mar 17 18:48:09.339901 systemd[1]: Reached target getty.target. Mar 17 18:48:09.342341 systemd[1]: Reached target multi-user.target. Mar 17 18:48:09.346818 systemd[1]: Starting systemd-update-utmp-runlevel.service... Mar 17 18:48:09.361768 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 17 18:48:09.361980 systemd[1]: Finished systemd-update-utmp-runlevel.service. Mar 17 18:48:09.365122 systemd[1]: Startup finished in 3.704s (firmware) + 23.897s (loader) + 937ms (kernel) + 11.978s (initrd) + 19.635s (userspace) = 1min 153ms. Mar 17 18:48:09.528633 kubelet[1498]: E0317 18:48:09.528528 1498 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:48:09.530208 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:48:09.530372 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:48:09.530657 systemd[1]: kubelet.service: Consumed 1.015s CPU time. Mar 17 18:48:09.712220 login[1519]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Mar 17 18:48:09.714341 login[1520]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:48:09.735333 systemd[1]: Created slice user-500.slice. Mar 17 18:48:09.736911 systemd[1]: Starting user-runtime-dir@500.service... Mar 17 18:48:09.742952 systemd-logind[1393]: New session 2 of user core. Mar 17 18:48:09.748289 systemd[1]: Finished user-runtime-dir@500.service. Mar 17 18:48:09.749919 systemd[1]: Starting user@500.service... Mar 17 18:48:09.763681 (systemd)[1523]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:48:09.792535 locksmithd[1493]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 18:48:09.865896 systemd[1523]: Queued start job for default target default.target. Mar 17 18:48:09.866459 systemd[1523]: Reached target paths.target. Mar 17 18:48:09.866487 systemd[1523]: Reached target sockets.target. Mar 17 18:48:09.866504 systemd[1523]: Reached target timers.target. Mar 17 18:48:09.866518 systemd[1523]: Reached target basic.target. Mar 17 18:48:09.866645 systemd[1]: Started user@500.service. Mar 17 18:48:09.867904 systemd[1]: Started session-2.scope. Mar 17 18:48:09.868460 systemd[1523]: Reached target default.target. Mar 17 18:48:09.868646 systemd[1523]: Startup finished in 98ms. Mar 17 18:48:10.712672 login[1519]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:48:10.718601 systemd-logind[1393]: New session 1 of user core. Mar 17 18:48:10.719332 systemd[1]: Started session-1.scope. Mar 17 18:48:14.583486 waagent[1514]: 2025-03-17T18:48:14.583368Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Mar 17 18:48:14.587990 waagent[1514]: 2025-03-17T18:48:14.587909Z INFO Daemon Daemon OS: flatcar 3510.3.7 Mar 17 18:48:14.590817 waagent[1514]: 2025-03-17T18:48:14.590745Z INFO Daemon Daemon Python: 3.9.16 Mar 17 18:48:14.593511 waagent[1514]: 2025-03-17T18:48:14.593435Z INFO Daemon Daemon Run daemon Mar 17 18:48:14.596198 waagent[1514]: 2025-03-17T18:48:14.596133Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.7' Mar 17 18:48:14.607969 waagent[1514]: 2025-03-17T18:48:14.607828Z 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 1. Mar 17 18:48:14.616281 waagent[1514]: 2025-03-17T18:48:14.616177Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.616652Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.617735Z INFO Daemon Daemon Using waagent for provisioning Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.619197Z INFO Daemon Daemon Activate resource disk Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.620070Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.628142Z INFO Daemon Daemon Found device: None Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.629146Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.630079Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.631807Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.632594Z INFO Daemon Daemon Running default provisioning handler Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.641947Z 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 1. Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.644801Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.645846Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:48:14.662877 waagent[1514]: 2025-03-17T18:48:14.646730Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 18:48:14.697460 waagent[1514]: 2025-03-17T18:48:14.697317Z INFO Daemon Daemon Successfully mounted dvd Mar 17 18:48:14.798817 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 18:48:14.815793 waagent[1514]: 2025-03-17T18:48:14.815667Z INFO Daemon Daemon Detect protocol endpoint Mar 17 18:48:14.831502 waagent[1514]: 2025-03-17T18:48:14.816240Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:48:14.831502 waagent[1514]: 2025-03-17T18:48:14.817392Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 18:48:14.831502 waagent[1514]: 2025-03-17T18:48:14.818254Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 18:48:14.831502 waagent[1514]: 2025-03-17T18:48:14.819363Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 18:48:14.831502 waagent[1514]: 2025-03-17T18:48:14.820209Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 18:48:14.924118 waagent[1514]: 2025-03-17T18:48:14.924042Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 18:48:14.928350 waagent[1514]: 2025-03-17T18:48:14.928305Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 18:48:14.931348 waagent[1514]: 2025-03-17T18:48:14.931291Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 18:48:15.697352 waagent[1514]: 2025-03-17T18:48:15.697197Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 18:48:15.710268 waagent[1514]: 2025-03-17T18:48:15.710186Z INFO Daemon Daemon Forcing an update of the goal state.. Mar 17 18:48:15.713926 waagent[1514]: 2025-03-17T18:48:15.713845Z INFO Daemon Daemon Fetching goal state [incarnation 1] Mar 17 18:48:15.843307 waagent[1514]: 2025-03-17T18:48:15.843182Z INFO Daemon Daemon Found private key matching thumbprint 21941C2D807910C8B6B07F8B0E9CB0880C5FCDFF Mar 17 18:48:15.854976 waagent[1514]: 2025-03-17T18:48:15.843738Z INFO Daemon Daemon Certificate with thumbprint 4016A80D0561C251388267BC8A44866F24A05316 has no matching private key. Mar 17 18:48:15.854976 waagent[1514]: 2025-03-17T18:48:15.845015Z INFO Daemon Daemon Fetch goal state completed Mar 17 18:48:15.867537 waagent[1514]: 2025-03-17T18:48:15.867470Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: dabfa116-c125-4086-9bff-f36c9daf1fe2 New eTag: 14623202308745891807] Mar 17 18:48:15.876102 waagent[1514]: 2025-03-17T18:48:15.868204Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:48:15.904783 waagent[1514]: 2025-03-17T18:48:15.904684Z INFO Daemon Daemon Starting provisioning Mar 17 18:48:15.912802 waagent[1514]: 2025-03-17T18:48:15.905213Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 18:48:15.912802 waagent[1514]: 2025-03-17T18:48:15.906253Z INFO Daemon Daemon Set hostname [ci-3510.3.7-a-a0f6600ba5] Mar 17 18:48:15.921472 waagent[1514]: 2025-03-17T18:48:15.921359Z INFO Daemon Daemon Publish hostname [ci-3510.3.7-a-a0f6600ba5] Mar 17 18:48:15.929789 waagent[1514]: 2025-03-17T18:48:15.922041Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 18:48:15.929789 waagent[1514]: 2025-03-17T18:48:15.923049Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 18:48:15.936482 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Mar 17 18:48:15.936735 systemd[1]: Stopped systemd-networkd-wait-online.service. Mar 17 18:48:15.936813 systemd[1]: Stopping systemd-networkd-wait-online.service... Mar 17 18:48:15.937175 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:48:15.943885 systemd-networkd[1169]: eth0: DHCPv6 lease lost Mar 17 18:48:15.945148 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:48:15.945337 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:48:15.947609 systemd[1]: Starting systemd-networkd.service... Mar 17 18:48:15.978732 systemd-networkd[1577]: enP60152s1: Link UP Mar 17 18:48:15.978743 systemd-networkd[1577]: enP60152s1: Gained carrier Mar 17 18:48:15.980152 systemd-networkd[1577]: eth0: Link UP Mar 17 18:48:15.980161 systemd-networkd[1577]: eth0: Gained carrier Mar 17 18:48:15.980569 systemd-networkd[1577]: lo: Link UP Mar 17 18:48:15.980578 systemd-networkd[1577]: lo: Gained carrier Mar 17 18:48:15.980958 systemd-networkd[1577]: eth0: Gained IPv6LL Mar 17 18:48:15.981574 systemd-networkd[1577]: Enumeration completed Mar 17 18:48:15.981750 systemd[1]: Started systemd-networkd.service. Mar 17 18:48:15.996443 waagent[1514]: 2025-03-17T18:48:15.983021Z INFO Daemon Daemon Create user account if not exists Mar 17 18:48:15.996443 waagent[1514]: 2025-03-17T18:48:15.983725Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 18:48:15.996443 waagent[1514]: 2025-03-17T18:48:15.984886Z INFO Daemon Daemon Configure sudoer Mar 17 18:48:15.996443 waagent[1514]: 2025-03-17T18:48:15.987271Z INFO Daemon Daemon Configure sshd Mar 17 18:48:15.996443 waagent[1514]: 2025-03-17T18:48:15.988187Z INFO Daemon Daemon Deploy ssh public key. Mar 17 18:48:15.992306 systemd-networkd[1577]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:48:16.004662 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:48:16.034919 systemd-networkd[1577]: eth0: DHCPv4 address 10.200.8.38/24, gateway 10.200.8.1 acquired from 168.63.129.16 Mar 17 18:48:16.037346 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:48:17.142689 waagent[1514]: 2025-03-17T18:48:17.142586Z INFO Daemon Daemon Provisioning complete Mar 17 18:48:17.157604 waagent[1514]: 2025-03-17T18:48:17.157517Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 18:48:17.165322 waagent[1514]: 2025-03-17T18:48:17.158072Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 18:48:17.165322 waagent[1514]: 2025-03-17T18:48:17.159863Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Mar 17 18:48:17.435005 waagent[1586]: 2025-03-17T18:48:17.434890Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Mar 17 18:48:17.435797 waagent[1586]: 2025-03-17T18:48:17.435725Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:17.435955 waagent[1586]: 2025-03-17T18:48:17.435899Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:17.447647 waagent[1586]: 2025-03-17T18:48:17.447547Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Mar 17 18:48:17.447836 waagent[1586]: 2025-03-17T18:48:17.447777Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Mar 17 18:48:17.515719 waagent[1586]: 2025-03-17T18:48:17.515585Z INFO ExtHandler ExtHandler Found private key matching thumbprint 21941C2D807910C8B6B07F8B0E9CB0880C5FCDFF Mar 17 18:48:17.515994 waagent[1586]: 2025-03-17T18:48:17.515933Z INFO ExtHandler ExtHandler Certificate with thumbprint 4016A80D0561C251388267BC8A44866F24A05316 has no matching private key. Mar 17 18:48:17.516234 waagent[1586]: 2025-03-17T18:48:17.516184Z INFO ExtHandler ExtHandler Fetch goal state completed Mar 17 18:48:17.531709 waagent[1586]: 2025-03-17T18:48:17.531638Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: 52b7f7b5-3932-4d91-b421-b1ca72c78da2 New eTag: 14623202308745891807] Mar 17 18:48:17.532266 waagent[1586]: 2025-03-17T18:48:17.532206Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:48:17.592824 waagent[1586]: 2025-03-17T18:48:17.592660Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:48:17.619531 waagent[1586]: 2025-03-17T18:48:17.619436Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1586 Mar 17 18:48:17.622971 waagent[1586]: 2025-03-17T18:48:17.622907Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:48:17.624201 waagent[1586]: 2025-03-17T18:48:17.624143Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:48:17.734166 waagent[1586]: 2025-03-17T18:48:17.734036Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:48:17.734553 waagent[1586]: 2025-03-17T18:48:17.734483Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:48:17.742624 waagent[1586]: 2025-03-17T18:48:17.742565Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:48:17.743120 waagent[1586]: 2025-03-17T18:48:17.743059Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:48:17.744193 waagent[1586]: 2025-03-17T18:48:17.744127Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True] Mar 17 18:48:17.745445 waagent[1586]: 2025-03-17T18:48:17.745387Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:48:17.745842 waagent[1586]: 2025-03-17T18:48:17.745786Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:17.746011 waagent[1586]: 2025-03-17T18:48:17.745962Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:17.746531 waagent[1586]: 2025-03-17T18:48:17.746472Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:48:17.746812 waagent[1586]: 2025-03-17T18:48:17.746755Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:48:17.746812 waagent[1586]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:48:17.746812 waagent[1586]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:48:17.746812 waagent[1586]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:48:17.746812 waagent[1586]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:17.746812 waagent[1586]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:17.746812 waagent[1586]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:17.750057 waagent[1586]: 2025-03-17T18:48:17.749809Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:48:17.750179 waagent[1586]: 2025-03-17T18:48:17.750118Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:17.750629 waagent[1586]: 2025-03-17T18:48:17.750574Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:17.751406 waagent[1586]: 2025-03-17T18:48:17.751349Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:48:17.751558 waagent[1586]: 2025-03-17T18:48:17.751510Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:48:17.751700 waagent[1586]: 2025-03-17T18:48:17.751654Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:48:17.753369 waagent[1586]: 2025-03-17T18:48:17.753308Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:48:17.753529 waagent[1586]: 2025-03-17T18:48:17.753478Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:48:17.754337 waagent[1586]: 2025-03-17T18:48:17.754271Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:48:17.754513 waagent[1586]: 2025-03-17T18:48:17.754454Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:48:17.754787 waagent[1586]: 2025-03-17T18:48:17.754736Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:48:17.766772 waagent[1586]: 2025-03-17T18:48:17.766717Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Mar 17 18:48:17.767356 waagent[1586]: 2025-03-17T18:48:17.767306Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:48:17.768157 waagent[1586]: 2025-03-17T18:48:17.768098Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Mar 17 18:48:17.786120 waagent[1586]: 2025-03-17T18:48:17.786059Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1577' Mar 17 18:48:17.835407 waagent[1586]: 2025-03-17T18:48:17.830794Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Mar 17 18:48:17.908375 waagent[1586]: 2025-03-17T18:48:17.908252Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:48:17.908375 waagent[1586]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:48:17.908375 waagent[1586]: 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 Mar 17 18:48:17.908375 waagent[1586]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1f:cf:9e brd ff:ff:ff:ff:ff:ff Mar 17 18:48:17.908375 waagent[1586]: 3: enP60152s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1f:cf:9e brd ff:ff:ff:ff:ff:ff\ altname enP60152p0s2 Mar 17 18:48:17.908375 waagent[1586]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:48:17.908375 waagent[1586]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:48:17.908375 waagent[1586]: 2: eth0 inet 10.200.8.38/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:48:17.908375 waagent[1586]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:48:17.908375 waagent[1586]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:48:17.908375 waagent[1586]: 2: eth0 inet6 fe80::7e1e:52ff:fe1f:cf9e/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:48:18.025828 waagent[1586]: 2025-03-17T18:48:18.025700Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.12.0.2 -- exiting Mar 17 18:48:18.163863 waagent[1514]: 2025-03-17T18:48:18.163668Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running Mar 17 18:48:18.170095 waagent[1514]: 2025-03-17T18:48:18.170027Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.12.0.2 to be the latest agent Mar 17 18:48:19.513440 waagent[1622]: 2025-03-17T18:48:19.513328Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.2) Mar 17 18:48:19.514188 waagent[1622]: 2025-03-17T18:48:19.514121Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.7 Mar 17 18:48:19.514337 waagent[1622]: 2025-03-17T18:48:19.514283Z INFO ExtHandler ExtHandler Python: 3.9.16 Mar 17 18:48:19.514484 waagent[1622]: 2025-03-17T18:48:19.514437Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Mar 17 18:48:19.524052 waagent[1622]: 2025-03-17T18:48:19.523957Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:48:19.524432 waagent[1622]: 2025-03-17T18:48:19.524375Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:19.524592 waagent[1622]: 2025-03-17T18:48:19.524544Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:19.536553 waagent[1622]: 2025-03-17T18:48:19.536481Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 18:48:19.545330 waagent[1622]: 2025-03-17T18:48:19.545258Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 17 18:48:19.546488 waagent[1622]: 2025-03-17T18:48:19.546413Z INFO ExtHandler Mar 17 18:48:19.546675 waagent[1622]: 2025-03-17T18:48:19.546612Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 814a13e6-7936-4b7d-9082-fdc5f6130201 eTag: 14623202308745891807 source: Fabric] Mar 17 18:48:19.547570 waagent[1622]: 2025-03-17T18:48:19.547499Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 18:48:19.549009 waagent[1622]: 2025-03-17T18:48:19.548936Z INFO ExtHandler Mar 17 18:48:19.549187 waagent[1622]: 2025-03-17T18:48:19.549125Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 18:48:19.556821 waagent[1622]: 2025-03-17T18:48:19.556753Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 18:48:19.557501 waagent[1622]: 2025-03-17T18:48:19.557437Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:48:19.577387 waagent[1622]: 2025-03-17T18:48:19.577304Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Mar 17 18:48:19.645020 waagent[1622]: 2025-03-17T18:48:19.644887Z INFO ExtHandler Downloaded certificate {'thumbprint': '4016A80D0561C251388267BC8A44866F24A05316', 'hasPrivateKey': False} Mar 17 18:48:19.646028 waagent[1622]: 2025-03-17T18:48:19.645961Z INFO ExtHandler Downloaded certificate {'thumbprint': '21941C2D807910C8B6B07F8B0E9CB0880C5FCDFF', 'hasPrivateKey': True} Mar 17 18:48:19.647006 waagent[1622]: 2025-03-17T18:48:19.646947Z INFO ExtHandler Fetch goal state completed Mar 17 18:48:19.661114 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Mar 17 18:48:19.661413 systemd[1]: Stopped kubelet.service. Mar 17 18:48:19.661465 systemd[1]: kubelet.service: Consumed 1.015s CPU time. Mar 17 18:48:19.663294 systemd[1]: Starting kubelet.service... Mar 17 18:48:19.670385 waagent[1622]: 2025-03-17T18:48:19.670272Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.15 3 Sep 2024 (Library: OpenSSL 3.0.15 3 Sep 2024) Mar 17 18:48:19.689874 waagent[1622]: 2025-03-17T18:48:19.689744Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.2 running as process 1622 Mar 17 18:48:19.694815 waagent[1622]: 2025-03-17T18:48:19.694726Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:48:19.696391 waagent[1622]: 2025-03-17T18:48:19.696322Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 17 18:48:19.696839 waagent[1622]: 2025-03-17T18:48:19.696783Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 17 18:48:19.700332 waagent[1622]: 2025-03-17T18:48:19.700259Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:48:19.709579 waagent[1622]: 2025-03-17T18:48:19.709511Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:48:19.710196 waagent[1622]: 2025-03-17T18:48:19.710136Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:48:19.724402 waagent[1622]: 2025-03-17T18:48:19.724329Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:48:19.725054 waagent[1622]: 2025-03-17T18:48:19.724978Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:48:19.735188 waagent[1622]: 2025-03-17T18:48:19.735062Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 18:48:19.736564 waagent[1622]: 2025-03-17T18:48:19.736474Z 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] Mar 17 18:48:19.738769 waagent[1622]: 2025-03-17T18:48:19.738485Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:48:19.739043 waagent[1622]: 2025-03-17T18:48:19.738984Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:19.739231 waagent[1622]: 2025-03-17T18:48:19.739172Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:19.739914 waagent[1622]: 2025-03-17T18:48:19.739827Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:48:19.740292 waagent[1622]: 2025-03-17T18:48:19.740221Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:48:19.740292 waagent[1622]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:48:19.740292 waagent[1622]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:48:19.740292 waagent[1622]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:48:19.740292 waagent[1622]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:19.740292 waagent[1622]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:19.740292 waagent[1622]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:48:19.744138 waagent[1622]: 2025-03-17T18:48:19.743671Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:48:19.745892 waagent[1622]: 2025-03-17T18:48:19.745481Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:48:19.745976 waagent[1622]: 2025-03-17T18:48:19.745921Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:48:19.747346 waagent[1622]: 2025-03-17T18:48:19.747278Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:48:19.747893 waagent[1622]: 2025-03-17T18:48:19.747802Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:48:19.748880 waagent[1622]: 2025-03-17T18:48:19.745809Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:48:19.748880 waagent[1622]: 2025-03-17T18:48:19.748143Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:48:19.748880 waagent[1622]: 2025-03-17T18:48:19.748272Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:48:19.754447 waagent[1622]: 2025-03-17T18:48:19.754278Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:48:19.754716 waagent[1622]: 2025-03-17T18:48:19.754650Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:48:19.755014 waagent[1622]: 2025-03-17T18:48:19.754949Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:48:19.776247 waagent[1622]: 2025-03-17T18:48:19.776111Z INFO ExtHandler ExtHandler Downloading agent manifest Mar 17 18:48:19.786221 waagent[1622]: 2025-03-17T18:48:19.786153Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:48:19.786221 waagent[1622]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:48:19.786221 waagent[1622]: 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 Mar 17 18:48:19.786221 waagent[1622]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1f:cf:9e brd ff:ff:ff:ff:ff:ff Mar 17 18:48:19.786221 waagent[1622]: 3: enP60152s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1f:cf:9e brd ff:ff:ff:ff:ff:ff\ altname enP60152p0s2 Mar 17 18:48:19.786221 waagent[1622]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:48:19.786221 waagent[1622]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:48:19.786221 waagent[1622]: 2: eth0 inet 10.200.8.38/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:48:19.786221 waagent[1622]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:48:19.786221 waagent[1622]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:48:19.786221 waagent[1622]: 2: eth0 inet6 fe80::7e1e:52ff:fe1f:cf9e/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:48:19.814028 waagent[1622]: 2025-03-17T18:48:19.813956Z INFO ExtHandler ExtHandler Mar 17 18:48:19.814332 waagent[1622]: 2025-03-17T18:48:19.814280Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: d65a655a-7de9-40b1-aafc-6571fd7de950 correlation 8936c036-fb2b-4367-96af-0ec1610d3320 created: 2025-03-17T18:46:57.653580Z] Mar 17 18:48:19.815427 waagent[1622]: 2025-03-17T18:48:19.815376Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 18:48:19.817279 waagent[1622]: 2025-03-17T18:48:19.817231Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 17 18:48:19.838447 waagent[1622]: 2025-03-17T18:48:19.838394Z INFO ExtHandler ExtHandler Looking for existing remote access users. Mar 17 18:48:20.357305 systemd[1]: Started kubelet.service. Mar 17 18:48:20.394232 waagent[1622]: 2025-03-17T18:48:20.393449Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.2 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 9651BD1D-C743-4535-9F4C-321FED739A7A;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1;UpdateMode: SelfUpdate;] Mar 17 18:48:20.432992 kubelet[1656]: E0317 18:48:20.432951 1656 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:48:20.438392 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:48:20.438501 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:48:20.466417 waagent[1622]: 2025-03-17T18:48:20.466313Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 17 18:48:20.466417 waagent[1622]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:48:20.466417 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.466417 waagent[1622]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:48:20.466417 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.466417 waagent[1622]: Chain OUTPUT (policy ACCEPT 6 packets, 505 bytes) Mar 17 18:48:20.466417 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.466417 waagent[1622]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:48:20.466417 waagent[1622]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:48:20.466417 waagent[1622]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:48:20.473442 waagent[1622]: 2025-03-17T18:48:20.473340Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 18:48:20.473442 waagent[1622]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:48:20.473442 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.473442 waagent[1622]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:48:20.473442 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.473442 waagent[1622]: Chain OUTPUT (policy ACCEPT 6 packets, 505 bytes) Mar 17 18:48:20.473442 waagent[1622]: pkts bytes target prot opt in out source destination Mar 17 18:48:20.473442 waagent[1622]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:48:20.473442 waagent[1622]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:48:20.473442 waagent[1622]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:48:20.474012 waagent[1622]: 2025-03-17T18:48:20.473957Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 18:48:30.660723 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Mar 17 18:48:30.661072 systemd[1]: Stopped kubelet.service. Mar 17 18:48:30.663062 systemd[1]: Starting kubelet.service... Mar 17 18:48:30.744579 systemd[1]: Started kubelet.service. Mar 17 18:48:30.778273 kubelet[1686]: E0317 18:48:30.778242 1686 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:48:30.779770 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:48:30.779939 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:48:40.910737 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 3. Mar 17 18:48:40.911072 systemd[1]: Stopped kubelet.service. Mar 17 18:48:40.913057 systemd[1]: Starting kubelet.service... Mar 17 18:48:41.030978 systemd[1]: Started kubelet.service. Mar 17 18:48:41.620531 kubelet[1695]: E0317 18:48:41.620484 1695 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:48:41.622269 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:48:41.622427 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:48:48.862555 kernel: hv_balloon: Max. dynamic memory size: 8192 MB Mar 17 18:48:51.660777 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 4. Mar 17 18:48:51.661127 systemd[1]: Stopped kubelet.service. Mar 17 18:48:51.663126 systemd[1]: Starting kubelet.service... Mar 17 18:48:51.745050 systemd[1]: Started kubelet.service. Mar 17 18:48:51.779588 kubelet[1704]: E0317 18:48:51.779549 1704 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:48:51.780979 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:48:51.781092 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:48:53.852407 update_engine[1396]: I0317 18:48:53.852332 1396 update_attempter.cc:509] Updating boot flags... Mar 17 18:49:01.910772 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 5. Mar 17 18:49:01.911113 systemd[1]: Stopped kubelet.service. Mar 17 18:49:01.913108 systemd[1]: Starting kubelet.service... Mar 17 18:49:01.995914 systemd[1]: Started kubelet.service. Mar 17 18:49:02.029771 kubelet[1782]: E0317 18:49:02.029728 1782 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:49:02.031385 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:49:02.031539 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:49:12.116026 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 6. Mar 17 18:49:12.116304 systemd[1]: Stopped kubelet.service. Mar 17 18:49:12.118322 systemd[1]: Starting kubelet.service... Mar 17 18:49:12.201374 systemd[1]: Started kubelet.service. Mar 17 18:49:12.753240 kubelet[1791]: E0317 18:49:12.753187 1791 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:49:12.754819 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:49:12.754996 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:49:13.576948 systemd[1]: Created slice system-sshd.slice. Mar 17 18:49:13.579132 systemd[1]: Started sshd@0-10.200.8.38:22-10.200.16.10:49646.service. Mar 17 18:49:14.420940 sshd[1797]: Accepted publickey for core from 10.200.16.10 port 49646 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:14.422567 sshd[1797]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:14.426158 systemd-logind[1393]: New session 3 of user core. Mar 17 18:49:14.427369 systemd[1]: Started session-3.scope. Mar 17 18:49:14.968397 systemd[1]: Started sshd@1-10.200.8.38:22-10.200.16.10:49662.service. Mar 17 18:49:15.592137 sshd[1802]: Accepted publickey for core from 10.200.16.10 port 49662 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:15.593840 sshd[1802]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:15.599466 systemd-logind[1393]: New session 4 of user core. Mar 17 18:49:15.600071 systemd[1]: Started session-4.scope. Mar 17 18:49:16.046408 sshd[1802]: pam_unix(sshd:session): session closed for user core Mar 17 18:49:16.049581 systemd[1]: sshd@1-10.200.8.38:22-10.200.16.10:49662.service: Deactivated successfully. Mar 17 18:49:16.050581 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 18:49:16.051344 systemd-logind[1393]: Session 4 logged out. Waiting for processes to exit. Mar 17 18:49:16.052195 systemd-logind[1393]: Removed session 4. Mar 17 18:49:16.151846 systemd[1]: Started sshd@2-10.200.8.38:22-10.200.16.10:49676.service. Mar 17 18:49:16.783271 sshd[1808]: Accepted publickey for core from 10.200.16.10 port 49676 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:16.784892 sshd[1808]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:16.790332 systemd[1]: Started session-5.scope. Mar 17 18:49:16.790937 systemd-logind[1393]: New session 5 of user core. Mar 17 18:49:17.225217 sshd[1808]: pam_unix(sshd:session): session closed for user core Mar 17 18:49:17.228479 systemd[1]: sshd@2-10.200.8.38:22-10.200.16.10:49676.service: Deactivated successfully. Mar 17 18:49:17.229451 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 18:49:17.230085 systemd-logind[1393]: Session 5 logged out. Waiting for processes to exit. Mar 17 18:49:17.230831 systemd-logind[1393]: Removed session 5. Mar 17 18:49:17.329865 systemd[1]: Started sshd@3-10.200.8.38:22-10.200.16.10:49690.service. Mar 17 18:49:17.951614 sshd[1814]: Accepted publickey for core from 10.200.16.10 port 49690 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:17.953215 sshd[1814]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:17.958475 systemd[1]: Started session-6.scope. Mar 17 18:49:17.959078 systemd-logind[1393]: New session 6 of user core. Mar 17 18:49:18.402078 sshd[1814]: pam_unix(sshd:session): session closed for user core Mar 17 18:49:18.405439 systemd[1]: sshd@3-10.200.8.38:22-10.200.16.10:49690.service: Deactivated successfully. Mar 17 18:49:18.406439 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 18:49:18.407228 systemd-logind[1393]: Session 6 logged out. Waiting for processes to exit. Mar 17 18:49:18.408174 systemd-logind[1393]: Removed session 6. Mar 17 18:49:18.506594 systemd[1]: Started sshd@4-10.200.8.38:22-10.200.16.10:51214.service. Mar 17 18:49:19.129683 sshd[1820]: Accepted publickey for core from 10.200.16.10 port 51214 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:19.131116 sshd[1820]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:19.135865 systemd[1]: Started session-7.scope. Mar 17 18:49:19.136465 systemd-logind[1393]: New session 7 of user core. Mar 17 18:49:19.575586 sshd[1820]: pam_unix(sshd:session): session closed for user core Mar 17 18:49:19.578906 systemd[1]: sshd@4-10.200.8.38:22-10.200.16.10:51214.service: Deactivated successfully. Mar 17 18:49:19.579896 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 18:49:19.580614 systemd-logind[1393]: Session 7 logged out. Waiting for processes to exit. Mar 17 18:49:19.581530 systemd-logind[1393]: Removed session 7. Mar 17 18:49:19.680082 systemd[1]: Started sshd@5-10.200.8.38:22-10.200.16.10:51218.service. Mar 17 18:49:20.302132 sshd[1826]: Accepted publickey for core from 10.200.16.10 port 51218 ssh2: RSA SHA256:Id7fTtJmja0nOLdf0IQA3jnxxJrUKKdGU1UW83zjTQg Mar 17 18:49:20.303484 sshd[1826]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:20.308040 systemd[1]: Started session-8.scope. Mar 17 18:49:20.308617 systemd-logind[1393]: New session 8 of user core. Mar 17 18:49:20.749415 sshd[1826]: pam_unix(sshd:session): session closed for user core Mar 17 18:49:20.752105 systemd[1]: sshd@5-10.200.8.38:22-10.200.16.10:51218.service: Deactivated successfully. Mar 17 18:49:20.753113 systemd-logind[1393]: Session 8 logged out. Waiting for processes to exit. Mar 17 18:49:20.753201 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 18:49:20.754271 systemd-logind[1393]: Removed session 8.