May 27 16:59:47.797530 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 27 16:59:47.797570 kernel: Linux version 6.12.30-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue May 27 15:31:23 -00 2025 May 27 16:59:47.797580 kernel: KASLR enabled May 27 16:59:47.797585 kernel: efi: EFI v2.7 by EDK II May 27 16:59:47.797591 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 May 27 16:59:47.797596 kernel: random: crng init done May 27 16:59:47.797603 kernel: secureboot: Secure boot disabled May 27 16:59:47.797608 kernel: ACPI: Early table checksum verification disabled May 27 16:59:47.797614 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) May 27 16:59:47.797621 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 27 16:59:47.797627 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797632 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797638 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797644 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797651 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797658 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797664 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797670 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797676 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 27 16:59:47.797682 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 27 16:59:47.797688 kernel: ACPI: Use ACPI SPCR as default console: Yes May 27 16:59:47.797694 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 27 16:59:47.797700 kernel: NODE_DATA(0) allocated [mem 0xdc965dc0-0xdc96cfff] May 27 16:59:47.797706 kernel: Zone ranges: May 27 16:59:47.797712 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 27 16:59:47.797719 kernel: DMA32 empty May 27 16:59:47.797725 kernel: Normal empty May 27 16:59:47.797731 kernel: Device empty May 27 16:59:47.797737 kernel: Movable zone start for each node May 27 16:59:47.797742 kernel: Early memory node ranges May 27 16:59:47.797749 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] May 27 16:59:47.797755 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] May 27 16:59:47.797761 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] May 27 16:59:47.797766 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] May 27 16:59:47.797772 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] May 27 16:59:47.797778 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] May 27 16:59:47.797784 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] May 27 16:59:47.797791 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] May 27 16:59:47.797797 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] May 27 16:59:47.797803 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 27 16:59:47.797811 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 27 16:59:47.797818 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 27 16:59:47.797824 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 27 16:59:47.797832 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 27 16:59:47.797838 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 27 16:59:47.797844 kernel: psci: probing for conduit method from ACPI. May 27 16:59:47.797850 kernel: psci: PSCIv1.1 detected in firmware. May 27 16:59:47.797857 kernel: psci: Using standard PSCI v0.2 function IDs May 27 16:59:47.797863 kernel: psci: Trusted OS migration not required May 27 16:59:47.797869 kernel: psci: SMC Calling Convention v1.1 May 27 16:59:47.797876 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 27 16:59:47.797882 kernel: percpu: Embedded 33 pages/cpu s98136 r8192 d28840 u135168 May 27 16:59:47.797889 kernel: pcpu-alloc: s98136 r8192 d28840 u135168 alloc=33*4096 May 27 16:59:47.797897 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 27 16:59:47.797903 kernel: Detected PIPT I-cache on CPU0 May 27 16:59:47.797909 kernel: CPU features: detected: GIC system register CPU interface May 27 16:59:47.797916 kernel: CPU features: detected: Spectre-v4 May 27 16:59:47.797922 kernel: CPU features: detected: Spectre-BHB May 27 16:59:47.797928 kernel: CPU features: kernel page table isolation forced ON by KASLR May 27 16:59:47.797935 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 27 16:59:47.797941 kernel: CPU features: detected: ARM erratum 1418040 May 27 16:59:47.797947 kernel: CPU features: detected: SSBS not fully self-synchronizing May 27 16:59:47.797954 kernel: alternatives: applying boot alternatives May 27 16:59:47.797961 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=4e706b869299e1c88703222069cdfa08c45ebce568f762053eea5b3f5f0939c3 May 27 16:59:47.797969 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 27 16:59:47.797975 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 27 16:59:47.797982 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 27 16:59:47.797988 kernel: Fallback order for Node 0: 0 May 27 16:59:47.797994 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 May 27 16:59:47.798001 kernel: Policy zone: DMA May 27 16:59:47.798007 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 27 16:59:47.798013 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB May 27 16:59:47.798020 kernel: software IO TLB: area num 4. May 27 16:59:47.798026 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB May 27 16:59:47.798032 kernel: software IO TLB: mapped [mem 0x00000000d8c00000-0x00000000d9000000] (4MB) May 27 16:59:47.798039 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 27 16:59:47.798046 kernel: rcu: Preemptible hierarchical RCU implementation. May 27 16:59:47.798053 kernel: rcu: RCU event tracing is enabled. May 27 16:59:47.798060 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 27 16:59:47.798066 kernel: Trampoline variant of Tasks RCU enabled. May 27 16:59:47.798073 kernel: Tracing variant of Tasks RCU enabled. May 27 16:59:47.798079 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 27 16:59:47.798086 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 27 16:59:47.798092 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 27 16:59:47.798099 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 27 16:59:47.798105 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 27 16:59:47.798111 kernel: GICv3: 256 SPIs implemented May 27 16:59:47.798119 kernel: GICv3: 0 Extended SPIs implemented May 27 16:59:47.798125 kernel: Root IRQ handler: gic_handle_irq May 27 16:59:47.798131 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 27 16:59:47.798138 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 May 27 16:59:47.798144 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 27 16:59:47.798150 kernel: ITS [mem 0x08080000-0x0809ffff] May 27 16:59:47.798157 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) May 27 16:59:47.798163 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) May 27 16:59:47.798170 kernel: GICv3: using LPI property table @0x00000000400f0000 May 27 16:59:47.798176 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040110000 May 27 16:59:47.798182 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 27 16:59:47.798189 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 16:59:47.798196 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 27 16:59:47.798203 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 27 16:59:47.798209 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 27 16:59:47.798216 kernel: arm-pv: using stolen time PV May 27 16:59:47.798222 kernel: Console: colour dummy device 80x25 May 27 16:59:47.798229 kernel: ACPI: Core revision 20240827 May 27 16:59:47.798236 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 27 16:59:47.798246 kernel: pid_max: default: 32768 minimum: 301 May 27 16:59:47.798254 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 27 16:59:47.798262 kernel: landlock: Up and running. May 27 16:59:47.798268 kernel: SELinux: Initializing. May 27 16:59:47.798275 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 16:59:47.798282 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 16:59:47.798289 kernel: rcu: Hierarchical SRCU implementation. May 27 16:59:47.798295 kernel: rcu: Max phase no-delay instances is 400. May 27 16:59:47.798302 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 27 16:59:47.798308 kernel: Remapping and enabling EFI services. May 27 16:59:47.798315 kernel: smp: Bringing up secondary CPUs ... May 27 16:59:47.798322 kernel: Detected PIPT I-cache on CPU1 May 27 16:59:47.798334 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 27 16:59:47.798341 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040120000 May 27 16:59:47.798349 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 16:59:47.798356 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 27 16:59:47.798362 kernel: Detected PIPT I-cache on CPU2 May 27 16:59:47.798369 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 27 16:59:47.798376 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040130000 May 27 16:59:47.798384 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 16:59:47.798391 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 27 16:59:47.798401 kernel: Detected PIPT I-cache on CPU3 May 27 16:59:47.798408 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 27 16:59:47.798415 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040140000 May 27 16:59:47.798423 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 16:59:47.798432 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 27 16:59:47.798441 kernel: smp: Brought up 1 node, 4 CPUs May 27 16:59:47.798447 kernel: SMP: Total of 4 processors activated. May 27 16:59:47.798454 kernel: CPU: All CPU(s) started at EL1 May 27 16:59:47.798463 kernel: CPU features: detected: 32-bit EL0 Support May 27 16:59:47.798470 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 27 16:59:47.798477 kernel: CPU features: detected: Common not Private translations May 27 16:59:47.798483 kernel: CPU features: detected: CRC32 instructions May 27 16:59:47.798490 kernel: CPU features: detected: Enhanced Virtualization Traps May 27 16:59:47.798497 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 27 16:59:47.798504 kernel: CPU features: detected: LSE atomic instructions May 27 16:59:47.798511 kernel: CPU features: detected: Privileged Access Never May 27 16:59:47.798518 kernel: CPU features: detected: RAS Extension Support May 27 16:59:47.798526 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 27 16:59:47.798538 kernel: alternatives: applying system-wide alternatives May 27 16:59:47.798545 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 May 27 16:59:47.798566 kernel: Memory: 2440984K/2572288K available (11072K kernel code, 2276K rwdata, 8936K rodata, 39424K init, 1034K bss, 125536K reserved, 0K cma-reserved) May 27 16:59:47.798576 kernel: devtmpfs: initialized May 27 16:59:47.798583 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 27 16:59:47.798590 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 27 16:59:47.798597 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 27 16:59:47.798604 kernel: 0 pages in range for non-PLT usage May 27 16:59:47.798613 kernel: 508544 pages in range for PLT usage May 27 16:59:47.798619 kernel: pinctrl core: initialized pinctrl subsystem May 27 16:59:47.798626 kernel: SMBIOS 3.0.0 present. May 27 16:59:47.798633 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 27 16:59:47.798640 kernel: DMI: Memory slots populated: 1/1 May 27 16:59:47.798647 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 27 16:59:47.798654 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 27 16:59:47.798661 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 27 16:59:47.798668 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 27 16:59:47.798676 kernel: audit: initializing netlink subsys (disabled) May 27 16:59:47.798683 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 May 27 16:59:47.798689 kernel: thermal_sys: Registered thermal governor 'step_wise' May 27 16:59:47.798696 kernel: cpuidle: using governor menu May 27 16:59:47.798703 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 27 16:59:47.798710 kernel: ASID allocator initialised with 32768 entries May 27 16:59:47.798717 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 27 16:59:47.798724 kernel: Serial: AMBA PL011 UART driver May 27 16:59:47.798730 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 27 16:59:47.798744 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 27 16:59:47.798751 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 27 16:59:47.798758 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 27 16:59:47.798764 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 27 16:59:47.798771 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 27 16:59:47.798778 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 27 16:59:47.798785 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 27 16:59:47.798792 kernel: ACPI: Added _OSI(Module Device) May 27 16:59:47.798799 kernel: ACPI: Added _OSI(Processor Device) May 27 16:59:47.798807 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 27 16:59:47.798813 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 27 16:59:47.798820 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 27 16:59:47.798827 kernel: ACPI: Interpreter enabled May 27 16:59:47.798834 kernel: ACPI: Using GIC for interrupt routing May 27 16:59:47.798841 kernel: ACPI: MCFG table detected, 1 entries May 27 16:59:47.798848 kernel: ACPI: CPU0 has been hot-added May 27 16:59:47.798855 kernel: ACPI: CPU1 has been hot-added May 27 16:59:47.798862 kernel: ACPI: CPU2 has been hot-added May 27 16:59:47.798869 kernel: ACPI: CPU3 has been hot-added May 27 16:59:47.798877 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 27 16:59:47.798884 kernel: printk: legacy console [ttyAMA0] enabled May 27 16:59:47.798891 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 27 16:59:47.799022 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 27 16:59:47.799090 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 27 16:59:47.799150 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 27 16:59:47.799223 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 27 16:59:47.799283 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 27 16:59:47.799292 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 27 16:59:47.799300 kernel: PCI host bridge to bus 0000:00 May 27 16:59:47.799364 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 27 16:59:47.799422 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 27 16:59:47.799476 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 27 16:59:47.799529 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 27 16:59:47.799677 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint May 27 16:59:47.799749 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint May 27 16:59:47.799813 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] May 27 16:59:47.799876 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] May 27 16:59:47.799937 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] May 27 16:59:47.799997 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned May 27 16:59:47.800058 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned May 27 16:59:47.800122 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned May 27 16:59:47.800179 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 27 16:59:47.800233 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 27 16:59:47.800288 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 27 16:59:47.800297 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 27 16:59:47.800304 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 27 16:59:47.800311 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 27 16:59:47.800320 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 27 16:59:47.800327 kernel: iommu: Default domain type: Translated May 27 16:59:47.800334 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 27 16:59:47.800341 kernel: efivars: Registered efivars operations May 27 16:59:47.800348 kernel: vgaarb: loaded May 27 16:59:47.800355 kernel: clocksource: Switched to clocksource arch_sys_counter May 27 16:59:47.800362 kernel: VFS: Disk quotas dquot_6.6.0 May 27 16:59:47.800369 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 27 16:59:47.800376 kernel: pnp: PnP ACPI init May 27 16:59:47.800445 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 27 16:59:47.800455 kernel: pnp: PnP ACPI: found 1 devices May 27 16:59:47.800462 kernel: NET: Registered PF_INET protocol family May 27 16:59:47.800469 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 27 16:59:47.800476 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 27 16:59:47.800483 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 27 16:59:47.800490 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 27 16:59:47.800497 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 27 16:59:47.800506 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 27 16:59:47.800513 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 16:59:47.800520 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 16:59:47.800527 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 27 16:59:47.800541 kernel: PCI: CLS 0 bytes, default 64 May 27 16:59:47.800565 kernel: kvm [1]: HYP mode not available May 27 16:59:47.800573 kernel: Initialise system trusted keyrings May 27 16:59:47.800580 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 27 16:59:47.800587 kernel: Key type asymmetric registered May 27 16:59:47.800596 kernel: Asymmetric key parser 'x509' registered May 27 16:59:47.800603 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 27 16:59:47.800610 kernel: io scheduler mq-deadline registered May 27 16:59:47.800617 kernel: io scheduler kyber registered May 27 16:59:47.800623 kernel: io scheduler bfq registered May 27 16:59:47.800631 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 27 16:59:47.800637 kernel: ACPI: button: Power Button [PWRB] May 27 16:59:47.800645 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 27 16:59:47.800715 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 27 16:59:47.800726 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 27 16:59:47.800734 kernel: thunder_xcv, ver 1.0 May 27 16:59:47.800740 kernel: thunder_bgx, ver 1.0 May 27 16:59:47.800747 kernel: nicpf, ver 1.0 May 27 16:59:47.800754 kernel: nicvf, ver 1.0 May 27 16:59:47.800829 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 27 16:59:47.800886 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-27T16:59:47 UTC (1748365187) May 27 16:59:47.800896 kernel: hid: raw HID events driver (C) Jiri Kosina May 27 16:59:47.800904 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available May 27 16:59:47.800911 kernel: watchdog: NMI not fully supported May 27 16:59:47.800918 kernel: watchdog: Hard watchdog permanently disabled May 27 16:59:47.800925 kernel: NET: Registered PF_INET6 protocol family May 27 16:59:47.800932 kernel: Segment Routing with IPv6 May 27 16:59:47.800939 kernel: In-situ OAM (IOAM) with IPv6 May 27 16:59:47.800946 kernel: NET: Registered PF_PACKET protocol family May 27 16:59:47.800953 kernel: Key type dns_resolver registered May 27 16:59:47.800960 kernel: registered taskstats version 1 May 27 16:59:47.800967 kernel: Loading compiled-in X.509 certificates May 27 16:59:47.800975 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: 8e5e45c34fa91568ef1fa3bdfd5a71a43b4c4580' May 27 16:59:47.800982 kernel: Demotion targets for Node 0: null May 27 16:59:47.800988 kernel: Key type .fscrypt registered May 27 16:59:47.800995 kernel: Key type fscrypt-provisioning registered May 27 16:59:47.801002 kernel: ima: No TPM chip found, activating TPM-bypass! May 27 16:59:47.801009 kernel: ima: Allocated hash algorithm: sha1 May 27 16:59:47.801016 kernel: ima: No architecture policies found May 27 16:59:47.801023 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 27 16:59:47.801031 kernel: clk: Disabling unused clocks May 27 16:59:47.801038 kernel: PM: genpd: Disabling unused power domains May 27 16:59:47.801045 kernel: Warning: unable to open an initial console. May 27 16:59:47.801052 kernel: Freeing unused kernel memory: 39424K May 27 16:59:47.801059 kernel: Run /init as init process May 27 16:59:47.801066 kernel: with arguments: May 27 16:59:47.801073 kernel: /init May 27 16:59:47.801079 kernel: with environment: May 27 16:59:47.801086 kernel: HOME=/ May 27 16:59:47.801094 kernel: TERM=linux May 27 16:59:47.801100 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 27 16:59:47.801108 systemd[1]: Successfully made /usr/ read-only. May 27 16:59:47.801118 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) May 27 16:59:47.801126 systemd[1]: Detected virtualization kvm. May 27 16:59:47.801133 systemd[1]: Detected architecture arm64. May 27 16:59:47.801140 systemd[1]: Running in initrd. May 27 16:59:47.801148 systemd[1]: No hostname configured, using default hostname. May 27 16:59:47.801157 systemd[1]: Hostname set to . May 27 16:59:47.801164 systemd[1]: Initializing machine ID from VM UUID. May 27 16:59:47.801171 systemd[1]: Queued start job for default target initrd.target. May 27 16:59:47.801179 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 16:59:47.801186 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 16:59:47.801194 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 27 16:59:47.801202 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 16:59:47.801210 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 27 16:59:47.801220 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 27 16:59:47.801228 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 27 16:59:47.801236 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 27 16:59:47.801243 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 16:59:47.801251 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 16:59:47.801258 systemd[1]: Reached target paths.target - Path Units. May 27 16:59:47.801266 systemd[1]: Reached target slices.target - Slice Units. May 27 16:59:47.801274 systemd[1]: Reached target swap.target - Swaps. May 27 16:59:47.801281 systemd[1]: Reached target timers.target - Timer Units. May 27 16:59:47.801289 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 27 16:59:47.801296 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 16:59:47.801303 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 27 16:59:47.801311 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 27 16:59:47.801318 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 16:59:47.801326 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 16:59:47.801334 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 16:59:47.801342 systemd[1]: Reached target sockets.target - Socket Units. May 27 16:59:47.801349 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 27 16:59:47.801357 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 16:59:47.801365 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 27 16:59:47.801372 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). May 27 16:59:47.801380 systemd[1]: Starting systemd-fsck-usr.service... May 27 16:59:47.801387 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 16:59:47.801395 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 16:59:47.801403 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 16:59:47.801411 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 27 16:59:47.801418 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 16:59:47.801426 systemd[1]: Finished systemd-fsck-usr.service. May 27 16:59:47.801448 systemd-journald[243]: Collecting audit messages is disabled. May 27 16:59:47.801466 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 16:59:47.801474 systemd-journald[243]: Journal started May 27 16:59:47.801493 systemd-journald[243]: Runtime Journal (/run/log/journal/d83ac5789e424f9cbd15f85d84b71e77) is 6M, max 48.5M, 42.4M free. May 27 16:59:47.796028 systemd-modules-load[245]: Inserted module 'overlay' May 27 16:59:47.810490 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 16:59:47.810517 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 27 16:59:47.812579 systemd[1]: Started systemd-journald.service - Journal Service. May 27 16:59:47.812605 kernel: Bridge firewalling registered May 27 16:59:47.812774 systemd-modules-load[245]: Inserted module 'br_netfilter' May 27 16:59:47.814006 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 16:59:47.815596 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 16:59:47.819863 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 27 16:59:47.821492 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 16:59:47.823388 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 16:59:47.839332 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 16:59:47.845356 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 16:59:47.846671 systemd-tmpfiles[267]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 27 16:59:47.846890 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 16:59:47.850961 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 16:59:47.855001 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 16:59:47.858654 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 16:59:47.867032 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 27 16:59:47.880945 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=4e706b869299e1c88703222069cdfa08c45ebce568f762053eea5b3f5f0939c3 May 27 16:59:47.896580 systemd-resolved[284]: Positive Trust Anchors: May 27 16:59:47.896599 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 16:59:47.896630 systemd-resolved[284]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 27 16:59:47.901443 systemd-resolved[284]: Defaulting to hostname 'linux'. May 27 16:59:47.902570 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 16:59:47.903691 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 16:59:47.962571 kernel: SCSI subsystem initialized May 27 16:59:47.969579 kernel: Loading iSCSI transport class v2.0-870. May 27 16:59:47.974584 kernel: iscsi: registered transport (tcp) May 27 16:59:47.986795 kernel: iscsi: registered transport (qla4xxx) May 27 16:59:47.986821 kernel: QLogic iSCSI HBA Driver May 27 16:59:48.004751 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 16:59:48.027655 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 16:59:48.029878 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 16:59:48.076033 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 27 16:59:48.078047 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 27 16:59:48.136586 kernel: raid6: neonx8 gen() 15771 MB/s May 27 16:59:48.153562 kernel: raid6: neonx4 gen() 15820 MB/s May 27 16:59:48.170565 kernel: raid6: neonx2 gen() 13297 MB/s May 27 16:59:48.187561 kernel: raid6: neonx1 gen() 10437 MB/s May 27 16:59:48.204597 kernel: raid6: int64x8 gen() 6911 MB/s May 27 16:59:48.221570 kernel: raid6: int64x4 gen() 7362 MB/s May 27 16:59:48.238571 kernel: raid6: int64x2 gen() 6109 MB/s May 27 16:59:48.255564 kernel: raid6: int64x1 gen() 5058 MB/s May 27 16:59:48.255590 kernel: raid6: using algorithm neonx4 gen() 15820 MB/s May 27 16:59:48.272584 kernel: raid6: .... xor() 12402 MB/s, rmw enabled May 27 16:59:48.272617 kernel: raid6: using neon recovery algorithm May 27 16:59:48.277898 kernel: xor: measuring software checksum speed May 27 16:59:48.277920 kernel: 8regs : 21636 MB/sec May 27 16:59:48.277929 kernel: 32regs : 21710 MB/sec May 27 16:59:48.278826 kernel: arm64_neon : 28128 MB/sec May 27 16:59:48.278848 kernel: xor: using function: arm64_neon (28128 MB/sec) May 27 16:59:48.330603 kernel: Btrfs loaded, zoned=no, fsverity=no May 27 16:59:48.338018 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 27 16:59:48.340497 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 16:59:48.373635 systemd-udevd[497]: Using default interface naming scheme 'v255'. May 27 16:59:48.377785 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 16:59:48.380177 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 27 16:59:48.409929 dracut-pre-trigger[506]: rd.md=0: removing MD RAID activation May 27 16:59:48.430845 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 27 16:59:48.434656 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 16:59:48.485355 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 16:59:48.488001 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 27 16:59:48.532056 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 27 16:59:48.538595 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 27 16:59:48.544571 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 16:59:48.547450 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 16:59:48.547588 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 16:59:48.550777 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 27 16:59:48.552723 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 16:59:48.571741 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 27 16:59:48.578878 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 27 16:59:48.580596 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 27 16:59:48.581992 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 16:59:48.599584 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 27 16:59:48.605381 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 27 16:59:48.606295 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 27 16:59:48.608907 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 27 16:59:48.610770 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 16:59:48.612471 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 16:59:48.615001 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 27 16:59:48.616821 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 27 16:59:48.634857 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 16:59:48.634582 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 27 16:59:49.647337 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 16:59:49.647407 disk-uuid[595]: The operation has completed successfully. May 27 16:59:49.649629 kernel: block device autoloading is deprecated and will be removed. May 27 16:59:49.694327 systemd[1]: disk-uuid.service: Deactivated successfully. May 27 16:59:49.694427 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 27 16:59:49.719488 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 27 16:59:49.736197 sh[612]: Success May 27 16:59:49.752097 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 27 16:59:49.752138 kernel: device-mapper: uevent: version 1.0.3 May 27 16:59:49.752149 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 27 16:59:49.760644 kernel: device-mapper: verity: sha256 using shash "sha256-ce" May 27 16:59:49.786286 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 27 16:59:49.788650 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 27 16:59:49.802724 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 27 16:59:49.813324 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 27 16:59:49.814564 kernel: BTRFS: device fsid 3c8c76ef-f1da-40fe-979d-11bdf765e403 devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (625) May 27 16:59:49.818250 kernel: BTRFS info (device dm-0): first mount of filesystem 3c8c76ef-f1da-40fe-979d-11bdf765e403 May 27 16:59:49.818268 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 27 16:59:49.818278 kernel: BTRFS info (device dm-0): using free-space-tree May 27 16:59:49.822676 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 27 16:59:49.823776 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 27 16:59:49.824880 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 27 16:59:49.825622 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 27 16:59:49.826963 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 27 16:59:49.852583 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (657) May 27 16:59:49.854958 kernel: BTRFS info (device vda6): first mount of filesystem 0631e8fb-ef71-4ba1-b2b8-88386996a754 May 27 16:59:49.854993 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 16:59:49.855701 kernel: BTRFS info (device vda6): using free-space-tree May 27 16:59:49.863054 kernel: BTRFS info (device vda6): last unmount of filesystem 0631e8fb-ef71-4ba1-b2b8-88386996a754 May 27 16:59:49.862518 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 27 16:59:49.864395 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 27 16:59:49.936317 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 16:59:49.940583 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 16:59:49.978749 systemd-networkd[798]: lo: Link UP May 27 16:59:49.978761 systemd-networkd[798]: lo: Gained carrier May 27 16:59:49.979407 systemd-networkd[798]: Enumeration completed May 27 16:59:49.979627 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 16:59:49.980165 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 16:59:49.980169 systemd-networkd[798]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 16:59:49.980905 systemd-networkd[798]: eth0: Link UP May 27 16:59:49.980908 systemd-networkd[798]: eth0: Gained carrier May 27 16:59:49.980916 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 16:59:49.981029 systemd[1]: Reached target network.target - Network. May 27 16:59:50.000455 ignition[703]: Ignition 2.21.0 May 27 16:59:50.000472 ignition[703]: Stage: fetch-offline May 27 16:59:50.000506 ignition[703]: no configs at "/usr/lib/ignition/base.d" May 27 16:59:50.000513 ignition[703]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:50.002597 systemd-networkd[798]: eth0: DHCPv4 address 10.0.0.40/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 27 16:59:50.000748 ignition[703]: parsed url from cmdline: "" May 27 16:59:50.000752 ignition[703]: no config URL provided May 27 16:59:50.000756 ignition[703]: reading system config file "/usr/lib/ignition/user.ign" May 27 16:59:50.000763 ignition[703]: no config at "/usr/lib/ignition/user.ign" May 27 16:59:50.000780 ignition[703]: op(1): [started] loading QEMU firmware config module May 27 16:59:50.000784 ignition[703]: op(1): executing: "modprobe" "qemu_fw_cfg" May 27 16:59:50.006733 ignition[703]: op(1): [finished] loading QEMU firmware config module May 27 16:59:50.012443 ignition[703]: parsing config with SHA512: 3a55e46eecd41a435c890f0b967c39e6a4afecd6a95b6d3a60b01cee98440dae9184e74302a764330f271811ba91b965e343933d22c5305909e1ea9bdb5d1efc May 27 16:59:50.016965 unknown[703]: fetched base config from "system" May 27 16:59:50.016984 unknown[703]: fetched user config from "qemu" May 27 16:59:50.017124 ignition[703]: fetch-offline: fetch-offline passed May 27 16:59:50.017190 ignition[703]: Ignition finished successfully May 27 16:59:50.019442 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 27 16:59:50.021435 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 27 16:59:50.022181 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 27 16:59:50.050921 ignition[811]: Ignition 2.21.0 May 27 16:59:50.050939 ignition[811]: Stage: kargs May 27 16:59:50.051063 ignition[811]: no configs at "/usr/lib/ignition/base.d" May 27 16:59:50.051071 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:50.051570 ignition[811]: kargs: kargs passed May 27 16:59:50.051612 ignition[811]: Ignition finished successfully May 27 16:59:50.055589 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 27 16:59:50.057501 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 27 16:59:50.085158 ignition[820]: Ignition 2.21.0 May 27 16:59:50.085175 ignition[820]: Stage: disks May 27 16:59:50.085309 ignition[820]: no configs at "/usr/lib/ignition/base.d" May 27 16:59:50.085318 ignition[820]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:50.088151 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 27 16:59:50.086544 ignition[820]: disks: disks passed May 27 16:59:50.089987 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 27 16:59:50.086604 ignition[820]: Ignition finished successfully May 27 16:59:50.091322 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 27 16:59:50.092628 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 16:59:50.094223 systemd[1]: Reached target sysinit.target - System Initialization. May 27 16:59:50.095505 systemd[1]: Reached target basic.target - Basic System. May 27 16:59:50.098029 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 27 16:59:50.118594 systemd-fsck[830]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 27 16:59:50.206932 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 27 16:59:50.209134 systemd[1]: Mounting sysroot.mount - /sysroot... May 27 16:59:50.271578 kernel: EXT4-fs (vda9): mounted filesystem a5483afc-8426-4c3e-85ef-8146f9077e7d r/w with ordered data mode. Quota mode: none. May 27 16:59:50.272519 systemd[1]: Mounted sysroot.mount - /sysroot. May 27 16:59:50.273775 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 27 16:59:50.276068 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 16:59:50.277411 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 27 16:59:50.278372 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 27 16:59:50.278426 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 27 16:59:50.278448 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 27 16:59:50.289026 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 27 16:59:50.291311 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 27 16:59:50.296259 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (838) May 27 16:59:50.296281 kernel: BTRFS info (device vda6): first mount of filesystem 0631e8fb-ef71-4ba1-b2b8-88386996a754 May 27 16:59:50.296296 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 16:59:50.296305 kernel: BTRFS info (device vda6): using free-space-tree May 27 16:59:50.297430 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 16:59:50.335190 initrd-setup-root[864]: cut: /sysroot/etc/passwd: No such file or directory May 27 16:59:50.338260 initrd-setup-root[871]: cut: /sysroot/etc/group: No such file or directory May 27 16:59:50.341147 initrd-setup-root[878]: cut: /sysroot/etc/shadow: No such file or directory May 27 16:59:50.344097 initrd-setup-root[885]: cut: /sysroot/etc/gshadow: No such file or directory May 27 16:59:50.415152 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 27 16:59:50.417932 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 27 16:59:50.419440 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 27 16:59:50.432610 kernel: BTRFS info (device vda6): last unmount of filesystem 0631e8fb-ef71-4ba1-b2b8-88386996a754 May 27 16:59:50.447634 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 27 16:59:50.459084 ignition[954]: INFO : Ignition 2.21.0 May 27 16:59:50.459084 ignition[954]: INFO : Stage: mount May 27 16:59:50.460270 ignition[954]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 16:59:50.460270 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:50.460270 ignition[954]: INFO : mount: mount passed May 27 16:59:50.460270 ignition[954]: INFO : Ignition finished successfully May 27 16:59:50.461240 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 27 16:59:50.463458 systemd[1]: Starting ignition-files.service - Ignition (files)... May 27 16:59:50.812057 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 27 16:59:50.813612 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 16:59:50.833189 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (966) May 27 16:59:50.833232 kernel: BTRFS info (device vda6): first mount of filesystem 0631e8fb-ef71-4ba1-b2b8-88386996a754 May 27 16:59:50.833244 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 16:59:50.833912 kernel: BTRFS info (device vda6): using free-space-tree May 27 16:59:50.840287 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 16:59:50.874757 ignition[983]: INFO : Ignition 2.21.0 May 27 16:59:50.874757 ignition[983]: INFO : Stage: files May 27 16:59:50.877251 ignition[983]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 16:59:50.877251 ignition[983]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:50.877251 ignition[983]: DEBUG : files: compiled without relabeling support, skipping May 27 16:59:50.883655 ignition[983]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 27 16:59:50.883655 ignition[983]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 27 16:59:50.883655 ignition[983]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 27 16:59:50.883655 ignition[983]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 27 16:59:50.883655 ignition[983]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 27 16:59:50.880097 unknown[983]: wrote ssh authorized keys file for user: core May 27 16:59:50.891419 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 27 16:59:50.891419 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 27 16:59:50.891419 ignition[983]: INFO : files: op(4): [started] processing unit "docker.service" May 27 16:59:50.891419 ignition[983]: INFO : files: op(4): [finished] processing unit "docker.service" May 27 16:59:50.891419 ignition[983]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" May 27 16:59:50.891419 ignition[983]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 27 16:59:50.900529 ignition[983]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 27 16:59:50.900529 ignition[983]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" May 27 16:59:50.900529 ignition[983]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" May 27 16:59:50.928740 ignition[983]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" May 27 16:59:50.933101 ignition[983]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 27 16:59:50.934642 ignition[983]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" May 27 16:59:50.934642 ignition[983]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" May 27 16:59:50.934642 ignition[983]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" May 27 16:59:50.934642 ignition[983]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" May 27 16:59:50.934642 ignition[983]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" May 27 16:59:50.934642 ignition[983]: INFO : files: files passed May 27 16:59:50.934642 ignition[983]: INFO : Ignition finished successfully May 27 16:59:50.937632 systemd[1]: Finished ignition-files.service - Ignition (files). May 27 16:59:50.940338 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 27 16:59:50.942544 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 27 16:59:50.965005 initrd-setup-root-after-ignition[1011]: grep: /sysroot/oem/oem-release: No such file or directory May 27 16:59:50.971661 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 16:59:50.971661 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 27 16:59:50.975543 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 16:59:50.975004 systemd[1]: ignition-quench.service: Deactivated successfully. May 27 16:59:50.975132 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 27 16:59:50.978488 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 16:59:50.980545 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 27 16:59:50.983201 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 27 16:59:51.029628 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 27 16:59:51.029756 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 27 16:59:51.032056 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 27 16:59:51.033946 systemd[1]: Reached target initrd.target - Initrd Default Target. May 27 16:59:51.035898 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 27 16:59:51.036828 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 27 16:59:51.067032 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 16:59:51.069653 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 27 16:59:51.090978 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 27 16:59:51.091944 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 16:59:51.093713 systemd[1]: Stopped target timers.target - Timer Units. May 27 16:59:51.095604 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 27 16:59:51.095738 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 16:59:51.099486 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 27 16:59:51.101054 systemd[1]: Stopped target basic.target - Basic System. May 27 16:59:51.102338 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 27 16:59:51.103650 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 27 16:59:51.105134 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 27 16:59:51.106587 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 27 16:59:51.108143 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 27 16:59:51.109516 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 27 16:59:51.110959 systemd[1]: Stopped target sysinit.target - System Initialization. May 27 16:59:51.112378 systemd[1]: Stopped target local-fs.target - Local File Systems. May 27 16:59:51.113673 systemd[1]: Stopped target swap.target - Swaps. May 27 16:59:51.115187 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 27 16:59:51.115310 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 27 16:59:51.117067 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 27 16:59:51.118502 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 16:59:51.119944 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 27 16:59:51.120027 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 16:59:51.121486 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 27 16:59:51.121621 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 27 16:59:51.123796 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 27 16:59:51.123904 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 27 16:59:51.124865 systemd[1]: Stopped target paths.target - Path Units. May 27 16:59:51.125984 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 27 16:59:51.126075 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 16:59:51.127459 systemd[1]: Stopped target slices.target - Slice Units. May 27 16:59:51.128684 systemd[1]: Stopped target sockets.target - Socket Units. May 27 16:59:51.129991 systemd[1]: iscsid.socket: Deactivated successfully. May 27 16:59:51.130079 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 27 16:59:51.131218 systemd[1]: iscsiuio.socket: Deactivated successfully. May 27 16:59:51.131300 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 16:59:51.132562 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 27 16:59:51.132680 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 16:59:51.134342 systemd[1]: ignition-files.service: Deactivated successfully. May 27 16:59:51.134444 systemd[1]: Stopped ignition-files.service - Ignition (files). May 27 16:59:51.136301 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 27 16:59:51.138088 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 27 16:59:51.139406 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 27 16:59:51.139536 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 27 16:59:51.141169 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 27 16:59:51.141271 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 27 16:59:51.155879 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 27 16:59:51.172239 ignition[1039]: INFO : Ignition 2.21.0 May 27 16:59:51.172239 ignition[1039]: INFO : Stage: umount May 27 16:59:51.172239 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 16:59:51.172239 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 16:59:51.172239 ignition[1039]: INFO : umount: umount passed May 27 16:59:51.172239 ignition[1039]: INFO : Ignition finished successfully May 27 16:59:51.157579 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 27 16:59:51.163635 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 27 16:59:51.168966 systemd[1]: sysroot-boot.service: Deactivated successfully. May 27 16:59:51.169078 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 27 16:59:51.173046 systemd[1]: ignition-mount.service: Deactivated successfully. May 27 16:59:51.173143 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 27 16:59:51.174296 systemd[1]: Stopped target network.target - Network. May 27 16:59:51.175596 systemd[1]: ignition-disks.service: Deactivated successfully. May 27 16:59:51.175648 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 27 16:59:51.177211 systemd[1]: ignition-kargs.service: Deactivated successfully. May 27 16:59:51.177253 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 27 16:59:51.179914 systemd[1]: ignition-setup.service: Deactivated successfully. May 27 16:59:51.179961 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 27 16:59:51.181701 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 27 16:59:51.181741 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 27 16:59:51.183692 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 27 16:59:51.183737 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 27 16:59:51.185659 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 27 16:59:51.187518 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 27 16:59:51.191739 systemd[1]: systemd-resolved.service: Deactivated successfully. May 27 16:59:51.191841 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 27 16:59:51.195158 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 27 16:59:51.195606 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 27 16:59:51.195684 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 16:59:51.198409 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 27 16:59:51.198681 systemd[1]: systemd-networkd.service: Deactivated successfully. May 27 16:59:51.198794 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 27 16:59:51.202783 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 27 16:59:51.204436 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 27 16:59:51.204473 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 27 16:59:51.207122 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 27 16:59:51.207909 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 27 16:59:51.207960 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 16:59:51.209788 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 27 16:59:51.209836 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 27 16:59:51.212192 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 27 16:59:51.212233 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 27 16:59:51.214372 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 16:59:51.232842 systemd[1]: systemd-udevd.service: Deactivated successfully. May 27 16:59:51.232995 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 16:59:51.235497 systemd[1]: network-cleanup.service: Deactivated successfully. May 27 16:59:51.235663 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 27 16:59:51.237511 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 27 16:59:51.237634 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 27 16:59:51.238707 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 27 16:59:51.238740 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 27 16:59:51.240416 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 27 16:59:51.240465 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 27 16:59:51.243046 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 27 16:59:51.243096 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 27 16:59:51.245705 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 27 16:59:51.245760 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 16:59:51.249238 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 27 16:59:51.250287 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 27 16:59:51.250346 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 27 16:59:51.255712 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 27 16:59:51.255758 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 16:59:51.258608 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 27 16:59:51.258648 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 16:59:51.261526 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 27 16:59:51.261576 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 27 16:59:51.263703 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 16:59:51.263746 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 16:59:51.267343 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 27 16:59:51.268610 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 27 16:59:51.270269 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 27 16:59:51.272517 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 27 16:59:51.288566 systemd[1]: Switching root. May 27 16:59:51.321487 systemd-journald[243]: Journal stopped May 27 16:59:51.961744 systemd-journald[243]: Received SIGTERM from PID 1 (systemd). May 27 16:59:51.961791 kernel: SELinux: policy capability network_peer_controls=1 May 27 16:59:51.961804 kernel: SELinux: policy capability open_perms=1 May 27 16:59:51.961816 kernel: SELinux: policy capability extended_socket_class=1 May 27 16:59:51.961826 kernel: SELinux: policy capability always_check_network=0 May 27 16:59:51.961836 kernel: SELinux: policy capability cgroup_seclabel=1 May 27 16:59:51.961845 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 27 16:59:51.961857 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 27 16:59:51.961866 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 27 16:59:51.961879 kernel: SELinux: policy capability userspace_initial_context=0 May 27 16:59:51.961890 kernel: audit: type=1403 audit(1748365191.401:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 27 16:59:51.961905 systemd[1]: Successfully loaded SELinux policy in 51.420ms. May 27 16:59:51.961921 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.259ms. May 27 16:59:51.961932 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) May 27 16:59:51.961943 systemd[1]: Detected virtualization kvm. May 27 16:59:51.961953 systemd[1]: Detected architecture arm64. May 27 16:59:51.961962 systemd[1]: Detected first boot. May 27 16:59:51.961974 systemd[1]: Initializing machine ID from VM UUID. May 27 16:59:51.961984 zram_generator::config[1086]: No configuration found. May 27 16:59:51.961996 kernel: NET: Registered PF_VSOCK protocol family May 27 16:59:51.962005 systemd[1]: Populated /etc with preset unit settings. May 27 16:59:51.962015 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 27 16:59:51.962026 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 27 16:59:51.962036 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 27 16:59:51.962047 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 27 16:59:51.962057 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 27 16:59:51.962067 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 27 16:59:51.962076 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 27 16:59:51.962086 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 27 16:59:51.962096 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 27 16:59:51.962107 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 27 16:59:51.962116 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 27 16:59:51.962127 systemd[1]: Created slice user.slice - User and Session Slice. May 27 16:59:51.962137 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 16:59:51.962147 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 16:59:51.962156 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 27 16:59:51.962166 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 27 16:59:51.962176 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 27 16:59:51.962187 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 16:59:51.962197 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 27 16:59:51.962207 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 16:59:51.962218 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 16:59:51.962228 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 27 16:59:51.962238 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 27 16:59:51.962247 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 27 16:59:51.962257 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 27 16:59:51.962267 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 16:59:51.962277 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 16:59:51.962288 systemd[1]: Reached target slices.target - Slice Units. May 27 16:59:51.962299 systemd[1]: Reached target swap.target - Swaps. May 27 16:59:51.962309 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 27 16:59:51.962319 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 27 16:59:51.962329 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 27 16:59:51.962339 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 16:59:51.962349 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 16:59:51.962359 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 16:59:51.962369 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 27 16:59:51.962379 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 27 16:59:51.962390 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 27 16:59:51.962400 systemd[1]: Mounting media.mount - External Media Directory... May 27 16:59:51.962410 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 27 16:59:51.962420 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 27 16:59:51.962430 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 27 16:59:51.962440 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). May 27 16:59:51.962450 systemd[1]: Reached target machines.target - Containers. May 27 16:59:51.962459 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 27 16:59:51.962470 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 16:59:51.962480 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 16:59:51.962491 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 27 16:59:51.962501 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 16:59:51.962510 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 16:59:51.962526 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 16:59:51.962537 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 27 16:59:51.962553 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 16:59:51.962564 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 27 16:59:51.962576 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 27 16:59:51.962586 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 27 16:59:51.962595 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 27 16:59:51.962606 systemd[1]: Stopped systemd-fsck-usr.service. May 27 16:59:51.962616 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). May 27 16:59:51.962626 kernel: fuse: init (API version 7.41) May 27 16:59:51.962635 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 16:59:51.962645 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 16:59:51.962656 kernel: loop: module loaded May 27 16:59:51.962665 kernel: ACPI: bus type drm_connector registered May 27 16:59:51.962674 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 16:59:51.962684 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 27 16:59:51.962694 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 27 16:59:51.962704 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 16:59:51.962715 systemd[1]: verity-setup.service: Deactivated successfully. May 27 16:59:51.962725 systemd[1]: Stopped verity-setup.service. May 27 16:59:51.962753 systemd-journald[1161]: Collecting audit messages is disabled. May 27 16:59:51.962779 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 27 16:59:51.962789 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 27 16:59:51.962799 systemd-journald[1161]: Journal started May 27 16:59:51.962820 systemd-journald[1161]: Runtime Journal (/run/log/journal/d83ac5789e424f9cbd15f85d84b71e77) is 6M, max 48.5M, 42.4M free. May 27 16:59:51.964630 systemd[1]: Mounted media.mount - External Media Directory. May 27 16:59:51.763021 systemd[1]: Queued start job for default target multi-user.target. May 27 16:59:51.787336 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 27 16:59:51.787690 systemd[1]: systemd-journald.service: Deactivated successfully. May 27 16:59:51.966597 systemd[1]: Started systemd-journald.service - Journal Service. May 27 16:59:51.966989 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 27 16:59:51.967878 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 27 16:59:51.968794 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 27 16:59:51.971003 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 27 16:59:51.973868 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 16:59:51.975311 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 27 16:59:51.975478 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 27 16:59:51.976914 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 16:59:51.977654 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 16:59:51.978767 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 16:59:51.978931 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 16:59:51.979941 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 16:59:51.980096 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 16:59:51.981212 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 27 16:59:51.981370 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 27 16:59:51.982410 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 16:59:51.982602 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 16:59:51.983769 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 16:59:51.984845 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 16:59:51.986920 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 27 16:59:51.988336 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 27 16:59:52.000028 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 16:59:52.002398 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 27 16:59:52.004405 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 27 16:59:52.005333 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 27 16:59:52.005363 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 16:59:52.006954 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 27 16:59:52.014628 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 27 16:59:52.015699 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 16:59:52.016637 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 27 16:59:52.018427 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 27 16:59:52.019652 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 16:59:52.020826 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 27 16:59:52.021881 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 16:59:52.024730 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 16:59:52.028832 systemd-journald[1161]: Time spent on flushing to /var/log/journal/d83ac5789e424f9cbd15f85d84b71e77 is 22.485ms for 852 entries. May 27 16:59:52.028832 systemd-journald[1161]: System Journal (/var/log/journal/d83ac5789e424f9cbd15f85d84b71e77) is 8M, max 195.6M, 187.6M free. May 27 16:59:52.063117 systemd-journald[1161]: Received client request to flush runtime journal. May 27 16:59:52.063164 kernel: loop0: detected capacity change from 0 to 107312 May 27 16:59:52.028879 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 27 16:59:52.031969 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 16:59:52.036583 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 16:59:52.037861 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 27 16:59:52.039153 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 27 16:59:52.040598 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 27 16:59:52.045748 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 27 16:59:52.048477 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 27 16:59:52.052559 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 16:59:52.066369 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 27 16:59:52.068499 systemd-tmpfiles[1204]: ACLs are not supported, ignoring. May 27 16:59:52.068512 systemd-tmpfiles[1204]: ACLs are not supported, ignoring. May 27 16:59:52.073716 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 16:59:52.076281 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 27 16:59:52.081585 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 27 16:59:52.087198 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 27 16:59:52.105329 kernel: loop1: detected capacity change from 0 to 138376 May 27 16:59:52.111279 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 27 16:59:52.114001 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 16:59:52.136570 kernel: loop2: detected capacity change from 0 to 107312 May 27 16:59:52.145131 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. May 27 16:59:52.145151 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. May 27 16:59:52.146670 kernel: loop3: detected capacity change from 0 to 138376 May 27 16:59:52.148980 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 16:59:52.153053 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 27 16:59:52.153386 (sd-merge)[1225]: Merged extensions into '/usr'. May 27 16:59:52.156499 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 27 16:59:52.159460 systemd[1]: Starting ensure-sysext.service... May 27 16:59:52.161769 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 16:59:52.192182 systemd[1]: Reload requested from client PID 1229 ('systemctl') (unit ensure-sysext.service)... May 27 16:59:52.192198 systemd[1]: Reloading... May 27 16:59:52.197673 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 27 16:59:52.197700 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 27 16:59:52.197891 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 27 16:59:52.198068 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 27 16:59:52.199263 systemd-tmpfiles[1230]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 27 16:59:52.199755 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. May 27 16:59:52.200019 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. May 27 16:59:52.204473 systemd-tmpfiles[1230]: Detected autofs mount point /boot during canonicalization of boot. May 27 16:59:52.204485 systemd-tmpfiles[1230]: Skipping /boot May 27 16:59:52.221787 systemd-tmpfiles[1230]: Detected autofs mount point /boot during canonicalization of boot. May 27 16:59:52.221799 systemd-tmpfiles[1230]: Skipping /boot May 27 16:59:52.262621 zram_generator::config[1257]: No configuration found. May 27 16:59:52.315631 ldconfig[1197]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 16:59:52.340966 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 27 16:59:52.402341 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 27 16:59:52.402712 systemd[1]: Reloading finished in 210 ms. May 27 16:59:52.424104 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 27 16:59:52.440054 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 16:59:52.448034 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 16:59:52.449988 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 27 16:59:52.459514 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 27 16:59:52.464287 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 16:59:52.467709 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 27 16:59:52.470440 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 16:59:52.472666 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 16:59:52.474599 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 16:59:52.477810 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 16:59:52.480711 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 16:59:52.480832 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). May 27 16:59:52.482742 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 16:59:52.482887 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 16:59:52.482968 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). May 27 16:59:52.485853 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 16:59:52.495686 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 16:59:52.496670 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 16:59:52.496801 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). May 27 16:59:52.499210 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 27 16:59:52.503662 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 16:59:52.507714 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 16:59:52.509317 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 16:59:52.509532 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 16:59:52.512182 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 16:59:52.512348 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 16:59:52.514200 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 16:59:52.514373 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 16:59:52.519969 systemd[1]: Finished ensure-sysext.service. May 27 16:59:52.524341 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 27 16:59:52.529963 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 16:59:52.530223 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 16:59:52.534796 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 27 16:59:52.537742 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 27 16:59:52.538789 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). May 27 16:59:52.544991 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 27 16:59:52.561558 augenrules[1334]: No rules May 27 16:59:52.562868 systemd[1]: audit-rules.service: Deactivated successfully. May 27 16:59:52.563162 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 16:59:52.591230 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 27 16:59:52.624863 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 27 16:59:52.627705 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 16:59:52.630734 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 27 16:59:52.654673 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 27 16:59:52.656050 systemd[1]: Reached target time-set.target - System Time Set. May 27 16:59:52.662929 systemd-resolved[1299]: Positive Trust Anchors: May 27 16:59:52.662945 systemd-resolved[1299]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 16:59:52.662977 systemd-resolved[1299]: 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 May 27 16:59:52.666889 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 27 16:59:52.673630 systemd-resolved[1299]: Defaulting to hostname 'linux'. May 27 16:59:52.676857 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 16:59:52.678031 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 16:59:52.684509 systemd-udevd[1346]: Using default interface naming scheme 'v255'. May 27 16:59:52.699398 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 16:59:52.701052 systemd[1]: Reached target sysinit.target - System Initialization. May 27 16:59:52.702912 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 27 16:59:52.704135 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 27 16:59:52.706242 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 27 16:59:52.707534 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 27 16:59:52.708826 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 27 16:59:52.710128 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 27 16:59:52.710163 systemd[1]: Reached target paths.target - Path Units. May 27 16:59:52.711233 systemd[1]: Reached target timers.target - Timer Units. May 27 16:59:52.712923 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 27 16:59:52.715461 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 16:59:52.719254 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 27 16:59:52.720618 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 27 16:59:52.721885 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 27 16:59:52.727307 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 27 16:59:52.728802 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 27 16:59:52.732644 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 16:59:52.733805 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 16:59:52.736743 systemd[1]: Reached target sockets.target - Socket Units. May 27 16:59:52.737616 systemd[1]: Reached target basic.target - Basic System. May 27 16:59:52.738339 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 27 16:59:52.738366 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 27 16:59:52.739232 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 27 16:59:52.741937 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 27 16:59:52.743817 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 27 16:59:52.747107 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 27 16:59:52.747858 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). May 27 16:59:52.754308 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 16:59:52.756088 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 27 16:59:52.758725 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 27 16:59:52.765949 systemd[1]: Starting systemd-logind.service - User Login Management... May 27 16:59:52.767567 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 27 16:59:52.767985 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 27 16:59:52.768752 systemd[1]: Starting update-engine.service - Update Engine... May 27 16:59:52.772544 jq[1382]: false May 27 16:59:52.772793 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 27 16:59:52.774112 systemd[1]: motdgen.service: Deactivated successfully. May 27 16:59:52.774329 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 16:59:52.776803 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 27 16:59:52.778604 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 27 16:59:52.779220 jq[1395]: true May 27 16:59:52.782241 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 27 16:59:52.782427 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 27 16:59:52.789740 jq[1396]: true May 27 16:59:52.797444 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 27 16:59:52.806248 extend-filesystems[1383]: Found loop2 May 27 16:59:52.808122 extend-filesystems[1383]: Found loop3 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda May 27 16:59:52.808122 extend-filesystems[1383]: Found vda1 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda2 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda3 May 27 16:59:52.808122 extend-filesystems[1383]: Found usr May 27 16:59:52.808122 extend-filesystems[1383]: Found vda4 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda6 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda7 May 27 16:59:52.808122 extend-filesystems[1383]: Found vda9 May 27 16:59:52.807852 systemd[1]: extend-filesystems.service: Deactivated successfully. May 27 16:59:52.808908 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 27 16:59:52.830197 dbus-daemon[1380]: [system] SELinux support is enabled May 27 16:59:52.830401 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 27 16:59:52.833513 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). May 27 16:59:52.834362 update_engine[1391]: I20250527 16:59:52.834209 1391 main.cc:92] Flatcar Update Engine starting May 27 16:59:52.836698 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 27 16:59:52.837736 update_engine[1391]: I20250527 16:59:52.836694 1391 update_check_scheduler.cc:74] Next update check in 10m54s May 27 16:59:52.837876 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). May 27 16:59:52.837964 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 27 16:59:52.839066 systemd[1]: Started update-engine.service - Update Engine. May 27 16:59:52.843874 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 27 16:59:52.848910 bash[1422]: Updated "/home/core/.ssh/authorized_keys" May 27 16:59:52.848882 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 27 16:59:52.851587 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 27 16:59:52.853097 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 27 16:59:52.884871 systemd-logind[1388]: New seat seat0. May 27 16:59:52.885605 systemd[1]: Started systemd-logind.service - User Login Management. May 27 16:59:52.901460 systemd-networkd[1379]: lo: Link UP May 27 16:59:52.901473 systemd-networkd[1379]: lo: Gained carrier May 27 16:59:52.902314 systemd-networkd[1379]: Enumeration completed May 27 16:59:52.902801 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 16:59:52.902805 systemd-networkd[1379]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 16:59:52.903437 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 27 16:59:52.903877 systemd-networkd[1379]: eth0: Link UP May 27 16:59:52.904001 systemd-networkd[1379]: eth0: Gained carrier May 27 16:59:52.904015 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 16:59:52.907375 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 16:59:52.908900 systemd[1]: Reached target network.target - Network. May 27 16:59:52.912758 systemd[1]: Starting containerd.service - containerd container runtime... May 27 16:59:52.914761 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 27 16:59:52.919951 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 27 16:59:52.924627 systemd-networkd[1379]: eth0: DHCPv4 address 10.0.0.40/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 27 16:59:52.924925 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 27 16:59:52.928846 systemd-timesyncd[1328]: Network configuration changed, trying to establish connection. May 27 16:59:52.525503 systemd-resolved[1299]: Clock change detected. Flushing caches. May 27 16:59:52.530559 systemd-journald[1161]: Time jumped backwards, rotating. May 27 16:59:52.525560 systemd-timesyncd[1328]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 27 16:59:52.525602 systemd-timesyncd[1328]: Initial clock synchronization to Tue 2025-05-27 16:59:52.525453 UTC. May 27 16:59:52.535846 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 27 16:59:52.544756 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 27 16:59:52.545638 (ntainerd)[1448]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 16:59:52.549679 locksmithd[1423]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 27 16:59:52.651486 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 16:59:52.659106 systemd-logind[1388]: Watching system buttons on /dev/input/event0 (Power Button) May 27 16:59:52.707960 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 16:59:52.754118 containerd[1448]: time="2025-05-27T16:59:52Z" 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 May 27 16:59:52.755938 containerd[1448]: time="2025-05-27T16:59:52.755895540Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764685540Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9µs" May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764720660Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764737660Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764880500Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764894500Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764914460Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764960060Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.764970500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.765158220Z" 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 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.765170780Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.765180620Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 16:59:52.765553 containerd[1448]: time="2025-05-27T16:59:52.765187820Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 16:59:52.765759 containerd[1448]: time="2025-05-27T16:59:52.765258140Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 16:59:52.765759 containerd[1448]: time="2025-05-27T16:59:52.765422940Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 16:59:52.765759 containerd[1448]: time="2025-05-27T16:59:52.765447340Z" 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 May 27 16:59:52.765759 containerd[1448]: time="2025-05-27T16:59:52.765457460Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 16:59:52.765759 containerd[1448]: time="2025-05-27T16:59:52.765504940Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 16:59:52.766131 containerd[1448]: time="2025-05-27T16:59:52.766107500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 16:59:52.766252 containerd[1448]: time="2025-05-27T16:59:52.766234900Z" level=info msg="metadata content store policy set" policy=shared May 27 16:59:52.770141 containerd[1448]: time="2025-05-27T16:59:52.770112420Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 16:59:52.770248 containerd[1448]: time="2025-05-27T16:59:52.770235460Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 16:59:52.770357 containerd[1448]: time="2025-05-27T16:59:52.770341820Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 16:59:52.770414 containerd[1448]: time="2025-05-27T16:59:52.770401020Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 16:59:52.770461 containerd[1448]: time="2025-05-27T16:59:52.770449060Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 16:59:52.770542 containerd[1448]: time="2025-05-27T16:59:52.770509020Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 16:59:52.770682 containerd[1448]: time="2025-05-27T16:59:52.770664940Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 16:59:52.770740 containerd[1448]: time="2025-05-27T16:59:52.770726180Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 16:59:52.770805 containerd[1448]: time="2025-05-27T16:59:52.770792180Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 16:59:52.770860 containerd[1448]: time="2025-05-27T16:59:52.770848180Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 16:59:52.770906 containerd[1448]: time="2025-05-27T16:59:52.770894340Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 16:59:52.770957 containerd[1448]: time="2025-05-27T16:59:52.770944980Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 16:59:52.771122 containerd[1448]: time="2025-05-27T16:59:52.771099340Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 16:59:52.771192 containerd[1448]: time="2025-05-27T16:59:52.771178700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 16:59:52.771247 containerd[1448]: time="2025-05-27T16:59:52.771233220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 16:59:52.771297 containerd[1448]: time="2025-05-27T16:59:52.771284220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 16:59:52.771357 containerd[1448]: time="2025-05-27T16:59:52.771344260Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 16:59:52.771408 containerd[1448]: time="2025-05-27T16:59:52.771395420Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 16:59:52.771462 containerd[1448]: time="2025-05-27T16:59:52.771447940Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 16:59:52.771600 containerd[1448]: time="2025-05-27T16:59:52.771581540Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 16:59:52.771670 containerd[1448]: time="2025-05-27T16:59:52.771657900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 16:59:52.771719 containerd[1448]: time="2025-05-27T16:59:52.771707940Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 16:59:52.771768 containerd[1448]: time="2025-05-27T16:59:52.771754980Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 16:59:52.773832 containerd[1448]: time="2025-05-27T16:59:52.773805340Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 16:59:52.773906 containerd[1448]: time="2025-05-27T16:59:52.773893780Z" level=info msg="Start snapshots syncer" May 27 16:59:52.773995 containerd[1448]: time="2025-05-27T16:59:52.773981460Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 16:59:52.774652 containerd[1448]: time="2025-05-27T16:59:52.774607620Z" 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\"}" May 27 16:59:52.774931 containerd[1448]: time="2025-05-27T16:59:52.774912300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 16:59:52.775066 containerd[1448]: time="2025-05-27T16:59:52.775049620Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 16:59:52.775258 containerd[1448]: time="2025-05-27T16:59:52.775236220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 16:59:52.775332 containerd[1448]: time="2025-05-27T16:59:52.775318260Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 16:59:52.775395 containerd[1448]: time="2025-05-27T16:59:52.775382100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 16:59:52.775445 containerd[1448]: time="2025-05-27T16:59:52.775434140Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 16:59:52.775506 containerd[1448]: time="2025-05-27T16:59:52.775481860Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 16:59:52.775591 containerd[1448]: time="2025-05-27T16:59:52.775556420Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 16:59:52.775648 containerd[1448]: time="2025-05-27T16:59:52.775633620Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 16:59:52.775726 containerd[1448]: time="2025-05-27T16:59:52.775710900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 16:59:52.775787 containerd[1448]: time="2025-05-27T16:59:52.775773580Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 16:59:52.775834 containerd[1448]: time="2025-05-27T16:59:52.775822500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 16:59:52.775933 containerd[1448]: time="2025-05-27T16:59:52.775916180Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 16:59:52.776008 containerd[1448]: time="2025-05-27T16:59:52.775976700Z" 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 May 27 16:59:52.776055 containerd[1448]: time="2025-05-27T16:59:52.776042980Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 16:59:52.776101 containerd[1448]: time="2025-05-27T16:59:52.776087540Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 16:59:52.776151 containerd[1448]: time="2025-05-27T16:59:52.776139860Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 16:59:52.776198 containerd[1448]: time="2025-05-27T16:59:52.776186100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 16:59:52.776248 containerd[1448]: time="2025-05-27T16:59:52.776235260Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 16:59:52.776365 containerd[1448]: time="2025-05-27T16:59:52.776353700Z" level=info msg="runtime interface created" May 27 16:59:52.776405 containerd[1448]: time="2025-05-27T16:59:52.776394820Z" level=info msg="created NRI interface" May 27 16:59:52.776455 containerd[1448]: time="2025-05-27T16:59:52.776442060Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 16:59:52.776528 containerd[1448]: time="2025-05-27T16:59:52.776502660Z" level=info msg="Connect containerd service" May 27 16:59:52.776623 containerd[1448]: time="2025-05-27T16:59:52.776606820Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 16:59:52.777460 containerd[1448]: time="2025-05-27T16:59:52.777431180Z" 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" May 27 16:59:52.877548 sshd_keygen[1407]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 27 16:59:52.880064 containerd[1448]: time="2025-05-27T16:59:52.880025900Z" level=info msg="Start subscribing containerd event" May 27 16:59:52.880188 containerd[1448]: time="2025-05-27T16:59:52.880171580Z" level=info msg="Start recovering state" May 27 16:59:52.880692 containerd[1448]: time="2025-05-27T16:59:52.880671500Z" level=info msg="Start event monitor" May 27 16:59:52.880786 containerd[1448]: time="2025-05-27T16:59:52.880773020Z" level=info msg="Start cni network conf syncer for default" May 27 16:59:52.880831 containerd[1448]: time="2025-05-27T16:59:52.880820700Z" level=info msg="Start streaming server" May 27 16:59:52.880877 containerd[1448]: time="2025-05-27T16:59:52.880866860Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 16:59:52.880921 containerd[1448]: time="2025-05-27T16:59:52.880909820Z" level=info msg="runtime interface starting up..." May 27 16:59:52.880961 containerd[1448]: time="2025-05-27T16:59:52.880951220Z" level=info msg="starting plugins..." May 27 16:59:52.881016 containerd[1448]: time="2025-05-27T16:59:52.881003780Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 16:59:52.881131 containerd[1448]: time="2025-05-27T16:59:52.880591780Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 16:59:52.881232 containerd[1448]: time="2025-05-27T16:59:52.881217980Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 16:59:52.881426 systemd[1]: Started containerd.service - containerd container runtime. May 27 16:59:52.883032 containerd[1448]: time="2025-05-27T16:59:52.882909780Z" level=info msg="containerd successfully booted in 0.129110s" May 27 16:59:52.896541 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 27 16:59:52.899605 systemd[1]: Starting issuegen.service - Generate /run/issue... May 27 16:59:52.920652 systemd[1]: issuegen.service: Deactivated successfully. May 27 16:59:52.920864 systemd[1]: Finished issuegen.service - Generate /run/issue. May 27 16:59:52.923125 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 27 16:59:52.942537 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 27 16:59:52.946758 systemd[1]: Started getty@tty1.service - Getty on tty1. May 27 16:59:52.948476 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 27 16:59:52.949514 systemd[1]: Reached target getty.target - Login Prompts. May 27 16:59:54.133648 systemd-networkd[1379]: eth0: Gained IPv6LL May 27 16:59:54.137616 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 27 16:59:54.139378 systemd[1]: Reached target network-online.target - Network is Online. May 27 16:59:54.141871 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 27 16:59:54.143979 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 27 16:59:54.172477 systemd[1]: coreos-metadata.service: Deactivated successfully. May 27 16:59:54.172747 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 27 16:59:54.174316 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 27 16:59:54.176159 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 27 16:59:54.177677 systemd[1]: Reached target multi-user.target - Multi-User System. May 27 16:59:54.178908 systemd[1]: Startup finished in 2.087s (kernel) + 3.780s (initrd) + 3.233s (userspace) = 9.101s. May 27 17:00:00.507432 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 27 17:00:00.510864 systemd[1]: Started sshd@0-10.0.0.40:22-10.0.0.1:44604.service - OpenSSH per-connection server daemon (10.0.0.1:44604). May 27 17:00:00.581110 sshd[1529]: Accepted publickey for core from 10.0.0.1 port 44604 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:00.582734 sshd-session[1529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:00.588935 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 27 17:00:00.589875 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 27 17:00:00.596376 systemd-logind[1388]: New session 1 of user core. May 27 17:00:00.608430 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 27 17:00:00.612173 systemd[1]: Starting user@500.service - User Manager for UID 500... May 27 17:00:00.628436 (systemd)[1533]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 27 17:00:00.630556 systemd-logind[1388]: New session c1 of user core. May 27 17:00:00.749947 systemd[1533]: Queued start job for default target default.target. May 27 17:00:00.759383 systemd[1533]: Created slice app.slice - User Application Slice. May 27 17:00:00.759413 systemd[1533]: Reached target paths.target - Paths. May 27 17:00:00.759448 systemd[1533]: Reached target timers.target - Timers. May 27 17:00:00.760631 systemd[1533]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 17:00:00.769383 systemd[1533]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 17:00:00.769440 systemd[1533]: Reached target sockets.target - Sockets. May 27 17:00:00.769486 systemd[1533]: Reached target basic.target - Basic System. May 27 17:00:00.769534 systemd[1533]: Reached target default.target - Main User Target. May 27 17:00:00.769560 systemd[1533]: Startup finished in 133ms. May 27 17:00:00.769768 systemd[1]: Started user@500.service - User Manager for UID 500. May 27 17:00:00.771046 systemd[1]: Started session-1.scope - Session 1 of User core. May 27 17:00:00.836896 systemd[1]: Started sshd@1-10.0.0.40:22-10.0.0.1:44614.service - OpenSSH per-connection server daemon (10.0.0.1:44614). May 27 17:00:00.892391 sshd[1544]: Accepted publickey for core from 10.0.0.1 port 44614 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:00.893838 sshd-session[1544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:00.897905 systemd-logind[1388]: New session 2 of user core. May 27 17:00:00.919708 systemd[1]: Started session-2.scope - Session 2 of User core. May 27 17:00:00.971679 sshd[1546]: Connection closed by 10.0.0.1 port 44614 May 27 17:00:00.972211 sshd-session[1544]: pam_unix(sshd:session): session closed for user core May 27 17:00:00.990136 systemd[1]: sshd@1-10.0.0.40:22-10.0.0.1:44614.service: Deactivated successfully. May 27 17:00:00.992966 systemd[1]: session-2.scope: Deactivated successfully. May 27 17:00:00.993707 systemd-logind[1388]: Session 2 logged out. Waiting for processes to exit. May 27 17:00:00.996452 systemd[1]: Started sshd@2-10.0.0.40:22-10.0.0.1:44626.service - OpenSSH per-connection server daemon (10.0.0.1:44626). May 27 17:00:00.997071 systemd-logind[1388]: Removed session 2. May 27 17:00:01.042998 sshd[1552]: Accepted publickey for core from 10.0.0.1 port 44626 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:01.044819 sshd-session[1552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:01.049419 systemd-logind[1388]: New session 3 of user core. May 27 17:00:01.058689 systemd[1]: Started session-3.scope - Session 3 of User core. May 27 17:00:01.108049 sshd[1554]: Connection closed by 10.0.0.1 port 44626 May 27 17:00:01.107210 sshd-session[1552]: pam_unix(sshd:session): session closed for user core May 27 17:00:01.122473 systemd[1]: sshd@2-10.0.0.40:22-10.0.0.1:44626.service: Deactivated successfully. May 27 17:00:01.123898 systemd[1]: session-3.scope: Deactivated successfully. May 27 17:00:01.124544 systemd-logind[1388]: Session 3 logged out. Waiting for processes to exit. May 27 17:00:01.126957 systemd[1]: Started sshd@3-10.0.0.40:22-10.0.0.1:44636.service - OpenSSH per-connection server daemon (10.0.0.1:44636). May 27 17:00:01.127375 systemd-logind[1388]: Removed session 3. May 27 17:00:01.172911 sshd[1560]: Accepted publickey for core from 10.0.0.1 port 44636 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:01.174307 sshd-session[1560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:01.178878 systemd-logind[1388]: New session 4 of user core. May 27 17:00:01.190677 systemd[1]: Started session-4.scope - Session 4 of User core. May 27 17:00:01.243185 sshd[1562]: Connection closed by 10.0.0.1 port 44636 May 27 17:00:01.243056 sshd-session[1560]: pam_unix(sshd:session): session closed for user core May 27 17:00:01.258586 systemd[1]: sshd@3-10.0.0.40:22-10.0.0.1:44636.service: Deactivated successfully. May 27 17:00:01.260833 systemd[1]: session-4.scope: Deactivated successfully. May 27 17:00:01.262306 systemd-logind[1388]: Session 4 logged out. Waiting for processes to exit. May 27 17:00:01.264532 systemd[1]: Started sshd@4-10.0.0.40:22-10.0.0.1:44640.service - OpenSSH per-connection server daemon (10.0.0.1:44640). May 27 17:00:01.265476 systemd-logind[1388]: Removed session 4. May 27 17:00:01.303586 sshd[1568]: Accepted publickey for core from 10.0.0.1 port 44640 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:01.304882 sshd-session[1568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:01.309245 systemd-logind[1388]: New session 5 of user core. May 27 17:00:01.319671 systemd[1]: Started session-5.scope - Session 5 of User core. May 27 17:00:01.385924 sudo[1571]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 27 17:00:01.386181 sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:00:01.402053 sudo[1571]: pam_unix(sudo:session): session closed for user root May 27 17:00:01.407050 sshd[1570]: Connection closed by 10.0.0.1 port 44640 May 27 17:00:01.407453 sshd-session[1568]: pam_unix(sshd:session): session closed for user core May 27 17:00:01.415423 systemd[1]: sshd@4-10.0.0.40:22-10.0.0.1:44640.service: Deactivated successfully. May 27 17:00:01.416895 systemd[1]: session-5.scope: Deactivated successfully. May 27 17:00:01.417547 systemd-logind[1388]: Session 5 logged out. Waiting for processes to exit. May 27 17:00:01.420096 systemd[1]: Started sshd@5-10.0.0.40:22-10.0.0.1:44652.service - OpenSSH per-connection server daemon (10.0.0.1:44652). May 27 17:00:01.420873 systemd-logind[1388]: Removed session 5. May 27 17:00:01.474659 sshd[1577]: Accepted publickey for core from 10.0.0.1 port 44652 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:01.475949 sshd-session[1577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:01.479907 systemd-logind[1388]: New session 6 of user core. May 27 17:00:01.488705 systemd[1]: Started session-6.scope - Session 6 of User core. May 27 17:00:01.538870 sudo[1581]: 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 May 27 17:00:01.539127 sudo[1581]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:00:01.543430 sudo[1581]: pam_unix(sudo:session): session closed for user root May 27 17:00:01.547823 sudo[1580]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 27 17:00:01.548075 sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:00:01.556471 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 17:00:01.597387 augenrules[1603]: No rules May 27 17:00:01.598616 systemd[1]: audit-rules.service: Deactivated successfully. May 27 17:00:01.600597 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 17:00:01.602776 sudo[1580]: pam_unix(sudo:session): session closed for user root May 27 17:00:01.604578 sshd[1579]: Connection closed by 10.0.0.1 port 44652 May 27 17:00:01.604478 sshd-session[1577]: pam_unix(sshd:session): session closed for user core May 27 17:00:01.615344 systemd[1]: sshd@5-10.0.0.40:22-10.0.0.1:44652.service: Deactivated successfully. May 27 17:00:01.616601 systemd[1]: session-6.scope: Deactivated successfully. May 27 17:00:01.619275 systemd-logind[1388]: Session 6 logged out. Waiting for processes to exit. May 27 17:00:01.620354 systemd[1]: Started sshd@6-10.0.0.40:22-10.0.0.1:44666.service - OpenSSH per-connection server daemon (10.0.0.1:44666). May 27 17:00:01.621118 systemd-logind[1388]: Removed session 6. May 27 17:00:01.670952 sshd[1612]: Accepted publickey for core from 10.0.0.1 port 44666 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:01.672088 sshd-session[1612]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:01.676435 systemd-logind[1388]: New session 7 of user core. May 27 17:00:01.686701 systemd[1]: Started session-7.scope - Session 7 of User core. May 27 17:00:01.744087 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 17:00:01.755847 (dockerd)[1616]: 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 May 27 17:00:02.023660 dockerd[1616]: time="2025-05-27T17:00:02.023601940Z" level=info msg="Starting up" May 27 17:00:02.025569 dockerd[1616]: time="2025-05-27T17:00:02.025514420Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 17:00:02.071134 dockerd[1616]: time="2025-05-27T17:00:02.071084660Z" level=info msg="Loading containers: start." May 27 17:00:02.079552 kernel: Initializing XFRM netlink socket May 27 17:00:02.270403 systemd-networkd[1379]: docker0: Link UP May 27 17:00:02.273299 dockerd[1616]: time="2025-05-27T17:00:02.273258180Z" level=info msg="Loading containers: done." May 27 17:00:02.287674 dockerd[1616]: time="2025-05-27T17:00:02.287585540Z" 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 May 27 17:00:02.287674 dockerd[1616]: time="2025-05-27T17:00:02.287662260Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 17:00:02.287830 dockerd[1616]: time="2025-05-27T17:00:02.287764700Z" level=info msg="Initializing buildkit" May 27 17:00:02.313857 dockerd[1616]: time="2025-05-27T17:00:02.313801780Z" level=info msg="Completed buildkit initialization" May 27 17:00:02.320664 dockerd[1616]: time="2025-05-27T17:00:02.320604700Z" level=info msg="Daemon has completed initialization" May 27 17:00:02.320757 dockerd[1616]: time="2025-05-27T17:00:02.320680140Z" level=info msg="API listen on /run/docker.sock" May 27 17:00:02.320820 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 17:00:02.331978 sshd[1614]: Connection closed by 10.0.0.1 port 44666 May 27 17:00:02.332318 sshd-session[1612]: pam_unix(sshd:session): session closed for user core May 27 17:00:02.347576 systemd[1]: sshd@6-10.0.0.40:22-10.0.0.1:44666.service: Deactivated successfully. May 27 17:00:02.349861 systemd[1]: session-7.scope: Deactivated successfully. May 27 17:00:02.350477 systemd-logind[1388]: Session 7 logged out. Waiting for processes to exit. May 27 17:00:02.354353 systemd[1]: Started sshd@7-10.0.0.40:22-10.0.0.1:44678.service - OpenSSH per-connection server daemon (10.0.0.1:44678). May 27 17:00:02.355258 systemd-logind[1388]: Removed session 7. May 27 17:00:02.408413 sshd[1819]: Accepted publickey for core from 10.0.0.1 port 44678 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:02.409557 sshd-session[1819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:02.413128 systemd-logind[1388]: New session 8 of user core. May 27 17:00:02.419688 systemd[1]: Started session-8.scope - Session 8 of User core. May 27 17:00:12.667962 systemd[1]: var-lib-docker-overlay2-935661ddca230509956ba784afbb3775cba8a7b1e4d79547c4bc53085da08b0c\x2dinit-merged.mount: Deactivated successfully. May 27 17:00:12.706769 containerd[1448]: time="2025-05-27T17:00:12.706710780Z" level=info msg="connecting to shim 632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe" address="unix:///run/containerd/s/81d4fb9c2ef5b7fab6e2b5fa0a0da0eebd45d22fbcd6578eb7f3f3a6f0ff9ef8" namespace=moby protocol=ttrpc version=3 May 27 17:00:12.730693 systemd[1]: Started docker-632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe.scope - libcontainer container 632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe. May 27 17:00:12.743126 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 17:00:12.761901 kernel: docker0: port 1(veth6868454) entered blocking state May 27 17:00:12.761977 kernel: docker0: port 1(veth6868454) entered disabled state May 27 17:00:12.761993 kernel: veth6868454: entered allmulticast mode May 27 17:00:12.762006 kernel: veth6868454: entered promiscuous mode May 27 17:00:12.762938 systemd-networkd[1379]: veth6868454: Link UP May 27 17:00:12.768543 kernel: eth0: renamed from veth9f2de86 May 27 17:00:12.771025 kernel: docker0: port 1(veth6868454) entered blocking state May 27 17:00:12.771097 kernel: docker0: port 1(veth6868454) entered forwarding state May 27 17:00:12.771160 systemd-networkd[1379]: veth6868454: Gained carrier May 27 17:00:12.771870 systemd-networkd[1379]: docker0: Gained carrier May 27 17:00:12.794448 sshd[1821]: Connection closed by 10.0.0.1 port 44678 May 27 17:00:12.795074 sshd-session[1819]: pam_unix(sshd:session): session closed for user core May 27 17:00:12.807166 systemd[1]: sshd@7-10.0.0.40:22-10.0.0.1:44678.service: Deactivated successfully. May 27 17:00:12.810549 systemd[1]: session-8.scope: Deactivated successfully. May 27 17:00:12.811342 systemd-logind[1388]: Session 8 logged out. Waiting for processes to exit. May 27 17:00:12.815807 systemd[1]: Started sshd@8-10.0.0.40:22-10.0.0.1:35960.service - OpenSSH per-connection server daemon (10.0.0.1:35960). May 27 17:00:12.816499 systemd-logind[1388]: Removed session 8. May 27 17:00:12.862684 sshd[1895]: Accepted publickey for core from 10.0.0.1 port 35960 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:12.864276 sshd-session[1895]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:12.869177 systemd-logind[1388]: New session 9 of user core. May 27 17:00:12.876723 systemd[1]: Started session-9.scope - Session 9 of User core. May 27 17:00:12.931841 sshd[1897]: Connection closed by 10.0.0.1 port 35960 May 27 17:00:12.932614 sshd-session[1895]: pam_unix(sshd:session): session closed for user core May 27 17:00:12.954247 systemd[1]: sshd@8-10.0.0.40:22-10.0.0.1:35960.service: Deactivated successfully. May 27 17:00:12.957146 systemd[1]: session-9.scope: Deactivated successfully. May 27 17:00:12.959248 systemd-logind[1388]: Session 9 logged out. Waiting for processes to exit. May 27 17:00:12.964047 systemd[1]: Started sshd@9-10.0.0.40:22-10.0.0.1:35970.service - OpenSSH per-connection server daemon (10.0.0.1:35970). May 27 17:00:12.964762 systemd-logind[1388]: Removed session 9. May 27 17:00:13.010648 sshd[1903]: Accepted publickey for core from 10.0.0.1 port 35970 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:13.012069 sshd-session[1903]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:13.016816 systemd-logind[1388]: New session 10 of user core. May 27 17:00:13.022666 systemd[1]: Started session-10.scope - Session 10 of User core. May 27 17:00:13.075380 sudo[1906]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1448 May 27 17:00:13.075699 sudo[1906]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:00:13.083478 dockerd[1616]: time="2025-05-27T17:00:13.083170740Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 27 17:00:13.083478 dockerd[1616]: time="2025-05-27T17:00:13.083213580Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 27 17:00:13.083478 dockerd[1616]: time="2025-05-27T17:00:13.083183620Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 27 17:00:13.083478 dockerd[1616]: time="2025-05-27T17:00:13.083270020Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 27 17:00:13.083872 containerd[1448]: time="2025-05-27T17:00:13.082994700Z" level=info msg="Stop CRI service" May 27 17:00:13.083224 sudo[1906]: pam_unix(sudo:session): session closed for user root May 27 17:00:13.084073 containerd[1448]: time="2025-05-27T17:00:13.083983980Z" level=info msg="Stop CRI service" May 27 17:00:13.084073 containerd[1448]: time="2025-05-27T17:00:13.084052220Z" level=info msg="Event monitor stopped" May 27 17:00:13.084073 containerd[1448]: time="2025-05-27T17:00:13.084060420Z" level=info msg="Stream server stopped" May 27 17:00:13.086565 sshd[1905]: Connection closed by 10.0.0.1 port 35970 May 27 17:00:13.086339 sshd-session[1903]: pam_unix(sshd:session): session closed for user core May 27 17:00:13.099335 systemd[1]: containerd.service: Deactivated successfully. May 27 17:00:13.099450 systemd[1]: containerd.service: Unit process 1843 (containerd-shim) remains running after unit stopped. May 27 17:00:13.099755 systemd[1]: containerd.service: Consumed 370ms CPU time, 136.4M memory peak. May 27 17:00:13.100133 systemd[1]: sshd@9-10.0.0.40:22-10.0.0.1:35970.service: Deactivated successfully. May 27 17:00:13.103090 systemd[1]: session-10.scope: Deactivated successfully. May 27 17:00:13.104368 systemd-logind[1388]: Session 10 logged out. Waiting for processes to exit. May 27 17:00:13.107074 systemd[1]: Started sshd@10-10.0.0.40:22-10.0.0.1:35982.service - OpenSSH per-connection server daemon (10.0.0.1:35982). May 27 17:00:13.107660 systemd-logind[1388]: Removed session 10. May 27 17:00:13.162495 sshd[1913]: Accepted publickey for core from 10.0.0.1 port 35982 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:13.163942 sshd-session[1913]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:13.168592 systemd-logind[1388]: New session 11 of user core. May 27 17:00:13.182717 systemd[1]: Started session-11.scope - Session 11 of User core. May 27 17:00:13.238514 sshd[1915]: Connection closed by 10.0.0.1 port 35982 May 27 17:00:13.238985 sshd-session[1913]: pam_unix(sshd:session): session closed for user core May 27 17:00:13.243287 systemd[1]: sshd@10-10.0.0.40:22-10.0.0.1:35982.service: Deactivated successfully. May 27 17:00:13.246553 systemd[1]: session-11.scope: Deactivated successfully. May 27 17:00:13.247461 systemd-logind[1388]: Session 11 logged out. Waiting for processes to exit. May 27 17:00:13.248460 systemd-logind[1388]: Removed session 11. May 27 17:00:14.101682 systemd-networkd[1379]: docker0: Gained IPv6LL May 27 17:00:14.677743 systemd-networkd[1379]: veth6868454: Gained IPv6LL May 27 17:00:18.254307 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. May 27 17:00:18.254683 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 27 17:00:18.254866 dockerd[1616]: time="2025-05-27T17:00:18.254770140Z" level=info msg="Processing signal 'terminated'" May 27 17:00:19.259220 systemd[1]: Started sshd@11-10.0.0.40:22-10.0.0.1:35988.service - OpenSSH per-connection server daemon (10.0.0.1:35988). May 27 17:00:19.299047 sshd[1921]: Accepted publickey for core from 10.0.0.1 port 35988 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:19.299750 sshd-session[1921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:19.305104 systemd-logind[1388]: New session 12 of user core. May 27 17:00:19.311186 systemd[1]: Started session-12.scope - Session 12 of User core. May 27 17:00:19.373687 sshd[1923]: Connection closed by 10.0.0.1 port 35988 May 27 17:00:19.374145 sshd-session[1921]: pam_unix(sshd:session): session closed for user core May 27 17:00:19.379831 systemd[1]: sshd@11-10.0.0.40:22-10.0.0.1:35988.service: Deactivated successfully. May 27 17:00:19.382702 systemd[1]: session-12.scope: Deactivated successfully. May 27 17:00:19.385307 systemd-logind[1388]: Session 12 logged out. Waiting for processes to exit. May 27 17:00:19.388113 systemd-logind[1388]: Removed session 12. May 27 17:00:20.256429 dockerd[1616]: time="2025-05-27T17:00:20.256346980Z" level=error msg="Error sending stop (signal 15) to container" container=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe error="Cannot kill container 632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" May 27 17:00:20.256429 dockerd[1616]: time="2025-05-27T17:00:20.256426260Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe May 27 17:00:25.390637 systemd[1]: Started sshd@12-10.0.0.40:22-10.0.0.1:53472.service - OpenSSH per-connection server daemon (10.0.0.1:53472). May 27 17:00:25.435841 sshd[1929]: Accepted publickey for core from 10.0.0.1 port 53472 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:25.436972 sshd-session[1929]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:25.441469 systemd-logind[1388]: New session 13 of user core. May 27 17:00:25.459684 systemd[1]: Started session-13.scope - Session 13 of User core. May 27 17:00:25.511900 sshd[1931]: Connection closed by 10.0.0.1 port 53472 May 27 17:00:25.512373 sshd-session[1929]: pam_unix(sshd:session): session closed for user core May 27 17:00:25.515539 systemd[1]: sshd@12-10.0.0.40:22-10.0.0.1:53472.service: Deactivated successfully. May 27 17:00:25.517148 systemd[1]: session-13.scope: Deactivated successfully. May 27 17:00:25.518047 systemd-logind[1388]: Session 13 logged out. Waiting for processes to exit. May 27 17:00:25.519175 systemd-logind[1388]: Removed session 13. May 27 17:00:30.259659 dockerd[1616]: time="2025-05-27T17:00:30.259557232Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe error="context deadline exceeded" May 27 17:00:30.260453 systemd[1]: docker-632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe.scope: Deactivated successfully. May 27 17:00:31.535912 systemd[1]: Started sshd@13-10.0.0.40:22-10.0.0.1:53484.service - OpenSSH per-connection server daemon (10.0.0.1:53484). May 27 17:00:31.581225 sshd[1937]: Accepted publickey for core from 10.0.0.1 port 53484 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:31.582451 sshd-session[1937]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:31.594536 systemd-logind[1388]: New session 14 of user core. May 27 17:00:31.606783 systemd[1]: Started session-14.scope - Session 14 of User core. May 27 17:00:31.659460 sshd[1939]: Connection closed by 10.0.0.1 port 53484 May 27 17:00:31.659765 sshd-session[1937]: pam_unix(sshd:session): session closed for user core May 27 17:00:31.662909 systemd-logind[1388]: Session 14 logged out. Waiting for processes to exit. May 27 17:00:31.663094 systemd[1]: sshd@13-10.0.0.40:22-10.0.0.1:53484.service: Deactivated successfully. May 27 17:00:31.664452 systemd[1]: session-14.scope: Deactivated successfully. May 27 17:00:31.665953 systemd-logind[1388]: Removed session 14. May 27 17:00:33.255409 dockerd[1616]: time="2025-05-27T17:00:33.255317802Z" level=error msg="Force shutdown daemon" May 27 17:00:33.255828 dockerd[1616]: time="2025-05-27T17:00:33.255571643Z" level=info msg="Daemon shutdown complete" May 27 17:00:33.255828 dockerd[1616]: time="2025-05-27T17:00:33.255690003Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: latest balancer error: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" May 27 17:00:33.259086 systemd[1]: docker.service: Deactivated successfully. May 27 17:00:33.259426 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 27 17:00:33.259488 systemd[1]: docker.service: Consumed 810ms CPU time, 131.2M memory peak. May 27 17:00:33.259876 systemd[1]: docker.socket: Deactivated successfully. May 27 17:00:33.260159 systemd[1]: Closed docker.socket - Docker Socket for the API. May 27 17:00:33.260196 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 27 17:00:33.261645 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 17:00:33.261808 systemd[1]: containerd.service: Found left-over process 1843 (containerd-shim) in control group while starting unit. Ignoring. May 27 17:00:33.261818 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 17:00:33.275940 systemd[1]: Starting containerd.service - containerd container runtime... May 27 17:00:33.276395 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 17:00:33.285865 systemd[1]: containerd.service: Found left-over process 1843 (containerd-shim) in control group while starting unit. Ignoring. May 27 17:00:33.285877 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 17:00:33.289604 (ntainerd)[1950]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 17:00:33.301287 containerd[1950]: time="2025-05-27T17:00:33Z" 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 May 27 17:00:33.301707 containerd[1950]: time="2025-05-27T17:00:33.301661193Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 17:00:33.309348 containerd[1950]: time="2025-05-27T17:00:33.309307298Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8µs" May 27 17:00:33.309348 containerd[1950]: time="2025-05-27T17:00:33.309336858Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 17:00:33.309348 containerd[1950]: time="2025-05-27T17:00:33.309354338Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 17:00:33.309466 containerd[1950]: time="2025-05-27T17:00:33.309386218Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 17:00:33.309466 containerd[1950]: time="2025-05-27T17:00:33.309405898Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 17:00:33.309637 containerd[1950]: time="2025-05-27T17:00:33.309603779Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:00:33.309670 containerd[1950]: time="2025-05-27T17:00:33.309634259Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:00:33.309670 containerd[1950]: time="2025-05-27T17:00:33.309645459Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 17:00:33.309829 containerd[1950]: time="2025-05-27T17:00:33.309811299Z" 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 May 27 17:00:33.309851 containerd[1950]: time="2025-05-27T17:00:33.309827179Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:00:33.309851 containerd[1950]: time="2025-05-27T17:00:33.309837379Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:00:33.309851 containerd[1950]: time="2025-05-27T17:00:33.309844819Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 17:00:33.309902 containerd[1950]: time="2025-05-27T17:00:33.309869219Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 17:00:33.309956 containerd[1950]: time="2025-05-27T17:00:33.309943500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 17:00:33.309975 containerd[1950]: time="2025-05-27T17:00:33.309966540Z" 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 May 27 17:00:33.309993 containerd[1950]: time="2025-05-27T17:00:33.309975940Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 17:00:33.310015 containerd[1950]: time="2025-05-27T17:00:33.310001220Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 17:00:33.310202 containerd[1950]: time="2025-05-27T17:00:33.310188100Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 17:00:33.310233 containerd[1950]: time="2025-05-27T17:00:33.310209261Z" level=info msg="metadata content store policy set" policy=shared May 27 17:00:33.310331 containerd[1950]: time="2025-05-27T17:00:33.310316901Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 17:00:33.310359 containerd[1950]: time="2025-05-27T17:00:33.310344821Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 17:00:33.310359 containerd[1950]: time="2025-05-27T17:00:33.310355901Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 17:00:33.310401 containerd[1950]: time="2025-05-27T17:00:33.310366141Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 17:00:33.310401 containerd[1950]: time="2025-05-27T17:00:33.310376821Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 17:00:33.310401 containerd[1950]: time="2025-05-27T17:00:33.310388021Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310406621Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310420141Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310429741Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310439341Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310448941Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 17:00:33.310470 containerd[1950]: time="2025-05-27T17:00:33.310459661Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 17:00:33.310680 containerd[1950]: time="2025-05-27T17:00:33.310661502Z" level=info msg="connecting to shim 632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe" address="unix:///run/containerd/s/81d4fb9c2ef5b7fab6e2b5fa0a0da0eebd45d22fbcd6578eb7f3f3a6f0ff9ef8" namespace=moby protocol=ttrpc version=3 May 27 17:00:33.317173 containerd[1950]: time="2025-05-27T17:00:33.317142443Z" level=info msg="cleaning leaked shim process" id=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe namespace=moby May 27 17:00:33.325056 containerd[1950]: time="2025-05-27T17:00:33.324975789Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 27 17:00:33.325284 containerd[1950]: time="2025-05-27T17:00:33.325249709Z" level=info msg="shim disconnected" id=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe namespace=moby May 27 17:00:33.325317 containerd[1950]: time="2025-05-27T17:00:33.325276030Z" level=warning msg="cleaning up after shim disconnected" id=632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe namespace=moby May 27 17:00:33.325317 containerd[1950]: time="2025-05-27T17:00:33.325305990Z" level=info msg="cleaning up dead shim" namespace=moby May 27 17:00:33.334155 containerd[1950]: time="2025-05-27T17:00:33.334097538Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby May 27 17:00:33.334232 containerd[1950]: time="2025-05-27T17:00:33.334209219Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 17:00:33.334253 containerd[1950]: time="2025-05-27T17:00:33.334232099Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 17:00:33.334271 containerd[1950]: time="2025-05-27T17:00:33.334251059Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 17:00:33.334271 containerd[1950]: time="2025-05-27T17:00:33.334263939Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 17:00:33.334308 containerd[1950]: time="2025-05-27T17:00:33.334274699Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 17:00:33.334308 containerd[1950]: time="2025-05-27T17:00:33.334286699Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 17:00:33.334308 containerd[1950]: time="2025-05-27T17:00:33.334298459Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 17:00:33.334361 containerd[1950]: time="2025-05-27T17:00:33.334309019Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 17:00:33.334361 containerd[1950]: time="2025-05-27T17:00:33.334320499Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 17:00:33.334361 containerd[1950]: time="2025-05-27T17:00:33.334331619Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 17:00:33.334361 containerd[1950]: time="2025-05-27T17:00:33.334341259Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 17:00:33.334438 containerd[1950]: time="2025-05-27T17:00:33.334406859Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 17:00:33.334438 containerd[1950]: time="2025-05-27T17:00:33.334425299Z" level=info msg="Start snapshots syncer" May 27 17:00:33.334475 containerd[1950]: time="2025-05-27T17:00:33.334447019Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 17:00:33.334739 containerd[1950]: time="2025-05-27T17:00:33.334693380Z" 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\"}" May 27 17:00:33.334830 containerd[1950]: time="2025-05-27T17:00:33.334750820Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 17:00:33.334830 containerd[1950]: time="2025-05-27T17:00:33.334823141Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 17:00:33.334902 containerd[1950]: time="2025-05-27T17:00:33.334867421Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 17:00:33.334902 containerd[1950]: time="2025-05-27T17:00:33.334889541Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 17:00:33.334902 containerd[1950]: time="2025-05-27T17:00:33.334900181Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 17:00:33.334997 containerd[1950]: time="2025-05-27T17:00:33.334911901Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 17:00:33.334997 containerd[1950]: time="2025-05-27T17:00:33.334930381Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 17:00:33.334997 containerd[1950]: time="2025-05-27T17:00:33.334941701Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 17:00:33.334997 containerd[1950]: time="2025-05-27T17:00:33.334952141Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 17:00:33.334997 containerd[1950]: time="2025-05-27T17:00:33.334990581Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335002301Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335012661Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335044181Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335058621Z" 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 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335067701Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335078261Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335086061Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335095182Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 17:00:33.335113 containerd[1950]: time="2025-05-27T17:00:33.335104622Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 17:00:33.335300 containerd[1950]: time="2025-05-27T17:00:33.335121942Z" level=info msg="runtime interface created" May 27 17:00:33.335300 containerd[1950]: time="2025-05-27T17:00:33.335127782Z" level=info msg="created NRI interface" May 27 17:00:33.335300 containerd[1950]: time="2025-05-27T17:00:33.335138382Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 17:00:33.335300 containerd[1950]: time="2025-05-27T17:00:33.335148782Z" level=info msg="Connect containerd service" May 27 17:00:33.335300 containerd[1950]: time="2025-05-27T17:00:33.335173982Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 17:00:33.335708 containerd[1950]: time="2025-05-27T17:00:33.335684463Z" 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" May 27 17:00:33.343684 containerd[1950]: time="2025-05-27T17:00:33.343607529Z" level=info msg="Start subscribing containerd event" May 27 17:00:33.343684 containerd[1950]: time="2025-05-27T17:00:33.343655009Z" level=info msg="Start recovering state" May 27 17:00:33.343785 containerd[1950]: time="2025-05-27T17:00:33.343723370Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 17:00:33.343785 containerd[1950]: time="2025-05-27T17:00:33.343764770Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 17:00:33.343785 containerd[1950]: time="2025-05-27T17:00:33.343767930Z" level=info msg="Start event monitor" May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343791130Z" level=info msg="Start cni network conf syncer for default" May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343799530Z" level=info msg="Start streaming server" May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343808490Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343815050Z" level=info msg="runtime interface starting up..." May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343820730Z" level=info msg="starting plugins..." May 27 17:00:33.343842 containerd[1950]: time="2025-05-27T17:00:33.343828650Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 17:00:33.343969 containerd[1950]: time="2025-05-27T17:00:33.343935610Z" level=info msg="containerd successfully booted in 0.042801s" May 27 17:00:33.344021 systemd[1]: Started containerd.service - containerd container runtime. May 27 17:00:33.345462 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 17:00:33.355869 (dockerd)[1997]: 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 May 27 17:00:33.378658 dockerd[1997]: time="2025-05-27T17:00:33.378608043Z" level=info msg="Starting up" May 27 17:00:33.379105 dockerd[1997]: time="2025-05-27T17:00:33.379088845Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 17:00:33.410433 dockerd[1997]: time="2025-05-27T17:00:33.410386827Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 27 17:00:33.419983 dockerd[1997]: time="2025-05-27T17:00:33.419860777Z" level=info msg="Loading containers: start." May 27 17:00:33.430216 systemd[1]: var-lib-docker-overlay2-935661ddca230509956ba784afbb3775cba8a7b1e4d79547c4bc53085da08b0c-merged.mount: Deactivated successfully. May 27 17:00:33.790822 systemd-networkd[1379]: veth6868454: Link DOWN May 27 17:00:33.791662 kernel: docker0: port 1(veth6868454) entered disabled state May 27 17:00:33.790827 systemd-networkd[1379]: veth6868454: Lost carrier May 27 17:00:33.792662 kernel: veth6868454 (unregistering): left allmulticast mode May 27 17:00:33.792699 kernel: veth6868454 (unregistering): left promiscuous mode May 27 17:00:33.792714 kernel: docker0: port 1(veth6868454) entered disabled state May 27 17:00:33.794126 systemd-networkd[1379]: docker0: Lost carrier May 27 17:00:33.794715 dockerd[1997]: time="2025-05-27T17:00:33.794684597Z" level=info msg="Removing stale sandbox d3f1acf277556e07f34f9538f19fcf11104de07ea7ac4cb5e19b679c30de6bbf (632106249a79820569c30e0c351e5e98f0547e5eb28e30c58fd03f4f86086fbe)" May 27 17:00:33.797507 dockerd[1997]: time="2025-05-27T17:00:33.797409846Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 27 17:00:33.797507 dockerd[1997]: time="2025-05-27T17:00:33.797454526Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 41f7bfb657607039703dc6f87c5a0ea5cc65740da4504c337b6c494ca8d620cf d7a7c30f2f5ca9300190bfce3371d928580e45c80ba28268f11ae816d2f62265], retrying...." May 27 17:00:33.984015 dockerd[1997]: time="2025-05-27T17:00:33.983831013Z" level=warning msg="error locating sandbox id d3f1acf277556e07f34f9538f19fcf11104de07ea7ac4cb5e19b679c30de6bbf: sandbox d3f1acf277556e07f34f9538f19fcf11104de07ea7ac4cb5e19b679c30de6bbf not found" May 27 17:00:33.984107 dockerd[1997]: time="2025-05-27T17:00:33.984041333Z" level=info msg="Loading containers: done." May 27 17:00:33.993317 dockerd[1997]: time="2025-05-27T17:00:33.993261203Z" 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 May 27 17:00:33.993445 dockerd[1997]: time="2025-05-27T17:00:33.993333604Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 17:00:33.993445 dockerd[1997]: time="2025-05-27T17:00:33.993369524Z" level=info msg="Initializing buildkit" May 27 17:00:34.005202 dockerd[1997]: time="2025-05-27T17:00:34.005104561Z" level=info msg="Completed buildkit initialization" May 27 17:00:34.011712 dockerd[1997]: time="2025-05-27T17:00:34.011637781Z" level=info msg="Daemon has completed initialization" May 27 17:00:34.011800 dockerd[1997]: time="2025-05-27T17:00:34.011709381Z" level=info msg="API listen on /run/docker.sock" May 27 17:00:34.011917 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 17:00:34.398444 systemd[1]: run-docker-netns-d3f1acf27755.mount: Deactivated successfully. May 27 17:00:37.674015 systemd[1]: Started sshd@14-10.0.0.40:22-10.0.0.1:58788.service - OpenSSH per-connection server daemon (10.0.0.1:58788). May 27 17:00:37.718993 sshd[2217]: Accepted publickey for core from 10.0.0.1 port 58788 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:37.720216 sshd-session[2217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:37.724051 systemd-logind[1388]: New session 15 of user core. May 27 17:00:37.737690 systemd[1]: Started session-15.scope - Session 15 of User core. May 27 17:00:37.790378 sshd[2219]: Connection closed by 10.0.0.1 port 58788 May 27 17:00:37.790859 sshd-session[2217]: pam_unix(sshd:session): session closed for user core May 27 17:00:37.799408 systemd[1]: sshd@14-10.0.0.40:22-10.0.0.1:58788.service: Deactivated successfully. May 27 17:00:37.801819 systemd[1]: session-15.scope: Deactivated successfully. May 27 17:00:37.802392 systemd-logind[1388]: Session 15 logged out. Waiting for processes to exit. May 27 17:00:37.805570 systemd[1]: Started sshd@15-10.0.0.40:22-10.0.0.1:58790.service - OpenSSH per-connection server daemon (10.0.0.1:58790). May 27 17:00:37.806056 systemd-logind[1388]: Removed session 15. May 27 17:00:37.855822 sshd[2225]: Accepted publickey for core from 10.0.0.1 port 58790 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:37.857770 sshd-session[2225]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:37.862271 systemd-logind[1388]: New session 16 of user core. May 27 17:00:37.876686 systemd[1]: Started session-16.scope - Session 16 of User core. May 27 17:00:37.930510 sshd[2227]: Connection closed by 10.0.0.1 port 58790 May 27 17:00:37.929968 sshd-session[2225]: pam_unix(sshd:session): session closed for user core May 27 17:00:37.950735 systemd[1]: sshd@15-10.0.0.40:22-10.0.0.1:58790.service: Deactivated successfully. May 27 17:00:37.952248 systemd[1]: session-16.scope: Deactivated successfully. May 27 17:00:37.952989 systemd-logind[1388]: Session 16 logged out. Waiting for processes to exit. May 27 17:00:37.955499 systemd[1]: Started sshd@16-10.0.0.40:22-10.0.0.1:58792.service - OpenSSH per-connection server daemon (10.0.0.1:58792). May 27 17:00:37.956352 systemd-logind[1388]: Removed session 16. May 27 17:00:38.005975 sshd[2233]: Accepted publickey for core from 10.0.0.1 port 58792 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:38.007174 sshd-session[2233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:38.011113 systemd-logind[1388]: New session 17 of user core. May 27 17:00:38.022691 systemd[1]: Started session-17.scope - Session 17 of User core. May 27 17:00:38.077605 update_engine[1391]: I20250527 17:00:38.077555 1391 update_attempter.cc:509] Updating boot flags... May 27 17:00:38.078538 sshd[2235]: Connection closed by 10.0.0.1 port 58792 May 27 17:00:38.078933 sshd-session[2233]: pam_unix(sshd:session): session closed for user core May 27 17:00:38.090739 systemd[1]: sshd@16-10.0.0.40:22-10.0.0.1:58792.service: Deactivated successfully. May 27 17:00:38.092219 systemd[1]: session-17.scope: Deactivated successfully. May 27 17:00:38.094470 systemd-logind[1388]: Session 17 logged out. Waiting for processes to exit. May 27 17:00:38.095424 systemd[1]: Started sshd@17-10.0.0.40:22-10.0.0.1:58802.service - OpenSSH per-connection server daemon (10.0.0.1:58802). May 27 17:00:38.097241 systemd-logind[1388]: Removed session 17. May 27 17:00:38.173963 sshd[2252]: Accepted publickey for core from 10.0.0.1 port 58802 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:38.175173 sshd-session[2252]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:38.179102 systemd-logind[1388]: New session 18 of user core. May 27 17:00:38.195732 systemd[1]: Started session-18.scope - Session 18 of User core. May 27 17:00:38.269540 systemd[1]: var-lib-docker-overlay2-9867f9eeab229cd09aabd40b3e3dc868f05c2bfe76f4427226a0dadd88173662\x2dinit-merged.mount: Deactivated successfully. May 27 17:00:38.300515 containerd[1950]: time="2025-05-27T17:00:38.300469283Z" level=info msg="connecting to shim a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181" address="unix:///run/containerd/s/28117c9a5e0e7182afd86bfd5b8caa43824ceb5725328196c4aca6dbeba28938" namespace=moby protocol=ttrpc version=3 May 27 17:00:38.330737 systemd[1]: Started docker-a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181.scope - libcontainer container a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181. May 27 17:00:38.340412 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 17:00:38.353684 kernel: docker0: port 1(vethe0fd778) entered blocking state May 27 17:00:38.353752 kernel: docker0: port 1(vethe0fd778) entered disabled state May 27 17:00:38.353768 kernel: vethe0fd778: entered allmulticast mode May 27 17:00:38.354582 kernel: vethe0fd778: entered promiscuous mode May 27 17:00:38.355849 systemd-networkd[1379]: vethe0fd778: Link UP May 27 17:00:38.360492 kernel: eth0: renamed from veth249ceaf May 27 17:00:38.360911 kernel: docker0: port 1(vethe0fd778) entered blocking state May 27 17:00:38.360935 kernel: docker0: port 1(vethe0fd778) entered forwarding state May 27 17:00:38.361610 systemd-networkd[1379]: vethe0fd778: Gained carrier May 27 17:00:38.361981 systemd-networkd[1379]: docker0: Gained carrier May 27 17:00:38.378912 sshd[2266]: Connection closed by 10.0.0.1 port 58802 May 27 17:00:38.379612 sshd-session[2252]: pam_unix(sshd:session): session closed for user core May 27 17:00:38.390487 systemd[1]: sshd@17-10.0.0.40:22-10.0.0.1:58802.service: Deactivated successfully. May 27 17:00:38.392411 systemd[1]: session-18.scope: Deactivated successfully. May 27 17:00:38.393158 systemd-logind[1388]: Session 18 logged out. Waiting for processes to exit. May 27 17:00:38.396262 systemd[1]: Started sshd@18-10.0.0.40:22-10.0.0.1:58806.service - OpenSSH per-connection server daemon (10.0.0.1:58806). May 27 17:00:38.397312 systemd-logind[1388]: Removed session 18. May 27 17:00:38.455402 sshd[2329]: Accepted publickey for core from 10.0.0.1 port 58806 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:38.456465 sshd-session[2329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:38.461162 systemd-logind[1388]: New session 19 of user core. May 27 17:00:38.470691 systemd[1]: Started session-19.scope - Session 19 of User core. May 27 17:00:38.522885 sshd[2331]: Connection closed by 10.0.0.1 port 58806 May 27 17:00:38.523344 sshd-session[2329]: pam_unix(sshd:session): session closed for user core May 27 17:00:38.534723 systemd[1]: sshd@18-10.0.0.40:22-10.0.0.1:58806.service: Deactivated successfully. May 27 17:00:38.536235 systemd[1]: session-19.scope: Deactivated successfully. May 27 17:00:38.536982 systemd-logind[1388]: Session 19 logged out. Waiting for processes to exit. May 27 17:00:38.539491 systemd[1]: Started sshd@19-10.0.0.40:22-10.0.0.1:58816.service - OpenSSH per-connection server daemon (10.0.0.1:58816). May 27 17:00:38.540325 systemd-logind[1388]: Removed session 19. May 27 17:00:38.587923 sshd[2337]: Accepted publickey for core from 10.0.0.1 port 58816 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:38.589229 sshd-session[2337]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:38.593245 systemd-logind[1388]: New session 20 of user core. May 27 17:00:38.601688 systemd[1]: Started session-20.scope - Session 20 of User core. May 27 17:00:38.652647 sudo[2340]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1950 May 27 17:00:38.653231 sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:00:38.656076 sudo[2340]: pam_unix(sudo:session): session closed for user root May 27 17:00:38.657210 sshd[2339]: Connection closed by 10.0.0.1 port 58816 May 27 17:00:38.657771 sshd-session[2337]: pam_unix(sshd:session): session closed for user core May 27 17:00:38.658457 dockerd[1997]: time="2025-05-27T17:00:38.658326086Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 27 17:00:38.658457 dockerd[1997]: time="2025-05-27T17:00:38.658364406Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 27 17:00:38.658457 dockerd[1997]: time="2025-05-27T17:00:38.658369126Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 27 17:00:38.658457 dockerd[1997]: time="2025-05-27T17:00:38.658434327Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 27 17:00:38.665389 systemd[1]: containerd.service: Deactivated successfully. May 27 17:00:38.665495 systemd[1]: containerd.service: Unit process 2286 (containerd-shim) remains running after unit stopped. May 27 17:00:38.665788 systemd[1]: containerd.service: Consumed 136ms CPU time, 136.4M memory peak. May 27 17:00:38.666142 systemd[1]: sshd@19-10.0.0.40:22-10.0.0.1:58816.service: Deactivated successfully. May 27 17:00:38.667369 systemd[1]: session-20.scope: Deactivated successfully. May 27 17:00:38.671107 systemd-logind[1388]: Session 20 logged out. Waiting for processes to exit. May 27 17:00:38.672947 systemd[1]: Started sshd@20-10.0.0.40:22-10.0.0.1:58820.service - OpenSSH per-connection server daemon (10.0.0.1:58820). May 27 17:00:38.673904 systemd-logind[1388]: Removed session 20. May 27 17:00:38.728831 sshd[2347]: Accepted publickey for core from 10.0.0.1 port 58820 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:38.730155 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:38.734707 systemd-logind[1388]: New session 21 of user core. May 27 17:00:38.742691 systemd[1]: Started session-21.scope - Session 21 of User core. May 27 17:00:38.796291 sshd[2349]: Connection closed by 10.0.0.1 port 58820 May 27 17:00:38.796621 sshd-session[2347]: pam_unix(sshd:session): session closed for user core May 27 17:00:38.799903 systemd[1]: sshd@20-10.0.0.40:22-10.0.0.1:58820.service: Deactivated successfully. May 27 17:00:38.802878 systemd[1]: session-21.scope: Deactivated successfully. May 27 17:00:38.803725 systemd-logind[1388]: Session 21 logged out. Waiting for processes to exit. May 27 17:00:38.804943 systemd-logind[1388]: Removed session 21. May 27 17:00:40.343058 systemd-networkd[1379]: vethe0fd778: Gained IPv6LL May 27 17:00:43.754277 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. May 27 17:00:43.754657 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 27 17:00:43.754979 dockerd[1997]: time="2025-05-27T17:00:43.754932931Z" level=info msg="Processing signal 'terminated'" May 27 17:00:44.811228 systemd[1]: Started sshd@21-10.0.0.40:22-10.0.0.1:51544.service - OpenSSH per-connection server daemon (10.0.0.1:51544). May 27 17:00:44.875973 sshd[2355]: Accepted publickey for core from 10.0.0.1 port 51544 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:44.877147 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:44.881411 systemd-logind[1388]: New session 22 of user core. May 27 17:00:44.891654 systemd[1]: Started session-22.scope - Session 22 of User core. May 27 17:00:44.943955 sshd[2357]: Connection closed by 10.0.0.1 port 51544 May 27 17:00:44.944385 sshd-session[2355]: pam_unix(sshd:session): session closed for user core May 27 17:00:44.947470 systemd[1]: sshd@21-10.0.0.40:22-10.0.0.1:51544.service: Deactivated successfully. May 27 17:00:44.949893 systemd[1]: session-22.scope: Deactivated successfully. May 27 17:00:44.951063 systemd-logind[1388]: Session 22 logged out. Waiting for processes to exit. May 27 17:00:44.952097 systemd-logind[1388]: Removed session 22. May 27 17:00:45.756717 dockerd[1997]: time="2025-05-27T17:00:45.756641445Z" level=error msg="Error sending stop (signal 15) to container" container=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 error="Cannot kill container a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" May 27 17:00:45.756717 dockerd[1997]: time="2025-05-27T17:00:45.756714366Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 May 27 17:00:50.966472 systemd[1]: Started sshd@22-10.0.0.40:22-10.0.0.1:51560.service - OpenSSH per-connection server daemon (10.0.0.1:51560). May 27 17:00:51.028815 sshd[2363]: Accepted publickey for core from 10.0.0.1 port 51560 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:51.033195 sshd-session[2363]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:51.038582 systemd-logind[1388]: New session 23 of user core. May 27 17:00:51.054758 systemd[1]: Started session-23.scope - Session 23 of User core. May 27 17:00:51.109923 sshd[2365]: Connection closed by 10.0.0.1 port 51560 May 27 17:00:51.110152 sshd-session[2363]: pam_unix(sshd:session): session closed for user core May 27 17:00:51.113655 systemd[1]: sshd@22-10.0.0.40:22-10.0.0.1:51560.service: Deactivated successfully. May 27 17:00:51.115299 systemd[1]: session-23.scope: Deactivated successfully. May 27 17:00:51.117744 systemd-logind[1388]: Session 23 logged out. Waiting for processes to exit. May 27 17:00:51.119298 systemd-logind[1388]: Removed session 23. May 27 17:00:55.757349 dockerd[1997]: time="2025-05-27T17:00:55.757267262Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 error="context deadline exceeded" May 27 17:00:55.758213 systemd[1]: docker-a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181.scope: Deactivated successfully. May 27 17:00:57.133405 systemd[1]: Started sshd@23-10.0.0.40:22-10.0.0.1:40178.service - OpenSSH per-connection server daemon (10.0.0.1:40178). May 27 17:00:57.170820 sshd[2371]: Accepted publickey for core from 10.0.0.1 port 40178 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:00:57.171936 sshd-session[2371]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:00:57.175731 systemd-logind[1388]: New session 24 of user core. May 27 17:00:57.193739 systemd[1]: Started session-24.scope - Session 24 of User core. May 27 17:00:57.246829 sshd[2373]: Connection closed by 10.0.0.1 port 40178 May 27 17:00:57.248095 sshd-session[2371]: pam_unix(sshd:session): session closed for user core May 27 17:00:57.250862 systemd[1]: sshd@23-10.0.0.40:22-10.0.0.1:40178.service: Deactivated successfully. May 27 17:00:57.252331 systemd[1]: session-24.scope: Deactivated successfully. May 27 17:00:57.254073 systemd-logind[1388]: Session 24 logged out. Waiting for processes to exit. May 27 17:00:57.255216 systemd-logind[1388]: Removed session 24. May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755237302Z" level=error msg="Force shutdown daemon" May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755375662Z" level=info msg="Daemon shutdown complete" May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755457502Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: latest balancer error: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755493862Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=moby May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755471462Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: latest balancer error: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" May 27 17:00:58.756550 dockerd[1997]: time="2025-05-27T17:00:58.755539382Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby May 27 17:00:58.760206 systemd[1]: docker.service: Deactivated successfully. May 27 17:00:58.760892 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 27 17:00:58.761350 systemd[1]: docker.socket: Deactivated successfully. May 27 17:00:58.761630 systemd[1]: Closed docker.socket - Docker Socket for the API. May 27 17:00:58.761667 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 27 17:00:58.763201 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 17:00:58.763424 systemd[1]: containerd.service: Found left-over process 2286 (containerd-shim) in control group while starting unit. Ignoring. May 27 17:00:58.763431 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 17:00:58.781345 systemd[1]: Starting containerd.service - containerd container runtime... May 27 17:00:58.781709 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 17:00:58.792846 systemd[1]: containerd.service: Found left-over process 2286 (containerd-shim) in control group while starting unit. Ignoring. May 27 17:00:58.792856 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 17:00:58.796781 (ntainerd)[2384]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 17:00:58.808506 containerd[2384]: time="2025-05-27T17:00:58Z" 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 May 27 17:00:58.808895 containerd[2384]: time="2025-05-27T17:00:58.808868776Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816614301Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="27.52µs" May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816652301Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816669861Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816706421Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816717901Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816904982Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816929222Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.816940222Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.817144502Z" 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 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.817156142Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.817166742Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:00:58.817370 containerd[2384]: time="2025-05-27T17:00:58.817175382Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 17:00:58.817650 containerd[2384]: time="2025-05-27T17:00:58.817202382Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 17:00:58.817650 containerd[2384]: time="2025-05-27T17:00:58.817274422Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 17:00:58.817650 containerd[2384]: time="2025-05-27T17:00:58.817294342Z" 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 May 27 17:00:58.817650 containerd[2384]: time="2025-05-27T17:00:58.817312342Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 17:00:58.817650 containerd[2384]: time="2025-05-27T17:00:58.817347342Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 17:00:58.818278 containerd[2384]: time="2025-05-27T17:00:58.818105982Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 17:00:58.818278 containerd[2384]: time="2025-05-27T17:00:58.818155462Z" level=info msg="metadata content store policy set" policy=shared May 27 17:00:58.818425 containerd[2384]: time="2025-05-27T17:00:58.818394263Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 17:00:58.818494 containerd[2384]: time="2025-05-27T17:00:58.818452423Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 17:00:58.818584 containerd[2384]: time="2025-05-27T17:00:58.818569343Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 17:00:58.818667 containerd[2384]: time="2025-05-27T17:00:58.818652623Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 17:00:58.818717 containerd[2384]: time="2025-05-27T17:00:58.818705823Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 17:00:58.818763 containerd[2384]: time="2025-05-27T17:00:58.818751463Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 17:00:58.818810 containerd[2384]: time="2025-05-27T17:00:58.818798903Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 17:00:58.818873 containerd[2384]: time="2025-05-27T17:00:58.818860103Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 17:00:58.818920 containerd[2384]: time="2025-05-27T17:00:58.818909423Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 17:00:58.818968 containerd[2384]: time="2025-05-27T17:00:58.818957023Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 17:00:58.819014 containerd[2384]: time="2025-05-27T17:00:58.819002823Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 17:00:58.819063 containerd[2384]: time="2025-05-27T17:00:58.819051023Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 17:00:58.819459 containerd[2384]: time="2025-05-27T17:00:58.819332303Z" level=info msg="connecting to shim a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181" address="unix:///run/containerd/s/28117c9a5e0e7182afd86bfd5b8caa43824ceb5725328196c4aca6dbeba28938" namespace=moby protocol=ttrpc version=3 May 27 17:00:58.825992 containerd[2384]: time="2025-05-27T17:00:58.825961667Z" level=info msg="cleaning leaked shim process" id=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 namespace=moby May 27 17:00:58.834614 containerd[2384]: time="2025-05-27T17:00:58.834556113Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 27 17:00:58.834951 containerd[2384]: time="2025-05-27T17:00:58.834890073Z" level=info msg="shim disconnected" id=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 namespace=moby May 27 17:00:58.834987 containerd[2384]: time="2025-05-27T17:00:58.834952753Z" level=warning msg="cleaning up after shim disconnected" id=a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181 namespace=moby May 27 17:00:58.834987 containerd[2384]: time="2025-05-27T17:00:58.834961353Z" level=info msg="cleaning up dead shim" namespace=moby May 27 17:00:58.845001 containerd[2384]: time="2025-05-27T17:00:58.844917840Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/3: file already closed" namespace=moby May 27 17:00:58.845107 containerd[2384]: time="2025-05-27T17:00:58.845051440Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 17:00:58.845107 containerd[2384]: time="2025-05-27T17:00:58.845075800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 17:00:58.845107 containerd[2384]: time="2025-05-27T17:00:58.845091600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 17:00:58.845107 containerd[2384]: time="2025-05-27T17:00:58.845104360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845115800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845127520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845138440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845148680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845160920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845171880Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 17:00:58.845182 containerd[2384]: time="2025-05-27T17:00:58.845181520Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 17:00:58.845295 containerd[2384]: time="2025-05-27T17:00:58.845242160Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 17:00:58.845295 containerd[2384]: time="2025-05-27T17:00:58.845265040Z" level=info msg="Start snapshots syncer" May 27 17:00:58.845295 containerd[2384]: time="2025-05-27T17:00:58.845284640Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 17:00:58.845537 containerd[2384]: time="2025-05-27T17:00:58.845494280Z" 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\"}" May 27 17:00:58.845650 containerd[2384]: time="2025-05-27T17:00:58.845554720Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 17:00:58.845650 containerd[2384]: time="2025-05-27T17:00:58.845622480Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845668720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845689080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845701640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845712640Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845724120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 17:00:58.845723 containerd[2384]: time="2025-05-27T17:00:58.845735240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 17:00:58.845841 containerd[2384]: time="2025-05-27T17:00:58.845755760Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 17:00:58.845841 containerd[2384]: time="2025-05-27T17:00:58.845781960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 17:00:58.845841 containerd[2384]: time="2025-05-27T17:00:58.845793680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 17:00:58.845841 containerd[2384]: time="2025-05-27T17:00:58.845804840Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 17:00:58.845841 containerd[2384]: time="2025-05-27T17:00:58.845838000Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845852960Z" 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 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845861640Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845871240Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845879160Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845889160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845899040Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845915560Z" level=info msg="runtime interface created" May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845920920Z" level=info msg="created NRI interface" May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845929080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 17:00:58.845945 containerd[2384]: time="2025-05-27T17:00:58.845939320Z" level=info msg="Connect containerd service" May 27 17:00:58.846154 containerd[2384]: time="2025-05-27T17:00:58.845965720Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 17:00:58.846412 containerd[2384]: time="2025-05-27T17:00:58.846389801Z" 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" May 27 17:00:58.854347 containerd[2384]: time="2025-05-27T17:00:58.854306726Z" level=info msg="Start subscribing containerd event" May 27 17:00:58.854457 containerd[2384]: time="2025-05-27T17:00:58.854372806Z" level=info msg="Start recovering state" May 27 17:00:58.854457 containerd[2384]: time="2025-05-27T17:00:58.854437366Z" level=info msg="Start event monitor" May 27 17:00:58.854504 containerd[2384]: time="2025-05-27T17:00:58.854459766Z" level=info msg="Start cni network conf syncer for default" May 27 17:00:58.854504 containerd[2384]: time="2025-05-27T17:00:58.854467606Z" level=info msg="Start streaming server" May 27 17:00:58.854504 containerd[2384]: time="2025-05-27T17:00:58.854474926Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 17:00:58.854582 containerd[2384]: time="2025-05-27T17:00:58.854475366Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 17:00:58.854582 containerd[2384]: time="2025-05-27T17:00:58.854540926Z" level=info msg="runtime interface starting up..." May 27 17:00:58.854582 containerd[2384]: time="2025-05-27T17:00:58.854548286Z" level=info msg="starting plugins..." May 27 17:00:58.854582 containerd[2384]: time="2025-05-27T17:00:58.854555326Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 17:00:58.854582 containerd[2384]: time="2025-05-27T17:00:58.854560806Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 17:00:58.854710 containerd[2384]: time="2025-05-27T17:00:58.854691246Z" level=info msg="containerd successfully booted in 0.046335s" May 27 17:00:58.854781 systemd[1]: Started containerd.service - containerd container runtime. May 27 17:00:58.856370 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 17:00:58.870054 (dockerd)[2432]: 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 May 27 17:00:58.894883 dockerd[2432]: time="2025-05-27T17:00:58.894831192Z" level=info msg="Starting up" May 27 17:00:58.895788 dockerd[2432]: time="2025-05-27T17:00:58.895738313Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 17:00:58.919641 dockerd[2432]: time="2025-05-27T17:00:58.919552848Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 27 17:00:58.927153 dockerd[2432]: time="2025-05-27T17:00:58.927122773Z" level=info msg="Loading containers: start." May 27 17:00:58.940540 systemd[1]: var-lib-docker-overlay2-9867f9eeab229cd09aabd40b3e3dc868f05c2bfe76f4427226a0dadd88173662-merged.mount: Deactivated successfully. May 27 17:00:59.247787 systemd-networkd[1379]: vethe0fd778: Link DOWN May 27 17:00:59.248827 kernel: docker0: port 1(vethe0fd778) entered disabled state May 27 17:00:59.247796 systemd-networkd[1379]: vethe0fd778: Lost carrier May 27 17:00:59.251320 kernel: vethe0fd778 (unregistering): left allmulticast mode May 27 17:00:59.251380 kernel: vethe0fd778 (unregistering): left promiscuous mode May 27 17:00:59.252429 kernel: docker0: port 1(vethe0fd778) entered disabled state May 27 17:00:59.253766 systemd-networkd[1379]: docker0: Lost carrier May 27 17:00:59.254643 dockerd[2432]: time="2025-05-27T17:00:59.254298335Z" level=info msg="Removing stale sandbox 0ba81480736005027380110183f8ca9af1ddac48cc2ba4772359d8ac7519c254 (a0d7c88fae1fe21e4b94d55737f034a92444c41411dd703f8c7090e82dbd9181)" May 27 17:00:59.256894 dockerd[2432]: time="2025-05-27T17:00:59.256852376Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 27 17:00:59.256954 dockerd[2432]: time="2025-05-27T17:00:59.256895376Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint a7005c74fddf87252b21918d712e89a71e66090a1e18007e3cd54422f9d784cd 5fc0586ca1f8cc1678aba0aeb8ddd2dc41ffbc8b69b4176656a06b832429278f], retrying...." May 27 17:00:59.444537 dockerd[2432]: time="2025-05-27T17:00:59.444481090Z" level=warning msg="error locating sandbox id d3f1acf277556e07f34f9538f19fcf11104de07ea7ac4cb5e19b679c30de6bbf: sandbox d3f1acf277556e07f34f9538f19fcf11104de07ea7ac4cb5e19b679c30de6bbf not found" May 27 17:00:59.444537 dockerd[2432]: time="2025-05-27T17:00:59.444537931Z" level=warning msg="error locating sandbox id 0ba81480736005027380110183f8ca9af1ddac48cc2ba4772359d8ac7519c254: sandbox 0ba81480736005027380110183f8ca9af1ddac48cc2ba4772359d8ac7519c254 not found" May 27 17:00:59.444660 dockerd[2432]: time="2025-05-27T17:00:59.444596611Z" level=info msg="Loading containers: done." May 27 17:00:59.454094 dockerd[2432]: time="2025-05-27T17:00:59.454046256Z" 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 May 27 17:00:59.454222 dockerd[2432]: time="2025-05-27T17:00:59.454133176Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 17:00:59.454222 dockerd[2432]: time="2025-05-27T17:00:59.454173816Z" level=info msg="Initializing buildkit" May 27 17:00:59.467604 dockerd[2432]: time="2025-05-27T17:00:59.467560824Z" level=info msg="Completed buildkit initialization" May 27 17:00:59.474130 dockerd[2432]: time="2025-05-27T17:00:59.474073308Z" level=info msg="Daemon has completed initialization" May 27 17:00:59.474268 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 17:00:59.475008 dockerd[2432]: time="2025-05-27T17:00:59.474179429Z" level=info msg="API listen on /run/docker.sock" May 27 17:00:59.912426 systemd[1]: run-docker-netns-0ba814807360.mount: Deactivated successfully. May 27 17:01:03.266743 systemd[1]: Started sshd@24-10.0.0.40:22-10.0.0.1:39474.service - OpenSSH per-connection server daemon (10.0.0.1:39474). May 27 17:01:03.304953 sshd[2654]: Accepted publickey for core from 10.0.0.1 port 39474 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:01:03.306286 sshd-session[2654]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:01:03.309886 systemd-logind[1388]: New session 25 of user core. May 27 17:01:03.319686 systemd[1]: Started session-25.scope - Session 25 of User core. May 27 17:01:03.372153 sshd[2656]: Connection closed by 10.0.0.1 port 39474 May 27 17:01:03.372647 sshd-session[2654]: pam_unix(sshd:session): session closed for user core May 27 17:01:03.381509 systemd[1]: sshd@24-10.0.0.40:22-10.0.0.1:39474.service: Deactivated successfully. May 27 17:01:03.383790 systemd[1]: session-25.scope: Deactivated successfully. May 27 17:01:03.384384 systemd-logind[1388]: Session 25 logged out. Waiting for processes to exit. May 27 17:01:03.386496 systemd[1]: Started sshd@25-10.0.0.40:22-10.0.0.1:39478.service - OpenSSH per-connection server daemon (10.0.0.1:39478). May 27 17:01:03.387092 systemd-logind[1388]: Removed session 25. May 27 17:01:03.437675 sshd[2662]: Accepted publickey for core from 10.0.0.1 port 39478 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:01:03.438822 sshd-session[2662]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:01:03.443440 systemd-logind[1388]: New session 26 of user core. May 27 17:01:03.455668 systemd[1]: Started session-26.scope - Session 26 of User core. May 27 17:01:03.511540 sshd[2664]: Connection closed by 10.0.0.1 port 39478 May 27 17:01:03.511914 sshd-session[2662]: pam_unix(sshd:session): session closed for user core May 27 17:01:03.523246 systemd[1]: sshd@25-10.0.0.40:22-10.0.0.1:39478.service: Deactivated successfully. May 27 17:01:03.524632 systemd[1]: session-26.scope: Deactivated successfully. May 27 17:01:03.525266 systemd-logind[1388]: Session 26 logged out. Waiting for processes to exit. May 27 17:01:03.527426 systemd[1]: Started sshd@26-10.0.0.40:22-10.0.0.1:39488.service - OpenSSH per-connection server daemon (10.0.0.1:39488). May 27 17:01:03.527898 systemd-logind[1388]: Removed session 26. May 27 17:01:03.586120 sshd[2670]: Accepted publickey for core from 10.0.0.1 port 39488 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:01:03.587339 sshd-session[2670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:01:03.596290 systemd-logind[1388]: New session 27 of user core. May 27 17:01:03.606728 systemd[1]: Started session-27.scope - Session 27 of User core. May 27 17:01:03.665488 sshd[2672]: Connection closed by 10.0.0.1 port 39488 May 27 17:01:03.666205 sshd-session[2670]: pam_unix(sshd:session): session closed for user core May 27 17:01:03.683787 systemd[1]: sshd@26-10.0.0.40:22-10.0.0.1:39488.service: Deactivated successfully. May 27 17:01:03.685795 systemd[1]: session-27.scope: Deactivated successfully. May 27 17:01:03.686543 systemd-logind[1388]: Session 27 logged out. Waiting for processes to exit. May 27 17:01:03.688907 systemd[1]: Started sshd@27-10.0.0.40:22-10.0.0.1:39490.service - OpenSSH per-connection server daemon (10.0.0.1:39490). May 27 17:01:03.689883 systemd-logind[1388]: Removed session 27. May 27 17:01:03.731760 sshd[2685]: Accepted publickey for core from 10.0.0.1 port 39490 ssh2: RSA SHA256:ZZNcfTFkFYX46lZGwGlqysxQ9Yikwv1d/hmoNWRTIVY May 27 17:01:03.733062 sshd-session[2685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:01:03.737607 systemd-logind[1388]: New session 28 of user core. May 27 17:01:03.752688 systemd[1]: Started session-28.scope - Session 28 of User core. May 27 17:01:03.828274 systemd[1]: var-lib-docker-overlay2-6b596db30c8bef230b935d8654c3cbaf97de5a9f534ed82321e821b63c39992b\x2dinit-merged.mount: Deactivated successfully. May 27 17:01:03.844809 systemd[1]: var-lib-docker-overlay2-6b596db30c8bef230b935d8654c3cbaf97de5a9f534ed82321e821b63c39992b-merged.mount: Deactivated successfully. May 27 17:01:03.867261 containerd[2384]: time="2025-05-27T17:01:03.867209531Z" level=info msg="connecting to shim 6aad8131d19ee9a503013284490e390dd81a8db5527ff551fdedb0c98d5af485" address="unix:///run/containerd/s/09f8cba3d7187fff95e38a3093b653e77eea14f28a049fdd714210036dc6f32f" namespace=moby protocol=ttrpc version=3 May 27 17:01:03.888665 systemd[1]: Started docker-6aad8131d19ee9a503013284490e390dd81a8db5527ff551fdedb0c98d5af485.scope - libcontainer container 6aad8131d19ee9a503013284490e390dd81a8db5527ff551fdedb0c98d5af485. May 27 17:01:03.898833 systemd-resolved[1299]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 17:01:03.915493 kernel: docker0: port 1(vethb7a7ac7) entered blocking state May 27 17:01:03.915585 kernel: docker0: port 1(vethb7a7ac7) entered disabled state May 27 17:01:03.915609 kernel: vethb7a7ac7: entered allmulticast mode May 27 17:01:03.915621 kernel: vethb7a7ac7: entered promiscuous mode May 27 17:01:03.915852 systemd-networkd[1379]: vethb7a7ac7: Link UP May 27 17:01:03.920550 kernel: eth0: renamed from vetha8e9786 May 27 17:01:03.922653 kernel: docker0: port 1(vethb7a7ac7) entered blocking state May 27 17:01:03.922704 kernel: docker0: port 1(vethb7a7ac7) entered forwarding state May 27 17:01:03.923438 systemd-networkd[1379]: vethb7a7ac7: Gained carrier May 27 17:01:03.923641 systemd-networkd[1379]: docker0: Gained carrier May 27 17:01:03.941460 sshd[2687]: Connection closed by 10.0.0.1 port 39490 May 27 17:01:03.941760 sshd-session[2685]: pam_unix(sshd:session): session closed for user core May 27 17:01:03.945302 systemd[1]: sshd@27-10.0.0.40:22-10.0.0.1:39490.service: Deactivated successfully. May 27 17:01:03.947720 systemd[1]: session-28.scope: Deactivated successfully. May 27 17:01:03.948312 systemd-logind[1388]: Session 28 logged out. Waiting for processes to exit. May 27 17:01:03.949514 systemd-logind[1388]: Removed session 28.