Oct 12 23:50:12.809305 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 12 23:50:12.809326 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Sun Oct 12 22:32:01 -00 2025 Oct 12 23:50:12.809336 kernel: KASLR enabled Oct 12 23:50:12.809342 kernel: efi: EFI v2.7 by EDK II Oct 12 23:50:12.809347 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb21fd18 Oct 12 23:50:12.809353 kernel: random: crng init done Oct 12 23:50:12.809360 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Oct 12 23:50:12.809371 kernel: secureboot: Secure boot enabled Oct 12 23:50:12.809378 kernel: ACPI: Early table checksum verification disabled Oct 12 23:50:12.809385 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) Oct 12 23:50:12.809391 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 12 23:50:12.809396 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809402 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809408 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809415 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809422 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809428 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809434 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809440 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809446 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 12 23:50:12.809452 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 12 23:50:12.809458 kernel: ACPI: Use ACPI SPCR as default console: No Oct 12 23:50:12.809464 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 12 23:50:12.809470 kernel: NODE_DATA(0) allocated [mem 0xdc737a00-0xdc73efff] Oct 12 23:50:12.809476 kernel: Zone ranges: Oct 12 23:50:12.809483 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 12 23:50:12.809489 kernel: DMA32 empty Oct 12 23:50:12.809495 kernel: Normal empty Oct 12 23:50:12.809501 kernel: Device empty Oct 12 23:50:12.809507 kernel: Movable zone start for each node Oct 12 23:50:12.809513 kernel: Early memory node ranges Oct 12 23:50:12.809519 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] Oct 12 23:50:12.809525 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] Oct 12 23:50:12.809531 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] Oct 12 23:50:12.809537 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] Oct 12 23:50:12.809542 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] Oct 12 23:50:12.809548 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] Oct 12 23:50:12.809556 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] Oct 12 23:50:12.809562 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Oct 12 23:50:12.809568 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 12 23:50:12.809581 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 12 23:50:12.809587 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 12 23:50:12.809594 kernel: cma: Reserved 16 MiB at 0x00000000d7a00000 on node -1 Oct 12 23:50:12.809600 kernel: psci: probing for conduit method from ACPI. Oct 12 23:50:12.809608 kernel: psci: PSCIv1.1 detected in firmware. Oct 12 23:50:12.809614 kernel: psci: Using standard PSCI v0.2 function IDs Oct 12 23:50:12.809621 kernel: psci: Trusted OS migration not required Oct 12 23:50:12.809627 kernel: psci: SMC Calling Convention v1.1 Oct 12 23:50:12.809634 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 12 23:50:12.809640 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 12 23:50:12.809647 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 12 23:50:12.809654 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 12 23:50:12.809661 kernel: Detected PIPT I-cache on CPU0 Oct 12 23:50:12.809668 kernel: CPU features: detected: GIC system register CPU interface Oct 12 23:50:12.809675 kernel: CPU features: detected: Spectre-v4 Oct 12 23:50:12.809682 kernel: CPU features: detected: Spectre-BHB Oct 12 23:50:12.809688 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 12 23:50:12.809700 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 12 23:50:12.809707 kernel: CPU features: detected: ARM erratum 1418040 Oct 12 23:50:12.809716 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 12 23:50:12.809724 kernel: alternatives: applying boot alternatives Oct 12 23:50:12.809731 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=37fc523060a9b8894388e25ab0f082059dd744d472a2b8577211d4b3dd66a910 Oct 12 23:50:12.809738 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 12 23:50:12.809747 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 12 23:50:12.809760 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 12 23:50:12.809767 kernel: Fallback order for Node 0: 0 Oct 12 23:50:12.809774 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 12 23:50:12.809780 kernel: Policy zone: DMA Oct 12 23:50:12.809787 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 12 23:50:12.809793 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 12 23:50:12.809800 kernel: software IO TLB: area num 4. Oct 12 23:50:12.809809 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 12 23:50:12.809816 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) Oct 12 23:50:12.809822 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 12 23:50:12.809828 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 12 23:50:12.809836 kernel: rcu: RCU event tracing is enabled. Oct 12 23:50:12.809844 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 12 23:50:12.809853 kernel: Trampoline variant of Tasks RCU enabled. Oct 12 23:50:12.809861 kernel: Tracing variant of Tasks RCU enabled. Oct 12 23:50:12.809867 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 12 23:50:12.809874 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 12 23:50:12.809881 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 12 23:50:12.809888 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 12 23:50:12.809895 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 12 23:50:12.809901 kernel: GICv3: 256 SPIs implemented Oct 12 23:50:12.809908 kernel: GICv3: 0 Extended SPIs implemented Oct 12 23:50:12.809915 kernel: Root IRQ handler: gic_handle_irq Oct 12 23:50:12.809924 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 12 23:50:12.809931 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 12 23:50:12.809938 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 12 23:50:12.809945 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 12 23:50:12.809951 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 12 23:50:12.809958 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 12 23:50:12.809965 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 12 23:50:12.809971 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 12 23:50:12.809989 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 12 23:50:12.809996 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 12 23:50:12.810003 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 12 23:50:12.810010 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 12 23:50:12.810019 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 12 23:50:12.810026 kernel: arm-pv: using stolen time PV Oct 12 23:50:12.810033 kernel: Console: colour dummy device 80x25 Oct 12 23:50:12.810040 kernel: ACPI: Core revision 20240827 Oct 12 23:50:12.810048 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 12 23:50:12.810055 kernel: pid_max: default: 32768 minimum: 301 Oct 12 23:50:12.810062 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 12 23:50:12.810069 kernel: landlock: Up and running. Oct 12 23:50:12.810075 kernel: SELinux: Initializing. Oct 12 23:50:12.810083 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 12 23:50:12.810091 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 12 23:50:12.810097 kernel: rcu: Hierarchical SRCU implementation. Oct 12 23:50:12.810105 kernel: rcu: Max phase no-delay instances is 400. Oct 12 23:50:12.810112 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 12 23:50:12.810119 kernel: Remapping and enabling EFI services. Oct 12 23:50:12.810126 kernel: smp: Bringing up secondary CPUs ... Oct 12 23:50:12.810132 kernel: Detected PIPT I-cache on CPU1 Oct 12 23:50:12.810139 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 12 23:50:12.810148 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 12 23:50:12.810159 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 12 23:50:12.810167 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 12 23:50:12.810176 kernel: Detected PIPT I-cache on CPU2 Oct 12 23:50:12.810183 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 12 23:50:12.810190 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 12 23:50:12.810198 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 12 23:50:12.810205 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 12 23:50:12.810212 kernel: Detected PIPT I-cache on CPU3 Oct 12 23:50:12.810221 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 12 23:50:12.810232 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 12 23:50:12.810240 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 12 23:50:12.810247 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 12 23:50:12.810254 kernel: smp: Brought up 1 node, 4 CPUs Oct 12 23:50:12.810262 kernel: SMP: Total of 4 processors activated. Oct 12 23:50:12.810269 kernel: CPU: All CPU(s) started at EL1 Oct 12 23:50:12.810276 kernel: CPU features: detected: 32-bit EL0 Support Oct 12 23:50:12.810283 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 12 23:50:12.810292 kernel: CPU features: detected: Common not Private translations Oct 12 23:50:12.810300 kernel: CPU features: detected: CRC32 instructions Oct 12 23:50:12.810307 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 12 23:50:12.810314 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 12 23:50:12.810321 kernel: CPU features: detected: LSE atomic instructions Oct 12 23:50:12.810328 kernel: CPU features: detected: Privileged Access Never Oct 12 23:50:12.810336 kernel: CPU features: detected: RAS Extension Support Oct 12 23:50:12.810343 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 12 23:50:12.810350 kernel: alternatives: applying system-wide alternatives Oct 12 23:50:12.810359 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 12 23:50:12.810366 kernel: Memory: 2422372K/2572288K available (11136K kernel code, 2450K rwdata, 9076K rodata, 38976K init, 1038K bss, 127580K reserved, 16384K cma-reserved) Oct 12 23:50:12.810373 kernel: devtmpfs: initialized Oct 12 23:50:12.810381 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 12 23:50:12.810389 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 12 23:50:12.810396 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 12 23:50:12.810403 kernel: 0 pages in range for non-PLT usage Oct 12 23:50:12.810410 kernel: 508560 pages in range for PLT usage Oct 12 23:50:12.810417 kernel: pinctrl core: initialized pinctrl subsystem Oct 12 23:50:12.810426 kernel: SMBIOS 3.0.0 present. Oct 12 23:50:12.810433 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 12 23:50:12.810440 kernel: DMI: Memory slots populated: 1/1 Oct 12 23:50:12.810447 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 12 23:50:12.810454 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 12 23:50:12.810461 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 12 23:50:12.810468 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 12 23:50:12.810489 kernel: audit: initializing netlink subsys (disabled) Oct 12 23:50:12.810499 kernel: audit: type=2000 audit(0.025:1): state=initialized audit_enabled=0 res=1 Oct 12 23:50:12.810513 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 12 23:50:12.810522 kernel: cpuidle: using governor menu Oct 12 23:50:12.810530 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 12 23:50:12.810537 kernel: ASID allocator initialised with 32768 entries Oct 12 23:50:12.810544 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 12 23:50:12.810551 kernel: Serial: AMBA PL011 UART driver Oct 12 23:50:12.810558 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 12 23:50:12.810566 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 12 23:50:12.810577 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 12 23:50:12.810585 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 12 23:50:12.810592 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 12 23:50:12.810599 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 12 23:50:12.810606 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 12 23:50:12.810613 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 12 23:50:12.810620 kernel: ACPI: Added _OSI(Module Device) Oct 12 23:50:12.810627 kernel: ACPI: Added _OSI(Processor Device) Oct 12 23:50:12.810635 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 12 23:50:12.810642 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 12 23:50:12.810651 kernel: ACPI: Interpreter enabled Oct 12 23:50:12.810658 kernel: ACPI: Using GIC for interrupt routing Oct 12 23:50:12.810665 kernel: ACPI: MCFG table detected, 1 entries Oct 12 23:50:12.810672 kernel: ACPI: CPU0 has been hot-added Oct 12 23:50:12.810680 kernel: ACPI: CPU1 has been hot-added Oct 12 23:50:12.810687 kernel: ACPI: CPU2 has been hot-added Oct 12 23:50:12.810698 kernel: ACPI: CPU3 has been hot-added Oct 12 23:50:12.810706 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 12 23:50:12.810715 kernel: printk: legacy console [ttyAMA0] enabled Oct 12 23:50:12.810724 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 12 23:50:12.810894 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 12 23:50:12.810965 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 12 23:50:12.811204 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 12 23:50:12.811274 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 12 23:50:12.811335 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 12 23:50:12.811344 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 12 23:50:12.811357 kernel: PCI host bridge to bus 0000:00 Oct 12 23:50:12.811424 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 12 23:50:12.811501 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 12 23:50:12.811554 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 12 23:50:12.811620 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 12 23:50:12.811723 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 12 23:50:12.811810 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 12 23:50:12.812016 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 12 23:50:12.812098 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 12 23:50:12.812168 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 12 23:50:12.812230 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 12 23:50:12.812289 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 12 23:50:12.812383 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 12 23:50:12.812454 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 12 23:50:12.812511 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 12 23:50:12.812566 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 12 23:50:12.812580 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 12 23:50:12.812589 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 12 23:50:12.812596 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 12 23:50:12.812603 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 12 23:50:12.812611 kernel: iommu: Default domain type: Translated Oct 12 23:50:12.812620 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 12 23:50:12.812627 kernel: efivars: Registered efivars operations Oct 12 23:50:12.812634 kernel: vgaarb: loaded Oct 12 23:50:12.812641 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 12 23:50:12.812648 kernel: VFS: Disk quotas dquot_6.6.0 Oct 12 23:50:12.812655 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 12 23:50:12.812663 kernel: pnp: PnP ACPI init Oct 12 23:50:12.812767 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 12 23:50:12.812779 kernel: pnp: PnP ACPI: found 1 devices Oct 12 23:50:12.812790 kernel: NET: Registered PF_INET protocol family Oct 12 23:50:12.812797 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 12 23:50:12.812805 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 12 23:50:12.812818 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 12 23:50:12.812826 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 12 23:50:12.812833 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 12 23:50:12.812841 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 12 23:50:12.812849 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 12 23:50:12.812860 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 12 23:50:12.812870 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 12 23:50:12.812878 kernel: PCI: CLS 0 bytes, default 64 Oct 12 23:50:12.812885 kernel: kvm [1]: HYP mode not available Oct 12 23:50:12.812893 kernel: Initialise system trusted keyrings Oct 12 23:50:12.812901 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 12 23:50:12.812909 kernel: Key type asymmetric registered Oct 12 23:50:12.812916 kernel: Asymmetric key parser 'x509' registered Oct 12 23:50:12.812924 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 12 23:50:12.813288 kernel: io scheduler mq-deadline registered Oct 12 23:50:12.813319 kernel: io scheduler kyber registered Oct 12 23:50:12.813327 kernel: io scheduler bfq registered Oct 12 23:50:12.813335 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 12 23:50:12.813342 kernel: ACPI: button: Power Button [PWRB] Oct 12 23:50:12.813351 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 12 23:50:12.813469 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 12 23:50:12.813481 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 12 23:50:12.813489 kernel: thunder_xcv, ver 1.0 Oct 12 23:50:12.813496 kernel: thunder_bgx, ver 1.0 Oct 12 23:50:12.813506 kernel: nicpf, ver 1.0 Oct 12 23:50:12.813513 kernel: nicvf, ver 1.0 Oct 12 23:50:12.813601 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 12 23:50:12.813666 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-12T23:50:12 UTC (1760313012) Oct 12 23:50:12.813676 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 12 23:50:12.813685 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 12 23:50:12.813701 kernel: watchdog: NMI not fully supported Oct 12 23:50:12.813716 kernel: watchdog: Hard watchdog permanently disabled Oct 12 23:50:12.813727 kernel: NET: Registered PF_INET6 protocol family Oct 12 23:50:12.813734 kernel: Segment Routing with IPv6 Oct 12 23:50:12.813742 kernel: In-situ OAM (IOAM) with IPv6 Oct 12 23:50:12.813756 kernel: NET: Registered PF_PACKET protocol family Oct 12 23:50:12.813764 kernel: Key type dns_resolver registered Oct 12 23:50:12.813772 kernel: registered taskstats version 1 Oct 12 23:50:12.813780 kernel: Loading compiled-in X.509 certificates Oct 12 23:50:12.813788 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: b8447a1087a9e9c4d5b9d4c2f2bba5a69a74f139' Oct 12 23:50:12.813796 kernel: Demotion targets for Node 0: null Oct 12 23:50:12.813805 kernel: Key type .fscrypt registered Oct 12 23:50:12.813813 kernel: Key type fscrypt-provisioning registered Oct 12 23:50:12.813821 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 12 23:50:12.813829 kernel: ima: Allocated hash algorithm: sha1 Oct 12 23:50:12.813837 kernel: ima: No architecture policies found Oct 12 23:50:12.813845 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 12 23:50:12.813857 kernel: clk: Disabling unused clocks Oct 12 23:50:12.813865 kernel: PM: genpd: Disabling unused power domains Oct 12 23:50:12.813873 kernel: Warning: unable to open an initial console. Oct 12 23:50:12.813883 kernel: Freeing unused kernel memory: 38976K Oct 12 23:50:12.813890 kernel: Run /init as init process Oct 12 23:50:12.813898 kernel: with arguments: Oct 12 23:50:12.813905 kernel: /init Oct 12 23:50:12.813915 kernel: with environment: Oct 12 23:50:12.813931 kernel: HOME=/ Oct 12 23:50:12.813938 kernel: TERM=linux Oct 12 23:50:12.813946 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 12 23:50:12.813955 systemd[1]: Successfully made /usr/ read-only. Oct 12 23:50:12.813968 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) Oct 12 23:50:12.813991 systemd[1]: Detected virtualization kvm. Oct 12 23:50:12.813999 systemd[1]: Detected architecture arm64. Oct 12 23:50:12.814007 systemd[1]: Running in initrd. Oct 12 23:50:12.814014 systemd[1]: No hostname configured, using default hostname. Oct 12 23:50:12.814023 systemd[1]: Hostname set to . Oct 12 23:50:12.814031 systemd[1]: Initializing machine ID from VM UUID. Oct 12 23:50:12.814041 systemd[1]: Queued start job for default target initrd.target. Oct 12 23:50:12.814049 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 12 23:50:12.814058 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 12 23:50:12.814067 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 12 23:50:12.814075 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 12 23:50:12.814083 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 12 23:50:12.814092 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 12 23:50:12.814103 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 12 23:50:12.814112 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 12 23:50:12.814120 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 12 23:50:12.814129 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 12 23:50:12.814138 systemd[1]: Reached target paths.target - Path Units. Oct 12 23:50:12.814146 systemd[1]: Reached target slices.target - Slice Units. Oct 12 23:50:12.814154 systemd[1]: Reached target swap.target - Swaps. Oct 12 23:50:12.814162 systemd[1]: Reached target timers.target - Timer Units. Oct 12 23:50:12.814171 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 12 23:50:12.814179 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 12 23:50:12.814187 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 12 23:50:12.814195 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 12 23:50:12.814204 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 12 23:50:12.814212 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 12 23:50:12.814221 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 12 23:50:12.814230 systemd[1]: Reached target sockets.target - Socket Units. Oct 12 23:50:12.814237 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 12 23:50:12.814247 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 12 23:50:12.814255 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 12 23:50:12.814264 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). Oct 12 23:50:12.814272 systemd[1]: Starting systemd-fsck-usr.service... Oct 12 23:50:12.814280 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 12 23:50:12.814288 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 12 23:50:12.814296 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 12 23:50:12.814304 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 12 23:50:12.814313 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 12 23:50:12.814321 systemd[1]: Finished systemd-fsck-usr.service. Oct 12 23:50:12.814329 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 12 23:50:12.814361 systemd-journald[244]: Collecting audit messages is disabled. Oct 12 23:50:12.814384 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 12 23:50:12.814393 systemd-journald[244]: Journal started Oct 12 23:50:12.814413 systemd-journald[244]: Runtime Journal (/run/log/journal/1923959d331243a6899c854b64cbe3a9) is 6M, max 48.5M, 42.4M free. Oct 12 23:50:12.803053 systemd-modules-load[245]: Inserted module 'overlay' Oct 12 23:50:12.824087 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 12 23:50:12.824115 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 12 23:50:12.827550 systemd[1]: Started systemd-journald.service - Journal Service. Oct 12 23:50:12.828998 kernel: Bridge firewalling registered Oct 12 23:50:12.830065 systemd-modules-load[245]: Inserted module 'br_netfilter' Oct 12 23:50:12.837055 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 12 23:50:12.838582 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 12 23:50:12.842411 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 12 23:50:12.847376 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 12 23:50:12.849271 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 12 23:50:12.853351 systemd-tmpfiles[269]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 12 23:50:12.859517 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 12 23:50:12.863098 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 12 23:50:12.867467 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 12 23:50:12.868831 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 12 23:50:12.870992 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 12 23:50:12.875675 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 12 23:50:12.889860 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=37fc523060a9b8894388e25ab0f082059dd744d472a2b8577211d4b3dd66a910 Oct 12 23:50:12.923251 systemd-resolved[293]: Positive Trust Anchors: Oct 12 23:50:12.924347 systemd-resolved[293]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 12 23:50:12.924382 systemd-resolved[293]: 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 Oct 12 23:50:12.929887 systemd-resolved[293]: Defaulting to hostname 'linux'. Oct 12 23:50:12.934600 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 12 23:50:12.936007 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 12 23:50:12.979018 kernel: SCSI subsystem initialized Oct 12 23:50:12.985008 kernel: Loading iSCSI transport class v2.0-870. Oct 12 23:50:12.994334 kernel: iscsi: registered transport (tcp) Oct 12 23:50:13.012023 kernel: iscsi: registered transport (qla4xxx) Oct 12 23:50:13.012085 kernel: QLogic iSCSI HBA Driver Oct 12 23:50:13.031512 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 12 23:50:13.054474 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 12 23:50:13.057901 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 12 23:50:13.123583 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 12 23:50:13.127969 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 12 23:50:13.192029 kernel: raid6: neonx8 gen() 15769 MB/s Oct 12 23:50:13.209010 kernel: raid6: neonx4 gen() 15376 MB/s Oct 12 23:50:13.226001 kernel: raid6: neonx2 gen() 12934 MB/s Oct 12 23:50:13.243043 kernel: raid6: neonx1 gen() 10243 MB/s Oct 12 23:50:13.261015 kernel: raid6: int64x8 gen() 6890 MB/s Oct 12 23:50:13.278019 kernel: raid6: int64x4 gen() 7224 MB/s Oct 12 23:50:13.295023 kernel: raid6: int64x2 gen() 6005 MB/s Oct 12 23:50:13.312334 kernel: raid6: int64x1 gen() 4973 MB/s Oct 12 23:50:13.312397 kernel: raid6: using algorithm neonx8 gen() 15769 MB/s Oct 12 23:50:13.330345 kernel: raid6: .... xor() 11781 MB/s, rmw enabled Oct 12 23:50:13.330414 kernel: raid6: using neon recovery algorithm Oct 12 23:50:13.338609 kernel: xor: measuring software checksum speed Oct 12 23:50:13.338683 kernel: 8regs : 21596 MB/sec Oct 12 23:50:13.338703 kernel: 32regs : 21636 MB/sec Oct 12 23:50:13.338712 kernel: arm64_neon : 27785 MB/sec Oct 12 23:50:13.339316 kernel: xor: using function: arm64_neon (27785 MB/sec) Oct 12 23:50:13.395027 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 12 23:50:13.401585 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 12 23:50:13.408641 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 12 23:50:13.445649 systemd-udevd[502]: Using default interface naming scheme 'v255'. Oct 12 23:50:13.450858 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 12 23:50:13.457126 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 12 23:50:13.480585 dracut-pre-trigger[513]: rd.md=0: removing MD RAID activation Oct 12 23:50:13.505127 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 12 23:50:13.508039 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 12 23:50:13.585139 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 12 23:50:13.588621 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 12 23:50:13.657892 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 12 23:50:13.658156 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 12 23:50:13.662005 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 12 23:50:13.670674 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 12 23:50:13.670815 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 12 23:50:13.674076 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 12 23:50:13.679605 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 12 23:50:13.712154 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 12 23:50:13.714935 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 12 23:50:13.716362 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 12 23:50:13.724792 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 12 23:50:13.731642 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 12 23:50:13.732992 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 12 23:50:13.743401 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 12 23:50:13.744790 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 12 23:50:13.747079 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 12 23:50:13.749462 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 12 23:50:13.752431 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 12 23:50:13.754430 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 12 23:50:13.779056 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 12 23:50:13.803016 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 12 23:50:14.828995 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 12 23:50:14.829596 disk-uuid[601]: The operation has completed successfully. Oct 12 23:50:14.865834 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 12 23:50:14.865958 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 12 23:50:14.884331 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 12 23:50:14.905322 sh[612]: Success Oct 12 23:50:14.920232 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 12 23:50:14.920292 kernel: device-mapper: uevent: version 1.0.3 Oct 12 23:50:14.920304 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 12 23:50:14.928013 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 12 23:50:14.960389 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 12 23:50:14.963557 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 12 23:50:14.978785 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 12 23:50:14.992995 kernel: BTRFS: device fsid e4495086-3456-43e0-be7b-4c3c53a67174 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (624) Oct 12 23:50:14.993058 kernel: BTRFS info (device dm-0): first mount of filesystem e4495086-3456-43e0-be7b-4c3c53a67174 Oct 12 23:50:14.993070 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 12 23:50:14.997522 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 12 23:50:14.997578 kernel: BTRFS info (device dm-0): enabling free space tree Oct 12 23:50:14.998755 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 12 23:50:15.000188 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 12 23:50:15.001792 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 12 23:50:15.002625 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 12 23:50:15.004430 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 12 23:50:15.037996 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (657) Oct 12 23:50:15.040606 kernel: BTRFS info (device vda6): first mount of filesystem 51f6bef3-5c80-492f-be85-d924f50fa726 Oct 12 23:50:15.040658 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 12 23:50:15.046244 kernel: BTRFS info (device vda6): turning on async discard Oct 12 23:50:15.046308 kernel: BTRFS info (device vda6): enabling free space tree Oct 12 23:50:15.052051 kernel: BTRFS info (device vda6): last unmount of filesystem 51f6bef3-5c80-492f-be85-d924f50fa726 Oct 12 23:50:15.055620 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 12 23:50:15.058129 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 12 23:50:15.145917 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 12 23:50:15.150641 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 12 23:50:15.168646 ignition[700]: Ignition 2.22.0 Oct 12 23:50:15.168658 ignition[700]: Stage: fetch-offline Oct 12 23:50:15.168702 ignition[700]: no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:15.168709 ignition[700]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:15.168795 ignition[700]: parsed url from cmdline: "" Oct 12 23:50:15.168798 ignition[700]: no config URL provided Oct 12 23:50:15.168803 ignition[700]: reading system config file "/usr/lib/ignition/user.ign" Oct 12 23:50:15.168811 ignition[700]: no config at "/usr/lib/ignition/user.ign" Oct 12 23:50:15.168833 ignition[700]: op(1): [started] loading QEMU firmware config module Oct 12 23:50:15.168839 ignition[700]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 12 23:50:15.175917 ignition[700]: op(1): [finished] loading QEMU firmware config module Oct 12 23:50:15.182820 ignition[700]: parsing config with SHA512: e443291ec1cbf422cd3f907401b2a60b7359499e3a59509c9d49c2d824f80b45085a954e3d7d79d77590dc0c93f16a4a744753dce263ce9956290c846444d932 Oct 12 23:50:15.189223 unknown[700]: fetched base config from "system" Oct 12 23:50:15.189237 unknown[700]: fetched user config from "qemu" Oct 12 23:50:15.189402 ignition[700]: fetch-offline: fetch-offline passed Oct 12 23:50:15.189463 ignition[700]: Ignition finished successfully Oct 12 23:50:15.193155 systemd-networkd[805]: lo: Link UP Oct 12 23:50:15.193159 systemd-networkd[805]: lo: Gained carrier Oct 12 23:50:15.194078 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 12 23:50:15.196378 systemd-networkd[805]: Enumeration completed Oct 12 23:50:15.196495 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 12 23:50:15.196828 systemd-networkd[805]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 12 23:50:15.196832 systemd-networkd[805]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 12 23:50:15.198134 systemd-networkd[805]: eth0: Link UP Oct 12 23:50:15.198240 systemd-networkd[805]: eth0: Gained carrier Oct 12 23:50:15.198251 systemd-networkd[805]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 12 23:50:15.198503 systemd[1]: Reached target network.target - Network. Oct 12 23:50:15.200326 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 12 23:50:15.201157 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 12 23:50:15.216065 systemd-networkd[805]: eth0: DHCPv4 address 10.0.0.2/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 12 23:50:15.236404 ignition[812]: Ignition 2.22.0 Oct 12 23:50:15.236428 ignition[812]: Stage: kargs Oct 12 23:50:15.236579 ignition[812]: no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:15.239368 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 12 23:50:15.236589 ignition[812]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:15.237129 ignition[812]: kargs: kargs passed Oct 12 23:50:15.243188 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 12 23:50:15.237180 ignition[812]: Ignition finished successfully Oct 12 23:50:15.274375 ignition[821]: Ignition 2.22.0 Oct 12 23:50:15.274392 ignition[821]: Stage: disks Oct 12 23:50:15.274527 ignition[821]: no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:15.274535 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:15.276896 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 12 23:50:15.275108 ignition[821]: disks: disks passed Oct 12 23:50:15.279124 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 12 23:50:15.275161 ignition[821]: Ignition finished successfully Oct 12 23:50:15.281054 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 12 23:50:15.282954 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 12 23:50:15.285191 systemd[1]: Reached target sysinit.target - System Initialization. Oct 12 23:50:15.286999 systemd[1]: Reached target basic.target - Basic System. Oct 12 23:50:15.290334 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 12 23:50:15.328683 systemd-fsck[831]: ROOT: clean, 15/553520 files, 52789/553472 blocks Oct 12 23:50:15.422098 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 12 23:50:15.424799 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 12 23:50:15.500025 kernel: EXT4-fs (vda9): mounted filesystem 1aa1d0b4-cbac-4728-b9e0-662fa574e9ad r/w with ordered data mode. Quota mode: none. Oct 12 23:50:15.500483 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 12 23:50:15.501939 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 12 23:50:15.504638 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 12 23:50:15.506742 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 12 23:50:15.507942 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 12 23:50:15.508021 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 12 23:50:15.508048 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 12 23:50:15.523993 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (839) Oct 12 23:50:15.524033 kernel: BTRFS info (device vda6): first mount of filesystem 51f6bef3-5c80-492f-be85-d924f50fa726 Oct 12 23:50:15.524737 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 12 23:50:15.528954 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 12 23:50:15.529363 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 12 23:50:15.532265 kernel: BTRFS info (device vda6): turning on async discard Oct 12 23:50:15.532289 kernel: BTRFS info (device vda6): enabling free space tree Oct 12 23:50:15.534411 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 12 23:50:15.577649 initrd-setup-root[863]: cut: /sysroot/etc/passwd: No such file or directory Oct 12 23:50:15.581778 initrd-setup-root[870]: cut: /sysroot/etc/group: No such file or directory Oct 12 23:50:15.585915 initrd-setup-root[877]: cut: /sysroot/etc/shadow: No such file or directory Oct 12 23:50:15.590706 initrd-setup-root[884]: cut: /sysroot/etc/gshadow: No such file or directory Oct 12 23:50:15.684050 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 12 23:50:15.686655 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 12 23:50:15.688509 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 12 23:50:15.711049 kernel: BTRFS info (device vda6): last unmount of filesystem 51f6bef3-5c80-492f-be85-d924f50fa726 Oct 12 23:50:15.724125 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 12 23:50:15.741904 ignition[952]: INFO : Ignition 2.22.0 Oct 12 23:50:15.741904 ignition[952]: INFO : Stage: mount Oct 12 23:50:15.744717 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:15.744717 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:15.744717 ignition[952]: INFO : mount: mount passed Oct 12 23:50:15.744717 ignition[952]: INFO : Ignition finished successfully Oct 12 23:50:15.744774 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 12 23:50:15.748113 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 12 23:50:15.990319 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 12 23:50:15.991904 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 12 23:50:16.019043 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (965) Oct 12 23:50:16.019084 kernel: BTRFS info (device vda6): first mount of filesystem 51f6bef3-5c80-492f-be85-d924f50fa726 Oct 12 23:50:16.019095 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 12 23:50:16.022994 kernel: BTRFS info (device vda6): turning on async discard Oct 12 23:50:16.023016 kernel: BTRFS info (device vda6): enabling free space tree Oct 12 23:50:16.024353 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 12 23:50:16.053766 ignition[982]: INFO : Ignition 2.22.0 Oct 12 23:50:16.053766 ignition[982]: INFO : Stage: files Oct 12 23:50:16.053766 ignition[982]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:16.053766 ignition[982]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:16.053766 ignition[982]: DEBUG : files: compiled without relabeling support, skipping Oct 12 23:50:16.059525 ignition[982]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 12 23:50:16.059525 ignition[982]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 12 23:50:16.062648 ignition[982]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 12 23:50:16.062648 ignition[982]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 12 23:50:16.066010 ignition[982]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 12 23:50:16.062662 unknown[982]: wrote ssh authorized keys file for user: core Oct 12 23:50:16.071082 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 12 23:50:16.073272 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 12 23:50:16.073272 ignition[982]: INFO : files: op(4): [started] processing unit "docker.service" Oct 12 23:50:16.073272 ignition[982]: INFO : files: op(4): [finished] processing unit "docker.service" Oct 12 23:50:16.073272 ignition[982]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Oct 12 23:50:16.073272 ignition[982]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 12 23:50:16.084079 ignition[982]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 12 23:50:16.084079 ignition[982]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Oct 12 23:50:16.084079 ignition[982]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Oct 12 23:50:16.104149 ignition[982]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 12 23:50:16.107640 ignition[982]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 12 23:50:16.109344 ignition[982]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Oct 12 23:50:16.109344 ignition[982]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Oct 12 23:50:16.109344 ignition[982]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Oct 12 23:50:16.109344 ignition[982]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 12 23:50:16.109344 ignition[982]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 12 23:50:16.109344 ignition[982]: INFO : files: files passed Oct 12 23:50:16.109344 ignition[982]: INFO : Ignition finished successfully Oct 12 23:50:16.109419 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 12 23:50:16.113294 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 12 23:50:16.115383 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 12 23:50:16.128443 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 12 23:50:16.128572 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 12 23:50:16.132404 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory Oct 12 23:50:16.134052 initrd-setup-root-after-ignition[1012]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 12 23:50:16.134052 initrd-setup-root-after-ignition[1012]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 12 23:50:16.139055 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 12 23:50:16.134422 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 12 23:50:16.137578 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 12 23:50:16.141299 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 12 23:50:16.180466 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 12 23:50:16.180629 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 12 23:50:16.183118 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 12 23:50:16.185134 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 12 23:50:16.187168 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 12 23:50:16.188113 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 12 23:50:16.214810 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 12 23:50:16.217908 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 12 23:50:16.241850 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 12 23:50:16.243466 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 12 23:50:16.246046 systemd[1]: Stopped target timers.target - Timer Units. Oct 12 23:50:16.248150 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 12 23:50:16.248295 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 12 23:50:16.251303 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 12 23:50:16.253786 systemd[1]: Stopped target basic.target - Basic System. Oct 12 23:50:16.255766 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 12 23:50:16.257839 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 12 23:50:16.260186 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 12 23:50:16.262473 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 12 23:50:16.268090 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 12 23:50:16.270315 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 12 23:50:16.272633 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 12 23:50:16.275077 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 12 23:50:16.277225 systemd[1]: Stopped target swap.target - Swaps. Oct 12 23:50:16.279140 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 12 23:50:16.279291 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 12 23:50:16.282144 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 12 23:50:16.284461 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 12 23:50:16.286773 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 12 23:50:16.287037 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 12 23:50:16.289590 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 12 23:50:16.289747 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 12 23:50:16.293433 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 12 23:50:16.293566 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 12 23:50:16.296111 systemd[1]: Stopped target paths.target - Path Units. Oct 12 23:50:16.298138 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 12 23:50:16.298286 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 12 23:50:16.300732 systemd[1]: Stopped target slices.target - Slice Units. Oct 12 23:50:16.303137 systemd[1]: Stopped target sockets.target - Socket Units. Oct 12 23:50:16.305296 systemd[1]: iscsid.socket: Deactivated successfully. Oct 12 23:50:16.305399 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 12 23:50:16.307620 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 12 23:50:16.307725 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 12 23:50:16.310565 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 12 23:50:16.310713 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 12 23:50:16.312910 systemd[1]: ignition-files.service: Deactivated successfully. Oct 12 23:50:16.313051 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 12 23:50:16.315968 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 12 23:50:16.318911 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 12 23:50:16.320118 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 12 23:50:16.320277 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 12 23:50:16.323051 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 12 23:50:16.323179 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 12 23:50:16.330322 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 12 23:50:16.336027 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 12 23:50:16.343100 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 12 23:50:16.347104 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 12 23:50:16.348075 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 12 23:50:16.352995 ignition[1037]: INFO : Ignition 2.22.0 Oct 12 23:50:16.352995 ignition[1037]: INFO : Stage: umount Oct 12 23:50:16.355014 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 12 23:50:16.355014 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 12 23:50:16.355014 ignition[1037]: INFO : umount: umount passed Oct 12 23:50:16.355014 ignition[1037]: INFO : Ignition finished successfully Oct 12 23:50:16.355600 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 12 23:50:16.355728 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 12 23:50:16.358362 systemd[1]: Stopped target network.target - Network. Oct 12 23:50:16.360632 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 12 23:50:16.360716 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 12 23:50:16.362667 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 12 23:50:16.362740 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 12 23:50:16.364816 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 12 23:50:16.364876 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 12 23:50:16.367254 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 12 23:50:16.367303 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 12 23:50:16.369704 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 12 23:50:16.369762 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 12 23:50:16.371775 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 12 23:50:16.373801 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 12 23:50:16.379228 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 12 23:50:16.379344 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 12 23:50:16.384611 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 12 23:50:16.384890 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 12 23:50:16.385027 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 12 23:50:16.390923 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 12 23:50:16.391716 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 12 23:50:16.393528 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 12 23:50:16.393569 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 12 23:50:16.396732 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 12 23:50:16.398036 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 12 23:50:16.398104 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 12 23:50:16.400956 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 12 23:50:16.401017 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 12 23:50:16.404997 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 12 23:50:16.405066 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 12 23:50:16.407912 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 12 23:50:16.407972 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 12 23:50:16.411764 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 12 23:50:16.429743 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 12 23:50:16.429929 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 12 23:50:16.432729 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 12 23:50:16.432830 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 12 23:50:16.436641 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 12 23:50:16.436719 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 12 23:50:16.438479 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 12 23:50:16.438517 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 12 23:50:16.440714 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 12 23:50:16.440777 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 12 23:50:16.444230 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 12 23:50:16.444301 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 12 23:50:16.448129 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 12 23:50:16.448192 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 12 23:50:16.452716 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 12 23:50:16.454051 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 12 23:50:16.454109 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 12 23:50:16.457706 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 12 23:50:16.457757 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 12 23:50:16.462564 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 12 23:50:16.462615 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 12 23:50:16.468504 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 12 23:50:16.468576 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 12 23:50:16.471662 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 12 23:50:16.471724 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 12 23:50:16.476671 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 12 23:50:16.478010 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 12 23:50:16.480841 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 12 23:50:16.484090 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 12 23:50:16.511161 systemd[1]: Switching root. Oct 12 23:50:16.553390 systemd-journald[244]: Journal stopped Oct 12 23:50:17.659582 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Oct 12 23:50:17.659657 kernel: SELinux: policy capability network_peer_controls=1 Oct 12 23:50:17.659677 kernel: SELinux: policy capability open_perms=1 Oct 12 23:50:17.659699 kernel: SELinux: policy capability extended_socket_class=1 Oct 12 23:50:17.659709 kernel: SELinux: policy capability always_check_network=0 Oct 12 23:50:17.659719 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 12 23:50:17.659729 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 12 23:50:17.659792 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 12 23:50:17.659850 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 12 23:50:17.659896 kernel: SELinux: policy capability userspace_initial_context=0 Oct 12 23:50:17.659931 kernel: audit: type=1403 audit(1760313016.767:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 12 23:50:17.659950 systemd[1]: Successfully loaded SELinux policy in 103.673ms. Oct 12 23:50:17.659998 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.566ms. Oct 12 23:50:17.660012 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) Oct 12 23:50:17.660023 systemd[1]: Detected virtualization kvm. Oct 12 23:50:17.660034 systemd[1]: Detected architecture arm64. Oct 12 23:50:17.660044 systemd[1]: Detected first boot. Oct 12 23:50:17.660054 systemd[1]: Initializing machine ID from VM UUID. Oct 12 23:50:17.660064 zram_generator::config[1082]: No configuration found. Oct 12 23:50:17.660075 kernel: NET: Registered PF_VSOCK protocol family Oct 12 23:50:17.660086 systemd[1]: Populated /etc with preset unit settings. Oct 12 23:50:17.660099 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 12 23:50:17.660110 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 12 23:50:17.660120 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 12 23:50:17.660132 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 12 23:50:17.660142 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 12 23:50:17.660153 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 12 23:50:17.660164 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 12 23:50:17.660179 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 12 23:50:17.660189 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 12 23:50:17.660200 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 12 23:50:17.660211 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 12 23:50:17.660221 systemd[1]: Created slice user.slice - User and Session Slice. Oct 12 23:50:17.660232 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 12 23:50:17.660243 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 12 23:50:17.660255 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 12 23:50:17.660266 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 12 23:50:17.660280 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 12 23:50:17.660295 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 12 23:50:17.660305 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 12 23:50:17.660315 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 12 23:50:17.660326 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 12 23:50:17.660337 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 12 23:50:17.660352 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 12 23:50:17.660362 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 12 23:50:17.660373 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 12 23:50:17.660383 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 12 23:50:17.660393 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 12 23:50:17.660416 systemd[1]: Reached target slices.target - Slice Units. Oct 12 23:50:17.660426 systemd[1]: Reached target swap.target - Swaps. Oct 12 23:50:17.660436 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 12 23:50:17.660453 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 12 23:50:17.660464 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 12 23:50:17.660474 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 12 23:50:17.660485 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 12 23:50:17.660496 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 12 23:50:17.660506 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 12 23:50:17.660516 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 12 23:50:17.660525 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 12 23:50:17.660536 systemd[1]: Mounting media.mount - External Media Directory... Oct 12 23:50:17.660548 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 12 23:50:17.660558 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 12 23:50:17.660568 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 12 23:50:17.660584 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). Oct 12 23:50:17.660594 systemd[1]: Reached target machines.target - Containers. Oct 12 23:50:17.660604 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 12 23:50:17.660619 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 12 23:50:17.660629 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 12 23:50:17.660639 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 12 23:50:17.660652 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 12 23:50:17.660662 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 12 23:50:17.660672 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 12 23:50:17.660688 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 12 23:50:17.660698 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 12 23:50:17.660711 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 12 23:50:17.660721 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 12 23:50:17.660731 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 12 23:50:17.660742 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 12 23:50:17.660754 systemd[1]: Stopped systemd-fsck-usr.service. Oct 12 23:50:17.660764 kernel: loop: module loaded Oct 12 23:50:17.660774 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). Oct 12 23:50:17.660783 kernel: fuse: init (API version 7.41) Oct 12 23:50:17.660792 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 12 23:50:17.660803 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 12 23:50:17.660813 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 12 23:50:17.660826 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 12 23:50:17.660837 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 12 23:50:17.660875 systemd-journald[1147]: Collecting audit messages is disabled. Oct 12 23:50:17.660897 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 12 23:50:17.660907 kernel: ACPI: bus type drm_connector registered Oct 12 23:50:17.660918 systemd[1]: verity-setup.service: Deactivated successfully. Oct 12 23:50:17.660933 systemd-journald[1147]: Journal started Oct 12 23:50:17.660954 systemd-journald[1147]: Runtime Journal (/run/log/journal/1923959d331243a6899c854b64cbe3a9) is 6M, max 48.5M, 42.4M free. Oct 12 23:50:17.389711 systemd[1]: Queued start job for default target multi-user.target. Oct 12 23:50:17.413043 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 12 23:50:17.413454 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 12 23:50:17.662240 systemd[1]: Stopped verity-setup.service. Oct 12 23:50:17.668887 systemd[1]: Started systemd-journald.service - Journal Service. Oct 12 23:50:17.669704 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 12 23:50:17.671298 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 12 23:50:17.672941 systemd[1]: Mounted media.mount - External Media Directory. Oct 12 23:50:17.674324 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 12 23:50:17.675852 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 12 23:50:17.677530 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 12 23:50:17.679107 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 12 23:50:17.681474 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 12 23:50:17.681711 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 12 23:50:17.683504 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 12 23:50:17.683707 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 12 23:50:17.685712 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 12 23:50:17.685915 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 12 23:50:17.688047 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 12 23:50:17.688250 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 12 23:50:17.690372 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 12 23:50:17.690542 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 12 23:50:17.692352 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 12 23:50:17.692539 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 12 23:50:17.694301 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 12 23:50:17.695929 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 12 23:50:17.699110 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 12 23:50:17.700935 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 12 23:50:17.704518 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 12 23:50:17.718274 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 12 23:50:17.722957 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 12 23:50:17.725755 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 12 23:50:17.728208 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 12 23:50:17.729692 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 12 23:50:17.729738 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 12 23:50:17.732194 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 12 23:50:17.739100 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 12 23:50:17.740520 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 12 23:50:17.741747 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 12 23:50:17.744313 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 12 23:50:17.745918 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 12 23:50:17.747217 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 12 23:50:17.748709 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 12 23:50:17.750101 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 12 23:50:17.755772 systemd-journald[1147]: Time spent on flushing to /var/log/journal/1923959d331243a6899c854b64cbe3a9 is 11.409ms for 852 entries. Oct 12 23:50:17.755772 systemd-journald[1147]: System Journal (/var/log/journal/1923959d331243a6899c854b64cbe3a9) is 8M, max 195.6M, 187.6M free. Oct 12 23:50:17.855220 systemd-journald[1147]: Received client request to flush runtime journal. Oct 12 23:50:17.855291 kernel: loop0: detected capacity change from 0 to 100632 Oct 12 23:50:17.756247 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 12 23:50:17.760318 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 12 23:50:17.764549 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 12 23:50:17.766207 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 12 23:50:17.778716 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 12 23:50:17.799507 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 12 23:50:17.802312 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 12 23:50:17.806196 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 12 23:50:17.846802 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Oct 12 23:50:17.846816 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Oct 12 23:50:17.855533 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 12 23:50:17.859614 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 12 23:50:17.865639 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 12 23:50:17.951756 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 12 23:50:17.954594 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 12 23:50:17.970018 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 12 23:50:17.979464 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Oct 12 23:50:17.979483 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Oct 12 23:50:17.982991 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 12 23:50:17.989248 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 12 23:50:17.990997 kernel: loop1: detected capacity change from 0 to 119368 Oct 12 23:50:18.023056 kernel: loop2: detected capacity change from 0 to 100632 Oct 12 23:50:18.035803 kernel: loop3: detected capacity change from 0 to 119368 Oct 12 23:50:18.043816 (sd-merge)[1222]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Oct 12 23:50:18.044225 (sd-merge)[1222]: Merged extensions into '/usr'. Oct 12 23:50:18.047641 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 12 23:50:18.051200 systemd[1]: Starting ensure-sysext.service... Oct 12 23:50:18.053052 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 12 23:50:18.070893 systemd[1]: Reload requested from client PID 1224 ('systemctl') (unit ensure-sysext.service)... Oct 12 23:50:18.071354 systemd[1]: Reloading... Oct 12 23:50:18.074558 systemd-tmpfiles[1225]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 12 23:50:18.074593 systemd-tmpfiles[1225]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 12 23:50:18.074823 systemd-tmpfiles[1225]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 12 23:50:18.075082 systemd-tmpfiles[1225]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 12 23:50:18.075707 systemd-tmpfiles[1225]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 12 23:50:18.076006 systemd-tmpfiles[1225]: ACLs are not supported, ignoring. Oct 12 23:50:18.076059 systemd-tmpfiles[1225]: ACLs are not supported, ignoring. Oct 12 23:50:18.079356 systemd-tmpfiles[1225]: Detected autofs mount point /boot during canonicalization of boot. Oct 12 23:50:18.079370 systemd-tmpfiles[1225]: Skipping /boot Oct 12 23:50:18.086120 systemd-tmpfiles[1225]: Detected autofs mount point /boot during canonicalization of boot. Oct 12 23:50:18.086136 systemd-tmpfiles[1225]: Skipping /boot Oct 12 23:50:18.124057 zram_generator::config[1252]: No configuration found. Oct 12 23:50:18.265549 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 12 23:50:18.265864 systemd[1]: Reloading finished in 193 ms. Oct 12 23:50:18.324156 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 12 23:50:18.331360 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 12 23:50:18.334108 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 12 23:50:18.347848 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 12 23:50:18.354928 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 12 23:50:18.357602 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 12 23:50:18.361138 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 12 23:50:18.362364 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 12 23:50:18.367366 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 12 23:50:18.369926 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 12 23:50:18.371567 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 12 23:50:18.371735 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). Oct 12 23:50:18.374454 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 12 23:50:18.374608 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 12 23:50:18.374710 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). Oct 12 23:50:18.381813 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 12 23:50:18.385428 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 12 23:50:18.385637 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 12 23:50:18.387536 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 12 23:50:18.390046 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 12 23:50:18.392036 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 12 23:50:18.394436 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 12 23:50:18.394604 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 12 23:50:18.405426 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 12 23:50:18.407051 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 12 23:50:18.412018 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 12 23:50:18.417099 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 12 23:50:18.421331 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 12 23:50:18.422729 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 12 23:50:18.422882 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). Oct 12 23:50:18.426242 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 12 23:50:18.428254 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 12 23:50:18.433392 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 12 23:50:18.436496 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 12 23:50:18.438148 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 12 23:50:18.439956 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 12 23:50:18.443351 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 12 23:50:18.446905 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 12 23:50:18.447091 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 12 23:50:18.451739 augenrules[1330]: No rules Oct 12 23:50:18.451930 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 12 23:50:18.452149 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 12 23:50:18.458597 systemd[1]: audit-rules.service: Deactivated successfully. Oct 12 23:50:18.458908 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 12 23:50:18.467020 systemd[1]: Finished ensure-sysext.service. Oct 12 23:50:18.473171 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 12 23:50:18.473248 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 12 23:50:18.475599 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 12 23:50:18.480165 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 12 23:50:18.498390 systemd-resolved[1295]: Positive Trust Anchors: Oct 12 23:50:18.498739 systemd-resolved[1295]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 12 23:50:18.498776 systemd-resolved[1295]: 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 Oct 12 23:50:18.505391 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 12 23:50:18.506563 systemd-resolved[1295]: Defaulting to hostname 'linux'. Oct 12 23:50:18.508395 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). Oct 12 23:50:18.508567 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 12 23:50:18.513315 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 12 23:50:18.521520 systemd-udevd[1344]: Using default interface naming scheme 'v255'. Oct 12 23:50:18.540887 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 12 23:50:18.545751 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 12 23:50:18.548784 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 12 23:50:18.550662 systemd[1]: Reached target time-set.target - System Time Set. Oct 12 23:50:18.605882 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 12 23:50:18.648129 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 12 23:50:18.652599 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 12 23:50:18.688806 ldconfig[1194]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 12 23:50:18.698446 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 12 23:50:18.704693 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 12 23:50:18.707733 systemd-networkd[1351]: lo: Link UP Oct 12 23:50:18.707746 systemd-networkd[1351]: lo: Gained carrier Oct 12 23:50:18.708779 systemd-networkd[1351]: Enumeration completed Oct 12 23:50:18.709371 systemd-networkd[1351]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 12 23:50:18.709379 systemd-networkd[1351]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 12 23:50:18.709924 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 12 23:50:18.710421 systemd-networkd[1351]: eth0: Link UP Oct 12 23:50:18.710563 systemd-networkd[1351]: eth0: Gained carrier Oct 12 23:50:18.710579 systemd-networkd[1351]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 12 23:50:18.711669 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 12 23:50:18.713618 systemd[1]: Reached target network.target - Network. Oct 12 23:50:18.716422 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 12 23:50:18.722079 systemd-networkd[1351]: eth0: DHCPv4 address 10.0.0.2/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 12 23:50:18.723498 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 12 23:50:18.725132 systemd-timesyncd[1343]: Network configuration changed, trying to establish connection. Oct 12 23:50:18.726097 systemd-timesyncd[1343]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 12 23:50:18.726160 systemd-timesyncd[1343]: Initial clock synchronization to Sun 2025-10-12 23:50:18.789218 UTC. Oct 12 23:50:18.733293 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 12 23:50:18.735085 systemd[1]: Reached target sysinit.target - System Initialization. Oct 12 23:50:18.736605 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 12 23:50:18.738537 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 12 23:50:18.740196 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 12 23:50:18.741562 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 12 23:50:18.743034 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 12 23:50:18.744480 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 12 23:50:18.744522 systemd[1]: Reached target paths.target - Path Units. Oct 12 23:50:18.745668 systemd[1]: Reached target timers.target - Timer Units. Oct 12 23:50:18.747784 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 12 23:50:18.750675 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 12 23:50:18.753834 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 12 23:50:18.755601 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 12 23:50:18.757187 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 12 23:50:18.761348 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 12 23:50:18.763327 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 12 23:50:18.765845 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 12 23:50:18.767880 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 12 23:50:18.770038 systemd[1]: Reached target sockets.target - Socket Units. Oct 12 23:50:18.771270 systemd[1]: Reached target basic.target - Basic System. Oct 12 23:50:18.772509 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 12 23:50:18.772555 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 12 23:50:18.775131 systemd[1]: Starting containerd.service - containerd container runtime... Oct 12 23:50:18.778208 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 12 23:50:18.781254 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 12 23:50:18.786222 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 12 23:50:18.806206 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 12 23:50:18.807503 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). Oct 12 23:50:18.807944 jq[1411]: false Oct 12 23:50:18.808870 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 12 23:50:18.811559 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 12 23:50:18.816237 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 12 23:50:18.824287 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 12 23:50:18.826777 extend-filesystems[1413]: Found /dev/vda6 Oct 12 23:50:18.828055 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 12 23:50:18.828675 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 12 23:50:18.829741 systemd[1]: Starting update-engine.service - Update Engine... Oct 12 23:50:18.832158 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 12 23:50:18.836413 extend-filesystems[1413]: Found /dev/vda9 Oct 12 23:50:18.838708 extend-filesystems[1413]: Checking size of /dev/vda9 Oct 12 23:50:18.843277 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 12 23:50:18.848299 jq[1428]: true Oct 12 23:50:18.847814 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 12 23:50:18.848028 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 12 23:50:18.848322 systemd[1]: motdgen.service: Deactivated successfully. Oct 12 23:50:18.848491 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 12 23:50:18.849242 extend-filesystems[1413]: Old size kept for /dev/vda9 Oct 12 23:50:18.851588 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 12 23:50:18.851805 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 12 23:50:18.855282 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 12 23:50:18.855482 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 12 23:50:18.876869 update_engine[1427]: I20251012 23:50:18.876612 1427 main.cc:92] Flatcar Update Engine starting Oct 12 23:50:18.881379 jq[1437]: true Oct 12 23:50:18.878352 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 12 23:50:18.897209 (ntainerd)[1438]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 12 23:50:18.918500 dbus-daemon[1409]: [system] SELinux support is enabled Oct 12 23:50:18.919025 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 12 23:50:18.923280 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). Oct 12 23:50:18.923319 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 12 23:50:18.925940 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). Oct 12 23:50:18.925971 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 12 23:50:18.930142 systemd[1]: Started update-engine.service - Update Engine. Oct 12 23:50:18.931074 update_engine[1427]: I20251012 23:50:18.931023 1427 update_check_scheduler.cc:74] Next update check in 7m58s Oct 12 23:50:18.933148 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 12 23:50:18.957109 systemd-logind[1422]: Watching system buttons on /dev/input/event0 (Power Button) Oct 12 23:50:18.960136 systemd-logind[1422]: New seat seat0. Oct 12 23:50:18.967426 systemd[1]: Started systemd-logind.service - User Login Management. Oct 12 23:50:18.971520 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 12 23:50:18.993049 locksmithd[1470]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 12 23:50:19.024782 bash[1468]: Updated "/home/core/.ssh/authorized_keys" Oct 12 23:50:19.030035 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 12 23:50:19.032690 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 12 23:50:19.084705 containerd[1438]: time="2025-10-12T23:50:19Z" 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 Oct 12 23:50:19.085947 containerd[1438]: time="2025-10-12T23:50:19.085895189Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095419668Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.637µs" Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095463561Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095482797Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095648127Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095663468Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095687161Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095735792Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095747639Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.095973929Z" 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 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.096016898Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.096027942Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096512 containerd[1438]: time="2025-10-12T23:50:19.096035813Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096129100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096312221Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096339167Z" 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 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096349407Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096388440Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096630633Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 12 23:50:19.096788 containerd[1438]: time="2025-10-12T23:50:19.096731189Z" level=info msg="metadata content store policy set" policy=shared Oct 12 23:50:19.160931 containerd[1438]: time="2025-10-12T23:50:19.160831666Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 12 23:50:19.160931 containerd[1438]: time="2025-10-12T23:50:19.160901300Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 12 23:50:19.160931 containerd[1438]: time="2025-10-12T23:50:19.160918809Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 12 23:50:19.160931 containerd[1438]: time="2025-10-12T23:50:19.160930455Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.160946839Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.160959810Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.160972621Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.161000330Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.161012216Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.161022256Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.161031572Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 12 23:50:19.161075 containerd[1438]: time="2025-10-12T23:50:19.161044062Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161197064Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161225616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161239471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161252040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161261718Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161279267Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161290752Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161300109Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161321955Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161334043Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 12 23:50:19.161347 containerd[1438]: time="2025-10-12T23:50:19.161344725Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 12 23:50:19.161541 containerd[1438]: time="2025-10-12T23:50:19.161532704Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 12 23:50:19.161560 containerd[1438]: time="2025-10-12T23:50:19.161548326Z" level=info msg="Start snapshots syncer" Oct 12 23:50:19.161607 containerd[1438]: time="2025-10-12T23:50:19.161576035Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 12 23:50:19.161846 containerd[1438]: time="2025-10-12T23:50:19.161794414Z" 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\"}" Oct 12 23:50:19.161991 containerd[1438]: time="2025-10-12T23:50:19.161848989Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 12 23:50:19.161991 containerd[1438]: time="2025-10-12T23:50:19.161918181Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 12 23:50:19.162058 containerd[1438]: time="2025-10-12T23:50:19.162038696Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 12 23:50:19.162088 containerd[1438]: time="2025-10-12T23:50:19.162065963Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 12 23:50:19.162088 containerd[1438]: time="2025-10-12T23:50:19.162077850Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 12 23:50:19.162124 containerd[1438]: time="2025-10-12T23:50:19.162089335Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 12 23:50:19.162124 containerd[1438]: time="2025-10-12T23:50:19.162101985Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 12 23:50:19.162124 containerd[1438]: time="2025-10-12T23:50:19.162112546Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 12 23:50:19.162173 containerd[1438]: time="2025-10-12T23:50:19.162123389Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 12 23:50:19.162173 containerd[1438]: time="2025-10-12T23:50:19.162146801Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 12 23:50:19.162173 containerd[1438]: time="2025-10-12T23:50:19.162156840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 12 23:50:19.162173 containerd[1438]: time="2025-10-12T23:50:19.162166519Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162202299Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162218804Z" 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 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162228482Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162238080Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162245550Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162254465Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 12 23:50:19.162326 containerd[1438]: time="2025-10-12T23:50:19.162264785Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 12 23:50:19.162547 containerd[1438]: time="2025-10-12T23:50:19.162363534Z" level=info msg="runtime interface created" Oct 12 23:50:19.162547 containerd[1438]: time="2025-10-12T23:50:19.162372489Z" level=info msg="created NRI interface" Oct 12 23:50:19.162547 containerd[1438]: time="2025-10-12T23:50:19.162381043Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 12 23:50:19.162547 containerd[1438]: time="2025-10-12T23:50:19.162392407Z" level=info msg="Connect containerd service" Oct 12 23:50:19.162547 containerd[1438]: time="2025-10-12T23:50:19.162419153Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 12 23:50:19.163138 containerd[1438]: time="2025-10-12T23:50:19.163102401Z" 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" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.232964510Z" level=info msg="Start subscribing containerd event" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233074744Z" level=info msg="Start recovering state" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233171244Z" level=info msg="Start event monitor" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233186102Z" level=info msg="Start cni network conf syncer for default" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233195097Z" level=info msg="Start streaming server" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233213369Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233235537Z" level=info msg="runtime interface starting up..." Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233241400Z" level=info msg="starting plugins..." Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233257302Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233354043Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233399662Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 12 23:50:19.233481 containerd[1438]: time="2025-10-12T23:50:19.233456325Z" level=info msg="containerd successfully booted in 0.150526s" Oct 12 23:50:19.233573 systemd[1]: Started containerd.service - containerd container runtime. Oct 12 23:50:20.280163 sshd_keygen[1439]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 12 23:50:20.305452 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 12 23:50:20.310112 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 12 23:50:20.333257 systemd[1]: issuegen.service: Deactivated successfully. Oct 12 23:50:20.333479 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 12 23:50:20.336259 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 12 23:50:20.358765 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 12 23:50:20.362286 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 12 23:50:20.364544 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 12 23:50:20.366148 systemd[1]: Reached target getty.target - Login Prompts. Oct 12 23:50:20.502577 systemd-networkd[1351]: eth0: Gained IPv6LL Oct 12 23:50:20.507091 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 12 23:50:20.510092 systemd[1]: Reached target network-online.target - Network is Online. Oct 12 23:50:20.515106 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 12 23:50:20.519762 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 12 23:50:20.563028 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 12 23:50:20.564995 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 12 23:50:20.565316 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 12 23:50:20.568701 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 12 23:50:20.568950 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 12 23:50:20.572608 systemd[1]: Startup finished in 2.072s (kernel) + 4.101s (initrd) + 3.912s (userspace) = 10.086s. Oct 12 23:50:25.309712 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 12 23:50:25.312198 systemd[1]: Started sshd@0-10.0.0.2:22-10.0.0.1:34642.service - OpenSSH per-connection server daemon (10.0.0.1:34642). Oct 12 23:50:25.418855 sshd[1536]: Accepted publickey for core from 10.0.0.1 port 34642 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:25.421192 sshd-session[1536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:25.430461 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 12 23:50:25.433147 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 12 23:50:25.439430 systemd-logind[1422]: New session 1 of user core. Oct 12 23:50:25.460042 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 12 23:50:25.462871 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 12 23:50:25.481334 (systemd)[1541]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 12 23:50:25.483749 systemd-logind[1422]: New session c1 of user core. Oct 12 23:50:25.599262 systemd[1541]: Queued start job for default target default.target. Oct 12 23:50:25.622086 systemd[1541]: Created slice app.slice - User Application Slice. Oct 12 23:50:25.622160 systemd[1541]: Reached target paths.target - Paths. Oct 12 23:50:25.622519 systemd[1541]: Reached target timers.target - Timers. Oct 12 23:50:25.623758 systemd[1541]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 12 23:50:25.639885 systemd[1541]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 12 23:50:25.640023 systemd[1541]: Reached target sockets.target - Sockets. Oct 12 23:50:25.640067 systemd[1541]: Reached target basic.target - Basic System. Oct 12 23:50:25.640094 systemd[1541]: Reached target default.target - Main User Target. Oct 12 23:50:25.640123 systemd[1541]: Startup finished in 149ms. Oct 12 23:50:25.640416 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 12 23:50:25.641968 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 12 23:50:25.714381 systemd[1]: Started sshd@1-10.0.0.2:22-10.0.0.1:34198.service - OpenSSH per-connection server daemon (10.0.0.1:34198). Oct 12 23:50:25.784131 sshd[1552]: Accepted publickey for core from 10.0.0.1 port 34198 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:25.785585 sshd-session[1552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:25.793371 systemd-logind[1422]: New session 2 of user core. Oct 12 23:50:25.811237 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 12 23:50:25.863769 sshd[1555]: Connection closed by 10.0.0.1 port 34198 Oct 12 23:50:25.864106 sshd-session[1552]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:25.881461 systemd[1]: sshd@1-10.0.0.2:22-10.0.0.1:34198.service: Deactivated successfully. Oct 12 23:50:25.883119 systemd[1]: session-2.scope: Deactivated successfully. Oct 12 23:50:25.884522 systemd-logind[1422]: Session 2 logged out. Waiting for processes to exit. Oct 12 23:50:25.886976 systemd[1]: Started sshd@2-10.0.0.2:22-10.0.0.1:34200.service - OpenSSH per-connection server daemon (10.0.0.1:34200). Oct 12 23:50:25.887634 systemd-logind[1422]: Removed session 2. Oct 12 23:50:25.945181 sshd[1561]: Accepted publickey for core from 10.0.0.1 port 34200 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:25.946500 sshd-session[1561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:25.950279 systemd-logind[1422]: New session 3 of user core. Oct 12 23:50:25.962159 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 12 23:50:26.010326 sshd[1565]: Connection closed by 10.0.0.1 port 34200 Oct 12 23:50:26.010851 sshd-session[1561]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:26.020050 systemd[1]: sshd@2-10.0.0.2:22-10.0.0.1:34200.service: Deactivated successfully. Oct 12 23:50:26.021596 systemd[1]: session-3.scope: Deactivated successfully. Oct 12 23:50:26.022403 systemd-logind[1422]: Session 3 logged out. Waiting for processes to exit. Oct 12 23:50:26.024696 systemd[1]: Started sshd@3-10.0.0.2:22-10.0.0.1:34206.service - OpenSSH per-connection server daemon (10.0.0.1:34206). Oct 12 23:50:26.025254 systemd-logind[1422]: Removed session 3. Oct 12 23:50:26.083046 sshd[1571]: Accepted publickey for core from 10.0.0.1 port 34206 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:26.084711 sshd-session[1571]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:26.092630 systemd-logind[1422]: New session 4 of user core. Oct 12 23:50:26.100157 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 12 23:50:26.152723 sshd[1574]: Connection closed by 10.0.0.1 port 34206 Oct 12 23:50:26.152529 sshd-session[1571]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:26.162148 systemd[1]: sshd@3-10.0.0.2:22-10.0.0.1:34206.service: Deactivated successfully. Oct 12 23:50:26.164508 systemd[1]: session-4.scope: Deactivated successfully. Oct 12 23:50:26.165243 systemd-logind[1422]: Session 4 logged out. Waiting for processes to exit. Oct 12 23:50:26.167634 systemd[1]: Started sshd@4-10.0.0.2:22-10.0.0.1:34220.service - OpenSSH per-connection server daemon (10.0.0.1:34220). Oct 12 23:50:26.168217 systemd-logind[1422]: Removed session 4. Oct 12 23:50:26.226687 sshd[1580]: Accepted publickey for core from 10.0.0.1 port 34220 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:26.228013 sshd-session[1580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:26.232274 systemd-logind[1422]: New session 5 of user core. Oct 12 23:50:26.240187 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 12 23:50:26.296410 sudo[1584]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 12 23:50:26.296687 sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 12 23:50:26.309959 sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 12 23:50:26.311508 sshd[1583]: Connection closed by 10.0.0.1 port 34220 Oct 12 23:50:26.312114 sshd-session[1580]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:26.324396 systemd[1]: sshd@4-10.0.0.2:22-10.0.0.1:34220.service: Deactivated successfully. Oct 12 23:50:26.327587 systemd[1]: session-5.scope: Deactivated successfully. Oct 12 23:50:26.328360 systemd-logind[1422]: Session 5 logged out. Waiting for processes to exit. Oct 12 23:50:26.331101 systemd[1]: Started sshd@5-10.0.0.2:22-10.0.0.1:34228.service - OpenSSH per-connection server daemon (10.0.0.1:34228). Oct 12 23:50:26.331841 systemd-logind[1422]: Removed session 5. Oct 12 23:50:26.395375 sshd[1590]: Accepted publickey for core from 10.0.0.1 port 34228 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:26.396840 sshd-session[1590]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:26.402549 systemd-logind[1422]: New session 6 of user core. Oct 12 23:50:26.414233 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 12 23:50:26.466175 sudo[1595]: 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 Oct 12 23:50:26.466445 sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 12 23:50:26.474177 sudo[1595]: pam_unix(sudo:session): session closed for user root Oct 12 23:50:26.479372 sudo[1594]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 12 23:50:26.479646 sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 12 23:50:26.488769 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 12 23:50:26.531916 augenrules[1617]: No rules Oct 12 23:50:26.533177 systemd[1]: audit-rules.service: Deactivated successfully. Oct 12 23:50:26.534086 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 12 23:50:26.535090 sudo[1594]: pam_unix(sudo:session): session closed for user root Oct 12 23:50:26.536396 sshd[1593]: Connection closed by 10.0.0.1 port 34228 Oct 12 23:50:26.537226 sshd-session[1590]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:26.548142 systemd[1]: sshd@5-10.0.0.2:22-10.0.0.1:34228.service: Deactivated successfully. Oct 12 23:50:26.551489 systemd[1]: session-6.scope: Deactivated successfully. Oct 12 23:50:26.552162 systemd-logind[1422]: Session 6 logged out. Waiting for processes to exit. Oct 12 23:50:26.554810 systemd[1]: Started sshd@6-10.0.0.2:22-10.0.0.1:34240.service - OpenSSH per-connection server daemon (10.0.0.1:34240). Oct 12 23:50:26.555318 systemd-logind[1422]: Removed session 6. Oct 12 23:50:26.612704 sshd[1626]: Accepted publickey for core from 10.0.0.1 port 34240 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:26.614079 sshd-session[1626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:26.618873 systemd-logind[1422]: New session 7 of user core. Oct 12 23:50:26.633171 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 12 23:50:26.686731 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 12 23:50:26.715345 (dockerd)[1631]: 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 Oct 12 23:50:26.930826 dockerd[1631]: time="2025-10-12T23:50:26.930408686Z" level=info msg="Starting up" Oct 12 23:50:26.931381 dockerd[1631]: time="2025-10-12T23:50:26.931349085Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 12 23:50:26.942826 dockerd[1631]: time="2025-10-12T23:50:26.942704888Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 12 23:50:26.980076 dockerd[1631]: time="2025-10-12T23:50:26.980024334Z" level=info msg="Loading containers: start." Oct 12 23:50:26.988013 kernel: Initializing XFRM netlink socket Oct 12 23:50:27.178764 systemd-networkd[1351]: docker0: Link UP Oct 12 23:50:27.182905 dockerd[1631]: time="2025-10-12T23:50:27.182859807Z" level=info msg="Loading containers: done." Oct 12 23:50:27.197260 dockerd[1631]: time="2025-10-12T23:50:27.197148251Z" 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 Oct 12 23:50:27.197260 dockerd[1631]: time="2025-10-12T23:50:27.197239626Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 12 23:50:27.197422 dockerd[1631]: time="2025-10-12T23:50:27.197335812Z" level=info msg="Initializing buildkit" Oct 12 23:50:27.221706 dockerd[1631]: time="2025-10-12T23:50:27.221670042Z" level=info msg="Completed buildkit initialization" Oct 12 23:50:27.226584 dockerd[1631]: time="2025-10-12T23:50:27.226532077Z" level=info msg="Daemon has completed initialization" Oct 12 23:50:27.226584 dockerd[1631]: time="2025-10-12T23:50:27.226622931Z" level=info msg="API listen on /run/docker.sock" Oct 12 23:50:27.226905 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 12 23:50:27.234921 sshd[1629]: Connection closed by 10.0.0.1 port 34240 Oct 12 23:50:27.235424 sshd-session[1626]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:27.247192 systemd[1]: sshd@6-10.0.0.2:22-10.0.0.1:34240.service: Deactivated successfully. Oct 12 23:50:27.249474 systemd[1]: session-7.scope: Deactivated successfully. Oct 12 23:50:27.250314 systemd-logind[1422]: Session 7 logged out. Waiting for processes to exit. Oct 12 23:50:27.252805 systemd[1]: Started sshd@7-10.0.0.2:22-10.0.0.1:34266.service - OpenSSH per-connection server daemon (10.0.0.1:34266). Oct 12 23:50:27.253469 systemd-logind[1422]: Removed session 7. Oct 12 23:50:27.316874 sshd[1846]: Accepted publickey for core from 10.0.0.1 port 34266 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:27.318184 sshd-session[1846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:27.322703 systemd-logind[1422]: New session 8 of user core. Oct 12 23:50:27.334192 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 12 23:50:28.470999 systemd[1]: var-lib-docker-overlay2-70fda54b5c56bea8e11917b1f8928d23ce8c74f9932411154b4fa2a10b0e2489\x2dinit-merged.mount: Deactivated successfully. Oct 12 23:50:28.497062 systemd[1]: var-lib-docker-overlay2-70fda54b5c56bea8e11917b1f8928d23ce8c74f9932411154b4fa2a10b0e2489-merged.mount: Deactivated successfully. Oct 12 23:50:28.525740 containerd[1438]: time="2025-10-12T23:50:28.525581391Z" level=info msg="connecting to shim 781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb" address="unix:///run/containerd/s/af4bf17cf0abc330b1b2a0db07ba628a9ba0b8f32c181a71cd08238feb895240" namespace=moby protocol=ttrpc version=3 Oct 12 23:50:28.559176 systemd[1]: Started docker-781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb.scope - libcontainer container 781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb. Oct 12 23:50:28.572396 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 12 23:50:28.594535 kernel: docker0: port 1(vethd9f3b8f) entered blocking state Oct 12 23:50:28.594649 kernel: docker0: port 1(vethd9f3b8f) entered disabled state Oct 12 23:50:28.594667 kernel: vethd9f3b8f: entered allmulticast mode Oct 12 23:50:28.596036 kernel: vethd9f3b8f: entered promiscuous mode Oct 12 23:50:28.596007 systemd-networkd[1351]: vethd9f3b8f: Link UP Oct 12 23:50:28.603391 kernel: eth0: renamed from veth1523295 Oct 12 23:50:28.605650 kernel: docker0: port 1(vethd9f3b8f) entered blocking state Oct 12 23:50:28.605752 kernel: docker0: port 1(vethd9f3b8f) entered forwarding state Oct 12 23:50:28.605769 systemd-networkd[1351]: vethd9f3b8f: Gained carrier Oct 12 23:50:28.606144 systemd-networkd[1351]: docker0: Gained carrier Oct 12 23:50:28.631251 sshd[1849]: Connection closed by 10.0.0.1 port 34266 Oct 12 23:50:28.631680 sshd-session[1846]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:28.646067 systemd[1]: sshd@7-10.0.0.2:22-10.0.0.1:34266.service: Deactivated successfully. Oct 12 23:50:28.648329 systemd[1]: session-8.scope: Deactivated successfully. Oct 12 23:50:28.649494 systemd-logind[1422]: Session 8 logged out. Waiting for processes to exit. Oct 12 23:50:28.651845 systemd-logind[1422]: Removed session 8. Oct 12 23:50:28.653723 systemd[1]: Started sshd@8-10.0.0.2:22-10.0.0.1:34284.service - OpenSSH per-connection server daemon (10.0.0.1:34284). Oct 12 23:50:28.716480 sshd[1921]: Accepted publickey for core from 10.0.0.1 port 34284 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:28.717916 sshd-session[1921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:28.722753 systemd-logind[1422]: New session 9 of user core. Oct 12 23:50:28.728183 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 12 23:50:28.784704 sshd[1924]: Connection closed by 10.0.0.1 port 34284 Oct 12 23:50:28.785034 sshd-session[1921]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:28.795468 systemd[1]: sshd@8-10.0.0.2:22-10.0.0.1:34284.service: Deactivated successfully. Oct 12 23:50:28.798849 systemd[1]: session-9.scope: Deactivated successfully. Oct 12 23:50:28.799725 systemd-logind[1422]: Session 9 logged out. Waiting for processes to exit. Oct 12 23:50:28.802484 systemd[1]: Started sshd@9-10.0.0.2:22-10.0.0.1:34298.service - OpenSSH per-connection server daemon (10.0.0.1:34298). Oct 12 23:50:28.803127 systemd-logind[1422]: Removed session 9. Oct 12 23:50:28.862084 sshd[1930]: Accepted publickey for core from 10.0.0.1 port 34298 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:28.863576 sshd-session[1930]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:28.867874 systemd-logind[1422]: New session 10 of user core. Oct 12 23:50:28.885217 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 12 23:50:28.938928 sudo[1936]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1438 Oct 12 23:50:28.939239 sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 12 23:50:28.946542 containerd[1438]: time="2025-10-12T23:50:28.946484113Z" level=info msg="Stop CRI service" Oct 12 23:50:28.946815 dockerd[1631]: time="2025-10-12T23:50:28.946748456Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Oct 12 23:50:28.946815 dockerd[1631]: time="2025-10-12T23:50:28.946789146Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Oct 12 23:50:28.947129 dockerd[1631]: time="2025-10-12T23:50:28.947074936Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Oct 12 23:50:28.947129 dockerd[1631]: time="2025-10-12T23:50:28.947093737Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Oct 12 23:50:28.946823 sudo[1936]: pam_unix(sudo:session): session closed for user root Oct 12 23:50:28.948876 systemd[1]: containerd.service: Deactivated successfully. Oct 12 23:50:28.949138 systemd[1]: containerd.service: Unit process 1871 (containerd-shim) remains running after unit stopped. Oct 12 23:50:28.949398 sshd[1935]: Connection closed by 10.0.0.1 port 34298 Oct 12 23:50:28.949759 systemd[1]: containerd.service: Consumed 314ms CPU time, 120.5M memory peak. Oct 12 23:50:28.950295 sshd-session[1930]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:28.962274 systemd[1]: sshd@9-10.0.0.2:22-10.0.0.1:34298.service: Deactivated successfully. Oct 12 23:50:28.964653 systemd[1]: session-10.scope: Deactivated successfully. Oct 12 23:50:28.966605 systemd-logind[1422]: Session 10 logged out. Waiting for processes to exit. Oct 12 23:50:28.971581 systemd[1]: Started sshd@10-10.0.0.2:22-10.0.0.1:34322.service - OpenSSH per-connection server daemon (10.0.0.1:34322). Oct 12 23:50:28.972189 systemd-logind[1422]: Removed session 10. Oct 12 23:50:29.028400 sshd[1943]: Accepted publickey for core from 10.0.0.1 port 34322 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:29.031588 sshd-session[1943]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:29.039841 systemd-logind[1422]: New session 11 of user core. Oct 12 23:50:29.053202 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 12 23:50:29.110855 sshd[1946]: Connection closed by 10.0.0.1 port 34322 Oct 12 23:50:29.110768 sshd-session[1943]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:29.114752 systemd[1]: sshd@10-10.0.0.2:22-10.0.0.1:34322.service: Deactivated successfully. Oct 12 23:50:29.117790 systemd[1]: session-11.scope: Deactivated successfully. Oct 12 23:50:29.119109 systemd-logind[1422]: Session 11 logged out. Waiting for processes to exit. Oct 12 23:50:29.121203 systemd-logind[1422]: Removed session 11. Oct 12 23:50:29.783758 systemd-networkd[1351]: docker0: Gained IPv6LL Oct 12 23:50:30.551170 systemd-networkd[1351]: vethd9f3b8f: Gained IPv6LL Oct 12 23:50:34.183517 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Oct 12 23:50:34.183853 systemd[1]: Stopping docker.service - Docker Application Container Engine... Oct 12 23:50:34.184103 dockerd[1631]: time="2025-10-12T23:50:34.184048197Z" level=info msg="Processing signal 'terminated'" Oct 12 23:50:35.142179 systemd[1]: Started sshd@11-10.0.0.2:22-10.0.0.1:34392.service - OpenSSH per-connection server daemon (10.0.0.1:34392). Oct 12 23:50:35.200016 sshd[1952]: Accepted publickey for core from 10.0.0.1 port 34392 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:35.201434 sshd-session[1952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:35.205668 systemd-logind[1422]: New session 12 of user core. Oct 12 23:50:35.215150 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 12 23:50:35.269429 sshd[1955]: Connection closed by 10.0.0.1 port 34392 Oct 12 23:50:35.269935 sshd-session[1952]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:35.273452 systemd[1]: sshd@11-10.0.0.2:22-10.0.0.1:34392.service: Deactivated successfully. Oct 12 23:50:35.275016 systemd[1]: session-12.scope: Deactivated successfully. Oct 12 23:50:35.276603 systemd-logind[1422]: Session 12 logged out. Waiting for processes to exit. Oct 12 23:50:35.277798 systemd-logind[1422]: Removed session 12. Oct 12 23:50:41.294589 systemd[1]: Started sshd@12-10.0.0.2:22-10.0.0.1:60906.service - OpenSSH per-connection server daemon (10.0.0.1:60906). Oct 12 23:50:41.357910 sshd[1961]: Accepted publickey for core from 10.0.0.1 port 60906 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:41.359430 sshd-session[1961]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:41.363993 systemd-logind[1422]: New session 13 of user core. Oct 12 23:50:41.374193 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 12 23:50:41.429598 sshd[1964]: Connection closed by 10.0.0.1 port 60906 Oct 12 23:50:41.429943 sshd-session[1961]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:41.433451 systemd[1]: sshd@12-10.0.0.2:22-10.0.0.1:60906.service: Deactivated successfully. Oct 12 23:50:41.435813 systemd[1]: session-13.scope: Deactivated successfully. Oct 12 23:50:41.436701 systemd-logind[1422]: Session 13 logged out. Waiting for processes to exit. Oct 12 23:50:41.438126 systemd-logind[1422]: Removed session 13. Oct 12 23:50:47.463002 systemd[1]: Started sshd@13-10.0.0.2:22-10.0.0.1:34080.service - OpenSSH per-connection server daemon (10.0.0.1:34080). Oct 12 23:50:47.512437 sshd[1970]: Accepted publickey for core from 10.0.0.1 port 34080 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:47.513748 sshd-session[1970]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:47.521085 systemd-logind[1422]: New session 14 of user core. Oct 12 23:50:47.527139 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 12 23:50:47.580958 sshd[1973]: Connection closed by 10.0.0.1 port 34080 Oct 12 23:50:47.581285 sshd-session[1970]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:47.584747 systemd[1]: sshd@13-10.0.0.2:22-10.0.0.1:34080.service: Deactivated successfully. Oct 12 23:50:47.586363 systemd[1]: session-14.scope: Deactivated successfully. Oct 12 23:50:47.587161 systemd-logind[1422]: Session 14 logged out. Waiting for processes to exit. Oct 12 23:50:47.588556 systemd-logind[1422]: Removed session 14. Oct 12 23:50:49.185016 dockerd[1631]: time="2025-10-12T23:50:49.184926661Z" level=error msg="Force shutdown daemon" Oct 12 23:50:49.185552 dockerd[1631]: time="2025-10-12T23:50:49.185099520Z" level=info msg="Daemon shutdown complete" Oct 12 23:50:49.187352 systemd[1]: docker.service: Deactivated successfully. Oct 12 23:50:49.187657 systemd[1]: Stopped docker.service - Docker Application Container Engine. Oct 12 23:50:49.187731 systemd[1]: docker.service: Consumed 1.006s CPU time, 122.9M memory peak. Oct 12 23:50:49.188768 systemd[1]: docker.socket: Deactivated successfully. Oct 12 23:50:49.189114 systemd[1]: Closed docker.socket - Docker Socket for the API. Oct 12 23:50:49.189155 systemd[1]: Stopping docker.socket - Docker Socket for the API... Oct 12 23:50:49.190538 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 12 23:50:49.190832 systemd[1]: containerd.service: Found left-over process 1871 (containerd-shim) in control group while starting unit. Ignoring. Oct 12 23:50:49.190841 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 12 23:50:49.200383 systemd[1]: Starting containerd.service - containerd container runtime... Oct 12 23:50:49.200668 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 12 23:50:49.213474 systemd[1]: containerd.service: Found left-over process 1871 (containerd-shim) in control group while starting unit. Ignoring. Oct 12 23:50:49.213486 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 12 23:50:49.217553 (ntainerd)[1984]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 12 23:50:49.230430 containerd[1984]: time="2025-10-12T23:50:49Z" 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 Oct 12 23:50:49.230822 containerd[1984]: time="2025-10-12T23:50:49.230787382Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 12 23:50:49.238693 containerd[1984]: time="2025-10-12T23:50:49.238644738Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.685µs" Oct 12 23:50:49.238693 containerd[1984]: time="2025-10-12T23:50:49.238680918Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 12 23:50:49.238693 containerd[1984]: time="2025-10-12T23:50:49.238698448Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 12 23:50:49.238808 containerd[1984]: time="2025-10-12T23:50:49.238728145Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 12 23:50:49.238808 containerd[1984]: time="2025-10-12T23:50:49.238748196Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 12 23:50:49.238961 containerd[1984]: time="2025-10-12T23:50:49.238928539Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239016 containerd[1984]: time="2025-10-12T23:50:49.238960637Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239016 containerd[1984]: time="2025-10-12T23:50:49.238971284Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239210 containerd[1984]: time="2025-10-12T23:50:49.239176160Z" 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 Oct 12 23:50:49.239210 containerd[1984]: time="2025-10-12T23:50:49.239196412Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239210 containerd[1984]: time="2025-10-12T23:50:49.239208219Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239275 containerd[1984]: time="2025-10-12T23:50:49.239216143Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239275 containerd[1984]: time="2025-10-12T23:50:49.239235354Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239327 containerd[1984]: time="2025-10-12T23:50:49.239313238Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 12 23:50:49.239357 containerd[1984]: time="2025-10-12T23:50:49.239344496Z" 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 Oct 12 23:50:49.239378 containerd[1984]: time="2025-10-12T23:50:49.239356663Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 12 23:50:49.239396 containerd[1984]: time="2025-10-12T23:50:49.239388961Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 12 23:50:49.239611 containerd[1984]: time="2025-10-12T23:50:49.239581951Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 12 23:50:49.239611 containerd[1984]: time="2025-10-12T23:50:49.239606285Z" level=info msg="metadata content store policy set" policy=shared Oct 12 23:50:49.239730 containerd[1984]: time="2025-10-12T23:50:49.239716108Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 12 23:50:49.239770 containerd[1984]: time="2025-10-12T23:50:49.239757331Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 12 23:50:49.239791 containerd[1984]: time="2025-10-12T23:50:49.239775342Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 12 23:50:49.239811 containerd[1984]: time="2025-10-12T23:50:49.239792872Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 12 23:50:49.239811 containerd[1984]: time="2025-10-12T23:50:49.239804918Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 12 23:50:49.239851 containerd[1984]: time="2025-10-12T23:50:49.239815765Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 12 23:50:49.239851 containerd[1984]: time="2025-10-12T23:50:49.239827011Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 12 23:50:49.239851 containerd[1984]: time="2025-10-12T23:50:49.239838017Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 12 23:50:49.239851 containerd[1984]: time="2025-10-12T23:50:49.239848223Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 12 23:50:49.239919 containerd[1984]: time="2025-10-12T23:50:49.239858269Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 12 23:50:49.239919 containerd[1984]: time="2025-10-12T23:50:49.239867314Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 12 23:50:49.239919 containerd[1984]: time="2025-10-12T23:50:49.239878880Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 12 23:50:49.240296 containerd[1984]: time="2025-10-12T23:50:49.240189137Z" level=info msg="connecting to shim 781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb" address="unix:///run/containerd/s/af4bf17cf0abc330b1b2a0db07ba628a9ba0b8f32c181a71cd08238feb895240" namespace=moby protocol=ttrpc version=3 Oct 12 23:50:49.248078 containerd[1984]: time="2025-10-12T23:50:49.248047493Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 12 23:50:49.248188 containerd[1984]: time="2025-10-12T23:50:49.248172084Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 12 23:50:49.248267 containerd[1984]: time="2025-10-12T23:50:49.248252690Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 12 23:50:49.248325 containerd[1984]: time="2025-10-12T23:50:49.248312404Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 12 23:50:49.248380 containerd[1984]: time="2025-10-12T23:50:49.248367716Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 12 23:50:49.248442 containerd[1984]: time="2025-10-12T23:50:49.248429911Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 12 23:50:49.248506 containerd[1984]: time="2025-10-12T23:50:49.248493067Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 12 23:50:49.248576 containerd[1984]: time="2025-10-12T23:50:49.248561666Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 12 23:50:49.248637 containerd[1984]: time="2025-10-12T23:50:49.248623341Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 12 23:50:49.248696 containerd[1984]: time="2025-10-12T23:50:49.248683856Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 12 23:50:49.248762 containerd[1984]: time="2025-10-12T23:50:49.248739847Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 12 23:50:49.248878 containerd[1984]: time="2025-10-12T23:50:49.248862037Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 12 23:50:49.248939 containerd[1984]: time="2025-10-12T23:50:49.248927674Z" level=info msg="Start snapshots syncer" Oct 12 23:50:49.249033 containerd[1984]: time="2025-10-12T23:50:49.249018526Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 12 23:50:49.249306 containerd[1984]: time="2025-10-12T23:50:49.249269909Z" 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\"}" Oct 12 23:50:49.249437 containerd[1984]: time="2025-10-12T23:50:49.249420395Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 12 23:50:49.249578 containerd[1984]: time="2025-10-12T23:50:49.249559955Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 12 23:50:49.249679 containerd[1984]: time="2025-10-12T23:50:49.249663454Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 12 23:50:49.249774 containerd[1984]: time="2025-10-12T23:50:49.249731452Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 12 23:50:49.249847 containerd[1984]: time="2025-10-12T23:50:49.249831549Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 12 23:50:49.249900 containerd[1984]: time="2025-10-12T23:50:49.249887701Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 12 23:50:49.249950 containerd[1984]: time="2025-10-12T23:50:49.249939531Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 12 23:50:49.250017 containerd[1984]: time="2025-10-12T23:50:49.250004568Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 12 23:50:49.250083 containerd[1984]: time="2025-10-12T23:50:49.250068684Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 12 23:50:49.250154 containerd[1984]: time="2025-10-12T23:50:49.250142006Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 12 23:50:49.250206 containerd[1984]: time="2025-10-12T23:50:49.250194156Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 12 23:50:49.250265 containerd[1984]: time="2025-10-12T23:50:49.250252789Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 12 23:50:49.250358 containerd[1984]: time="2025-10-12T23:50:49.250342200Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 12 23:50:49.250432 containerd[1984]: time="2025-10-12T23:50:49.250417483Z" 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 Oct 12 23:50:49.250480 containerd[1984]: time="2025-10-12T23:50:49.250468592Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:50:49.250535 containerd[1984]: time="2025-10-12T23:50:49.250521983Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:50:49.250587 containerd[1984]: time="2025-10-12T23:50:49.250575933Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 12 23:50:49.250656 containerd[1984]: time="2025-10-12T23:50:49.250637488Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 12 23:50:49.250719 containerd[1984]: time="2025-10-12T23:50:49.250705927Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 12 23:50:49.250786 containerd[1984]: time="2025-10-12T23:50:49.250774246Z" level=info msg="runtime interface created" Oct 12 23:50:49.250829 containerd[1984]: time="2025-10-12T23:50:49.250819432Z" level=info msg="created NRI interface" Oct 12 23:50:49.250882 containerd[1984]: time="2025-10-12T23:50:49.250869661Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 12 23:50:49.250934 containerd[1984]: time="2025-10-12T23:50:49.250921370Z" level=info msg="Connect containerd service" Oct 12 23:50:49.251020 containerd[1984]: time="2025-10-12T23:50:49.251006699Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 12 23:50:49.251624 containerd[1984]: time="2025-10-12T23:50:49.251595714Z" 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" Oct 12 23:50:49.260231 containerd[1984]: time="2025-10-12T23:50:49.260176642Z" level=info msg="Start subscribing containerd event" Oct 12 23:50:49.260298 containerd[1984]: time="2025-10-12T23:50:49.260238437Z" level=info msg="Start recovering state" Oct 12 23:50:49.260319 containerd[1984]: time="2025-10-12T23:50:49.260302073Z" level=info msg="Start event monitor" Oct 12 23:50:49.260337 containerd[1984]: time="2025-10-12T23:50:49.260317802Z" level=info msg="Start cni network conf syncer for default" Oct 12 23:50:49.260337 containerd[1984]: time="2025-10-12T23:50:49.260327327Z" level=info msg="Start streaming server" Oct 12 23:50:49.260337 containerd[1984]: time="2025-10-12T23:50:49.260335132Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 12 23:50:49.260396 containerd[1984]: time="2025-10-12T23:50:49.260342056Z" level=info msg="runtime interface starting up..." Oct 12 23:50:49.260396 containerd[1984]: time="2025-10-12T23:50:49.260347859Z" level=info msg="starting plugins..." Oct 12 23:50:49.260396 containerd[1984]: time="2025-10-12T23:50:49.260355343Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 12 23:50:49.260552 containerd[1984]: time="2025-10-12T23:50:49.260531404Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 12 23:50:49.260667 containerd[1984]: time="2025-10-12T23:50:49.260645068Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 12 23:50:49.260789 containerd[1984]: time="2025-10-12T23:50:49.260773862Z" level=info msg="containerd successfully booted in 0.030513s" Oct 12 23:50:49.260862 systemd[1]: Started containerd.service - containerd container runtime. Oct 12 23:50:49.263335 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 12 23:50:49.283397 (dockerd)[2012]: 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 Oct 12 23:50:49.309137 dockerd[2012]: time="2025-10-12T23:50:49.309079936Z" level=info msg="Starting up" Oct 12 23:50:49.309667 dockerd[2012]: time="2025-10-12T23:50:49.309648499Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 12 23:50:49.319302 dockerd[2012]: time="2025-10-12T23:50:49.319265617Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 12 23:50:49.343043 dockerd[2012]: time="2025-10-12T23:50:49.343003778Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Oct 12 23:50:49.350383 dockerd[2012]: time="2025-10-12T23:50:49.350325508Z" level=info msg="Loading containers: start." Oct 12 23:50:53.596053 systemd[1]: Started sshd@14-10.0.0.2:22-10.0.0.1:34102.service - OpenSSH per-connection server daemon (10.0.0.1:34102). Oct 12 23:50:53.661993 sshd[2046]: Accepted publickey for core from 10.0.0.1 port 34102 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:53.663172 sshd-session[2046]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:53.667440 systemd-logind[1422]: New session 15 of user core. Oct 12 23:50:53.688181 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 12 23:50:53.740781 sshd[2049]: Connection closed by 10.0.0.1 port 34102 Oct 12 23:50:53.741105 sshd-session[2046]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:53.755065 systemd[1]: sshd@14-10.0.0.2:22-10.0.0.1:34102.service: Deactivated successfully. Oct 12 23:50:53.757333 systemd[1]: session-15.scope: Deactivated successfully. Oct 12 23:50:53.758111 systemd-logind[1422]: Session 15 logged out. Waiting for processes to exit. Oct 12 23:50:53.760153 systemd[1]: Started sshd@15-10.0.0.2:22-10.0.0.1:34108.service - OpenSSH per-connection server daemon (10.0.0.1:34108). Oct 12 23:50:53.760697 systemd-logind[1422]: Removed session 15. Oct 12 23:50:53.822910 sshd[2055]: Accepted publickey for core from 10.0.0.1 port 34108 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:53.824158 sshd-session[2055]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:53.827749 systemd-logind[1422]: New session 16 of user core. Oct 12 23:50:53.835121 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 12 23:50:53.888240 sshd[2058]: Connection closed by 10.0.0.1 port 34108 Oct 12 23:50:53.888662 sshd-session[2055]: pam_unix(sshd:session): session closed for user core Oct 12 23:50:53.905105 systemd[1]: sshd@15-10.0.0.2:22-10.0.0.1:34108.service: Deactivated successfully. Oct 12 23:50:53.908176 systemd[1]: session-16.scope: Deactivated successfully. Oct 12 23:50:53.908820 systemd-logind[1422]: Session 16 logged out. Waiting for processes to exit. Oct 12 23:50:53.910915 systemd[1]: Started sshd@16-10.0.0.2:22-10.0.0.1:34124.service - OpenSSH per-connection server daemon (10.0.0.1:34124). Oct 12 23:50:53.911478 systemd-logind[1422]: Removed session 16. Oct 12 23:50:53.967386 sshd[2064]: Accepted publickey for core from 10.0.0.1 port 34124 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:50:53.968461 sshd-session[2064]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:50:53.972962 systemd-logind[1422]: New session 17 of user core. Oct 12 23:50:53.984145 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 12 23:50:59.372609 dockerd[2012]: time="2025-10-12T23:50:59.372396512Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb Oct 12 23:50:59.417467 systemd[1]: docker-781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb.scope: Deactivated successfully. Oct 12 23:50:59.433130 dockerd[2012]: time="2025-10-12T23:50:59.433066240Z" level=info msg="ignoring event" container=781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 12 23:50:59.434016 containerd[1984]: time="2025-10-12T23:50:59.433962828Z" level=info msg="shim disconnected" id=781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb namespace=moby Oct 12 23:50:59.434655 containerd[1984]: time="2025-10-12T23:50:59.434326817Z" level=warning msg="cleaning up after shim disconnected" id=781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb namespace=moby Oct 12 23:50:59.434655 containerd[1984]: time="2025-10-12T23:50:59.434515353Z" level=info msg="cleaning up dead shim" namespace=moby Oct 12 23:50:59.447598 systemd[1]: var-lib-docker-overlay2-70fda54b5c56bea8e11917b1f8928d23ce8c74f9932411154b4fa2a10b0e2489-merged.mount: Deactivated successfully. Oct 12 23:50:59.935240 kernel: docker0: port 1(vethd9f3b8f) entered disabled state Oct 12 23:50:59.935351 kernel: vethd9f3b8f (unregistering): left allmulticast mode Oct 12 23:50:59.933722 systemd-networkd[1351]: vethd9f3b8f: Link DOWN Oct 12 23:50:59.933727 systemd-networkd[1351]: vethd9f3b8f: Lost carrier Oct 12 23:50:59.934498 systemd[1]: run-docker-netns-48f3d7f0fd50.mount: Deactivated successfully. Oct 12 23:50:59.937400 kernel: vethd9f3b8f (unregistering): left promiscuous mode Oct 12 23:50:59.937450 kernel: docker0: port 1(vethd9f3b8f) entered disabled state Oct 12 23:50:59.938656 systemd-networkd[1351]: docker0: Lost carrier Oct 12 23:50:59.940736 dockerd[2012]: time="2025-10-12T23:50:59.940534752Z" level=info msg="Removing stale sandbox 48f3d7f0fd50f97afc70586fe7e8dcebe5d2cb7de8b5addb1992580310add959 (781fe94b05e976d9bf7ab9cf0f689364f4b17649a4dbeded957c34bbb7aa16fb)" Oct 12 23:50:59.943613 dockerd[2012]: time="2025-10-12T23:50:59.943576821Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Oct 12 23:50:59.943692 dockerd[2012]: time="2025-10-12T23:50:59.943645521Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint f5c8784452b615b8ac1e3bc4d29c4a82a132ae83a525df722c3d9a5cc4c8c7bc 295b26f826b2ff90c16736d746bb0e5ea7fde02d4b95a10c023ec9c90dfa8d51], retrying...." Oct 12 23:50:59.947474 systemd[1]: run-docker-netns-48f3d7f0fd50.mount: Deactivated successfully. Oct 12 23:51:00.165187 dockerd[2012]: time="2025-10-12T23:51:00.165133557Z" level=warning msg="error locating sandbox id 48f3d7f0fd50f97afc70586fe7e8dcebe5d2cb7de8b5addb1992580310add959: sandbox 48f3d7f0fd50f97afc70586fe7e8dcebe5d2cb7de8b5addb1992580310add959 not found" Oct 12 23:51:00.165308 dockerd[2012]: time="2025-10-12T23:51:00.165282039Z" level=info msg="Loading containers: done." Oct 12 23:51:00.176057 dockerd[2012]: time="2025-10-12T23:51:00.175955349Z" 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 Oct 12 23:51:00.176221 dockerd[2012]: time="2025-10-12T23:51:00.176101150Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 12 23:51:00.176221 dockerd[2012]: time="2025-10-12T23:51:00.176136440Z" level=info msg="Initializing buildkit" Oct 12 23:51:00.190843 dockerd[2012]: time="2025-10-12T23:51:00.190731208Z" level=info msg="Completed buildkit initialization" Oct 12 23:51:00.197875 dockerd[2012]: time="2025-10-12T23:51:00.197819674Z" level=info msg="Daemon has completed initialization" Oct 12 23:51:00.198011 dockerd[2012]: time="2025-10-12T23:51:00.197913180Z" level=info msg="API listen on /run/docker.sock" Oct 12 23:51:00.198150 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 12 23:51:00.205111 sshd[2067]: Connection closed by 10.0.0.1 port 34124 Oct 12 23:51:00.205594 sshd-session[2064]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:00.220894 systemd[1]: sshd@16-10.0.0.2:22-10.0.0.1:34124.service: Deactivated successfully. Oct 12 23:51:00.223136 systemd[1]: session-17.scope: Deactivated successfully. Oct 12 23:51:00.225656 systemd-logind[1422]: Session 17 logged out. Waiting for processes to exit. Oct 12 23:51:00.228854 systemd[1]: Started sshd@17-10.0.0.2:22-10.0.0.1:51642.service - OpenSSH per-connection server daemon (10.0.0.1:51642). Oct 12 23:51:00.229599 systemd-logind[1422]: Removed session 17. Oct 12 23:51:00.292639 sshd[2316]: Accepted publickey for core from 10.0.0.1 port 51642 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:00.294199 sshd-session[2316]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:00.299266 systemd-logind[1422]: New session 18 of user core. Oct 12 23:51:00.309199 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 12 23:51:00.460062 systemd[1]: var-lib-docker-overlay2-203bebc8d33319506ccb049242fbef4ed826c8549a1c8337e5bd6615c1f60315\x2dinit-merged.mount: Deactivated successfully. Oct 12 23:51:00.507097 containerd[1984]: time="2025-10-12T23:51:00.507048458Z" level=info msg="connecting to shim f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70" address="unix:///run/containerd/s/4acb0a8afb01f3b8c4539713275300dbc65aef5c39e190e9df7a0862c9a8161c" namespace=moby protocol=ttrpc version=3 Oct 12 23:51:00.532331 systemd[1]: Started docker-f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70.scope - libcontainer container f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70. Oct 12 23:51:00.542654 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 12 23:51:00.556638 kernel: docker0: port 1(veth12743b4) entered blocking state Oct 12 23:51:00.556746 kernel: docker0: port 1(veth12743b4) entered disabled state Oct 12 23:51:00.556765 kernel: veth12743b4: entered allmulticast mode Oct 12 23:51:00.558071 kernel: veth12743b4: entered promiscuous mode Oct 12 23:51:00.558278 systemd-networkd[1351]: veth12743b4: Link UP Oct 12 23:51:00.567000 kernel: eth0: renamed from veth81d0f01 Oct 12 23:51:00.569202 kernel: docker0: port 1(veth12743b4) entered blocking state Oct 12 23:51:00.569255 kernel: docker0: port 1(veth12743b4) entered forwarding state Oct 12 23:51:00.569353 systemd-networkd[1351]: veth12743b4: Gained carrier Oct 12 23:51:00.569686 systemd-networkd[1351]: docker0: Gained carrier Oct 12 23:51:00.589319 sshd[2319]: Connection closed by 10.0.0.1 port 51642 Oct 12 23:51:00.590033 sshd-session[2316]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:00.596943 systemd[1]: sshd@17-10.0.0.2:22-10.0.0.1:51642.service: Deactivated successfully. Oct 12 23:51:00.598660 systemd[1]: session-18.scope: Deactivated successfully. Oct 12 23:51:00.600059 systemd-logind[1422]: Session 18 logged out. Waiting for processes to exit. Oct 12 23:51:00.602336 systemd[1]: Started sshd@18-10.0.0.2:22-10.0.0.1:51658.service - OpenSSH per-connection server daemon (10.0.0.1:51658). Oct 12 23:51:00.603278 systemd-logind[1422]: Removed session 18. Oct 12 23:51:00.670391 sshd[2387]: Accepted publickey for core from 10.0.0.1 port 51658 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:00.671691 sshd-session[2387]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:00.676133 systemd-logind[1422]: New session 19 of user core. Oct 12 23:51:00.692177 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 12 23:51:00.745334 sshd[2390]: Connection closed by 10.0.0.1 port 51658 Oct 12 23:51:00.746272 sshd-session[2387]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:00.758198 systemd[1]: sshd@18-10.0.0.2:22-10.0.0.1:51658.service: Deactivated successfully. Oct 12 23:51:00.759583 systemd[1]: session-19.scope: Deactivated successfully. Oct 12 23:51:00.760232 systemd-logind[1422]: Session 19 logged out. Waiting for processes to exit. Oct 12 23:51:00.762086 systemd[1]: Started sshd@19-10.0.0.2:22-10.0.0.1:51672.service - OpenSSH per-connection server daemon (10.0.0.1:51672). Oct 12 23:51:00.762960 systemd-logind[1422]: Removed session 19. Oct 12 23:51:00.829711 sshd[2396]: Accepted publickey for core from 10.0.0.1 port 51672 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:00.830971 sshd-session[2396]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:00.838634 systemd-logind[1422]: New session 20 of user core. Oct 12 23:51:00.858140 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 12 23:51:00.911015 sudo[2400]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1984 Oct 12 23:51:00.911280 sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 12 23:51:00.914301 sudo[2400]: pam_unix(sudo:session): session closed for user root Oct 12 23:51:00.916259 sshd[2399]: Connection closed by 10.0.0.1 port 51672 Oct 12 23:51:00.917286 dockerd[2012]: time="2025-10-12T23:51:00.916171026Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Oct 12 23:51:00.917286 dockerd[2012]: time="2025-10-12T23:51:00.916205675Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Oct 12 23:51:00.917286 dockerd[2012]: time="2025-10-12T23:51:00.916251088Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Oct 12 23:51:00.917286 dockerd[2012]: time="2025-10-12T23:51:00.916280616Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Oct 12 23:51:00.916625 sshd-session[2396]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:00.928874 systemd[1]: containerd.service: Deactivated successfully. Oct 12 23:51:00.928990 systemd[1]: containerd.service: Unit process 2337 (containerd-shim) remains running after unit stopped. Oct 12 23:51:00.929208 systemd[1]: containerd.service: Consumed 169ms CPU time, 121.9M memory peak. Oct 12 23:51:00.929630 systemd[1]: sshd@19-10.0.0.2:22-10.0.0.1:51672.service: Deactivated successfully. Oct 12 23:51:00.932301 systemd[1]: session-20.scope: Deactivated successfully. Oct 12 23:51:00.933519 systemd-logind[1422]: Session 20 logged out. Waiting for processes to exit. Oct 12 23:51:00.936205 systemd[1]: Started sshd@20-10.0.0.2:22-10.0.0.1:51692.service - OpenSSH per-connection server daemon (10.0.0.1:51692). Oct 12 23:51:00.937176 systemd-logind[1422]: Removed session 20. Oct 12 23:51:00.998144 sshd[2407]: Accepted publickey for core from 10.0.0.1 port 51692 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:00.999745 sshd-session[2407]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:01.004067 systemd-logind[1422]: New session 21 of user core. Oct 12 23:51:01.022208 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 12 23:51:01.076329 sshd[2410]: Connection closed by 10.0.0.1 port 51692 Oct 12 23:51:01.076648 sshd-session[2407]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:01.080682 systemd[1]: sshd@20-10.0.0.2:22-10.0.0.1:51692.service: Deactivated successfully. Oct 12 23:51:01.082132 systemd[1]: session-21.scope: Deactivated successfully. Oct 12 23:51:01.083770 systemd-logind[1422]: Session 21 logged out. Waiting for processes to exit. Oct 12 23:51:01.084673 systemd-logind[1422]: Removed session 21. Oct 12 23:51:02.550202 systemd-networkd[1351]: veth12743b4: Gained IPv6LL Oct 12 23:51:04.489602 update_engine[1427]: I20251012 23:51:04.489466 1427 update_attempter.cc:509] Updating boot flags... Oct 12 23:51:05.933609 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Oct 12 23:51:05.933944 systemd[1]: Stopping docker.service - Docker Application Container Engine... Oct 12 23:51:05.934261 dockerd[2012]: time="2025-10-12T23:51:05.934181640Z" level=info msg="Processing signal 'terminated'" Oct 12 23:51:07.102087 systemd[1]: Started sshd@21-10.0.0.2:22-10.0.0.1:42692.service - OpenSSH per-connection server daemon (10.0.0.1:42692). Oct 12 23:51:07.166480 sshd[2434]: Accepted publickey for core from 10.0.0.1 port 42692 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:07.167847 sshd-session[2434]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:07.174621 systemd-logind[1422]: New session 22 of user core. Oct 12 23:51:07.182165 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 12 23:51:07.239064 sshd[2437]: Connection closed by 10.0.0.1 port 42692 Oct 12 23:51:07.239392 sshd-session[2434]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:07.243108 systemd[1]: sshd@21-10.0.0.2:22-10.0.0.1:42692.service: Deactivated successfully. Oct 12 23:51:07.246564 systemd[1]: session-22.scope: Deactivated successfully. Oct 12 23:51:07.247324 systemd-logind[1422]: Session 22 logged out. Waiting for processes to exit. Oct 12 23:51:07.248778 systemd-logind[1422]: Removed session 22. Oct 12 23:51:07.936577 dockerd[2012]: time="2025-10-12T23:51:07.936319885Z" level=error msg="Error sending stop (signal 15) to container" container=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 error="Cannot kill container f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Oct 12 23:51:07.936577 dockerd[2012]: time="2025-10-12T23:51:07.936385696Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 Oct 12 23:51:13.252682 systemd[1]: Started sshd@22-10.0.0.2:22-10.0.0.1:42706.service - OpenSSH per-connection server daemon (10.0.0.1:42706). Oct 12 23:51:13.302792 sshd[2443]: Accepted publickey for core from 10.0.0.1 port 42706 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:13.304544 sshd-session[2443]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:13.309101 systemd-logind[1422]: New session 23 of user core. Oct 12 23:51:13.321210 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 12 23:51:13.374095 sshd[2446]: Connection closed by 10.0.0.1 port 42706 Oct 12 23:51:13.374394 sshd-session[2443]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:13.377855 systemd[1]: sshd@22-10.0.0.2:22-10.0.0.1:42706.service: Deactivated successfully. Oct 12 23:51:13.380033 systemd[1]: session-23.scope: Deactivated successfully. Oct 12 23:51:13.381922 systemd-logind[1422]: Session 23 logged out. Waiting for processes to exit. Oct 12 23:51:13.382952 systemd-logind[1422]: Removed session 23. Oct 12 23:51:17.937799 dockerd[2012]: time="2025-10-12T23:51:17.937537337Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 error="context deadline exceeded" Oct 12 23:51:17.938817 systemd[1]: docker-f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70.scope: Deactivated successfully. Oct 12 23:51:19.390058 systemd[1]: Started sshd@23-10.0.0.2:22-10.0.0.1:54622.service - OpenSSH per-connection server daemon (10.0.0.1:54622). Oct 12 23:51:19.450701 sshd[2452]: Accepted publickey for core from 10.0.0.1 port 54622 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:19.452805 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:19.461723 systemd-logind[1422]: New session 24 of user core. Oct 12 23:51:19.482210 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 12 23:51:19.537712 sshd[2455]: Connection closed by 10.0.0.1 port 54622 Oct 12 23:51:19.537569 sshd-session[2452]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:19.541649 systemd[1]: sshd@23-10.0.0.2:22-10.0.0.1:54622.service: Deactivated successfully. Oct 12 23:51:19.543964 systemd[1]: session-24.scope: Deactivated successfully. Oct 12 23:51:19.550789 systemd-logind[1422]: Session 24 logged out. Waiting for processes to exit. Oct 12 23:51:19.552544 systemd-logind[1422]: Removed session 24. Oct 12 23:51:20.935650 dockerd[2012]: time="2025-10-12T23:51:20.935336668Z" level=error msg="Force shutdown daemon" Oct 12 23:51:20.935650 dockerd[2012]: time="2025-10-12T23:51:20.935446276Z" level=info msg="Daemon shutdown complete" Oct 12 23:51:20.939520 systemd[1]: docker.service: Deactivated successfully. Oct 12 23:51:20.939840 systemd[1]: Stopped docker.service - Docker Application Container Engine. Oct 12 23:51:20.940312 systemd[1]: docker.socket: Deactivated successfully. Oct 12 23:51:20.940622 systemd[1]: Closed docker.socket - Docker Socket for the API. Oct 12 23:51:20.940660 systemd[1]: Stopping docker.socket - Docker Socket for the API... Oct 12 23:51:20.942017 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 12 23:51:20.942183 systemd[1]: containerd.service: Found left-over process 2337 (containerd-shim) in control group while starting unit. Ignoring. Oct 12 23:51:20.942186 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 12 23:51:20.954401 systemd[1]: Starting containerd.service - containerd container runtime... Oct 12 23:51:20.956227 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 12 23:51:20.981340 systemd[1]: containerd.service: Found left-over process 2337 (containerd-shim) in control group while starting unit. Ignoring. Oct 12 23:51:20.981481 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 12 23:51:20.992626 (ntainerd)[2465]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 12 23:51:21.022693 containerd[2465]: time="2025-10-12T23:51: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 Oct 12 23:51:21.023529 containerd[2465]: time="2025-10-12T23:51:21.023442750Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031379803Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.361µs" Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031423326Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031444088Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031482851Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031495532Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031690546Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031721708Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031733749Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031950325Z" 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 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031966166Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.031999088Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032245 containerd[2465]: time="2025-10-12T23:51:21.032009249Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032028210Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032117857Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032137978Z" 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 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032149139Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032187382Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032429239Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032449601Z" level=info msg="metadata content store policy set" policy=shared Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032573370Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 12 23:51:21.032597 containerd[2465]: time="2025-10-12T23:51:21.032597171Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032610052Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032621613Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032643055Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032662856Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032674177Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032685098Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032695018Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032707779Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032716820Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 12 23:51:21.032763 containerd[2465]: time="2025-10-12T23:51:21.032731541Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 12 23:51:21.033089 containerd[2465]: time="2025-10-12T23:51:21.032914594Z" level=info msg="connecting to shim f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70" address="unix:///run/containerd/s/4acb0a8afb01f3b8c4539713275300dbc65aef5c39e190e9df7a0862c9a8161c" namespace=moby protocol=ttrpc version=3 Oct 12 23:51:21.043711 containerd[2465]: time="2025-10-12T23:51:21.043668851Z" level=info msg="cleaning leaked shim process" id=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 namespace=moby Oct 12 23:51:21.056837 containerd[2465]: time="2025-10-12T23:51:21.056731235Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Oct 12 23:51:21.057294 containerd[2465]: time="2025-10-12T23:51:21.057246952Z" level=info msg="shim disconnected" id=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 namespace=moby Oct 12 23:51:21.057440 containerd[2465]: time="2025-10-12T23:51:21.057384482Z" level=warning msg="cleaning up after shim disconnected" id=f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70 namespace=moby Oct 12 23:51:21.057523 containerd[2465]: time="2025-10-12T23:51:21.057507891Z" level=info msg="cleaning up dead shim" namespace=moby Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.070993465Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071049109Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071190400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071211641Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071227922Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071241643Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071265765Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071281246Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071299928Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071314329Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071331370Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071407375Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071428537Z" level=info msg="Start snapshots syncer" Oct 12 23:51:21.071642 containerd[2465]: time="2025-10-12T23:51:21.071452179Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 12 23:51:21.073451 containerd[2465]: time="2025-10-12T23:51:21.071789643Z" 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\"}" Oct 12 23:51:21.073451 containerd[2465]: time="2025-10-12T23:51:21.071847847Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.071932253Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072004618Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072033221Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072048462Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072062623Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072078424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072091265Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072108546Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072139148Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072155429Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072179791Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072220074Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072239355Z" 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 Oct 12 23:51:21.074497 containerd[2465]: time="2025-10-12T23:51:21.072248836Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072262357Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072274758Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072286519Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072301960Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072320241Z" level=info msg="runtime interface created" Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072325282Z" level=info msg="created NRI interface" Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072338403Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072350563Z" level=info msg="Connect containerd service" Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072387646Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 12 23:51:21.074811 containerd[2465]: time="2025-10-12T23:51:21.072913684Z" 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" Oct 12 23:51:21.086094 containerd[2465]: time="2025-10-12T23:51:21.085961027Z" level=info msg="Start subscribing containerd event" Oct 12 23:51:21.086094 containerd[2465]: time="2025-10-12T23:51:21.086077875Z" level=info msg="Start recovering state" Oct 12 23:51:21.086199 containerd[2465]: time="2025-10-12T23:51:21.086153441Z" level=info msg="Start event monitor" Oct 12 23:51:21.086199 containerd[2465]: time="2025-10-12T23:51:21.086166282Z" level=info msg="Start cni network conf syncer for default" Oct 12 23:51:21.086199 containerd[2465]: time="2025-10-12T23:51:21.086173242Z" level=info msg="Start streaming server" Oct 12 23:51:21.086199 containerd[2465]: time="2025-10-12T23:51:21.086181763Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 12 23:51:21.086307 containerd[2465]: time="2025-10-12T23:51:21.086207245Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 12 23:51:21.086307 containerd[2465]: time="2025-10-12T23:51:21.086211485Z" level=info msg="runtime interface starting up..." Oct 12 23:51:21.086307 containerd[2465]: time="2025-10-12T23:51:21.086289011Z" level=info msg="starting plugins..." Oct 12 23:51:21.086307 containerd[2465]: time="2025-10-12T23:51:21.086299611Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 12 23:51:21.086371 containerd[2465]: time="2025-10-12T23:51:21.086272689Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 12 23:51:21.086463 containerd[2465]: time="2025-10-12T23:51:21.086432861Z" level=info msg="containerd successfully booted in 0.064145s" Oct 12 23:51:21.086539 systemd[1]: Started containerd.service - containerd container runtime. Oct 12 23:51:21.088292 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 12 23:51:21.105326 (dockerd)[2517]: 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 Oct 12 23:51:21.131830 dockerd[2517]: time="2025-10-12T23:51:21.131493477Z" level=info msg="Starting up" Oct 12 23:51:21.133335 dockerd[2517]: time="2025-10-12T23:51:21.133309888Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 12 23:51:21.143205 dockerd[2517]: time="2025-10-12T23:51:21.143161880Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 12 23:51:21.384859 systemd[1]: var-lib-docker-overlay2-metacopy\x2dcheck3884986802-merged.mount: Deactivated successfully. Oct 12 23:51:21.400050 dockerd[2517]: time="2025-10-12T23:51:21.400008637Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Oct 12 23:51:21.407786 dockerd[2517]: time="2025-10-12T23:51:21.407738476Z" level=info msg="Loading containers: start." Oct 12 23:51:21.483326 systemd[1]: var-lib-docker-overlay2-203bebc8d33319506ccb049242fbef4ed826c8549a1c8337e5bd6615c1f60315-merged.mount: Deactivated successfully. Oct 12 23:51:22.074595 systemd-networkd[1351]: veth12743b4: Link DOWN Oct 12 23:51:22.074603 systemd-networkd[1351]: veth12743b4: Lost carrier Oct 12 23:51:22.076537 kernel: docker0: port 1(veth12743b4) entered disabled state Oct 12 23:51:22.076600 kernel: veth12743b4 (unregistering): left allmulticast mode Oct 12 23:51:22.076619 kernel: veth12743b4 (unregistering): left promiscuous mode Oct 12 23:51:22.077999 kernel: docker0: port 1(veth12743b4) entered disabled state Oct 12 23:51:22.078834 systemd-networkd[1351]: docker0: Lost carrier Oct 12 23:51:22.079137 dockerd[2517]: time="2025-10-12T23:51:22.079104070Z" level=info msg="Removing stale sandbox 96ca665ffbf3778e9f62b1b1ceff3f19cb2ea6e468ec99561968dc097a399f6d (f822fd82f4b02b071a02392225ef4c7806ee46fa635002c4ede532d5151c0f70)" Oct 12 23:51:22.083357 dockerd[2517]: time="2025-10-12T23:51:22.083312635Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Oct 12 23:51:22.083436 dockerd[2517]: time="2025-10-12T23:51:22.083363399Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 282d6ca612e36f72c84c985b7d9558a5b5daa471b56cda02db0058653ee7cb3c 6400aaa71f37b0a7190e7eedde59a6051f1ad92b827a8e7e9f154c6e9575a428], retrying...." Oct 12 23:51:22.225666 dockerd[2517]: time="2025-10-12T23:51:22.225619275Z" level=warning msg="error locating sandbox id 96ca665ffbf3778e9f62b1b1ceff3f19cb2ea6e468ec99561968dc097a399f6d: sandbox 96ca665ffbf3778e9f62b1b1ceff3f19cb2ea6e468ec99561968dc097a399f6d not found" Oct 12 23:51:22.225666 dockerd[2517]: time="2025-10-12T23:51:22.225662758Z" level=warning msg="error locating sandbox id 48f3d7f0fd50f97afc70586fe7e8dcebe5d2cb7de8b5addb1992580310add959: sandbox 48f3d7f0fd50f97afc70586fe7e8dcebe5d2cb7de8b5addb1992580310add959 not found" Oct 12 23:51:22.226098 dockerd[2517]: time="2025-10-12T23:51:22.225774885Z" level=info msg="Loading containers: done." Oct 12 23:51:22.235507 dockerd[2517]: time="2025-10-12T23:51:22.235445020Z" 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 Oct 12 23:51:22.235676 dockerd[2517]: time="2025-10-12T23:51:22.235539387Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 12 23:51:22.235676 dockerd[2517]: time="2025-10-12T23:51:22.235576069Z" level=info msg="Initializing buildkit" Oct 12 23:51:22.237724 systemd[1]: run-docker-netns-96ca665ffbf3.mount: Deactivated successfully. Oct 12 23:51:22.251970 dockerd[2517]: time="2025-10-12T23:51:22.251930537Z" level=info msg="Completed buildkit initialization" Oct 12 23:51:22.260304 dockerd[2517]: time="2025-10-12T23:51:22.260257981Z" level=info msg="Daemon has completed initialization" Oct 12 23:51:22.260304 dockerd[2517]: time="2025-10-12T23:51:22.260348067Z" level=info msg="API listen on /run/docker.sock" Oct 12 23:51:22.260511 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 12 23:51:25.552519 systemd[1]: Started sshd@24-10.0.0.2:22-10.0.0.1:57592.service - OpenSSH per-connection server daemon (10.0.0.1:57592). Oct 12 23:51:25.624593 sshd[2754]: Accepted publickey for core from 10.0.0.1 port 57592 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:25.625833 sshd-session[2754]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:25.630049 systemd-logind[1422]: New session 25 of user core. Oct 12 23:51:25.636156 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 12 23:51:25.689488 sshd[2757]: Connection closed by 10.0.0.1 port 57592 Oct 12 23:51:25.689831 sshd-session[2754]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:25.712111 systemd[1]: sshd@24-10.0.0.2:22-10.0.0.1:57592.service: Deactivated successfully. Oct 12 23:51:25.714284 systemd[1]: session-25.scope: Deactivated successfully. Oct 12 23:51:25.715179 systemd-logind[1422]: Session 25 logged out. Waiting for processes to exit. Oct 12 23:51:25.717517 systemd[1]: Started sshd@25-10.0.0.2:22-10.0.0.1:57594.service - OpenSSH per-connection server daemon (10.0.0.1:57594). Oct 12 23:51:25.720173 systemd-logind[1422]: Removed session 25. Oct 12 23:51:25.789595 sshd[2763]: Accepted publickey for core from 10.0.0.1 port 57594 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:25.790087 sshd-session[2763]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:25.794610 systemd-logind[1422]: New session 26 of user core. Oct 12 23:51:25.811178 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 12 23:51:25.868782 sshd[2766]: Connection closed by 10.0.0.1 port 57594 Oct 12 23:51:25.869169 sshd-session[2763]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:25.883200 systemd[1]: sshd@25-10.0.0.2:22-10.0.0.1:57594.service: Deactivated successfully. Oct 12 23:51:25.885412 systemd[1]: session-26.scope: Deactivated successfully. Oct 12 23:51:25.886193 systemd-logind[1422]: Session 26 logged out. Waiting for processes to exit. Oct 12 23:51:25.888248 systemd[1]: Started sshd@26-10.0.0.2:22-10.0.0.1:57598.service - OpenSSH per-connection server daemon (10.0.0.1:57598). Oct 12 23:51:25.889320 systemd-logind[1422]: Removed session 26. Oct 12 23:51:25.943112 sshd[2772]: Accepted publickey for core from 10.0.0.1 port 57598 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:25.944515 sshd-session[2772]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:25.949031 systemd-logind[1422]: New session 27 of user core. Oct 12 23:51:25.962182 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 12 23:51:26.023355 sshd[2776]: Connection closed by 10.0.0.1 port 57598 Oct 12 23:51:26.023678 sshd-session[2772]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:26.034087 systemd[1]: sshd@26-10.0.0.2:22-10.0.0.1:57598.service: Deactivated successfully. Oct 12 23:51:26.037291 systemd[1]: session-27.scope: Deactivated successfully. Oct 12 23:51:26.039613 systemd-logind[1422]: Session 27 logged out. Waiting for processes to exit. Oct 12 23:51:26.043938 systemd[1]: Started sshd@27-10.0.0.2:22-10.0.0.1:57610.service - OpenSSH per-connection server daemon (10.0.0.1:57610). Oct 12 23:51:26.044960 systemd-logind[1422]: Removed session 27. Oct 12 23:51:26.101367 sshd[2789]: Accepted publickey for core from 10.0.0.1 port 57610 ssh2: RSA SHA256:TSsUX0+AOpz/e050I2QTTANvLGIa9yseBHOQ57c0ZcY Oct 12 23:51:26.102673 sshd-session[2789]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 12 23:51:26.109085 systemd-logind[1422]: New session 28 of user core. Oct 12 23:51:26.117129 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 12 23:51:26.189018 systemd[1]: var-lib-docker-overlay2-a27fc4f6fb906c4a050d869c73b19c917e7d010e1b13fa682573a19f3b831a7a\x2dinit-merged.mount: Deactivated successfully. Oct 12 23:51:26.203013 systemd[1]: var-lib-docker-overlay2-a27fc4f6fb906c4a050d869c73b19c917e7d010e1b13fa682573a19f3b831a7a-merged.mount: Deactivated successfully. Oct 12 23:51:26.231003 containerd[2465]: time="2025-10-12T23:51:26.230465338Z" level=info msg="connecting to shim 707ecd2a2cc90e5861daca3db07460359e5e5b1f7be4bf362813dfa1fee40a23" address="unix:///run/containerd/s/57aa405198f4f5f2d00c128f4e829595e439b6deee74092e4dc415a4048a02a6" namespace=moby protocol=ttrpc version=3 Oct 12 23:51:26.256161 systemd[1]: Started docker-707ecd2a2cc90e5861daca3db07460359e5e5b1f7be4bf362813dfa1fee40a23.scope - libcontainer container 707ecd2a2cc90e5861daca3db07460359e5e5b1f7be4bf362813dfa1fee40a23. Oct 12 23:51:26.267838 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 12 23:51:26.287998 kernel: docker0: port 1(vethc56cb1d) entered blocking state Oct 12 23:51:26.288086 kernel: docker0: port 1(vethc56cb1d) entered disabled state Oct 12 23:51:26.288112 kernel: vethc56cb1d: entered allmulticast mode Oct 12 23:51:26.289104 kernel: vethc56cb1d: entered promiscuous mode Oct 12 23:51:26.291142 systemd-networkd[1351]: vethc56cb1d: Link UP Oct 12 23:51:26.297988 kernel: eth0: renamed from veth48bc7de Oct 12 23:51:26.301994 kernel: docker0: port 1(vethc56cb1d) entered blocking state Oct 12 23:51:26.302041 kernel: docker0: port 1(vethc56cb1d) entered forwarding state Oct 12 23:51:26.302175 systemd-networkd[1351]: vethc56cb1d: Gained carrier Oct 12 23:51:26.302369 systemd-networkd[1351]: docker0: Gained carrier Oct 12 23:51:26.321880 sshd[2792]: Connection closed by 10.0.0.1 port 57610 Oct 12 23:51:26.322179 sshd-session[2789]: pam_unix(sshd:session): session closed for user core Oct 12 23:51:26.325873 systemd[1]: sshd@27-10.0.0.2:22-10.0.0.1:57610.service: Deactivated successfully. Oct 12 23:51:26.328173 systemd[1]: session-28.scope: Deactivated successfully. Oct 12 23:51:26.330739 systemd-logind[1422]: Session 28 logged out. Waiting for processes to exit. Oct 12 23:51:26.332564 systemd-logind[1422]: Removed session 28. Oct 12 23:51:27.766212 systemd-networkd[1351]: vethc56cb1d: Gained IPv6LL