Sep 9 23:38:18.809017 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 9 23:38:18.809038 kernel: Linux version 6.12.45-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue Sep 9 22:08:34 -00 2025 Sep 9 23:38:18.809048 kernel: KASLR enabled Sep 9 23:38:18.809054 kernel: efi: EFI v2.7 by EDK II Sep 9 23:38:18.809060 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Sep 9 23:38:18.809066 kernel: random: crng init done Sep 9 23:38:18.809073 kernel: secureboot: Secure boot disabled Sep 9 23:38:18.809078 kernel: ACPI: Early table checksum verification disabled Sep 9 23:38:18.809084 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Sep 9 23:38:18.809091 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 9 23:38:18.809097 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809103 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809108 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809114 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809121 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809128 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809134 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809140 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809146 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:38:18.809152 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 9 23:38:18.809158 kernel: ACPI: Use ACPI SPCR as default console: No Sep 9 23:38:18.809164 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:38:18.809170 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Sep 9 23:38:18.809176 kernel: Zone ranges: Sep 9 23:38:18.809182 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:38:18.809190 kernel: DMA32 empty Sep 9 23:38:18.809195 kernel: Normal empty Sep 9 23:38:18.809201 kernel: Device empty Sep 9 23:38:18.809207 kernel: Movable zone start for each node Sep 9 23:38:18.809214 kernel: Early memory node ranges Sep 9 23:38:18.809220 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Sep 9 23:38:18.809226 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Sep 9 23:38:18.809232 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Sep 9 23:38:18.809238 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Sep 9 23:38:18.809244 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Sep 9 23:38:18.809250 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Sep 9 23:38:18.809256 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Sep 9 23:38:18.809264 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Sep 9 23:38:18.809269 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Sep 9 23:38:18.809275 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 9 23:38:18.809284 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 9 23:38:18.809290 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 9 23:38:18.809297 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 9 23:38:18.809304 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:38:18.809311 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 9 23:38:18.809317 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Sep 9 23:38:18.809324 kernel: psci: probing for conduit method from ACPI. Sep 9 23:38:18.809330 kernel: psci: PSCIv1.1 detected in firmware. Sep 9 23:38:18.809337 kernel: psci: Using standard PSCI v0.2 function IDs Sep 9 23:38:18.809343 kernel: psci: Trusted OS migration not required Sep 9 23:38:18.809349 kernel: psci: SMC Calling Convention v1.1 Sep 9 23:38:18.809356 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 9 23:38:18.809362 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 9 23:38:18.809370 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 9 23:38:18.809396 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 9 23:38:18.809403 kernel: Detected PIPT I-cache on CPU0 Sep 9 23:38:18.809410 kernel: CPU features: detected: GIC system register CPU interface Sep 9 23:38:18.809416 kernel: CPU features: detected: Spectre-v4 Sep 9 23:38:18.809422 kernel: CPU features: detected: Spectre-BHB Sep 9 23:38:18.809429 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 9 23:38:18.809435 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 9 23:38:18.809441 kernel: CPU features: detected: ARM erratum 1418040 Sep 9 23:38:18.809449 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 9 23:38:18.809456 kernel: alternatives: applying boot alternatives Sep 9 23:38:18.809463 kernel: 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=1a0303a4c67016bd8cbb391a5d1bb2355d0bb259dfb78ea746a1288c781f86ca Sep 9 23:38:18.809472 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 23:38:18.809478 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 23:38:18.809485 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 23:38:18.809491 kernel: Fallback order for Node 0: 0 Sep 9 23:38:18.809498 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 9 23:38:18.809504 kernel: Policy zone: DMA Sep 9 23:38:18.809510 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 23:38:18.809517 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 9 23:38:18.809523 kernel: software IO TLB: area num 4. Sep 9 23:38:18.809530 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 9 23:38:18.809536 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Sep 9 23:38:18.809544 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 9 23:38:18.809550 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 23:38:18.809557 kernel: rcu: RCU event tracing is enabled. Sep 9 23:38:18.809564 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 9 23:38:18.809570 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 23:38:18.809576 kernel: Tracing variant of Tasks RCU enabled. Sep 9 23:38:18.809589 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 23:38:18.809596 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 9 23:38:18.809602 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 23:38:18.809611 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 23:38:18.809618 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 9 23:38:18.809626 kernel: GICv3: 256 SPIs implemented Sep 9 23:38:18.809632 kernel: GICv3: 0 Extended SPIs implemented Sep 9 23:38:18.809638 kernel: Root IRQ handler: gic_handle_irq Sep 9 23:38:18.809645 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 9 23:38:18.809651 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 9 23:38:18.809657 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 9 23:38:18.809664 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 9 23:38:18.809670 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 9 23:38:18.809676 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 9 23:38:18.809683 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 9 23:38:18.809689 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 9 23:38:18.809695 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 23:38:18.809703 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:38:18.809709 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 9 23:38:18.809716 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 9 23:38:18.809722 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 9 23:38:18.809728 kernel: arm-pv: using stolen time PV Sep 9 23:38:18.809735 kernel: Console: colour dummy device 80x25 Sep 9 23:38:18.809742 kernel: ACPI: Core revision 20240827 Sep 9 23:38:18.809749 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 9 23:38:18.809755 kernel: pid_max: default: 32768 minimum: 301 Sep 9 23:38:18.809794 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 23:38:18.809806 kernel: landlock: Up and running. Sep 9 23:38:18.809813 kernel: SELinux: Initializing. Sep 9 23:38:18.809828 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 23:38:18.809834 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 23:38:18.809841 kernel: rcu: Hierarchical SRCU implementation. Sep 9 23:38:18.809848 kernel: rcu: Max phase no-delay instances is 400. Sep 9 23:38:18.809854 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 23:38:18.809861 kernel: Remapping and enabling EFI services. Sep 9 23:38:18.809867 kernel: smp: Bringing up secondary CPUs ... Sep 9 23:38:18.809880 kernel: Detected PIPT I-cache on CPU1 Sep 9 23:38:18.809887 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 9 23:38:18.809894 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 9 23:38:18.809902 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:38:18.809909 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 9 23:38:18.809915 kernel: Detected PIPT I-cache on CPU2 Sep 9 23:38:18.809923 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 9 23:38:18.809930 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 9 23:38:18.809938 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:38:18.809945 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 9 23:38:18.809952 kernel: Detected PIPT I-cache on CPU3 Sep 9 23:38:18.809958 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 9 23:38:18.809966 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 9 23:38:18.809972 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:38:18.809979 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 9 23:38:18.809986 kernel: smp: Brought up 1 node, 4 CPUs Sep 9 23:38:18.809993 kernel: SMP: Total of 4 processors activated. Sep 9 23:38:18.810001 kernel: CPU: All CPU(s) started at EL1 Sep 9 23:38:18.810008 kernel: CPU features: detected: 32-bit EL0 Support Sep 9 23:38:18.810015 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 9 23:38:18.810022 kernel: CPU features: detected: Common not Private translations Sep 9 23:38:18.810029 kernel: CPU features: detected: CRC32 instructions Sep 9 23:38:18.810035 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 9 23:38:18.810042 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 9 23:38:18.810049 kernel: CPU features: detected: LSE atomic instructions Sep 9 23:38:18.810056 kernel: CPU features: detected: Privileged Access Never Sep 9 23:38:18.810064 kernel: CPU features: detected: RAS Extension Support Sep 9 23:38:18.810071 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 9 23:38:18.810078 kernel: alternatives: applying system-wide alternatives Sep 9 23:38:18.810085 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 9 23:38:18.810093 kernel: Memory: 2424480K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 38976K init, 1038K bss, 125472K reserved, 16384K cma-reserved) Sep 9 23:38:18.810099 kernel: devtmpfs: initialized Sep 9 23:38:18.810106 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 23:38:18.810114 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 9 23:38:18.810121 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 9 23:38:18.810129 kernel: 0 pages in range for non-PLT usage Sep 9 23:38:18.810135 kernel: 508560 pages in range for PLT usage Sep 9 23:38:18.810142 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 23:38:18.810169 kernel: SMBIOS 3.0.0 present. Sep 9 23:38:18.810176 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 9 23:38:18.810183 kernel: DMI: Memory slots populated: 1/1 Sep 9 23:38:18.810189 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 23:38:18.810196 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 9 23:38:18.810204 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 9 23:38:18.810212 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 9 23:38:18.810219 kernel: audit: initializing netlink subsys (disabled) Sep 9 23:38:18.810226 kernel: audit: type=2000 audit(0.021:1): state=initialized audit_enabled=0 res=1 Sep 9 23:38:18.810233 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 23:38:18.810240 kernel: cpuidle: using governor menu Sep 9 23:38:18.810247 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 9 23:38:18.810254 kernel: ASID allocator initialised with 32768 entries Sep 9 23:38:18.810261 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 23:38:18.810268 kernel: Serial: AMBA PL011 UART driver Sep 9 23:38:18.810276 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 23:38:18.810283 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 23:38:18.810290 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 9 23:38:18.810297 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 9 23:38:18.810304 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 23:38:18.810311 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 23:38:18.810318 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 9 23:38:18.810325 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 9 23:38:18.810332 kernel: ACPI: Added _OSI(Module Device) Sep 9 23:38:18.810340 kernel: ACPI: Added _OSI(Processor Device) Sep 9 23:38:18.810347 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 23:38:18.810354 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 23:38:18.810361 kernel: ACPI: Interpreter enabled Sep 9 23:38:18.810367 kernel: ACPI: Using GIC for interrupt routing Sep 9 23:38:18.810374 kernel: ACPI: MCFG table detected, 1 entries Sep 9 23:38:18.810390 kernel: ACPI: CPU0 has been hot-added Sep 9 23:38:18.810397 kernel: ACPI: CPU1 has been hot-added Sep 9 23:38:18.810403 kernel: ACPI: CPU2 has been hot-added Sep 9 23:38:18.810410 kernel: ACPI: CPU3 has been hot-added Sep 9 23:38:18.810419 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 9 23:38:18.810426 kernel: printk: legacy console [ttyAMA0] enabled Sep 9 23:38:18.810433 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 23:38:18.810595 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 9 23:38:18.810669 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 9 23:38:18.810730 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 9 23:38:18.810789 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 9 23:38:18.810849 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 9 23:38:18.810859 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 9 23:38:18.810867 kernel: PCI host bridge to bus 0000:00 Sep 9 23:38:18.810940 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 9 23:38:18.810996 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 9 23:38:18.811051 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 9 23:38:18.811104 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 23:38:18.811197 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 9 23:38:18.811267 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 9 23:38:18.811329 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 9 23:38:18.811407 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 9 23:38:18.811472 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 9 23:38:18.811533 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 9 23:38:18.811607 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 9 23:38:18.811677 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 9 23:38:18.811734 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 9 23:38:18.811788 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 9 23:38:18.811842 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 9 23:38:18.811852 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 9 23:38:18.811859 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 9 23:38:18.811866 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 9 23:38:18.811875 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 9 23:38:18.811882 kernel: iommu: Default domain type: Translated Sep 9 23:38:18.811888 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 9 23:38:18.811895 kernel: efivars: Registered efivars operations Sep 9 23:38:18.811903 kernel: vgaarb: loaded Sep 9 23:38:18.811909 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 9 23:38:18.811917 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 23:38:18.811924 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 23:38:18.811931 kernel: pnp: PnP ACPI init Sep 9 23:38:18.812004 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 9 23:38:18.812026 kernel: pnp: PnP ACPI: found 1 devices Sep 9 23:38:18.812034 kernel: NET: Registered PF_INET protocol family Sep 9 23:38:18.812041 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 23:38:18.812049 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 9 23:38:18.812056 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 23:38:18.812064 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 9 23:38:18.812071 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 9 23:38:18.812080 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 9 23:38:18.812087 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 23:38:18.812094 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 23:38:18.812101 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 23:38:18.812108 kernel: PCI: CLS 0 bytes, default 64 Sep 9 23:38:18.812115 kernel: kvm [1]: HYP mode not available Sep 9 23:38:18.812122 kernel: Initialise system trusted keyrings Sep 9 23:38:18.812129 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 9 23:38:18.812136 kernel: Key type asymmetric registered Sep 9 23:38:18.812145 kernel: Asymmetric key parser 'x509' registered Sep 9 23:38:18.812152 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 9 23:38:18.812159 kernel: io scheduler mq-deadline registered Sep 9 23:38:18.812165 kernel: io scheduler kyber registered Sep 9 23:38:18.812172 kernel: io scheduler bfq registered Sep 9 23:38:18.812179 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 9 23:38:18.812186 kernel: ACPI: button: Power Button [PWRB] Sep 9 23:38:18.812194 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 9 23:38:18.812254 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 9 23:38:18.812265 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 23:38:18.812272 kernel: thunder_xcv, ver 1.0 Sep 9 23:38:18.812279 kernel: thunder_bgx, ver 1.0 Sep 9 23:38:18.812286 kernel: nicpf, ver 1.0 Sep 9 23:38:18.812293 kernel: nicvf, ver 1.0 Sep 9 23:38:18.812358 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 9 23:38:18.812428 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-09T23:38:18 UTC (1757461098) Sep 9 23:38:18.812439 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 23:38:18.812449 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 9 23:38:18.812456 kernel: watchdog: NMI not fully supported Sep 9 23:38:18.812463 kernel: watchdog: Hard watchdog permanently disabled Sep 9 23:38:18.812470 kernel: NET: Registered PF_INET6 protocol family Sep 9 23:38:18.812477 kernel: Segment Routing with IPv6 Sep 9 23:38:18.812484 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 23:38:18.812491 kernel: NET: Registered PF_PACKET protocol family Sep 9 23:38:18.812498 kernel: Key type dns_resolver registered Sep 9 23:38:18.812505 kernel: registered taskstats version 1 Sep 9 23:38:18.812512 kernel: Loading compiled-in X.509 certificates Sep 9 23:38:18.812520 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 820dabbdbfae37dcb388874c78ed83c436750814' Sep 9 23:38:18.812527 kernel: Demotion targets for Node 0: null Sep 9 23:38:18.812534 kernel: Key type .fscrypt registered Sep 9 23:38:18.812540 kernel: Key type fscrypt-provisioning registered Sep 9 23:38:18.812547 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 23:38:18.812554 kernel: ima: Allocated hash algorithm: sha1 Sep 9 23:38:18.812561 kernel: ima: No architecture policies found Sep 9 23:38:18.812568 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 9 23:38:18.812576 kernel: clk: Disabling unused clocks Sep 9 23:38:18.812605 kernel: PM: genpd: Disabling unused power domains Sep 9 23:38:18.812613 kernel: Warning: unable to open an initial console. Sep 9 23:38:18.812620 kernel: Freeing unused kernel memory: 38976K Sep 9 23:38:18.812627 kernel: Run /init as init process Sep 9 23:38:18.812634 kernel: with arguments: Sep 9 23:38:18.812641 kernel: /init Sep 9 23:38:18.812648 kernel: with environment: Sep 9 23:38:18.812655 kernel: HOME=/ Sep 9 23:38:18.812664 kernel: TERM=linux Sep 9 23:38:18.812671 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 23:38:18.812679 systemd[1]: Successfully made /usr/ read-only. Sep 9 23:38:18.812689 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 9 23:38:18.812697 systemd[1]: Detected virtualization kvm. Sep 9 23:38:18.812705 systemd[1]: Detected architecture arm64. Sep 9 23:38:18.812712 systemd[1]: Running in initrd. Sep 9 23:38:18.812720 systemd[1]: No hostname configured, using default hostname. Sep 9 23:38:18.812730 systemd[1]: Hostname set to . Sep 9 23:38:18.812737 systemd[1]: Initializing machine ID from VM UUID. Sep 9 23:38:18.812745 systemd[1]: Queued start job for default target initrd.target. Sep 9 23:38:18.812753 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:38:18.812761 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:38:18.812769 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 23:38:18.812777 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 23:38:18.812785 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 23:38:18.812796 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 23:38:18.812804 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 23:38:18.812812 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 23:38:18.812819 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:38:18.812827 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:38:18.812844 systemd[1]: Reached target paths.target - Path Units. Sep 9 23:38:18.812853 systemd[1]: Reached target slices.target - Slice Units. Sep 9 23:38:18.812861 systemd[1]: Reached target swap.target - Swaps. Sep 9 23:38:18.812869 systemd[1]: Reached target timers.target - Timer Units. Sep 9 23:38:18.812876 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 23:38:18.812884 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 23:38:18.812892 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 23:38:18.812900 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 23:38:18.812907 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:38:18.812915 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 23:38:18.812924 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:38:18.812932 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 23:38:18.812939 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 23:38:18.812947 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 23:38:18.812955 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 23:38:18.812963 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 9 23:38:18.812971 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 23:38:18.812979 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 23:38:18.812989 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 23:38:18.812996 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:38:18.813004 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:38:18.813012 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 23:38:18.813020 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 23:38:18.813047 systemd-journald[245]: Collecting audit messages is disabled. Sep 9 23:38:18.813066 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 23:38:18.813075 systemd-journald[245]: Journal started Sep 9 23:38:18.813101 systemd-journald[245]: Runtime Journal (/run/log/journal/397b746445cc4bca9e92853b4bc47989) is 6M, max 48.5M, 42.4M free. Sep 9 23:38:18.805927 systemd-modules-load[246]: Inserted module 'overlay' Sep 9 23:38:18.816029 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 23:38:18.820396 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 23:38:18.821798 systemd-modules-load[246]: Inserted module 'br_netfilter' Sep 9 23:38:18.822783 kernel: Bridge firewalling registered Sep 9 23:38:18.830152 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 23:38:18.832706 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 23:38:18.834174 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:38:18.837131 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 23:38:18.839474 systemd-tmpfiles[264]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 23:38:18.840061 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 23:38:18.842089 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 23:38:18.848413 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 23:38:18.856545 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:38:18.863247 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:38:18.865448 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:38:18.868463 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 23:38:18.883509 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 23:38:18.885847 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 23:38:18.901889 dracut-cmdline[289]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=1a0303a4c67016bd8cbb391a5d1bb2355d0bb259dfb78ea746a1288c781f86ca Sep 9 23:38:18.918896 systemd-resolved[284]: Positive Trust Anchors: Sep 9 23:38:18.918913 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 23:38:18.918946 systemd-resolved[284]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 23:38:18.924897 systemd-resolved[284]: Defaulting to hostname 'linux'. Sep 9 23:38:18.926296 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 23:38:18.929642 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:38:18.978406 kernel: SCSI subsystem initialized Sep 9 23:38:18.983399 kernel: Loading iSCSI transport class v2.0-870. Sep 9 23:38:18.990407 kernel: iscsi: registered transport (tcp) Sep 9 23:38:19.003591 kernel: iscsi: registered transport (qla4xxx) Sep 9 23:38:19.003654 kernel: QLogic iSCSI HBA Driver Sep 9 23:38:19.021242 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 23:38:19.041146 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:38:19.043935 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 23:38:19.088591 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 23:38:19.090972 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 23:38:19.157419 kernel: raid6: neonx8 gen() 15114 MB/s Sep 9 23:38:19.174407 kernel: raid6: neonx4 gen() 15502 MB/s Sep 9 23:38:19.191409 kernel: raid6: neonx2 gen() 11939 MB/s Sep 9 23:38:19.208404 kernel: raid6: neonx1 gen() 10250 MB/s Sep 9 23:38:19.225405 kernel: raid6: int64x8 gen() 6593 MB/s Sep 9 23:38:19.242412 kernel: raid6: int64x4 gen() 7253 MB/s Sep 9 23:38:19.259412 kernel: raid6: int64x2 gen() 5998 MB/s Sep 9 23:38:19.276773 kernel: raid6: int64x1 gen() 5039 MB/s Sep 9 23:38:19.276792 kernel: raid6: using algorithm neonx4 gen() 15502 MB/s Sep 9 23:38:19.294683 kernel: raid6: .... xor() 12031 MB/s, rmw enabled Sep 9 23:38:19.294701 kernel: raid6: using neon recovery algorithm Sep 9 23:38:19.300466 kernel: xor: measuring software checksum speed Sep 9 23:38:19.300486 kernel: 8regs : 21630 MB/sec Sep 9 23:38:19.301913 kernel: 32regs : 20897 MB/sec Sep 9 23:38:19.301927 kernel: arm64_neon : 27927 MB/sec Sep 9 23:38:19.301935 kernel: xor: using function: arm64_neon (27927 MB/sec) Sep 9 23:38:19.355419 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 23:38:19.362797 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 23:38:19.365648 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:38:19.399654 systemd-udevd[496]: Using default interface naming scheme 'v255'. Sep 9 23:38:19.403815 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:38:19.406490 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 23:38:19.434362 dracut-pre-trigger[505]: rd.md=0: removing MD RAID activation Sep 9 23:38:19.459895 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 23:38:19.462766 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 23:38:19.511431 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:38:19.514633 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 23:38:19.577672 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 9 23:38:19.580011 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 9 23:38:19.585417 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:38:19.590150 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 23:38:19.590321 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:38:19.595071 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:38:19.599241 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:38:19.627553 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 9 23:38:19.629406 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:38:19.637419 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 23:38:19.646244 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 9 23:38:19.653294 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 9 23:38:19.654830 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 9 23:38:19.664138 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 23:38:19.665761 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 23:38:19.668189 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:38:19.670674 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 23:38:19.673804 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 23:38:19.676558 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 23:38:19.697614 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 23:38:19.725411 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:38:20.759448 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:38:20.760012 disk-uuid[595]: The operation has completed successfully. Sep 9 23:38:20.797355 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 23:38:20.797477 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 23:38:20.819805 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 23:38:20.840926 sh[608]: Success Sep 9 23:38:20.855436 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 23:38:20.855498 kernel: device-mapper: uevent: version 1.0.3 Sep 9 23:38:20.855519 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 23:38:20.863407 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 9 23:38:20.892842 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 23:38:20.895839 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 23:38:20.911728 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 23:38:20.919271 kernel: BTRFS: device fsid 61baaba1-cd1f-4e69-9af9-cc1b703c9653 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (620) Sep 9 23:38:20.919308 kernel: BTRFS info (device dm-0): first mount of filesystem 61baaba1-cd1f-4e69-9af9-cc1b703c9653 Sep 9 23:38:20.919319 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:38:20.924934 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 23:38:20.924992 kernel: BTRFS info (device dm-0): enabling free space tree Sep 9 23:38:20.926184 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 23:38:20.927645 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 23:38:20.929154 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 23:38:20.930048 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 23:38:20.931731 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 23:38:20.965790 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (653) Sep 9 23:38:20.965849 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:38:20.965860 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:38:20.969559 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:38:20.969621 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:38:20.974405 kernel: BTRFS info (device vda6): last unmount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:38:20.975943 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 23:38:20.978101 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 23:38:21.049422 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 23:38:21.053571 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 23:38:21.092296 systemd-networkd[797]: lo: Link UP Sep 9 23:38:21.092306 systemd-networkd[797]: lo: Gained carrier Sep 9 23:38:21.093044 systemd-networkd[797]: Enumeration completed Sep 9 23:38:21.093652 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:38:21.093655 systemd-networkd[797]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 23:38:21.095434 ignition[700]: Ignition 2.21.0 Sep 9 23:38:21.094633 systemd-networkd[797]: eth0: Link UP Sep 9 23:38:21.095441 ignition[700]: Stage: fetch-offline Sep 9 23:38:21.094721 systemd-networkd[797]: eth0: Gained carrier Sep 9 23:38:21.095475 ignition[700]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:21.094730 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:38:21.095482 ignition[700]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:21.097456 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 23:38:21.095671 ignition[700]: parsed url from cmdline: "" Sep 9 23:38:21.098748 systemd[1]: Reached target network.target - Network. Sep 9 23:38:21.095675 ignition[700]: no config URL provided Sep 9 23:38:21.095679 ignition[700]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 23:38:21.095687 ignition[700]: no config at "/usr/lib/ignition/user.ign" Sep 9 23:38:21.115428 systemd-networkd[797]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 23:38:21.095710 ignition[700]: op(1): [started] loading QEMU firmware config module Sep 9 23:38:21.095715 ignition[700]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 9 23:38:21.118738 unknown[700]: fetched base config from "system" Sep 9 23:38:21.106404 ignition[700]: op(1): [finished] loading QEMU firmware config module Sep 9 23:38:21.118744 unknown[700]: fetched user config from "qemu" Sep 9 23:38:21.114454 ignition[700]: parsing config with SHA512: 7a3b4e596c7aa194f222acce47585c17744ba37c04cb1c84e731b8c2b2512a61444ed6564dbd322911fe56fcf3e5c9adddae5f3e92dfdec53d7d9193561077b4 Sep 9 23:38:21.122411 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 23:38:21.118898 ignition[700]: fetch-offline: fetch-offline passed Sep 9 23:38:21.123726 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 9 23:38:21.118980 ignition[700]: Ignition finished successfully Sep 9 23:38:21.125540 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 23:38:21.154224 ignition[806]: Ignition 2.21.0 Sep 9 23:38:21.154241 ignition[806]: Stage: kargs Sep 9 23:38:21.154423 ignition[806]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:21.154433 ignition[806]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:21.156394 ignition[806]: kargs: kargs passed Sep 9 23:38:21.156448 ignition[806]: Ignition finished successfully Sep 9 23:38:21.160613 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 23:38:21.162621 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 23:38:21.185677 ignition[814]: Ignition 2.21.0 Sep 9 23:38:21.185692 ignition[814]: Stage: disks Sep 9 23:38:21.185827 ignition[814]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:21.185836 ignition[814]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:21.188858 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 23:38:21.186978 ignition[814]: disks: disks passed Sep 9 23:38:21.190763 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 23:38:21.187041 ignition[814]: Ignition finished successfully Sep 9 23:38:21.192534 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 23:38:21.194239 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 23:38:21.196269 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 23:38:21.198107 systemd[1]: Reached target basic.target - Basic System. Sep 9 23:38:21.201084 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 23:38:21.233319 systemd-fsck[824]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 9 23:38:21.238774 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 23:38:21.241780 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 23:38:21.310406 kernel: EXT4-fs (vda9): mounted filesystem b3fb930d-58c7-4aff-a89a-67d23b38af56 r/w with ordered data mode. Quota mode: none. Sep 9 23:38:21.310799 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 23:38:21.312138 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 23:38:21.315146 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 23:38:21.318972 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 23:38:21.320089 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 9 23:38:21.320140 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 23:38:21.320166 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 23:38:21.339463 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 23:38:21.343185 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 23:38:21.349214 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (832) Sep 9 23:38:21.349246 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:38:21.349256 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:38:21.352861 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:38:21.352951 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:38:21.355928 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 23:38:21.385665 initrd-setup-root[856]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 23:38:21.388979 initrd-setup-root[863]: cut: /sysroot/etc/group: No such file or directory Sep 9 23:38:21.392622 initrd-setup-root[870]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 23:38:21.395641 initrd-setup-root[877]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 23:38:21.468860 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 23:38:21.471101 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 23:38:21.473979 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 23:38:21.499425 kernel: BTRFS info (device vda6): last unmount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:38:21.512722 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 23:38:21.525479 ignition[945]: INFO : Ignition 2.21.0 Sep 9 23:38:21.525479 ignition[945]: INFO : Stage: mount Sep 9 23:38:21.527782 ignition[945]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:21.527782 ignition[945]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:21.527782 ignition[945]: INFO : mount: mount passed Sep 9 23:38:21.527782 ignition[945]: INFO : Ignition finished successfully Sep 9 23:38:21.528546 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 23:38:21.531054 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 23:38:21.917543 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 23:38:21.919117 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 23:38:21.937409 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (958) Sep 9 23:38:21.940080 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:38:21.940106 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:38:21.942929 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:38:21.942961 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:38:21.944312 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 23:38:21.946709 systemd-resolved[284]: Detected conflict on linux IN A 10.0.0.86 Sep 9 23:38:21.946720 systemd-resolved[284]: Hostname conflict, changing published hostname from 'linux' to 'linux6'. Sep 9 23:38:21.970140 ignition[975]: INFO : Ignition 2.21.0 Sep 9 23:38:21.970140 ignition[975]: INFO : Stage: files Sep 9 23:38:21.972864 ignition[975]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:21.972864 ignition[975]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:21.972864 ignition[975]: DEBUG : files: compiled without relabeling support, skipping Sep 9 23:38:21.976441 ignition[975]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 23:38:21.976441 ignition[975]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 23:38:21.979441 ignition[975]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 23:38:21.979441 ignition[975]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 23:38:21.979441 ignition[975]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 23:38:21.977373 unknown[975]: wrote ssh authorized keys file for user: core Sep 9 23:38:21.985413 ignition[975]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 23:38:21.985413 ignition[975]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 23:38:21.985413 ignition[975]: INFO : files: op(4): [started] processing unit "docker.service" Sep 9 23:38:21.985413 ignition[975]: INFO : files: op(4): [finished] processing unit "docker.service" Sep 9 23:38:21.985413 ignition[975]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Sep 9 23:38:21.985413 ignition[975]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 23:38:21.996082 ignition[975]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 23:38:21.996082 ignition[975]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Sep 9 23:38:21.996082 ignition[975]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Sep 9 23:38:22.002342 ignition[975]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 23:38:22.005480 ignition[975]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 23:38:22.008197 ignition[975]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Sep 9 23:38:22.008197 ignition[975]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Sep 9 23:38:22.008197 ignition[975]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Sep 9 23:38:22.008197 ignition[975]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 23:38:22.008197 ignition[975]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 23:38:22.008197 ignition[975]: INFO : files: files passed Sep 9 23:38:22.008197 ignition[975]: INFO : Ignition finished successfully Sep 9 23:38:22.011879 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 23:38:22.013855 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 23:38:22.038274 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 23:38:22.040450 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 23:38:22.040575 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 23:38:22.046484 initrd-setup-root-after-ignition[1004]: grep: /sysroot/oem/oem-release: No such file or directory Sep 9 23:38:22.049526 initrd-setup-root-after-ignition[1006]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:38:22.049526 initrd-setup-root-after-ignition[1006]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:38:22.052843 initrd-setup-root-after-ignition[1010]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:38:22.052616 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 23:38:22.054309 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 23:38:22.058253 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 23:38:22.093340 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 23:38:22.094442 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 23:38:22.097745 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 23:38:22.098914 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 23:38:22.100782 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 23:38:22.101583 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 23:38:22.122807 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 23:38:22.127615 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 23:38:22.148704 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:38:22.150090 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:38:22.152235 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 23:38:22.154117 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 23:38:22.154243 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 23:38:22.156754 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 23:38:22.158883 systemd[1]: Stopped target basic.target - Basic System. Sep 9 23:38:22.160625 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 23:38:22.162427 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 23:38:22.164589 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 23:38:22.166618 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 23:38:22.168630 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 23:38:22.170555 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 23:38:22.172728 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 23:38:22.174809 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 23:38:22.176743 systemd[1]: Stopped target swap.target - Swaps. Sep 9 23:38:22.178441 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 23:38:22.178596 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 23:38:22.181401 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:38:22.183559 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:38:22.185701 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 23:38:22.186438 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:38:22.187955 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 23:38:22.188085 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 23:38:22.191183 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 23:38:22.191302 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 23:38:22.193461 systemd[1]: Stopped target paths.target - Path Units. Sep 9 23:38:22.195286 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 23:38:22.198429 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:38:22.200719 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 23:38:22.202983 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 23:38:22.204692 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 23:38:22.204785 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 23:38:22.206494 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 23:38:22.206583 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 23:38:22.208279 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 23:38:22.208411 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 23:38:22.210414 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 23:38:22.210519 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 23:38:22.213020 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 23:38:22.215817 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 23:38:22.217086 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 23:38:22.217217 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:38:22.219097 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 23:38:22.219195 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 23:38:22.224488 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 23:38:22.234553 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 23:38:22.243186 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 23:38:22.246591 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 23:38:22.246692 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 23:38:22.250090 ignition[1030]: INFO : Ignition 2.21.0 Sep 9 23:38:22.250090 ignition[1030]: INFO : Stage: umount Sep 9 23:38:22.251793 ignition[1030]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:38:22.251793 ignition[1030]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:38:22.254236 ignition[1030]: INFO : umount: umount passed Sep 9 23:38:22.255101 ignition[1030]: INFO : Ignition finished successfully Sep 9 23:38:22.255797 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 23:38:22.255898 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 23:38:22.257125 systemd[1]: Stopped target network.target - Network. Sep 9 23:38:22.258641 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 23:38:22.258701 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 23:38:22.260359 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 23:38:22.260460 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 23:38:22.262087 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 23:38:22.262137 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 23:38:22.263837 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 23:38:22.263878 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 23:38:22.265610 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 23:38:22.265663 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 23:38:22.267665 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 23:38:22.269372 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 23:38:22.272686 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 23:38:22.272784 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 23:38:22.276089 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 9 23:38:22.276319 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 23:38:22.276357 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:38:22.280065 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 9 23:38:22.280270 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 23:38:22.281428 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 23:38:22.285997 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 23:38:22.288121 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 23:38:22.288162 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:38:22.291270 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 23:38:22.292431 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 23:38:22.292509 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 23:38:22.294797 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 23:38:22.294844 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:38:22.297616 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 23:38:22.297660 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 23:38:22.299811 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:38:22.311013 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 23:38:22.312503 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:38:22.314047 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 23:38:22.314087 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 23:38:22.316257 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 23:38:22.316288 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:38:22.318269 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 23:38:22.318322 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 23:38:22.321420 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 23:38:22.321474 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 23:38:22.324309 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 23:38:22.324367 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 23:38:22.328169 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 23:38:22.329411 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 23:38:22.329468 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:38:22.332819 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 23:38:22.332866 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:38:22.336507 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 9 23:38:22.336550 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 23:38:22.340508 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 23:38:22.340550 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:38:22.343028 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 23:38:22.343076 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:38:22.347360 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 23:38:22.347499 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 23:38:22.348922 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 23:38:22.348985 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 23:38:22.351810 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 23:38:22.353801 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 23:38:22.374425 systemd[1]: Switching root. Sep 9 23:38:22.406641 systemd-journald[245]: Journal stopped Sep 9 23:38:23.119184 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Sep 9 23:38:23.119237 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 23:38:23.119250 kernel: SELinux: policy capability open_perms=1 Sep 9 23:38:23.119260 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 23:38:23.119269 kernel: SELinux: policy capability always_check_network=0 Sep 9 23:38:23.119278 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 23:38:23.119289 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 23:38:23.119305 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 23:38:23.119316 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 23:38:23.119325 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 23:38:23.119334 kernel: audit: type=1403 audit(1757461102.490:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 23:38:23.119347 systemd[1]: Successfully loaded SELinux policy in 47.298ms. Sep 9 23:38:23.119366 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.470ms. Sep 9 23:38:23.119386 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 9 23:38:23.119399 systemd[1]: Detected virtualization kvm. Sep 9 23:38:23.119409 systemd[1]: Detected architecture arm64. Sep 9 23:38:23.119418 systemd[1]: Detected first boot. Sep 9 23:38:23.119430 systemd[1]: Initializing machine ID from VM UUID. Sep 9 23:38:23.119440 zram_generator::config[1081]: No configuration found. Sep 9 23:38:23.119452 kernel: NET: Registered PF_VSOCK protocol family Sep 9 23:38:23.119461 systemd[1]: Populated /etc with preset unit settings. Sep 9 23:38:23.119472 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 9 23:38:23.119485 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 23:38:23.119496 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 23:38:23.119511 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 23:38:23.119522 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 23:38:23.119537 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 23:38:23.119547 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 23:38:23.119558 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 23:38:23.119581 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 23:38:23.119594 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 23:38:23.119608 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 23:38:23.119618 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 23:38:23.119629 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:38:23.119639 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:38:23.119650 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 23:38:23.119660 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 23:38:23.119671 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 23:38:23.119681 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 23:38:23.119696 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 9 23:38:23.119706 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:38:23.119716 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:38:23.119726 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 23:38:23.119736 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 23:38:23.119745 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 23:38:23.119755 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 23:38:23.119765 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:38:23.119776 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 23:38:23.119787 systemd[1]: Reached target slices.target - Slice Units. Sep 9 23:38:23.119797 systemd[1]: Reached target swap.target - Swaps. Sep 9 23:38:23.119807 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 23:38:23.119817 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 23:38:23.119827 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 23:38:23.119837 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:38:23.119848 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 23:38:23.119858 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:38:23.119868 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 23:38:23.119880 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 23:38:23.119890 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 23:38:23.119900 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 23:38:23.119910 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 23:38:23.119921 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 23:38:23.119930 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 23:38:23.119940 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 9 23:38:23.119950 systemd[1]: Reached target machines.target - Containers. Sep 9 23:38:23.119962 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 23:38:23.119972 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:38:23.119982 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 23:38:23.119992 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 23:38:23.120001 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:38:23.120011 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 23:38:23.120022 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:38:23.120033 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 23:38:23.120043 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:38:23.120055 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 23:38:23.120086 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 23:38:23.120098 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 23:38:23.120109 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 23:38:23.120119 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 23:38:23.120129 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 23:38:23.120138 kernel: fuse: init (API version 7.41) Sep 9 23:38:23.120147 kernel: loop: module loaded Sep 9 23:38:23.120158 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 23:38:23.120168 kernel: ACPI: bus type drm_connector registered Sep 9 23:38:23.120177 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 23:38:23.120187 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 23:38:23.120197 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 23:38:23.120207 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 23:38:23.120216 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 23:38:23.120246 systemd-journald[1156]: Collecting audit messages is disabled. Sep 9 23:38:23.120271 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 23:38:23.120282 systemd[1]: Stopped verity-setup.service. Sep 9 23:38:23.120293 systemd-journald[1156]: Journal started Sep 9 23:38:23.120316 systemd-journald[1156]: Runtime Journal (/run/log/journal/397b746445cc4bca9e92853b4bc47989) is 6M, max 48.5M, 42.4M free. Sep 9 23:38:22.879096 systemd[1]: Queued start job for default target multi-user.target. Sep 9 23:38:22.902366 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 9 23:38:22.902785 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 23:38:23.125467 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 23:38:23.126179 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 23:38:23.127550 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 23:38:23.128948 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 23:38:23.130160 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 23:38:23.131473 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 23:38:23.132833 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 23:38:23.137399 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 23:38:23.143665 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:38:23.145250 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 23:38:23.145449 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 23:38:23.146929 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:38:23.147107 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:38:23.148561 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 23:38:23.148743 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 23:38:23.150080 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:38:23.150230 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:38:23.151744 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 23:38:23.151890 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 23:38:23.153408 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:38:23.153576 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:38:23.155114 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 23:38:23.156630 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:38:23.158220 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 23:38:23.159964 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 23:38:23.170250 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:38:23.174346 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 23:38:23.177191 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 23:38:23.179598 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 23:38:23.180817 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 23:38:23.180863 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 23:38:23.182972 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 23:38:23.185632 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 23:38:23.186910 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:38:23.188204 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 23:38:23.190583 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 23:38:23.191944 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 23:38:23.193124 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 23:38:23.194560 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 23:38:23.196551 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 23:38:23.200840 systemd-journald[1156]: Time spent on flushing to /var/log/journal/397b746445cc4bca9e92853b4bc47989 is 29.106ms for 857 entries. Sep 9 23:38:23.200840 systemd-journald[1156]: System Journal (/var/log/journal/397b746445cc4bca9e92853b4bc47989) is 8M, max 195.6M, 187.6M free. Sep 9 23:38:23.248649 systemd-journald[1156]: Received client request to flush runtime journal. Sep 9 23:38:23.248710 kernel: loop0: detected capacity change from 0 to 138376 Sep 9 23:38:23.248731 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 23:38:23.201751 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 23:38:23.205794 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 23:38:23.208825 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 23:38:23.210504 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 23:38:23.215257 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 23:38:23.219748 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 23:38:23.222557 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 23:38:23.231934 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Sep 9 23:38:23.231945 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Sep 9 23:38:23.239232 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:38:23.242829 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 23:38:23.246037 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 23:38:23.251431 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 23:38:23.261534 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 23:38:23.273459 kernel: loop1: detected capacity change from 0 to 107312 Sep 9 23:38:23.288819 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 23:38:23.291717 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 23:38:23.311414 kernel: loop2: detected capacity change from 0 to 138376 Sep 9 23:38:23.322451 systemd-tmpfiles[1220]: ACLs are not supported, ignoring. Sep 9 23:38:23.323430 kernel: loop3: detected capacity change from 0 to 107312 Sep 9 23:38:23.322470 systemd-tmpfiles[1220]: ACLs are not supported, ignoring. Sep 9 23:38:23.328560 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:38:23.335557 (sd-merge)[1221]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 9 23:38:23.336002 (sd-merge)[1221]: Merged extensions into '/usr'. Sep 9 23:38:23.338989 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 23:38:23.344484 systemd[1]: Starting ensure-sysext.service... Sep 9 23:38:23.346714 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 23:38:23.366404 systemd[1]: Reload requested from client PID 1225 ('systemctl') (unit ensure-sysext.service)... Sep 9 23:38:23.366857 systemd[1]: Reloading... Sep 9 23:38:23.375452 systemd-tmpfiles[1226]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 23:38:23.375501 systemd-tmpfiles[1226]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 23:38:23.375786 systemd-tmpfiles[1226]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 23:38:23.375973 systemd-tmpfiles[1226]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 23:38:23.377022 systemd-tmpfiles[1226]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 23:38:23.377428 systemd-tmpfiles[1226]: ACLs are not supported, ignoring. Sep 9 23:38:23.377493 systemd-tmpfiles[1226]: ACLs are not supported, ignoring. Sep 9 23:38:23.381245 systemd-tmpfiles[1226]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 23:38:23.381257 systemd-tmpfiles[1226]: Skipping /boot Sep 9 23:38:23.394520 systemd-tmpfiles[1226]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 23:38:23.394537 systemd-tmpfiles[1226]: Skipping /boot Sep 9 23:38:23.424407 zram_generator::config[1250]: No configuration found. Sep 9 23:38:23.521053 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 9 23:38:23.554095 ldconfig[1193]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 23:38:23.585419 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 23:38:23.585515 systemd[1]: Reloading finished in 218 ms. Sep 9 23:38:23.618407 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 23:38:23.632986 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:38:23.643161 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 23:38:23.646862 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 23:38:23.653659 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 23:38:23.657457 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 23:38:23.662787 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 23:38:23.667271 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:38:23.669089 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:38:23.674782 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:38:23.677938 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:38:23.680112 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:38:23.680250 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 23:38:23.682741 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:38:23.682896 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:38:23.682981 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 23:38:23.684936 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 23:38:23.687216 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:38:23.688431 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:38:23.698428 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 23:38:23.701879 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:38:23.702077 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:38:23.705228 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:38:23.705448 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:38:23.707843 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 23:38:23.719411 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:38:23.721131 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:38:23.723733 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 23:38:23.734997 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:38:23.738547 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:38:23.740180 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:38:23.740245 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 23:38:23.740312 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 9 23:38:23.740811 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 23:38:23.744096 systemd[1]: Finished ensure-sysext.service. Sep 9 23:38:23.745487 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 23:38:23.747256 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:38:23.748042 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:38:23.749760 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 23:38:23.751500 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 23:38:23.753559 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:38:23.753759 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:38:23.763108 augenrules[1334]: No rules Sep 9 23:38:23.763254 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 23:38:23.768683 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 9 23:38:23.770481 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 23:38:23.770756 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 23:38:23.772215 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:38:23.772505 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:38:23.774804 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 23:38:23.788436 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 23:38:23.794828 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:38:23.797486 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 23:38:23.800104 systemd-resolved[1294]: Positive Trust Anchors: Sep 9 23:38:23.800142 systemd-resolved[1294]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 23:38:23.800176 systemd-resolved[1294]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 23:38:23.807157 systemd-resolved[1294]: Defaulting to hostname 'linux'. Sep 9 23:38:23.808946 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 23:38:23.810428 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:38:23.818046 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 23:38:23.831462 systemd-udevd[1348]: Using default interface naming scheme 'v255'. Sep 9 23:38:23.834669 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 9 23:38:23.836363 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 23:38:23.854432 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:38:23.856228 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 23:38:23.857584 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 23:38:23.859207 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 23:38:23.861012 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 23:38:23.862540 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 23:38:23.865487 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 23:38:23.866903 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 23:38:23.866949 systemd[1]: Reached target paths.target - Path Units. Sep 9 23:38:23.867955 systemd[1]: Reached target timers.target - Timer Units. Sep 9 23:38:23.870280 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 23:38:23.872991 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 23:38:23.876652 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 23:38:23.878464 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 9 23:38:23.880011 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 9 23:38:23.899918 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 23:38:23.901636 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 23:38:23.906671 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 23:38:23.911787 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 23:38:23.916582 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 23:38:23.917772 systemd[1]: Reached target basic.target - Basic System. Sep 9 23:38:23.918862 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 23:38:23.918894 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 23:38:23.920114 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 23:38:23.922558 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 23:38:23.927059 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 23:38:23.930666 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 23:38:23.932488 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 9 23:38:23.935685 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 23:38:23.945927 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 23:38:23.948999 jq[1385]: false Sep 9 23:38:23.949630 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 23:38:23.954634 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 23:38:23.957461 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 23:38:23.958004 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 23:38:23.961130 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 23:38:23.963636 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 23:38:23.964076 extend-filesystems[1387]: Found /dev/vda6 Sep 9 23:38:23.967778 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 23:38:23.972957 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 23:38:23.973167 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 23:38:23.975545 extend-filesystems[1387]: Found /dev/vda9 Sep 9 23:38:23.976707 extend-filesystems[1387]: Checking size of /dev/vda9 Sep 9 23:38:23.979138 jq[1403]: true Sep 9 23:38:23.989257 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 23:38:23.995537 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 23:38:23.997525 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 23:38:23.997737 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 23:38:24.004922 jq[1412]: true Sep 9 23:38:24.009703 dbus-daemon[1383]: [system] SELinux support is enabled Sep 9 23:38:24.009937 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 23:38:24.011366 systemd-networkd[1381]: lo: Link UP Sep 9 23:38:24.012718 update_engine[1402]: I20250909 23:38:24.012034 1402 main.cc:92] Flatcar Update Engine starting Sep 9 23:38:24.013317 extend-filesystems[1387]: Old size kept for /dev/vda9 Sep 9 23:38:24.014307 systemd-networkd[1381]: lo: Gained carrier Sep 9 23:38:24.015090 systemd-networkd[1381]: Enumeration completed Sep 9 23:38:24.015223 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 23:38:24.016793 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 23:38:24.017066 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 23:38:24.018207 update_engine[1402]: I20250909 23:38:24.018150 1402 update_check_scheduler.cc:74] Next update check in 9m22s Sep 9 23:38:24.019473 systemd[1]: Reached target network.target - Network. Sep 9 23:38:24.024895 systemd-networkd[1381]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:38:24.025075 systemd-networkd[1381]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 23:38:24.026961 systemd-networkd[1381]: eth0: Link UP Sep 9 23:38:24.027711 systemd-networkd[1381]: eth0: Gained carrier Sep 9 23:38:24.027734 systemd-networkd[1381]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:38:24.033696 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 23:38:24.035187 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 9 23:38:24.035232 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 23:38:24.042621 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 23:38:24.046494 systemd-networkd[1381]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 23:38:24.048503 systemd-timesyncd[1344]: Network configuration changed, trying to establish connection. Sep 9 23:38:24.049546 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 23:38:24.051039 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 9 23:38:24.051073 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 23:38:24.053093 systemd-timesyncd[1344]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 9 23:38:24.053143 systemd-timesyncd[1344]: Initial clock synchronization to Tue 2025-09-09 23:38:23.991822 UTC. Sep 9 23:38:24.062861 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 9 23:38:24.062937 systemd[1]: Started update-engine.service - Update Engine. Sep 9 23:38:24.066243 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 23:38:24.069808 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 23:38:24.069844 systemd-logind[1398]: New seat seat0. Sep 9 23:38:24.076806 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 23:38:24.078365 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 23:38:24.080478 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 23:38:24.089553 bash[1445]: Updated "/home/core/.ssh/authorized_keys" Sep 9 23:38:24.092047 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 23:38:24.100194 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 23:38:24.109827 (ntainerd)[1449]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 23:38:24.109978 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 23:38:24.178759 locksmithd[1447]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 23:38:24.212684 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:38:24.243523 systemd-logind[1398]: Watching system buttons on /dev/input/event0 (Power Button) Sep 9 23:38:24.297296 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:38:24.337960 containerd[1449]: time="2025-09-09T23:38:24Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 9 23:38:24.339790 containerd[1449]: time="2025-09-09T23:38:24.339740000Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.349840280Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="12.28µs" Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.349886320Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.349905760Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.350091560Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.350112400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.350138000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.350187640Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350409 containerd[1449]: time="2025-09-09T23:38:24.350200200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350811 containerd[1449]: time="2025-09-09T23:38:24.350782240Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350865 containerd[1449]: time="2025-09-09T23:38:24.350852840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350927 containerd[1449]: time="2025-09-09T23:38:24.350912640Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:38:24.350980 containerd[1449]: time="2025-09-09T23:38:24.350967880Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 23:38:24.351118 containerd[1449]: time="2025-09-09T23:38:24.351101160Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 23:38:24.351410 containerd[1449]: time="2025-09-09T23:38:24.351357600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:38:24.351507 containerd[1449]: time="2025-09-09T23:38:24.351489800Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:38:24.351590 containerd[1449]: time="2025-09-09T23:38:24.351574560Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 23:38:24.351678 containerd[1449]: time="2025-09-09T23:38:24.351662960Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 23:38:24.352023 containerd[1449]: time="2025-09-09T23:38:24.351968880Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 23:38:24.352088 containerd[1449]: time="2025-09-09T23:38:24.352072640Z" level=info msg="metadata content store policy set" policy=shared Sep 9 23:38:24.357908 containerd[1449]: time="2025-09-09T23:38:24.357859880Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 23:38:24.357959 containerd[1449]: time="2025-09-09T23:38:24.357930480Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 23:38:24.357959 containerd[1449]: time="2025-09-09T23:38:24.357946920Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.357961440Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.357974560Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.357984360Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.357996200Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.358008000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.358020600Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 23:38:24.358030 containerd[1449]: time="2025-09-09T23:38:24.358031000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 23:38:24.358141 containerd[1449]: time="2025-09-09T23:38:24.358042400Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 23:38:24.358141 containerd[1449]: time="2025-09-09T23:38:24.358060560Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 23:38:24.358244 containerd[1449]: time="2025-09-09T23:38:24.358213000Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 23:38:24.358244 containerd[1449]: time="2025-09-09T23:38:24.358241640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 23:38:24.358286 containerd[1449]: time="2025-09-09T23:38:24.358259240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 23:38:24.358286 containerd[1449]: time="2025-09-09T23:38:24.358270280Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 23:38:24.358286 containerd[1449]: time="2025-09-09T23:38:24.358281680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 23:38:24.358339 containerd[1449]: time="2025-09-09T23:38:24.358292600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 23:38:24.358339 containerd[1449]: time="2025-09-09T23:38:24.358304560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 23:38:24.358339 containerd[1449]: time="2025-09-09T23:38:24.358316080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 23:38:24.358339 containerd[1449]: time="2025-09-09T23:38:24.358328800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 23:38:24.358426 containerd[1449]: time="2025-09-09T23:38:24.358340040Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 23:38:24.358426 containerd[1449]: time="2025-09-09T23:38:24.358350840Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 23:38:24.358598 containerd[1449]: time="2025-09-09T23:38:24.358568680Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 23:38:24.358598 containerd[1449]: time="2025-09-09T23:38:24.358593000Z" level=info msg="Start snapshots syncer" Sep 9 23:38:24.358657 containerd[1449]: time="2025-09-09T23:38:24.358624600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 23:38:24.358985 containerd[1449]: time="2025-09-09T23:38:24.358941840Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 9 23:38:24.359094 containerd[1449]: time="2025-09-09T23:38:24.359006160Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 23:38:24.359172 containerd[1449]: time="2025-09-09T23:38:24.359149240Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 23:38:24.359310 containerd[1449]: time="2025-09-09T23:38:24.359285000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 23:38:24.359421 containerd[1449]: time="2025-09-09T23:38:24.359404160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 23:38:24.359456 containerd[1449]: time="2025-09-09T23:38:24.359424000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 23:38:24.359456 containerd[1449]: time="2025-09-09T23:38:24.359442080Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 23:38:24.359490 containerd[1449]: time="2025-09-09T23:38:24.359459560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 23:38:24.359490 containerd[1449]: time="2025-09-09T23:38:24.359477120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 23:38:24.359535 containerd[1449]: time="2025-09-09T23:38:24.359489800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 23:38:24.359535 containerd[1449]: time="2025-09-09T23:38:24.359529240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 23:38:24.359578 containerd[1449]: time="2025-09-09T23:38:24.359542800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 23:38:24.359578 containerd[1449]: time="2025-09-09T23:38:24.359569960Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359615960Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359641240Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359655840Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359684720Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359698520Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359712520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359724440Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359805960Z" level=info msg="runtime interface created" Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359812440Z" level=info msg="created NRI interface" Sep 9 23:38:24.359861 containerd[1449]: time="2025-09-09T23:38:24.359828320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 23:38:24.360053 containerd[1449]: time="2025-09-09T23:38:24.360028560Z" level=info msg="Connect containerd service" Sep 9 23:38:24.360141 containerd[1449]: time="2025-09-09T23:38:24.360114200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 23:38:24.361383 containerd[1449]: time="2025-09-09T23:38:24.361336400Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 23:38:24.445642 containerd[1449]: time="2025-09-09T23:38:24.445599960Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 23:38:24.445780 containerd[1449]: time="2025-09-09T23:38:24.445660200Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 23:38:24.445780 containerd[1449]: time="2025-09-09T23:38:24.445690000Z" level=info msg="Start subscribing containerd event" Sep 9 23:38:24.445780 containerd[1449]: time="2025-09-09T23:38:24.445739640Z" level=info msg="Start recovering state" Sep 9 23:38:24.445832 containerd[1449]: time="2025-09-09T23:38:24.445818280Z" level=info msg="Start event monitor" Sep 9 23:38:24.445849 containerd[1449]: time="2025-09-09T23:38:24.445835120Z" level=info msg="Start cni network conf syncer for default" Sep 9 23:38:24.445849 containerd[1449]: time="2025-09-09T23:38:24.445843720Z" level=info msg="Start streaming server" Sep 9 23:38:24.445880 containerd[1449]: time="2025-09-09T23:38:24.445851760Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 23:38:24.445880 containerd[1449]: time="2025-09-09T23:38:24.445858440Z" level=info msg="runtime interface starting up..." Sep 9 23:38:24.445880 containerd[1449]: time="2025-09-09T23:38:24.445864400Z" level=info msg="starting plugins..." Sep 9 23:38:24.445880 containerd[1449]: time="2025-09-09T23:38:24.445876680Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 23:38:24.445996 containerd[1449]: time="2025-09-09T23:38:24.445978000Z" level=info msg="containerd successfully booted in 0.108370s" Sep 9 23:38:24.446520 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 23:38:24.770145 sshd_keygen[1419]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 23:38:24.790706 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 23:38:24.793787 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 23:38:24.814651 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 23:38:24.814873 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 23:38:24.817941 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 23:38:24.852963 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 23:38:24.856253 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 23:38:24.858837 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 9 23:38:24.860509 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 23:38:25.874498 systemd-networkd[1381]: eth0: Gained IPv6LL Sep 9 23:38:25.877019 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 23:38:25.878955 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 23:38:25.883013 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 23:38:25.885605 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 23:38:25.918946 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 23:38:25.920738 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 23:38:25.921432 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 23:38:25.923593 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 23:38:25.923796 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 23:38:25.927524 systemd[1]: Startup finished in 2.125s (kernel) + 3.881s (initrd) + 3.486s (userspace) = 9.493s. Sep 9 23:38:31.649047 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 23:38:31.650292 systemd[1]: Started sshd@0-10.0.0.86:22-10.0.0.1:52708.service - OpenSSH per-connection server daemon (10.0.0.1:52708). Sep 9 23:38:31.712056 sshd[1540]: Accepted publickey for core from 10.0.0.1 port 52708 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:31.713837 sshd-session[1540]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:31.720494 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 23:38:31.721537 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 23:38:31.728926 systemd-logind[1398]: New session 1 of user core. Sep 9 23:38:31.757120 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 23:38:31.760162 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 23:38:31.774850 (systemd)[1544]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 23:38:31.777182 systemd-logind[1398]: New session c1 of user core. Sep 9 23:38:31.893225 systemd[1544]: Queued start job for default target default.target. Sep 9 23:38:31.904416 systemd[1544]: Created slice app.slice - User Application Slice. Sep 9 23:38:31.904448 systemd[1544]: Reached target paths.target - Paths. Sep 9 23:38:31.904489 systemd[1544]: Reached target timers.target - Timers. Sep 9 23:38:31.905806 systemd[1544]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 23:38:31.917057 systemd[1544]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 23:38:31.917184 systemd[1544]: Reached target sockets.target - Sockets. Sep 9 23:38:31.917363 systemd[1544]: Reached target basic.target - Basic System. Sep 9 23:38:31.917479 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 23:38:31.918191 systemd[1544]: Reached target default.target - Main User Target. Sep 9 23:38:31.918235 systemd[1544]: Startup finished in 134ms. Sep 9 23:38:31.919538 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 23:38:31.979738 systemd[1]: Started sshd@1-10.0.0.86:22-10.0.0.1:52722.service - OpenSSH per-connection server daemon (10.0.0.1:52722). Sep 9 23:38:32.030724 sshd[1555]: Accepted publickey for core from 10.0.0.1 port 52722 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.032137 sshd-session[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.039984 systemd-logind[1398]: New session 2 of user core. Sep 9 23:38:32.049583 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 23:38:32.105394 sshd[1557]: Connection closed by 10.0.0.1 port 52722 Sep 9 23:38:32.105895 sshd-session[1555]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:32.116982 systemd[1]: sshd@1-10.0.0.86:22-10.0.0.1:52722.service: Deactivated successfully. Sep 9 23:38:32.120619 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 23:38:32.121588 systemd-logind[1398]: Session 2 logged out. Waiting for processes to exit. Sep 9 23:38:32.124559 systemd[1]: Started sshd@2-10.0.0.86:22-10.0.0.1:52728.service - OpenSSH per-connection server daemon (10.0.0.1:52728). Sep 9 23:38:32.125584 systemd-logind[1398]: Removed session 2. Sep 9 23:38:32.180468 sshd[1563]: Accepted publickey for core from 10.0.0.1 port 52728 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.181921 sshd-session[1563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.189873 systemd-logind[1398]: New session 3 of user core. Sep 9 23:38:32.204578 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 23:38:32.254548 sshd[1565]: Connection closed by 10.0.0.1 port 52728 Sep 9 23:38:32.254964 sshd-session[1563]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:32.271272 systemd[1]: sshd@2-10.0.0.86:22-10.0.0.1:52728.service: Deactivated successfully. Sep 9 23:38:32.275512 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 23:38:32.277998 systemd-logind[1398]: Session 3 logged out. Waiting for processes to exit. Sep 9 23:38:32.281005 systemd[1]: Started sshd@3-10.0.0.86:22-10.0.0.1:52734.service - OpenSSH per-connection server daemon (10.0.0.1:52734). Sep 9 23:38:32.282156 systemd-logind[1398]: Removed session 3. Sep 9 23:38:32.338889 sshd[1571]: Accepted publickey for core from 10.0.0.1 port 52734 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.340242 sshd-session[1571]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.346752 systemd-logind[1398]: New session 4 of user core. Sep 9 23:38:32.354707 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 23:38:32.417201 sshd[1573]: Connection closed by 10.0.0.1 port 52734 Sep 9 23:38:32.417723 sshd-session[1571]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:32.428177 systemd[1]: sshd@3-10.0.0.86:22-10.0.0.1:52734.service: Deactivated successfully. Sep 9 23:38:32.430813 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 23:38:32.433969 systemd-logind[1398]: Session 4 logged out. Waiting for processes to exit. Sep 9 23:38:32.434992 systemd[1]: Started sshd@4-10.0.0.86:22-10.0.0.1:52750.service - OpenSSH per-connection server daemon (10.0.0.1:52750). Sep 9 23:38:32.436850 systemd-logind[1398]: Removed session 4. Sep 9 23:38:32.486872 sshd[1579]: Accepted publickey for core from 10.0.0.1 port 52750 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.488236 sshd-session[1579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.493437 systemd-logind[1398]: New session 5 of user core. Sep 9 23:38:32.503587 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 23:38:32.566345 sudo[1582]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 9 23:38:32.566635 sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:38:32.584954 sudo[1582]: pam_unix(sudo:session): session closed for user root Sep 9 23:38:32.587326 sshd[1581]: Connection closed by 10.0.0.1 port 52750 Sep 9 23:38:32.587962 sshd-session[1579]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:32.597524 systemd[1]: sshd@4-10.0.0.86:22-10.0.0.1:52750.service: Deactivated successfully. Sep 9 23:38:32.598982 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 23:38:32.599784 systemd-logind[1398]: Session 5 logged out. Waiting for processes to exit. Sep 9 23:38:32.601889 systemd[1]: Started sshd@5-10.0.0.86:22-10.0.0.1:52756.service - OpenSSH per-connection server daemon (10.0.0.1:52756). Sep 9 23:38:32.603814 systemd-logind[1398]: Removed session 5. Sep 9 23:38:32.662131 sshd[1588]: Accepted publickey for core from 10.0.0.1 port 52756 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.663513 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.670169 systemd-logind[1398]: New session 6 of user core. Sep 9 23:38:32.678586 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 23:38:32.730832 sudo[1592]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 9 23:38:32.731110 sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:38:32.744251 sudo[1592]: pam_unix(sudo:session): session closed for user root Sep 9 23:38:32.749285 sudo[1591]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 9 23:38:32.749584 sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:38:32.759168 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 23:38:32.809286 augenrules[1614]: No rules Sep 9 23:38:32.811428 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 23:38:32.811667 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 23:38:32.813108 sudo[1591]: pam_unix(sudo:session): session closed for user root Sep 9 23:38:32.814931 sshd[1590]: Connection closed by 10.0.0.1 port 52756 Sep 9 23:38:32.815794 sshd-session[1588]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:32.823700 systemd[1]: sshd@5-10.0.0.86:22-10.0.0.1:52756.service: Deactivated successfully. Sep 9 23:38:32.826001 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 23:38:32.826751 systemd-logind[1398]: Session 6 logged out. Waiting for processes to exit. Sep 9 23:38:32.829532 systemd[1]: Started sshd@6-10.0.0.86:22-10.0.0.1:52762.service - OpenSSH per-connection server daemon (10.0.0.1:52762). Sep 9 23:38:32.830746 systemd-logind[1398]: Removed session 6. Sep 9 23:38:32.880271 sshd[1623]: Accepted publickey for core from 10.0.0.1 port 52762 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:32.881747 sshd-session[1623]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:32.890582 systemd-logind[1398]: New session 7 of user core. Sep 9 23:38:32.894568 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 23:38:32.947614 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 23:38:32.968825 (dockerd)[1627]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 9 23:38:33.200046 dockerd[1627]: time="2025-09-09T23:38:33.199946586Z" level=info msg="Starting up" Sep 9 23:38:33.203497 dockerd[1627]: time="2025-09-09T23:38:33.203442555Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 9 23:38:33.264525 dockerd[1627]: time="2025-09-09T23:38:33.264458689Z" level=info msg="Loading containers: start." Sep 9 23:38:33.277410 kernel: Initializing XFRM netlink socket Sep 9 23:38:33.478933 systemd-networkd[1381]: docker0: Link UP Sep 9 23:38:33.483182 dockerd[1627]: time="2025-09-09T23:38:33.483124876Z" level=info msg="Loading containers: done." Sep 9 23:38:33.500830 dockerd[1627]: time="2025-09-09T23:38:33.500765993Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 9 23:38:33.501005 dockerd[1627]: time="2025-09-09T23:38:33.500879574Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Sep 9 23:38:33.501041 dockerd[1627]: time="2025-09-09T23:38:33.501020093Z" level=info msg="Initializing buildkit" Sep 9 23:38:33.528768 dockerd[1627]: time="2025-09-09T23:38:33.528705372Z" level=info msg="Completed buildkit initialization" Sep 9 23:38:33.534607 dockerd[1627]: time="2025-09-09T23:38:33.534561785Z" level=info msg="Daemon has completed initialization" Sep 9 23:38:33.535170 dockerd[1627]: time="2025-09-09T23:38:33.534634220Z" level=info msg="API listen on /run/docker.sock" Sep 9 23:38:33.534788 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 23:38:33.543064 sshd[1625]: Connection closed by 10.0.0.1 port 52762 Sep 9 23:38:33.543440 sshd-session[1623]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:33.555349 systemd[1]: sshd@6-10.0.0.86:22-10.0.0.1:52762.service: Deactivated successfully. Sep 9 23:38:33.558727 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 23:38:33.559472 systemd-logind[1398]: Session 7 logged out. Waiting for processes to exit. Sep 9 23:38:33.562055 systemd[1]: Started sshd@7-10.0.0.86:22-10.0.0.1:52770.service - OpenSSH per-connection server daemon (10.0.0.1:52770). Sep 9 23:38:33.562679 systemd-logind[1398]: Removed session 7. Sep 9 23:38:33.611744 sshd[1833]: Accepted publickey for core from 10.0.0.1 port 52770 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:33.613046 sshd-session[1833]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:33.616758 systemd-logind[1398]: New session 8 of user core. Sep 9 23:38:33.627556 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 23:38:34.821883 systemd[1]: var-lib-docker-overlay2-05019c2b6fc8fc6ab41f1f9eaef9d4d94d32c28adf9273d7717a14efb5b70441\x2dinit-merged.mount: Deactivated successfully. Sep 9 23:38:34.872409 containerd[1449]: time="2025-09-09T23:38:34.872220404Z" level=info msg="connecting to shim 8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f" address="unix:///run/containerd/s/c2b55ed7e66b36fcbf575789ab8ee64a1a64293d67eaa8cb33f311e2b6f25b00" namespace=moby protocol=ttrpc version=3 Sep 9 23:38:34.896589 systemd[1]: Started docker-8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f.scope - libcontainer container 8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f. Sep 9 23:38:34.908921 systemd-resolved[1294]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:38:34.927277 kernel: docker0: port 1(veth70ed885) entered blocking state Sep 9 23:38:34.927362 kernel: docker0: port 1(veth70ed885) entered disabled state Sep 9 23:38:34.927416 kernel: veth70ed885: entered allmulticast mode Sep 9 23:38:34.928698 kernel: veth70ed885: entered promiscuous mode Sep 9 23:38:34.928965 systemd-networkd[1381]: veth70ed885: Link UP Sep 9 23:38:34.958406 kernel: eth0: renamed from veth7d9b079 Sep 9 23:38:34.960532 kernel: docker0: port 1(veth70ed885) entered blocking state Sep 9 23:38:34.960764 kernel: docker0: port 1(veth70ed885) entered forwarding state Sep 9 23:38:34.960697 systemd-networkd[1381]: veth70ed885: Gained carrier Sep 9 23:38:34.960946 systemd-networkd[1381]: docker0: Gained carrier Sep 9 23:38:35.097530 sshd[1835]: Connection closed by 10.0.0.1 port 52770 Sep 9 23:38:35.097826 sshd-session[1833]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:35.107508 systemd[1]: sshd@7-10.0.0.86:22-10.0.0.1:52770.service: Deactivated successfully. Sep 9 23:38:35.110682 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 23:38:35.111464 systemd-logind[1398]: Session 8 logged out. Waiting for processes to exit. Sep 9 23:38:35.113894 systemd[1]: Started sshd@8-10.0.0.86:22-10.0.0.1:52772.service - OpenSSH per-connection server daemon (10.0.0.1:52772). Sep 9 23:38:35.114340 systemd-logind[1398]: Removed session 8. Sep 9 23:38:35.167142 sshd[1908]: Accepted publickey for core from 10.0.0.1 port 52772 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:35.168865 sshd-session[1908]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:35.174160 systemd-logind[1398]: New session 9 of user core. Sep 9 23:38:35.184570 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 9 23:38:35.240360 sshd[1910]: Connection closed by 10.0.0.1 port 52772 Sep 9 23:38:35.240855 sshd-session[1908]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:35.261078 systemd[1]: sshd@8-10.0.0.86:22-10.0.0.1:52772.service: Deactivated successfully. Sep 9 23:38:35.264904 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 23:38:35.265631 systemd-logind[1398]: Session 9 logged out. Waiting for processes to exit. Sep 9 23:38:35.268044 systemd[1]: Started sshd@9-10.0.0.86:22-10.0.0.1:52784.service - OpenSSH per-connection server daemon (10.0.0.1:52784). Sep 9 23:38:35.269024 systemd-logind[1398]: Removed session 9. Sep 9 23:38:35.326458 sshd[1916]: Accepted publickey for core from 10.0.0.1 port 52784 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:35.327740 sshd-session[1916]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:35.331689 systemd-logind[1398]: New session 10 of user core. Sep 9 23:38:35.343600 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 9 23:38:35.394233 sudo[1919]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1449 Sep 9 23:38:35.394976 sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:38:35.398390 sudo[1919]: pam_unix(sudo:session): session closed for user root Sep 9 23:38:35.398591 containerd[1449]: time="2025-09-09T23:38:35.398515738Z" level=info msg="Stop CRI service" Sep 9 23:38:35.398790 dockerd[1627]: time="2025-09-09T23:38:35.398734937Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 9 23:38:35.398790 dockerd[1627]: time="2025-09-09T23:38:35.398770346Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 9 23:38:35.399835 dockerd[1627]: time="2025-09-09T23:38:35.398765037Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 9 23:38:35.399835 dockerd[1627]: time="2025-09-09T23:38:35.398871942Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 9 23:38:35.399920 containerd[1449]: time="2025-09-09T23:38:35.399051023Z" level=info msg="Stop CRI service" Sep 9 23:38:35.399920 containerd[1449]: time="2025-09-09T23:38:35.399114136Z" level=info msg="Event monitor stopped" Sep 9 23:38:35.399920 containerd[1449]: time="2025-09-09T23:38:35.399121521Z" level=info msg="Stream server stopped" Sep 9 23:38:35.400234 sshd[1918]: Connection closed by 10.0.0.1 port 52784 Sep 9 23:38:35.400734 sshd-session[1916]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:35.419638 systemd[1]: containerd.service: Deactivated successfully. Sep 9 23:38:35.419735 systemd[1]: containerd.service: Unit process 1858 (containerd-shim) remains running after unit stopped. Sep 9 23:38:35.419951 systemd[1]: containerd.service: Consumed 309ms CPU time, 134.8M memory peak. Sep 9 23:38:35.420311 systemd[1]: sshd@9-10.0.0.86:22-10.0.0.1:52784.service: Deactivated successfully. Sep 9 23:38:35.423829 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 23:38:35.425196 systemd-logind[1398]: Session 10 logged out. Waiting for processes to exit. Sep 9 23:38:35.427343 systemd-logind[1398]: Removed session 10. Sep 9 23:38:35.429349 systemd[1]: Started sshd@10-10.0.0.86:22-10.0.0.1:52786.service - OpenSSH per-connection server daemon (10.0.0.1:52786). Sep 9 23:38:35.482949 sshd[1926]: Accepted publickey for core from 10.0.0.1 port 52786 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:35.484772 sshd-session[1926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:35.491149 systemd-logind[1398]: New session 11 of user core. Sep 9 23:38:35.507580 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 9 23:38:35.564015 sshd[1929]: Connection closed by 10.0.0.1 port 52786 Sep 9 23:38:35.564098 sshd-session[1926]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:35.569102 systemd[1]: sshd@10-10.0.0.86:22-10.0.0.1:52786.service: Deactivated successfully. Sep 9 23:38:35.571783 systemd[1]: session-11.scope: Deactivated successfully. Sep 9 23:38:35.572610 systemd-logind[1398]: Session 11 logged out. Waiting for processes to exit. Sep 9 23:38:35.574032 systemd-logind[1398]: Removed session 11. Sep 9 23:38:36.562631 systemd-networkd[1381]: docker0: Gained IPv6LL Sep 9 23:38:36.690642 systemd-networkd[1381]: veth70ed885: Gained IPv6LL Sep 9 23:38:40.654753 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Sep 9 23:38:40.655091 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 9 23:38:40.656026 dockerd[1627]: time="2025-09-09T23:38:40.655264268Z" level=info msg="Processing signal 'terminated'" Sep 9 23:38:41.582699 systemd[1]: Started sshd@11-10.0.0.86:22-10.0.0.1:47634.service - OpenSSH per-connection server daemon (10.0.0.1:47634). Sep 9 23:38:41.644620 sshd[1935]: Accepted publickey for core from 10.0.0.1 port 47634 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:41.649067 sshd-session[1935]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:41.656295 systemd-logind[1398]: New session 12 of user core. Sep 9 23:38:41.661706 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 9 23:38:41.727617 sshd[1937]: Connection closed by 10.0.0.1 port 47634 Sep 9 23:38:41.727216 sshd-session[1935]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:41.732035 systemd[1]: sshd@11-10.0.0.86:22-10.0.0.1:47634.service: Deactivated successfully. Sep 9 23:38:41.733749 systemd[1]: session-12.scope: Deactivated successfully. Sep 9 23:38:41.734836 systemd-logind[1398]: Session 12 logged out. Waiting for processes to exit. Sep 9 23:38:41.736758 systemd-logind[1398]: Removed session 12. Sep 9 23:38:42.656182 dockerd[1627]: time="2025-09-09T23:38:42.656099070Z" level=error msg="Error sending stop (signal 15) to container" container=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f error="Cannot kill container 8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" Sep 9 23:38:42.656182 dockerd[1627]: time="2025-09-09T23:38:42.656154159Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f Sep 9 23:38:47.735968 systemd[1]: Started sshd@12-10.0.0.86:22-10.0.0.1:47646.service - OpenSSH per-connection server daemon (10.0.0.1:47646). Sep 9 23:38:47.785763 sshd[1943]: Accepted publickey for core from 10.0.0.1 port 47646 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:47.787980 sshd-session[1943]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:47.792428 systemd-logind[1398]: New session 13 of user core. Sep 9 23:38:47.802570 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 9 23:38:47.860634 sshd[1945]: Connection closed by 10.0.0.1 port 47646 Sep 9 23:38:47.861105 sshd-session[1943]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:47.865326 systemd[1]: sshd@12-10.0.0.86:22-10.0.0.1:47646.service: Deactivated successfully. Sep 9 23:38:47.869602 systemd[1]: session-13.scope: Deactivated successfully. Sep 9 23:38:47.870602 systemd-logind[1398]: Session 13 logged out. Waiting for processes to exit. Sep 9 23:38:47.873257 systemd-logind[1398]: Removed session 13. Sep 9 23:38:52.656926 dockerd[1627]: time="2025-09-09T23:38:52.656868197Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f error="context deadline exceeded" Sep 9 23:38:52.657639 systemd[1]: docker-8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f.scope: Deactivated successfully. Sep 9 23:38:53.875426 systemd[1]: Started sshd@13-10.0.0.86:22-10.0.0.1:44494.service - OpenSSH per-connection server daemon (10.0.0.1:44494). Sep 9 23:38:53.950504 sshd[1951]: Accepted publickey for core from 10.0.0.1 port 44494 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:38:53.951155 sshd-session[1951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:38:53.955139 systemd-logind[1398]: New session 14 of user core. Sep 9 23:38:53.964584 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 9 23:38:54.018993 sshd[1953]: Connection closed by 10.0.0.1 port 44494 Sep 9 23:38:54.019309 sshd-session[1951]: pam_unix(sshd:session): session closed for user core Sep 9 23:38:54.022697 systemd[1]: sshd@13-10.0.0.86:22-10.0.0.1:44494.service: Deactivated successfully. Sep 9 23:38:54.025255 systemd[1]: session-14.scope: Deactivated successfully. Sep 9 23:38:54.026956 systemd-logind[1398]: Session 14 logged out. Waiting for processes to exit. Sep 9 23:38:54.029149 systemd-logind[1398]: Removed session 14. Sep 9 23:38:55.656151 dockerd[1627]: time="2025-09-09T23:38:55.656071605Z" level=error msg="Force shutdown daemon" Sep 9 23:38:55.660948 dockerd[1627]: time="2025-09-09T23:38:55.656248148Z" level=info msg="Daemon shutdown complete" Sep 9 23:38:55.658090 systemd[1]: docker.service: Deactivated successfully. Sep 9 23:38:55.658389 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 9 23:38:55.658459 systemd[1]: docker.service: Consumed 865ms CPU time, 129.6M memory peak. Sep 9 23:38:55.658819 systemd[1]: docker.socket: Deactivated successfully. Sep 9 23:38:55.660435 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 9 23:38:55.660484 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 9 23:38:55.661886 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 23:38:55.662046 systemd[1]: containerd.service: Found left-over process 1858 (containerd-shim) in control group while starting unit. Ignoring. Sep 9 23:38:55.662049 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 9 23:38:55.670671 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 23:38:55.670950 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 23:38:55.687852 systemd[1]: containerd.service: Found left-over process 1858 (containerd-shim) in control group while starting unit. Ignoring. Sep 9 23:38:55.687863 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 9 23:38:55.721871 (ntainerd)[1964]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 23:38:55.746450 containerd[1964]: time="2025-09-09T23:38:55Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 9 23:38:55.747307 containerd[1964]: time="2025-09-09T23:38:55.747264981Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 9 23:38:55.756108 containerd[1964]: time="2025-09-09T23:38:55.756055932Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.515µs" Sep 9 23:38:55.756108 containerd[1964]: time="2025-09-09T23:38:55.756089953Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 23:38:55.756108 containerd[1964]: time="2025-09-09T23:38:55.756109742Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 23:38:55.756220 containerd[1964]: time="2025-09-09T23:38:55.756147082Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 23:38:55.756220 containerd[1964]: time="2025-09-09T23:38:55.756159035Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 23:38:55.756388 containerd[1964]: time="2025-09-09T23:38:55.756344413Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756427 containerd[1964]: time="2025-09-09T23:38:55.756397983Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756427 containerd[1964]: time="2025-09-09T23:38:55.756411096Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756657 containerd[1964]: time="2025-09-09T23:38:55.756624738Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756657 containerd[1964]: time="2025-09-09T23:38:55.756645327Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756657 containerd[1964]: time="2025-09-09T23:38:55.756656840Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756724 containerd[1964]: time="2025-09-09T23:38:55.756664836Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756724 containerd[1964]: time="2025-09-09T23:38:55.756684545Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756790 containerd[1964]: time="2025-09-09T23:38:55.756770538Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756814 containerd[1964]: time="2025-09-09T23:38:55.756797243Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:38:55.756814 containerd[1964]: time="2025-09-09T23:38:55.756807477Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 23:38:55.756848 containerd[1964]: time="2025-09-09T23:38:55.756839739Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 23:38:55.757092 containerd[1964]: time="2025-09-09T23:38:55.757068333Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 23:38:55.757172 containerd[1964]: time="2025-09-09T23:38:55.757092720Z" level=info msg="metadata content store policy set" policy=shared Sep 9 23:38:55.757231 containerd[1964]: time="2025-09-09T23:38:55.757212574Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 23:38:55.757262 containerd[1964]: time="2025-09-09T23:38:55.757245915Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 23:38:55.757290 containerd[1964]: time="2025-09-09T23:38:55.757261107Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 23:38:55.757290 containerd[1964]: time="2025-09-09T23:38:55.757276858Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 23:38:55.757290 containerd[1964]: time="2025-09-09T23:38:55.757288812Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 23:38:55.757344 containerd[1964]: time="2025-09-09T23:38:55.757300725Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 23:38:55.757344 containerd[1964]: time="2025-09-09T23:38:55.757311679Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 23:38:55.757344 containerd[1964]: time="2025-09-09T23:38:55.757323593Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 23:38:55.757344 containerd[1964]: time="2025-09-09T23:38:55.757333707Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 23:38:55.757451 containerd[1964]: time="2025-09-09T23:38:55.757344821Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 23:38:55.757451 containerd[1964]: time="2025-09-09T23:38:55.757354775Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 23:38:55.757451 containerd[1964]: time="2025-09-09T23:38:55.757367448Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 23:38:55.757741 containerd[1964]: time="2025-09-09T23:38:55.757591005Z" level=info msg="connecting to shim 8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f" address="unix:///run/containerd/s/c2b55ed7e66b36fcbf575789ab8ee64a1a64293d67eaa8cb33f311e2b6f25b00" namespace=moby protocol=ttrpc version=3 Sep 9 23:38:55.768474 containerd[1964]: time="2025-09-09T23:38:55.768355907Z" level=info msg="cleaning leaked shim process" id=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f namespace=moby Sep 9 23:38:55.778657 containerd[1964]: time="2025-09-09T23:38:55.778564436Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Sep 9 23:38:55.779605 containerd[1964]: time="2025-09-09T23:38:55.779528264Z" level=info msg="shim disconnected" id=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f namespace=moby Sep 9 23:38:55.779868 containerd[1964]: time="2025-09-09T23:38:55.779724356Z" level=warning msg="cleaning up after shim disconnected" id=8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f namespace=moby Sep 9 23:38:55.779868 containerd[1964]: time="2025-09-09T23:38:55.779816745Z" level=info msg="cleaning up dead shim" namespace=moby Sep 9 23:38:55.792312 containerd[1964]: time="2025-09-09T23:38:55.792125155Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Sep 9 23:38:55.792499 containerd[1964]: time="2025-09-09T23:38:55.792293462Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 23:38:55.792625 containerd[1964]: time="2025-09-09T23:38:55.792606330Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 23:38:55.792690 containerd[1964]: time="2025-09-09T23:38:55.792677291Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 23:38:55.792738 containerd[1964]: time="2025-09-09T23:38:55.792727623Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 23:38:55.792789 containerd[1964]: time="2025-09-09T23:38:55.792778115Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 23:38:55.792849 containerd[1964]: time="2025-09-09T23:38:55.792835683Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 23:38:55.792907 containerd[1964]: time="2025-09-09T23:38:55.792893811Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 23:38:55.792963 containerd[1964]: time="2025-09-09T23:38:55.792949660Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 23:38:55.793031 containerd[1964]: time="2025-09-09T23:38:55.793016903Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 23:38:55.793088 containerd[1964]: time="2025-09-09T23:38:55.793076790Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 23:38:55.793139 containerd[1964]: time="2025-09-09T23:38:55.793126203Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 23:38:55.793291 containerd[1964]: time="2025-09-09T23:38:55.793271563Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 23:38:55.793351 containerd[1964]: time="2025-09-09T23:38:55.793340005Z" level=info msg="Start snapshots syncer" Sep 9 23:38:55.793596 containerd[1964]: time="2025-09-09T23:38:55.793562083Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 23:38:55.793879 containerd[1964]: time="2025-09-09T23:38:55.793814663Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 9 23:38:55.793985 containerd[1964]: time="2025-09-09T23:38:55.793883305Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 23:38:55.793985 containerd[1964]: time="2025-09-09T23:38:55.793966579Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 23:38:55.794059 containerd[1964]: time="2025-09-09T23:38:55.794018511Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 23:38:55.794059 containerd[1964]: time="2025-09-09T23:38:55.794046096Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 23:38:55.794059 containerd[1964]: time="2025-09-09T23:38:55.794059248Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 23:38:55.794129 containerd[1964]: time="2025-09-09T23:38:55.794071242Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 23:38:55.794129 containerd[1964]: time="2025-09-09T23:38:55.794084994Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 23:38:55.794129 containerd[1964]: time="2025-09-09T23:38:55.794097107Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 23:38:55.794129 containerd[1964]: time="2025-09-09T23:38:55.794107822Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 23:38:55.794196 containerd[1964]: time="2025-09-09T23:38:55.794140084Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 23:38:55.794196 containerd[1964]: time="2025-09-09T23:38:55.794152237Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 23:38:55.794196 containerd[1964]: time="2025-09-09T23:38:55.794164190Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 23:38:55.794247 containerd[1964]: time="2025-09-09T23:38:55.794211284Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:38:55.794247 containerd[1964]: time="2025-09-09T23:38:55.794227076Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:38:55.794247 containerd[1964]: time="2025-09-09T23:38:55.794235951Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:38:55.794247 containerd[1964]: time="2025-09-09T23:38:55.794244746Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794252462Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794261497Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794271691Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794286923Z" level=info msg="runtime interface created" Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794291840Z" level=info msg="created NRI interface" Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794299516Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 23:38:55.794336 containerd[1964]: time="2025-09-09T23:38:55.794309630Z" level=info msg="Connect containerd service" Sep 9 23:38:55.794563 containerd[1964]: time="2025-09-09T23:38:55.794341733Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 23:38:55.794958 containerd[1964]: time="2025-09-09T23:38:55.794916535Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805242439Z" level=info msg="Start subscribing containerd event" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805320596Z" level=info msg="Start recovering state" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805405989Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805439931Z" level=info msg="Start event monitor" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805457641Z" level=info msg="Start cni network conf syncer for default" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805467875Z" level=info msg="Start streaming server" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805475111Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805476670Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805525643Z" level=info msg="runtime interface starting up..." Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805532200Z" level=info msg="starting plugins..." Sep 9 23:38:55.805601 containerd[1964]: time="2025-09-09T23:38:55.805542594Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 23:38:55.805883 containerd[1964]: time="2025-09-09T23:38:55.805660369Z" level=info msg="containerd successfully booted in 0.059582s" Sep 9 23:38:55.805789 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 23:38:55.810635 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 23:38:55.826036 (dockerd)[2011]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 9 23:38:55.850816 dockerd[2011]: time="2025-09-09T23:38:55.850749857Z" level=info msg="Starting up" Sep 9 23:38:55.851607 dockerd[2011]: time="2025-09-09T23:38:55.851583877Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 9 23:38:55.873932 systemd[1]: var-lib-docker-overlay2-check\x2doverlayfs\x2dsupport157529095-merged.mount: Deactivated successfully. Sep 9 23:38:55.887960 dockerd[2011]: time="2025-09-09T23:38:55.887748767Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 9 23:38:55.895955 dockerd[2011]: time="2025-09-09T23:38:55.895883400Z" level=info msg="Loading containers: start." Sep 9 23:38:55.909109 systemd[1]: var-lib-docker-overlay2-05019c2b6fc8fc6ab41f1f9eaef9d4d94d32c28adf9273d7717a14efb5b70441-merged.mount: Deactivated successfully. Sep 9 23:38:56.276043 systemd-networkd[1381]: veth70ed885: Link DOWN Sep 9 23:38:56.276050 systemd-networkd[1381]: veth70ed885: Lost carrier Sep 9 23:38:56.277401 kernel: docker0: port 1(veth70ed885) entered disabled state Sep 9 23:38:56.280006 kernel: veth70ed885 (unregistering): left allmulticast mode Sep 9 23:38:56.280102 kernel: veth70ed885 (unregistering): left promiscuous mode Sep 9 23:38:56.280119 kernel: docker0: port 1(veth70ed885) entered disabled state Sep 9 23:38:56.281439 systemd-networkd[1381]: docker0: Lost carrier Sep 9 23:38:56.282054 dockerd[2011]: time="2025-09-09T23:38:56.282019114Z" level=info msg="Removing stale sandbox f211de357e2592a08b6cebab77a6003be1e8262b397f2309ac66f6ad09fe94d6 (8c353d6b6f24354fd57152cb4d561c74f9fd6f77e69b4fdb1b708bdf062b282f)" Sep 9 23:38:56.285819 dockerd[2011]: time="2025-09-09T23:38:56.285540973Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 9 23:38:56.285819 dockerd[2011]: time="2025-09-09T23:38:56.285592306Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint b8fc8a04c97461a16b37a827a5b8d5fc53112bab138e911c5019840fc26eee36 5e5f9100f00aa264d7911c20b93bb6d89e92b0ecb00f829c101e2a0e04a2e9ac], retrying...." Sep 9 23:38:56.422265 dockerd[2011]: time="2025-09-09T23:38:56.422216214Z" level=warning msg="error locating sandbox id f211de357e2592a08b6cebab77a6003be1e8262b397f2309ac66f6ad09fe94d6: sandbox f211de357e2592a08b6cebab77a6003be1e8262b397f2309ac66f6ad09fe94d6 not found" Sep 9 23:38:56.422556 dockerd[2011]: time="2025-09-09T23:38:56.422508303Z" level=info msg="Loading containers: done." Sep 9 23:38:56.435191 dockerd[2011]: time="2025-09-09T23:38:56.435145528Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 9 23:38:56.436216 dockerd[2011]: time="2025-09-09T23:38:56.435456008Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Sep 9 23:38:56.436216 dockerd[2011]: time="2025-09-09T23:38:56.435504982Z" level=info msg="Initializing buildkit" Sep 9 23:38:56.449008 dockerd[2011]: time="2025-09-09T23:38:56.448965541Z" level=info msg="Completed buildkit initialization" Sep 9 23:38:56.455327 dockerd[2011]: time="2025-09-09T23:38:56.455286193Z" level=info msg="Daemon has completed initialization" Sep 9 23:38:56.455516 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 23:38:56.456534 dockerd[2011]: time="2025-09-09T23:38:56.456487132Z" level=info msg="API listen on /run/docker.sock" Sep 9 23:38:56.870235 systemd[1]: run-docker-netns-f211de357e25.mount: Deactivated successfully. Sep 9 23:39:00.032798 systemd[1]: Started sshd@14-10.0.0.86:22-10.0.0.1:54058.service - OpenSSH per-connection server daemon (10.0.0.1:54058). Sep 9 23:39:00.095156 sshd[2233]: Accepted publickey for core from 10.0.0.1 port 54058 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:00.096456 sshd-session[2233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:00.101374 systemd-logind[1398]: New session 15 of user core. Sep 9 23:39:00.118602 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 9 23:39:00.175526 sshd[2235]: Connection closed by 10.0.0.1 port 54058 Sep 9 23:39:00.176093 sshd-session[2233]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:00.187573 systemd[1]: sshd@14-10.0.0.86:22-10.0.0.1:54058.service: Deactivated successfully. Sep 9 23:39:00.189154 systemd[1]: session-15.scope: Deactivated successfully. Sep 9 23:39:00.193277 systemd-logind[1398]: Session 15 logged out. Waiting for processes to exit. Sep 9 23:39:00.195304 systemd-logind[1398]: Removed session 15. Sep 9 23:39:00.196968 systemd[1]: Started sshd@15-10.0.0.86:22-10.0.0.1:54072.service - OpenSSH per-connection server daemon (10.0.0.1:54072). Sep 9 23:39:00.261166 sshd[2241]: Accepted publickey for core from 10.0.0.1 port 54072 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:00.262905 sshd-session[2241]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:00.268696 systemd-logind[1398]: New session 16 of user core. Sep 9 23:39:00.281739 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 9 23:39:00.346752 sshd[2243]: Connection closed by 10.0.0.1 port 54072 Sep 9 23:39:00.348098 sshd-session[2241]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:00.355729 systemd[1]: sshd@15-10.0.0.86:22-10.0.0.1:54072.service: Deactivated successfully. Sep 9 23:39:00.357516 systemd[1]: session-16.scope: Deactivated successfully. Sep 9 23:39:00.358470 systemd-logind[1398]: Session 16 logged out. Waiting for processes to exit. Sep 9 23:39:00.363002 systemd[1]: Started sshd@16-10.0.0.86:22-10.0.0.1:54084.service - OpenSSH per-connection server daemon (10.0.0.1:54084). Sep 9 23:39:00.363845 systemd-logind[1398]: Removed session 16. Sep 9 23:39:00.416931 sshd[2249]: Accepted publickey for core from 10.0.0.1 port 54084 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:00.418909 sshd-session[2249]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:00.423826 systemd-logind[1398]: New session 17 of user core. Sep 9 23:39:00.439609 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 9 23:39:00.499337 sshd[2251]: Connection closed by 10.0.0.1 port 54084 Sep 9 23:39:00.499823 sshd-session[2249]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:00.514549 systemd[1]: sshd@16-10.0.0.86:22-10.0.0.1:54084.service: Deactivated successfully. Sep 9 23:39:00.516123 systemd[1]: session-17.scope: Deactivated successfully. Sep 9 23:39:00.517970 systemd-logind[1398]: Session 17 logged out. Waiting for processes to exit. Sep 9 23:39:00.521567 systemd[1]: Started sshd@17-10.0.0.86:22-10.0.0.1:54094.service - OpenSSH per-connection server daemon (10.0.0.1:54094). Sep 9 23:39:00.522423 systemd-logind[1398]: Removed session 17. Sep 9 23:39:00.598556 sshd[2264]: Accepted publickey for core from 10.0.0.1 port 54094 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:00.599883 sshd-session[2264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:00.606030 systemd-logind[1398]: New session 18 of user core. Sep 9 23:39:00.621609 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 9 23:39:00.702396 systemd[1]: var-lib-docker-overlay2-352a3ea340fea4d7cfd4367626332aaa0367b4bc39ac8996137557e4f3b30f01\x2dinit-merged.mount: Deactivated successfully. Sep 9 23:39:00.756982 containerd[1964]: time="2025-09-09T23:39:00.756283864Z" level=info msg="connecting to shim 1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f" address="unix:///run/containerd/s/7487d8aea0ce16b5fefef439523adb5e330f69033a38205bd03689dcbada7e07" namespace=moby protocol=ttrpc version=3 Sep 9 23:39:00.782609 systemd[1]: Started docker-1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f.scope - libcontainer container 1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f. Sep 9 23:39:00.795644 systemd-resolved[1294]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:39:00.818995 kernel: docker0: port 1(vetha76d319) entered blocking state Sep 9 23:39:00.819085 kernel: docker0: port 1(vetha76d319) entered disabled state Sep 9 23:39:00.819102 kernel: vetha76d319: entered allmulticast mode Sep 9 23:39:00.819126 kernel: vetha76d319: entered promiscuous mode Sep 9 23:39:00.820731 systemd-networkd[1381]: vetha76d319: Link UP Sep 9 23:39:00.834420 kernel: eth0: renamed from vethb9f761c Sep 9 23:39:00.837721 kernel: docker0: port 1(vetha76d319) entered blocking state Sep 9 23:39:00.837798 kernel: docker0: port 1(vetha76d319) entered forwarding state Sep 9 23:39:00.837170 systemd-networkd[1381]: vetha76d319: Gained carrier Sep 9 23:39:00.838484 systemd-networkd[1381]: docker0: Gained carrier Sep 9 23:39:00.864065 sshd[2266]: Connection closed by 10.0.0.1 port 54094 Sep 9 23:39:00.864434 sshd-session[2264]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:00.874934 systemd[1]: sshd@17-10.0.0.86:22-10.0.0.1:54094.service: Deactivated successfully. Sep 9 23:39:00.877987 systemd[1]: session-18.scope: Deactivated successfully. Sep 9 23:39:00.878785 systemd-logind[1398]: Session 18 logged out. Waiting for processes to exit. Sep 9 23:39:00.882397 systemd[1]: Started sshd@18-10.0.0.86:22-10.0.0.1:54108.service - OpenSSH per-connection server daemon (10.0.0.1:54108). Sep 9 23:39:00.883488 systemd-logind[1398]: Removed session 18. Sep 9 23:39:00.926788 sshd[2336]: Accepted publickey for core from 10.0.0.1 port 54108 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:00.928320 sshd-session[2336]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:00.933460 systemd-logind[1398]: New session 19 of user core. Sep 9 23:39:00.943629 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 9 23:39:01.007306 sshd[2338]: Connection closed by 10.0.0.1 port 54108 Sep 9 23:39:01.008427 sshd-session[2336]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:01.021757 systemd[1]: sshd@18-10.0.0.86:22-10.0.0.1:54108.service: Deactivated successfully. Sep 9 23:39:01.025700 systemd[1]: session-19.scope: Deactivated successfully. Sep 9 23:39:01.026330 systemd-logind[1398]: Session 19 logged out. Waiting for processes to exit. Sep 9 23:39:01.029287 systemd-logind[1398]: Removed session 19. Sep 9 23:39:01.031886 systemd[1]: Started sshd@19-10.0.0.86:22-10.0.0.1:54124.service - OpenSSH per-connection server daemon (10.0.0.1:54124). Sep 9 23:39:01.100420 sshd[2344]: Accepted publickey for core from 10.0.0.1 port 54124 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:01.102469 sshd-session[2344]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:01.112585 systemd-logind[1398]: New session 20 of user core. Sep 9 23:39:01.122609 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 9 23:39:01.176298 sudo[2347]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1964 Sep 9 23:39:01.176644 sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:39:01.180098 sudo[2347]: pam_unix(sudo:session): session closed for user root Sep 9 23:39:01.182688 dockerd[2011]: time="2025-09-09T23:39:01.182484012Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 9 23:39:01.182688 dockerd[2011]: time="2025-09-09T23:39:01.182528796Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 9 23:39:01.183403 dockerd[2011]: time="2025-09-09T23:39:01.183188349Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 9 23:39:01.183403 dockerd[2011]: time="2025-09-09T23:39:01.183222896Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 9 23:39:01.183260 systemd[1]: containerd.service: Deactivated successfully. Sep 9 23:39:01.183458 systemd[1]: containerd.service: Unit process 2286 (containerd-shim) remains running after unit stopped. Sep 9 23:39:01.183739 systemd[1]: containerd.service: Consumed 174ms CPU time, 136.5M memory peak. Sep 9 23:39:01.184451 sshd[2346]: Connection closed by 10.0.0.1 port 54124 Sep 9 23:39:01.185004 sshd-session[2344]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:01.193441 systemd[1]: sshd@19-10.0.0.86:22-10.0.0.1:54124.service: Deactivated successfully. Sep 9 23:39:01.196073 systemd[1]: session-20.scope: Deactivated successfully. Sep 9 23:39:01.199155 systemd-logind[1398]: Session 20 logged out. Waiting for processes to exit. Sep 9 23:39:01.200732 systemd[1]: Started sshd@20-10.0.0.86:22-10.0.0.1:54128.service - OpenSSH per-connection server daemon (10.0.0.1:54128). Sep 9 23:39:01.202722 systemd-logind[1398]: Removed session 20. Sep 9 23:39:01.253538 sshd[2354]: Accepted publickey for core from 10.0.0.1 port 54128 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:01.255072 sshd-session[2354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:01.263439 systemd-logind[1398]: New session 21 of user core. Sep 9 23:39:01.278594 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 9 23:39:01.335488 sshd[2356]: Connection closed by 10.0.0.1 port 54128 Sep 9 23:39:01.335841 sshd-session[2354]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:01.340305 systemd[1]: sshd@20-10.0.0.86:22-10.0.0.1:54128.service: Deactivated successfully. Sep 9 23:39:01.343596 systemd[1]: session-21.scope: Deactivated successfully. Sep 9 23:39:01.344479 systemd-logind[1398]: Session 21 logged out. Waiting for processes to exit. Sep 9 23:39:01.345764 systemd-logind[1398]: Removed session 21. Sep 9 23:39:02.482759 systemd-networkd[1381]: vetha76d319: Gained IPv6LL Sep 9 23:39:06.404844 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Sep 9 23:39:06.405526 dockerd[2011]: time="2025-09-09T23:39:06.405355955Z" level=info msg="Processing signal 'terminated'" Sep 9 23:39:06.405731 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 9 23:39:07.350241 systemd[1]: Started sshd@21-10.0.0.86:22-10.0.0.1:54144.service - OpenSSH per-connection server daemon (10.0.0.1:54144). Sep 9 23:39:07.412787 sshd[2362]: Accepted publickey for core from 10.0.0.1 port 54144 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:07.414500 sshd-session[2362]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:07.419552 systemd-logind[1398]: New session 22 of user core. Sep 9 23:39:07.436687 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 9 23:39:07.492654 sshd[2364]: Connection closed by 10.0.0.1 port 54144 Sep 9 23:39:07.493186 sshd-session[2362]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:07.497249 systemd[1]: sshd@21-10.0.0.86:22-10.0.0.1:54144.service: Deactivated successfully. Sep 9 23:39:07.500028 systemd[1]: session-22.scope: Deactivated successfully. Sep 9 23:39:07.501067 systemd-logind[1398]: Session 22 logged out. Waiting for processes to exit. Sep 9 23:39:07.504266 systemd-logind[1398]: Removed session 22. Sep 9 23:39:08.407388 dockerd[2011]: time="2025-09-09T23:39:08.407324248Z" level=error msg="Error sending stop (signal 15) to container" container=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f error="Cannot kill container 1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Sep 9 23:39:08.407791 dockerd[2011]: time="2025-09-09T23:39:08.407408028Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f Sep 9 23:39:09.436889 update_engine[1402]: I20250909 23:39:09.436355 1402 update_attempter.cc:509] Updating boot flags... Sep 9 23:39:13.509720 systemd[1]: Started sshd@22-10.0.0.86:22-10.0.0.1:47172.service - OpenSSH per-connection server daemon (10.0.0.1:47172). Sep 9 23:39:13.573100 sshd[2386]: Accepted publickey for core from 10.0.0.1 port 47172 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:13.574965 sshd-session[2386]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:13.580480 systemd-logind[1398]: New session 23 of user core. Sep 9 23:39:13.586630 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 9 23:39:13.648202 sshd[2388]: Connection closed by 10.0.0.1 port 47172 Sep 9 23:39:13.648579 sshd-session[2386]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:13.653471 systemd-logind[1398]: Session 23 logged out. Waiting for processes to exit. Sep 9 23:39:13.653734 systemd[1]: sshd@22-10.0.0.86:22-10.0.0.1:47172.service: Deactivated successfully. Sep 9 23:39:13.655576 systemd[1]: session-23.scope: Deactivated successfully. Sep 9 23:39:13.658186 systemd-logind[1398]: Removed session 23. Sep 9 23:39:18.409311 dockerd[2011]: time="2025-09-09T23:39:18.409212423Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f error="context deadline exceeded" Sep 9 23:39:18.410021 systemd[1]: docker-1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f.scope: Deactivated successfully. Sep 9 23:39:19.672035 systemd[1]: Started sshd@23-10.0.0.86:22-10.0.0.1:47188.service - OpenSSH per-connection server daemon (10.0.0.1:47188). Sep 9 23:39:19.718003 sshd[2394]: Accepted publickey for core from 10.0.0.1 port 47188 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:19.720856 sshd-session[2394]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:19.725739 systemd-logind[1398]: New session 24 of user core. Sep 9 23:39:19.736601 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 9 23:39:19.794398 sshd[2396]: Connection closed by 10.0.0.1 port 47188 Sep 9 23:39:19.794907 sshd-session[2394]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:19.798227 systemd[1]: sshd@23-10.0.0.86:22-10.0.0.1:47188.service: Deactivated successfully. Sep 9 23:39:19.800063 systemd[1]: session-24.scope: Deactivated successfully. Sep 9 23:39:19.802427 systemd-logind[1398]: Session 24 logged out. Waiting for processes to exit. Sep 9 23:39:19.804346 systemd-logind[1398]: Removed session 24. Sep 9 23:39:21.405881 dockerd[2011]: time="2025-09-09T23:39:21.405756926Z" level=error msg="Force shutdown daemon" Sep 9 23:39:21.406426 dockerd[2011]: time="2025-09-09T23:39:21.406278232Z" level=info msg="Daemon shutdown complete" Sep 9 23:39:21.411086 systemd[1]: docker.service: Deactivated successfully. Sep 9 23:39:21.411474 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 9 23:39:21.411989 systemd[1]: docker.socket: Deactivated successfully. Sep 9 23:39:21.413429 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 9 23:39:21.413476 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 9 23:39:21.415022 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 23:39:21.415196 systemd[1]: containerd.service: Found left-over process 2286 (containerd-shim) in control group while starting unit. Ignoring. Sep 9 23:39:21.415202 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 9 23:39:21.420903 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 23:39:21.421514 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 23:39:21.432538 systemd[1]: containerd.service: Found left-over process 2286 (containerd-shim) in control group while starting unit. Ignoring. Sep 9 23:39:21.432542 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 9 23:39:21.437111 (ntainerd)[2407]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 23:39:21.451088 containerd[2407]: time="2025-09-09T23:39:21Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 9 23:39:21.451678 containerd[2407]: time="2025-09-09T23:39:21.451623483Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460225837Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.599µs" Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460269273Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460289551Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460336866Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460350305Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460564562Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460590640Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:39:21.460720 containerd[2407]: time="2025-09-09T23:39:21.460601039Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:39:21.464811 containerd[2407]: time="2025-09-09T23:39:21.461203857Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:39:21.464811 containerd[2407]: time="2025-09-09T23:39:21.464759890Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.464985947Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465004345Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465035782Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465150970Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465174368Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465184047Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465217203Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465483536Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465509293Z" level=info msg="metadata content store policy set" policy=shared Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465640080Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465684755Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465708673Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 23:39:21.466550 containerd[2407]: time="2025-09-09T23:39:21.465721152Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465732830Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465745149Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465757548Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465769507Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465780185Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465790464Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465800703Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.465813702Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 23:39:21.466873 containerd[2407]: time="2025-09-09T23:39:21.466033759Z" level=info msg="connecting to shim 1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f" address="unix:///run/containerd/s/7487d8aea0ce16b5fefef439523adb5e330f69033a38205bd03689dcbada7e07" namespace=moby protocol=ttrpc version=3 Sep 9 23:39:21.474603 containerd[2407]: time="2025-09-09T23:39:21.474555842Z" level=info msg="cleaning leaked shim process" id=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f namespace=moby Sep 9 23:39:21.485516 containerd[2407]: time="2025-09-09T23:39:21.485372608Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Sep 9 23:39:21.486067 containerd[2407]: time="2025-09-09T23:39:21.485947669Z" level=info msg="shim disconnected" id=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f namespace=moby Sep 9 23:39:21.486067 containerd[2407]: time="2025-09-09T23:39:21.485976226Z" level=warning msg="cleaning up after shim disconnected" id=1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f namespace=moby Sep 9 23:39:21.486067 containerd[2407]: time="2025-09-09T23:39:21.486020821Z" level=info msg="cleaning up dead shim" namespace=moby Sep 9 23:39:21.499488 containerd[2407]: time="2025-09-09T23:39:21.499294175Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Sep 9 23:39:21.499488 containerd[2407]: time="2025-09-09T23:39:21.499446639Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499503073Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499527711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499540789Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499557347Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499572946Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499588624Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 23:39:21.499610 containerd[2407]: time="2025-09-09T23:39:21.499600143Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 23:39:21.499779 containerd[2407]: time="2025-09-09T23:39:21.499624581Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 23:39:21.499779 containerd[2407]: time="2025-09-09T23:39:21.499643619Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 23:39:21.499779 containerd[2407]: time="2025-09-09T23:39:21.499667056Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 23:39:21.499779 containerd[2407]: time="2025-09-09T23:39:21.499746728Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 23:39:21.499779 containerd[2407]: time="2025-09-09T23:39:21.499766766Z" level=info msg="Start snapshots syncer" Sep 9 23:39:21.499877 containerd[2407]: time="2025-09-09T23:39:21.499795643Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 23:39:21.500207 containerd[2407]: time="2025-09-09T23:39:21.500152886Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 9 23:39:21.500313 containerd[2407]: time="2025-09-09T23:39:21.500224279Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 23:39:21.500313 containerd[2407]: time="2025-09-09T23:39:21.500305630Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 23:39:21.500527 containerd[2407]: time="2025-09-09T23:39:21.500358385Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500748505Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500774342Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500785461Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500797900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500808099Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500819538Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500848375Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500860773Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500871572Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500907169Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500922287Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500930686Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500940085Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:39:21.501108 containerd[2407]: time="2025-09-09T23:39:21.500948084Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.500957843Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.500968842Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.500995879Z" level=info msg="runtime interface created" Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.501001399Z" level=info msg="created NRI interface" Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.501010358Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.501024196Z" level=info msg="Connect containerd service" Sep 9 23:39:21.501415 containerd[2407]: time="2025-09-09T23:39:21.501053113Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 23:39:21.501553 containerd[2407]: time="2025-09-09T23:39:21.501528265Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511090000Z" level=info msg="Start subscribing containerd event" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511178911Z" level=info msg="Start recovering state" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511253943Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511315537Z" level=info msg="Start event monitor" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511327416Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511335255Z" level=info msg="Start cni network conf syncer for default" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511345694Z" level=info msg="Start streaming server" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511355693Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511403368Z" level=info msg="runtime interface starting up..." Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511411047Z" level=info msg="starting plugins..." Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511421446Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 23:39:21.513364 containerd[2407]: time="2025-09-09T23:39:21.511651022Z" level=info msg="containerd successfully booted in 0.060755s" Sep 9 23:39:21.511813 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 23:39:21.514280 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 23:39:21.543887 (dockerd)[2455]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 9 23:39:21.572015 dockerd[2455]: time="2025-09-09T23:39:21.571955693Z" level=info msg="Starting up" Sep 9 23:39:21.572575 dockerd[2455]: time="2025-09-09T23:39:21.572549352Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 9 23:39:21.600044 dockerd[2455]: time="2025-09-09T23:39:21.599990286Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 9 23:39:21.608699 dockerd[2455]: time="2025-09-09T23:39:21.608602439Z" level=info msg="Loading containers: start." Sep 9 23:39:21.625301 systemd[1]: var-lib-docker-overlay2-352a3ea340fea4d7cfd4367626332aaa0367b4bc39ac8996137557e4f3b30f01-merged.mount: Deactivated successfully. Sep 9 23:39:22.010698 systemd-networkd[1381]: vetha76d319: Link DOWN Sep 9 23:39:22.010702 systemd-networkd[1381]: vetha76d319: Lost carrier Sep 9 23:39:22.013085 kernel: docker0: port 1(vetha76d319) entered disabled state Sep 9 23:39:22.013172 kernel: vetha76d319 (unregistering): left allmulticast mode Sep 9 23:39:22.014048 kernel: vetha76d319 (unregistering): left promiscuous mode Sep 9 23:39:22.014966 kernel: docker0: port 1(vetha76d319) entered disabled state Sep 9 23:39:22.015708 systemd-networkd[1381]: docker0: Lost carrier Sep 9 23:39:22.017413 dockerd[2455]: time="2025-09-09T23:39:22.016663487Z" level=info msg="Removing stale sandbox 60e78083e4d94e2cd1a6b1780d6125dc6acee14ae94e1d63d9164cacf771d955 (1c20c77a0005b10e77581d555ee59d75345235c0d0605f6dbfb3686e401fb27f)" Sep 9 23:39:22.021959 dockerd[2455]: time="2025-09-09T23:39:22.021906741Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 9 23:39:22.022075 dockerd[2455]: time="2025-09-09T23:39:22.021982454Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 94fa6af7b35c5d53b609d1522c98195ed078340f28d07b89add30a058be925be f5af2c70d49c01d8d257c0483751bb9a2806d575c8e169ccc6823135548f713b], retrying...." Sep 9 23:39:22.261227 dockerd[2455]: time="2025-09-09T23:39:22.261149007Z" level=warning msg="error locating sandbox id f211de357e2592a08b6cebab77a6003be1e8262b397f2309ac66f6ad09fe94d6: sandbox f211de357e2592a08b6cebab77a6003be1e8262b397f2309ac66f6ad09fe94d6 not found" Sep 9 23:39:22.261227 dockerd[2455]: time="2025-09-09T23:39:22.261200922Z" level=warning msg="error locating sandbox id 60e78083e4d94e2cd1a6b1780d6125dc6acee14ae94e1d63d9164cacf771d955: sandbox 60e78083e4d94e2cd1a6b1780d6125dc6acee14ae94e1d63d9164cacf771d955 not found" Sep 9 23:39:22.261405 dockerd[2455]: time="2025-09-09T23:39:22.261268875Z" level=info msg="Loading containers: done." Sep 9 23:39:22.291689 dockerd[2455]: time="2025-09-09T23:39:22.291530274Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 9 23:39:22.291847 dockerd[2455]: time="2025-09-09T23:39:22.291691019Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Sep 9 23:39:22.291847 dockerd[2455]: time="2025-09-09T23:39:22.291746853Z" level=info msg="Initializing buildkit" Sep 9 23:39:22.308896 dockerd[2455]: time="2025-09-09T23:39:22.308790368Z" level=info msg="Completed buildkit initialization" Sep 9 23:39:22.316775 dockerd[2455]: time="2025-09-09T23:39:22.316713003Z" level=info msg="Daemon has completed initialization" Sep 9 23:39:22.316986 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 23:39:22.317488 dockerd[2455]: time="2025-09-09T23:39:22.317433014Z" level=info msg="API listen on /run/docker.sock" Sep 9 23:39:22.590579 systemd[1]: run-docker-netns-60e78083e4d9.mount: Deactivated successfully. Sep 9 23:39:25.816743 systemd[1]: Started sshd@24-10.0.0.86:22-10.0.0.1:60686.service - OpenSSH per-connection server daemon (10.0.0.1:60686). Sep 9 23:39:25.858000 sshd[2676]: Accepted publickey for core from 10.0.0.1 port 60686 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:25.859476 sshd-session[2676]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:25.864720 systemd-logind[1398]: New session 25 of user core. Sep 9 23:39:25.879702 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 9 23:39:25.936142 sshd[2678]: Connection closed by 10.0.0.1 port 60686 Sep 9 23:39:25.936484 sshd-session[2676]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:25.945467 systemd[1]: sshd@24-10.0.0.86:22-10.0.0.1:60686.service: Deactivated successfully. Sep 9 23:39:25.947500 systemd[1]: session-25.scope: Deactivated successfully. Sep 9 23:39:25.949274 systemd-logind[1398]: Session 25 logged out. Waiting for processes to exit. Sep 9 23:39:25.952086 systemd[1]: Started sshd@25-10.0.0.86:22-10.0.0.1:60690.service - OpenSSH per-connection server daemon (10.0.0.1:60690). Sep 9 23:39:25.952840 systemd-logind[1398]: Removed session 25. Sep 9 23:39:26.012957 sshd[2684]: Accepted publickey for core from 10.0.0.1 port 60690 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:26.014753 sshd-session[2684]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:26.019214 systemd-logind[1398]: New session 26 of user core. Sep 9 23:39:26.031609 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 9 23:39:26.087501 sshd[2686]: Connection closed by 10.0.0.1 port 60690 Sep 9 23:39:26.089350 sshd-session[2684]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:26.099634 systemd[1]: sshd@25-10.0.0.86:22-10.0.0.1:60690.service: Deactivated successfully. Sep 9 23:39:26.101795 systemd[1]: session-26.scope: Deactivated successfully. Sep 9 23:39:26.102576 systemd-logind[1398]: Session 26 logged out. Waiting for processes to exit. Sep 9 23:39:26.105794 systemd[1]: Started sshd@26-10.0.0.86:22-10.0.0.1:60702.service - OpenSSH per-connection server daemon (10.0.0.1:60702). Sep 9 23:39:26.106675 systemd-logind[1398]: Removed session 26. Sep 9 23:39:26.174929 sshd[2692]: Accepted publickey for core from 10.0.0.1 port 60702 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:26.176304 sshd-session[2692]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:26.184755 systemd-logind[1398]: New session 27 of user core. Sep 9 23:39:26.194576 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 9 23:39:26.264954 sshd[2694]: Connection closed by 10.0.0.1 port 60702 Sep 9 23:39:26.265926 sshd-session[2692]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:26.281223 systemd[1]: sshd@26-10.0.0.86:22-10.0.0.1:60702.service: Deactivated successfully. Sep 9 23:39:26.283133 systemd[1]: session-27.scope: Deactivated successfully. Sep 9 23:39:26.285586 systemd-logind[1398]: Session 27 logged out. Waiting for processes to exit. Sep 9 23:39:26.287624 systemd[1]: Started sshd@27-10.0.0.86:22-10.0.0.1:60708.service - OpenSSH per-connection server daemon (10.0.0.1:60708). Sep 9 23:39:26.288704 systemd-logind[1398]: Removed session 27. Sep 9 23:39:26.344350 sshd[2707]: Accepted publickey for core from 10.0.0.1 port 60708 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:39:26.345735 sshd-session[2707]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:39:26.351485 systemd-logind[1398]: New session 28 of user core. Sep 9 23:39:26.363619 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 9 23:39:26.445580 systemd[1]: var-lib-docker-overlay2-c5b533becdaebee9725e98b4497fe1c902cc3288ac090a0dead79a5c7f92c3ad\x2dinit-merged.mount: Deactivated successfully. Sep 9 23:39:26.509003 containerd[2407]: time="2025-09-09T23:39:26.508943887Z" level=info msg="connecting to shim 167b1c377abefb4bdcc3b421f108ec91eceb9e17b907272c51a0eb59e1a05c83" address="unix:///run/containerd/s/7a399995fd160bb61099156bfcc12eb0d52d6af73de1ed3cb9ce065b95b79432" namespace=moby protocol=ttrpc version=3 Sep 9 23:39:26.538616 systemd[1]: Started docker-167b1c377abefb4bdcc3b421f108ec91eceb9e17b907272c51a0eb59e1a05c83.scope - libcontainer container 167b1c377abefb4bdcc3b421f108ec91eceb9e17b907272c51a0eb59e1a05c83. Sep 9 23:39:26.555727 systemd-resolved[1294]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:39:26.573958 kernel: docker0: port 1(vethb2a5fde) entered blocking state Sep 9 23:39:26.574055 kernel: docker0: port 1(vethb2a5fde) entered disabled state Sep 9 23:39:26.574075 kernel: vethb2a5fde: entered allmulticast mode Sep 9 23:39:26.575439 kernel: vethb2a5fde: entered promiscuous mode Sep 9 23:39:26.575826 systemd-networkd[1381]: vethb2a5fde: Link UP Sep 9 23:39:26.592449 kernel: eth0: renamed from veth93564b8 Sep 9 23:39:26.594451 kernel: docker0: port 1(vethb2a5fde) entered blocking state Sep 9 23:39:26.594517 kernel: docker0: port 1(vethb2a5fde) entered forwarding state Sep 9 23:39:26.594308 systemd-networkd[1381]: vethb2a5fde: Gained carrier Sep 9 23:39:26.594792 systemd-networkd[1381]: docker0: Gained carrier Sep 9 23:39:26.618960 sshd[2709]: Connection closed by 10.0.0.1 port 60708 Sep 9 23:39:26.619492 sshd-session[2707]: pam_unix(sshd:session): session closed for user core Sep 9 23:39:26.624116 systemd[1]: sshd@27-10.0.0.86:22-10.0.0.1:60708.service: Deactivated successfully. Sep 9 23:39:26.626805 systemd[1]: session-28.scope: Deactivated successfully. Sep 9 23:39:26.627776 systemd-logind[1398]: Session 28 logged out. Waiting for processes to exit. Sep 9 23:39:26.628903 systemd-logind[1398]: Removed session 28.