May 27 03:07:46.824190 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 27 03:07:46.824210 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 01:20:04 -00 2025 May 27 03:07:46.824220 kernel: KASLR enabled May 27 03:07:46.824225 kernel: efi: EFI v2.7 by EDK II May 27 03:07:46.824230 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb21fd18 May 27 03:07:46.824236 kernel: random: crng init done May 27 03:07:46.824243 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 May 27 03:07:46.824248 kernel: secureboot: Secure boot enabled May 27 03:07:46.824254 kernel: ACPI: Early table checksum verification disabled May 27 03:07:46.824261 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) May 27 03:07:46.824267 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 27 03:07:46.824272 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824278 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824284 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824291 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824298 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824305 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824311 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824317 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824323 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 27 03:07:46.824329 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 27 03:07:46.824335 kernel: ACPI: Use ACPI SPCR as default console: Yes May 27 03:07:46.824341 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 27 03:07:46.824347 kernel: NODE_DATA(0) allocated [mem 0xdc737dc0-0xdc73efff] May 27 03:07:46.824353 kernel: Zone ranges: May 27 03:07:46.824360 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 27 03:07:46.824366 kernel: DMA32 empty May 27 03:07:46.824372 kernel: Normal empty May 27 03:07:46.824377 kernel: Device empty May 27 03:07:46.824392 kernel: Movable zone start for each node May 27 03:07:46.824399 kernel: Early memory node ranges May 27 03:07:46.824405 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] May 27 03:07:46.824410 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] May 27 03:07:46.824416 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] May 27 03:07:46.824422 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] May 27 03:07:46.824428 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] May 27 03:07:46.824434 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] May 27 03:07:46.824441 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] May 27 03:07:46.824447 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] May 27 03:07:46.824453 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 27 03:07:46.824463 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 27 03:07:46.824478 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 27 03:07:46.824486 kernel: psci: probing for conduit method from ACPI. May 27 03:07:46.824492 kernel: psci: PSCIv1.1 detected in firmware. May 27 03:07:46.824500 kernel: psci: Using standard PSCI v0.2 function IDs May 27 03:07:46.824506 kernel: psci: Trusted OS migration not required May 27 03:07:46.824513 kernel: psci: SMC Calling Convention v1.1 May 27 03:07:46.824519 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 27 03:07:46.824526 kernel: percpu: Embedded 33 pages/cpu s98136 r8192 d28840 u135168 May 27 03:07:46.824532 kernel: pcpu-alloc: s98136 r8192 d28840 u135168 alloc=33*4096 May 27 03:07:46.824539 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 27 03:07:46.824545 kernel: Detected PIPT I-cache on CPU0 May 27 03:07:46.824551 kernel: CPU features: detected: GIC system register CPU interface May 27 03:07:46.824559 kernel: CPU features: detected: Spectre-v4 May 27 03:07:46.824565 kernel: CPU features: detected: Spectre-BHB May 27 03:07:46.824571 kernel: CPU features: kernel page table isolation forced ON by KASLR May 27 03:07:46.824578 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 27 03:07:46.824584 kernel: CPU features: detected: ARM erratum 1418040 May 27 03:07:46.824590 kernel: CPU features: detected: SSBS not fully self-synchronizing May 27 03:07:46.824597 kernel: alternatives: applying boot alternatives May 27 03:07:46.824604 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=4c3f98aae7a61b3dcbab6391ba922461adab29dbcb79fd6e18169f93c5a4ab5a May 27 03:07:46.824610 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 27 03:07:46.824617 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 27 03:07:46.824623 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 27 03:07:46.824631 kernel: Fallback order for Node 0: 0 May 27 03:07:46.824637 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 May 27 03:07:46.824643 kernel: Policy zone: DMA May 27 03:07:46.824650 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 27 03:07:46.824656 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB May 27 03:07:46.824662 kernel: software IO TLB: area num 4. May 27 03:07:46.824669 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB May 27 03:07:46.824675 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) May 27 03:07:46.824681 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 27 03:07:46.824687 kernel: rcu: Preemptible hierarchical RCU implementation. May 27 03:07:46.824694 kernel: rcu: RCU event tracing is enabled. May 27 03:07:46.824701 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 27 03:07:46.824709 kernel: Trampoline variant of Tasks RCU enabled. May 27 03:07:46.824715 kernel: Tracing variant of Tasks RCU enabled. May 27 03:07:46.824721 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 27 03:07:46.824728 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 27 03:07:46.824734 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 27 03:07:46.824741 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 27 03:07:46.824747 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 27 03:07:46.824753 kernel: GICv3: 256 SPIs implemented May 27 03:07:46.824760 kernel: GICv3: 0 Extended SPIs implemented May 27 03:07:46.824766 kernel: Root IRQ handler: gic_handle_irq May 27 03:07:46.824772 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 27 03:07:46.824780 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 May 27 03:07:46.824786 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 27 03:07:46.824792 kernel: ITS [mem 0x08080000-0x0809ffff] May 27 03:07:46.824799 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) May 27 03:07:46.824805 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) May 27 03:07:46.824812 kernel: GICv3: using LPI property table @0x00000000400f0000 May 27 03:07:46.824818 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040110000 May 27 03:07:46.824825 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 27 03:07:46.824831 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 03:07:46.824837 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 27 03:07:46.824844 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 27 03:07:46.824850 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 27 03:07:46.824858 kernel: arm-pv: using stolen time PV May 27 03:07:46.824864 kernel: Console: colour dummy device 80x25 May 27 03:07:46.824871 kernel: ACPI: Core revision 20240827 May 27 03:07:46.824878 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 27 03:07:46.824884 kernel: pid_max: default: 32768 minimum: 301 May 27 03:07:46.824891 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 27 03:07:46.824897 kernel: landlock: Up and running. May 27 03:07:46.824904 kernel: SELinux: Initializing. May 27 03:07:46.824910 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 03:07:46.824918 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 03:07:46.824925 kernel: rcu: Hierarchical SRCU implementation. May 27 03:07:46.824932 kernel: rcu: Max phase no-delay instances is 400. May 27 03:07:46.824938 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 27 03:07:46.824945 kernel: Remapping and enabling EFI services. May 27 03:07:46.824951 kernel: smp: Bringing up secondary CPUs ... May 27 03:07:46.824958 kernel: Detected PIPT I-cache on CPU1 May 27 03:07:46.824965 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 27 03:07:46.824972 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040120000 May 27 03:07:46.824980 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 03:07:46.824991 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 27 03:07:46.824998 kernel: Detected PIPT I-cache on CPU2 May 27 03:07:46.825006 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 27 03:07:46.825013 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040130000 May 27 03:07:46.825020 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 03:07:46.825027 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 27 03:07:46.825033 kernel: Detected PIPT I-cache on CPU3 May 27 03:07:46.825041 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 27 03:07:46.825049 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040140000 May 27 03:07:46.825056 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 27 03:07:46.825062 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 27 03:07:46.825069 kernel: smp: Brought up 1 node, 4 CPUs May 27 03:07:46.825076 kernel: SMP: Total of 4 processors activated. May 27 03:07:46.825083 kernel: CPU: All CPU(s) started at EL1 May 27 03:07:46.825090 kernel: CPU features: detected: 32-bit EL0 Support May 27 03:07:46.825098 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 27 03:07:46.825106 kernel: CPU features: detected: Common not Private translations May 27 03:07:46.825113 kernel: CPU features: detected: CRC32 instructions May 27 03:07:46.825120 kernel: CPU features: detected: Enhanced Virtualization Traps May 27 03:07:46.825127 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 27 03:07:46.825134 kernel: CPU features: detected: LSE atomic instructions May 27 03:07:46.825141 kernel: CPU features: detected: Privileged Access Never May 27 03:07:46.825155 kernel: CPU features: detected: RAS Extension Support May 27 03:07:46.825163 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 27 03:07:46.825170 kernel: alternatives: applying system-wide alternatives May 27 03:07:46.825177 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 May 27 03:07:46.825187 kernel: Memory: 2438884K/2572288K available (11072K kernel code, 2276K rwdata, 8936K rodata, 39424K init, 1034K bss, 127636K reserved, 0K cma-reserved) May 27 03:07:46.825194 kernel: devtmpfs: initialized May 27 03:07:46.825201 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 27 03:07:46.825208 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 27 03:07:46.825214 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 27 03:07:46.825221 kernel: 0 pages in range for non-PLT usage May 27 03:07:46.825228 kernel: 508544 pages in range for PLT usage May 27 03:07:46.825235 kernel: pinctrl core: initialized pinctrl subsystem May 27 03:07:46.825242 kernel: SMBIOS 3.0.0 present. May 27 03:07:46.825250 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 27 03:07:46.825257 kernel: DMI: Memory slots populated: 1/1 May 27 03:07:46.825264 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 27 03:07:46.825271 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 27 03:07:46.825278 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 27 03:07:46.825285 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 27 03:07:46.825292 kernel: audit: initializing netlink subsys (disabled) May 27 03:07:46.825299 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 May 27 03:07:46.825307 kernel: thermal_sys: Registered thermal governor 'step_wise' May 27 03:07:46.825314 kernel: cpuidle: using governor menu May 27 03:07:46.825321 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 27 03:07:46.825327 kernel: ASID allocator initialised with 32768 entries May 27 03:07:46.825334 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 27 03:07:46.825341 kernel: Serial: AMBA PL011 UART driver May 27 03:07:46.825348 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 27 03:07:46.825355 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 27 03:07:46.825362 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 27 03:07:46.825370 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 27 03:07:46.825377 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 27 03:07:46.825390 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 27 03:07:46.825397 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 27 03:07:46.825405 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 27 03:07:46.825411 kernel: ACPI: Added _OSI(Module Device) May 27 03:07:46.825418 kernel: ACPI: Added _OSI(Processor Device) May 27 03:07:46.825425 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 27 03:07:46.825432 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 27 03:07:46.825438 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 27 03:07:46.825447 kernel: ACPI: Interpreter enabled May 27 03:07:46.825454 kernel: ACPI: Using GIC for interrupt routing May 27 03:07:46.825461 kernel: ACPI: MCFG table detected, 1 entries May 27 03:07:46.825473 kernel: ACPI: CPU0 has been hot-added May 27 03:07:46.825494 kernel: ACPI: CPU1 has been hot-added May 27 03:07:46.825501 kernel: ACPI: CPU2 has been hot-added May 27 03:07:46.825508 kernel: ACPI: CPU3 has been hot-added May 27 03:07:46.825515 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 27 03:07:46.825522 kernel: printk: legacy console [ttyAMA0] enabled May 27 03:07:46.825531 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 27 03:07:46.825666 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 27 03:07:46.825732 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 27 03:07:46.825792 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 27 03:07:46.825850 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 27 03:07:46.825908 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 27 03:07:46.825917 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 27 03:07:46.825926 kernel: PCI host bridge to bus 0000:00 May 27 03:07:46.825996 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 27 03:07:46.826053 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 27 03:07:46.826106 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 27 03:07:46.826160 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 27 03:07:46.826234 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint May 27 03:07:46.826304 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint May 27 03:07:46.826368 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] May 27 03:07:46.826445 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] May 27 03:07:46.826529 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] May 27 03:07:46.826592 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned May 27 03:07:46.826652 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned May 27 03:07:46.826713 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned May 27 03:07:46.826771 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 27 03:07:46.826824 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 27 03:07:46.826877 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 27 03:07:46.826886 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 27 03:07:46.826893 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 27 03:07:46.826900 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 27 03:07:46.826907 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 27 03:07:46.826914 kernel: iommu: Default domain type: Translated May 27 03:07:46.826923 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 27 03:07:46.826930 kernel: efivars: Registered efivars operations May 27 03:07:46.826937 kernel: vgaarb: loaded May 27 03:07:46.826944 kernel: clocksource: Switched to clocksource arch_sys_counter May 27 03:07:46.826951 kernel: VFS: Disk quotas dquot_6.6.0 May 27 03:07:46.826958 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 27 03:07:46.826964 kernel: pnp: PnP ACPI init May 27 03:07:46.827030 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 27 03:07:46.827040 kernel: pnp: PnP ACPI: found 1 devices May 27 03:07:46.827049 kernel: NET: Registered PF_INET protocol family May 27 03:07:46.827055 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 27 03:07:46.827062 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 27 03:07:46.827069 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 27 03:07:46.827076 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 27 03:07:46.827083 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 27 03:07:46.827091 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 27 03:07:46.827097 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 03:07:46.827104 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 03:07:46.827113 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 27 03:07:46.827120 kernel: PCI: CLS 0 bytes, default 64 May 27 03:07:46.827126 kernel: kvm [1]: HYP mode not available May 27 03:07:46.827133 kernel: Initialise system trusted keyrings May 27 03:07:46.827140 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 27 03:07:46.827147 kernel: Key type asymmetric registered May 27 03:07:46.827154 kernel: Asymmetric key parser 'x509' registered May 27 03:07:46.827161 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 27 03:07:46.827168 kernel: io scheduler mq-deadline registered May 27 03:07:46.827176 kernel: io scheduler kyber registered May 27 03:07:46.827183 kernel: io scheduler bfq registered May 27 03:07:46.827190 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 27 03:07:46.827197 kernel: ACPI: button: Power Button [PWRB] May 27 03:07:46.827204 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 27 03:07:46.827264 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 27 03:07:46.827273 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 27 03:07:46.827280 kernel: thunder_xcv, ver 1.0 May 27 03:07:46.827287 kernel: thunder_bgx, ver 1.0 May 27 03:07:46.827295 kernel: nicpf, ver 1.0 May 27 03:07:46.827302 kernel: nicvf, ver 1.0 May 27 03:07:46.827368 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 27 03:07:46.827438 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-27T03:07:46 UTC (1748315266) May 27 03:07:46.827448 kernel: hid: raw HID events driver (C) Jiri Kosina May 27 03:07:46.827455 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available May 27 03:07:46.827462 kernel: watchdog: NMI not fully supported May 27 03:07:46.827477 kernel: watchdog: Hard watchdog permanently disabled May 27 03:07:46.827499 kernel: NET: Registered PF_INET6 protocol family May 27 03:07:46.827506 kernel: Segment Routing with IPv6 May 27 03:07:46.827513 kernel: In-situ OAM (IOAM) with IPv6 May 27 03:07:46.827520 kernel: NET: Registered PF_PACKET protocol family May 27 03:07:46.827527 kernel: Key type dns_resolver registered May 27 03:07:46.827533 kernel: registered taskstats version 1 May 27 03:07:46.827540 kernel: Loading compiled-in X.509 certificates May 27 03:07:46.827547 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: 6bbf5412ef1f8a32378a640b6d048f74e6d74df0' May 27 03:07:46.827554 kernel: Demotion targets for Node 0: null May 27 03:07:46.827562 kernel: Key type .fscrypt registered May 27 03:07:46.827569 kernel: Key type fscrypt-provisioning registered May 27 03:07:46.827576 kernel: ima: No TPM chip found, activating TPM-bypass! May 27 03:07:46.827582 kernel: ima: Allocated hash algorithm: sha1 May 27 03:07:46.827589 kernel: ima: No architecture policies found May 27 03:07:46.827596 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 27 03:07:46.827603 kernel: clk: Disabling unused clocks May 27 03:07:46.827609 kernel: PM: genpd: Disabling unused power domains May 27 03:07:46.827616 kernel: Warning: unable to open an initial console. May 27 03:07:46.827625 kernel: Freeing unused kernel memory: 39424K May 27 03:07:46.827631 kernel: Run /init as init process May 27 03:07:46.827638 kernel: with arguments: May 27 03:07:46.827645 kernel: /init May 27 03:07:46.827652 kernel: with environment: May 27 03:07:46.827658 kernel: HOME=/ May 27 03:07:46.827665 kernel: TERM=linux May 27 03:07:46.827671 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 27 03:07:46.827679 systemd[1]: Successfully made /usr/ read-only. May 27 03:07:46.827690 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 03:07:46.827698 systemd[1]: Detected virtualization kvm. May 27 03:07:46.827705 systemd[1]: Detected architecture arm64. May 27 03:07:46.827713 systemd[1]: Running in initrd. May 27 03:07:46.827720 systemd[1]: No hostname configured, using default hostname. May 27 03:07:46.827727 systemd[1]: Hostname set to . May 27 03:07:46.827734 systemd[1]: Initializing machine ID from VM UUID. May 27 03:07:46.827743 systemd[1]: Queued start job for default target initrd.target. May 27 03:07:46.827751 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:07:46.827758 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:07:46.827766 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 27 03:07:46.827774 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 03:07:46.827781 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 27 03:07:46.827789 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 27 03:07:46.827799 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 03:07:46.827807 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 27 03:07:46.827814 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:07:46.827821 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 03:07:46.827829 systemd[1]: Reached target paths.target - Path Units. May 27 03:07:46.827836 systemd[1]: Reached target slices.target - Slice Units. May 27 03:07:46.827843 systemd[1]: Reached target swap.target - Swaps. May 27 03:07:46.827851 systemd[1]: Reached target timers.target - Timer Units. May 27 03:07:46.827859 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 27 03:07:46.827867 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 03:07:46.827874 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 27 03:07:46.827882 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 27 03:07:46.827889 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 03:07:46.827896 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 03:07:46.827904 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:07:46.827911 systemd[1]: Reached target sockets.target - Socket Units. May 27 03:07:46.827920 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 27 03:07:46.827927 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 03:07:46.827934 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 27 03:07:46.827942 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 03:07:46.827950 systemd[1]: Starting systemd-fsck-usr.service... May 27 03:07:46.827957 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 03:07:46.827964 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 03:07:46.827972 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:07:46.827979 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 27 03:07:46.827988 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:07:46.827995 systemd[1]: Finished systemd-fsck-usr.service. May 27 03:07:46.828003 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 03:07:46.828027 systemd-journald[245]: Collecting audit messages is disabled. May 27 03:07:46.828047 systemd-journald[245]: Journal started May 27 03:07:46.828065 systemd-journald[245]: Runtime Journal (/run/log/journal/864159f589bb4522af33854fe347b1d5) is 6M, max 48.5M, 42.4M free. May 27 03:07:46.818399 systemd-modules-load[248]: Inserted module 'overlay' May 27 03:07:46.832110 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:07:46.832129 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 03:07:46.835336 systemd[1]: Started systemd-journald.service - Journal Service. May 27 03:07:46.835370 kernel: Bridge firewalling registered May 27 03:07:46.834832 systemd-modules-load[248]: Inserted module 'br_netfilter' May 27 03:07:46.836053 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 03:07:46.837682 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 03:07:46.842173 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 27 03:07:46.844059 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 03:07:46.846172 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 03:07:46.852189 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 03:07:46.858057 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:07:46.859511 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 03:07:46.865886 systemd-tmpfiles[271]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 27 03:07:46.868709 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:07:46.871454 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 03:07:46.873461 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 27 03:07:46.875983 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 03:07:46.908987 dracut-cmdline[288]: 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=4c3f98aae7a61b3dcbab6391ba922461adab29dbcb79fd6e18169f93c5a4ab5a May 27 03:07:46.923457 systemd-resolved[289]: Positive Trust Anchors: May 27 03:07:46.923487 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 03:07:46.923518 systemd-resolved[289]: 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 03:07:46.928132 systemd-resolved[289]: Defaulting to hostname 'linux'. May 27 03:07:46.929325 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 03:07:46.932693 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 03:07:46.990507 kernel: SCSI subsystem initialized May 27 03:07:46.995490 kernel: Loading iSCSI transport class v2.0-870. May 27 03:07:47.003514 kernel: iscsi: registered transport (tcp) May 27 03:07:47.015490 kernel: iscsi: registered transport (qla4xxx) May 27 03:07:47.015514 kernel: QLogic iSCSI HBA Driver May 27 03:07:47.031305 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 03:07:47.048499 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:07:47.050043 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 03:07:47.094523 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 27 03:07:47.096676 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 27 03:07:47.160516 kernel: raid6: neonx8 gen() 15698 MB/s May 27 03:07:47.177492 kernel: raid6: neonx4 gen() 15720 MB/s May 27 03:07:47.194498 kernel: raid6: neonx2 gen() 13152 MB/s May 27 03:07:47.211496 kernel: raid6: neonx1 gen() 10494 MB/s May 27 03:07:47.228493 kernel: raid6: int64x8 gen() 6892 MB/s May 27 03:07:47.245494 kernel: raid6: int64x4 gen() 7346 MB/s May 27 03:07:47.262493 kernel: raid6: int64x2 gen() 6099 MB/s May 27 03:07:47.279659 kernel: raid6: int64x1 gen() 5049 MB/s May 27 03:07:47.279673 kernel: raid6: using algorithm neonx4 gen() 15720 MB/s May 27 03:07:47.297599 kernel: raid6: .... xor() 12355 MB/s, rmw enabled May 27 03:07:47.297612 kernel: raid6: using neon recovery algorithm May 27 03:07:47.302490 kernel: xor: measuring software checksum speed May 27 03:07:47.303761 kernel: 8regs : 18154 MB/sec May 27 03:07:47.303784 kernel: 32regs : 21693 MB/sec May 27 03:07:47.305094 kernel: arm64_neon : 27879 MB/sec May 27 03:07:47.305108 kernel: xor: using function: arm64_neon (27879 MB/sec) May 27 03:07:47.359499 kernel: Btrfs loaded, zoned=no, fsverity=no May 27 03:07:47.365277 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 27 03:07:47.367833 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:07:47.398212 systemd-udevd[499]: Using default interface naming scheme 'v255'. May 27 03:07:47.402455 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:07:47.404850 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 27 03:07:47.428863 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation May 27 03:07:47.451393 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 27 03:07:47.453747 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 03:07:47.508104 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:07:47.510693 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 27 03:07:47.559878 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 27 03:07:47.560046 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 27 03:07:47.562361 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:07:47.562509 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:07:47.566296 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:07:47.568659 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 03:07:47.568274 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:07:47.599300 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 27 03:07:47.600811 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 27 03:07:47.602767 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:07:47.611530 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 27 03:07:47.622487 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 03:07:47.623698 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 27 03:07:47.632841 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 27 03:07:47.634064 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 27 03:07:47.636082 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:07:47.638161 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 03:07:47.640777 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 27 03:07:47.642589 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 27 03:07:47.667462 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 27 03:07:47.672488 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 03:07:48.691503 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 27 03:07:48.692214 disk-uuid[598]: The operation has completed successfully. May 27 03:07:48.711112 systemd[1]: disk-uuid.service: Deactivated successfully. May 27 03:07:48.711205 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 27 03:07:48.741373 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 27 03:07:48.769158 sh[607]: Success May 27 03:07:48.784955 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 27 03:07:48.784992 kernel: device-mapper: uevent: version 1.0.3 May 27 03:07:48.786083 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 27 03:07:48.794517 kernel: device-mapper: verity: sha256 using shash "sha256-ce" May 27 03:07:48.818430 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 27 03:07:48.821071 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 27 03:07:48.831963 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 27 03:07:48.838133 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 27 03:07:48.838157 kernel: BTRFS: device fsid 5c6341ea-4eb5-44b6-ac57-c4d29847e384 devid 1 transid 41 /dev/mapper/usr (253:0) scanned by mount (619) May 27 03:07:48.839482 kernel: BTRFS info (device dm-0): first mount of filesystem 5c6341ea-4eb5-44b6-ac57-c4d29847e384 May 27 03:07:48.839508 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 27 03:07:48.840999 kernel: BTRFS info (device dm-0): using free-space-tree May 27 03:07:48.844067 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 27 03:07:48.845262 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 27 03:07:48.846697 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 27 03:07:48.847351 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 27 03:07:48.848918 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 27 03:07:48.874541 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (651) May 27 03:07:48.876948 kernel: BTRFS info (device vda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 03:07:48.876984 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 03:07:48.877898 kernel: BTRFS info (device vda6): using free-space-tree May 27 03:07:48.883494 kernel: BTRFS info (device vda6): last unmount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 03:07:48.883756 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 27 03:07:48.885528 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 27 03:07:48.949842 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 03:07:48.954692 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 03:07:48.998105 systemd-networkd[795]: lo: Link UP May 27 03:07:48.998118 systemd-networkd[795]: lo: Gained carrier May 27 03:07:48.998841 systemd-networkd[795]: Enumeration completed May 27 03:07:48.998950 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 03:07:48.999683 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:07:48.999686 systemd-networkd[795]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 03:07:49.000277 systemd[1]: Reached target network.target - Network. May 27 03:07:49.000568 systemd-networkd[795]: eth0: Link UP May 27 03:07:49.000571 systemd-networkd[795]: eth0: Gained carrier May 27 03:07:49.000579 systemd-networkd[795]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:07:49.029540 systemd-networkd[795]: eth0: DHCPv4 address 10.0.0.144/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 27 03:07:49.036030 ignition[697]: Ignition 2.21.0 May 27 03:07:49.036043 ignition[697]: Stage: fetch-offline May 27 03:07:49.036071 ignition[697]: no configs at "/usr/lib/ignition/base.d" May 27 03:07:49.036079 ignition[697]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:49.036266 ignition[697]: parsed url from cmdline: "" May 27 03:07:49.036269 ignition[697]: no config URL provided May 27 03:07:49.036273 ignition[697]: reading system config file "/usr/lib/ignition/user.ign" May 27 03:07:49.036280 ignition[697]: no config at "/usr/lib/ignition/user.ign" May 27 03:07:49.036297 ignition[697]: op(1): [started] loading QEMU firmware config module May 27 03:07:49.036301 ignition[697]: op(1): executing: "modprobe" "qemu_fw_cfg" May 27 03:07:49.041585 ignition[697]: op(1): [finished] loading QEMU firmware config module May 27 03:07:49.041602 ignition[697]: QEMU firmware config was not found. Ignoring... May 27 03:07:49.046793 ignition[697]: parsing config with SHA512: c30479696e32824d7fb7d73dab9c62f7dd27e8560b62f36d2572f628dd0b14e2866156c7ba90eccf05501f9fad3aa9cf77fa62671907f6f5b442e82c6f2291df May 27 03:07:49.052981 unknown[697]: fetched base config from "system" May 27 03:07:49.052991 unknown[697]: fetched user config from "qemu" May 27 03:07:49.054886 ignition[697]: fetch-offline: fetch-offline passed May 27 03:07:49.054984 ignition[697]: Ignition finished successfully May 27 03:07:49.057545 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 27 03:07:49.058915 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 27 03:07:49.059685 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 27 03:07:49.084664 ignition[809]: Ignition 2.21.0 May 27 03:07:49.084679 ignition[809]: Stage: kargs May 27 03:07:49.084804 ignition[809]: no configs at "/usr/lib/ignition/base.d" May 27 03:07:49.084813 ignition[809]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:49.085859 ignition[809]: kargs: kargs passed May 27 03:07:49.085904 ignition[809]: Ignition finished successfully May 27 03:07:49.090703 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 27 03:07:49.092825 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 27 03:07:49.116736 ignition[817]: Ignition 2.21.0 May 27 03:07:49.116751 ignition[817]: Stage: disks May 27 03:07:49.116903 ignition[817]: no configs at "/usr/lib/ignition/base.d" May 27 03:07:49.116912 ignition[817]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:49.119197 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 27 03:07:49.117430 ignition[817]: disks: disks passed May 27 03:07:49.117489 ignition[817]: Ignition finished successfully May 27 03:07:49.122960 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 27 03:07:49.124106 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 27 03:07:49.125893 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 03:07:49.127713 systemd[1]: Reached target sysinit.target - System Initialization. May 27 03:07:49.129624 systemd[1]: Reached target basic.target - Basic System. May 27 03:07:49.132207 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 27 03:07:49.166755 systemd-fsck[827]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 27 03:07:49.170688 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 27 03:07:49.173084 systemd[1]: Mounting sysroot.mount - /sysroot... May 27 03:07:49.237502 kernel: EXT4-fs (vda9): mounted filesystem 5656cec4-efbd-4a2d-be98-2263e6ae16bd r/w with ordered data mode. Quota mode: none. May 27 03:07:49.238083 systemd[1]: Mounted sysroot.mount - /sysroot. May 27 03:07:49.239334 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 27 03:07:49.242628 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 03:07:49.244891 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 27 03:07:49.245928 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 27 03:07:49.245966 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 27 03:07:49.246094 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 27 03:07:49.263079 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 27 03:07:49.265647 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 27 03:07:49.269752 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (835) May 27 03:07:49.269781 kernel: BTRFS info (device vda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 03:07:49.269791 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 03:07:49.271483 kernel: BTRFS info (device vda6): using free-space-tree May 27 03:07:49.277037 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 03:07:49.312874 initrd-setup-root[859]: cut: /sysroot/etc/passwd: No such file or directory May 27 03:07:49.315679 initrd-setup-root[866]: cut: /sysroot/etc/group: No such file or directory May 27 03:07:49.319130 initrd-setup-root[873]: cut: /sysroot/etc/shadow: No such file or directory May 27 03:07:49.322356 initrd-setup-root[880]: cut: /sysroot/etc/gshadow: No such file or directory May 27 03:07:49.386422 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 27 03:07:49.388358 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 27 03:07:49.389926 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 27 03:07:49.408509 kernel: BTRFS info (device vda6): last unmount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 03:07:49.423710 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 27 03:07:49.425551 ignition[949]: INFO : Ignition 2.21.0 May 27 03:07:49.425551 ignition[949]: INFO : Stage: mount May 27 03:07:49.427967 ignition[949]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:07:49.427967 ignition[949]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:49.427967 ignition[949]: INFO : mount: mount passed May 27 03:07:49.430819 ignition[949]: INFO : Ignition finished successfully May 27 03:07:49.430867 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 27 03:07:49.434232 systemd[1]: Starting ignition-files.service - Ignition (files)... May 27 03:07:49.837050 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 27 03:07:49.838558 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 03:07:49.868428 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (961) May 27 03:07:49.868456 kernel: BTRFS info (device vda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 03:07:49.868466 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 27 03:07:49.869383 kernel: BTRFS info (device vda6): using free-space-tree May 27 03:07:49.873698 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 03:07:49.901141 ignition[978]: INFO : Ignition 2.21.0 May 27 03:07:49.902613 ignition[978]: INFO : Stage: files May 27 03:07:49.902613 ignition[978]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:07:49.902613 ignition[978]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:49.906197 ignition[978]: DEBUG : files: compiled without relabeling support, skipping May 27 03:07:49.906197 ignition[978]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 27 03:07:49.906197 ignition[978]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 27 03:07:49.910100 ignition[978]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 27 03:07:49.910100 ignition[978]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 27 03:07:49.910100 ignition[978]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 27 03:07:49.907097 unknown[978]: wrote ssh authorized keys file for user: core May 27 03:07:49.915270 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 27 03:07:49.915270 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 27 03:07:49.915270 ignition[978]: INFO : files: op(4): [started] processing unit "docker.service" May 27 03:07:49.915270 ignition[978]: INFO : files: op(4): [finished] processing unit "docker.service" May 27 03:07:49.915270 ignition[978]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" May 27 03:07:49.922329 ignition[978]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 27 03:07:49.924537 ignition[978]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 27 03:07:49.924537 ignition[978]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" May 27 03:07:49.927752 ignition[978]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" May 27 03:07:49.943773 ignition[978]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" May 27 03:07:49.946692 ignition[978]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 27 03:07:49.949137 ignition[978]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" May 27 03:07:49.949137 ignition[978]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" May 27 03:07:49.949137 ignition[978]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" May 27 03:07:49.949137 ignition[978]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" May 27 03:07:49.949137 ignition[978]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" May 27 03:07:49.949137 ignition[978]: INFO : files: files passed May 27 03:07:49.949137 ignition[978]: INFO : Ignition finished successfully May 27 03:07:49.949754 systemd[1]: Finished ignition-files.service - Ignition (files). May 27 03:07:49.952164 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 27 03:07:49.954057 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 27 03:07:49.968256 systemd[1]: ignition-quench.service: Deactivated successfully. May 27 03:07:49.968347 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 27 03:07:49.971216 initrd-setup-root-after-ignition[1008]: grep: /sysroot/oem/oem-release: No such file or directory May 27 03:07:49.972994 initrd-setup-root-after-ignition[1010]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 03:07:49.972994 initrd-setup-root-after-ignition[1010]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 27 03:07:49.975816 initrd-setup-root-after-ignition[1014]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 03:07:49.975495 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 03:07:49.977215 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 27 03:07:49.979818 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 27 03:07:50.008113 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 27 03:07:50.008201 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 27 03:07:50.010276 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 27 03:07:50.012077 systemd[1]: Reached target initrd.target - Initrd Default Target. May 27 03:07:50.013795 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 27 03:07:50.014433 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 27 03:07:50.027967 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 03:07:50.030998 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 27 03:07:50.051340 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 27 03:07:50.052653 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:07:50.054669 systemd[1]: Stopped target timers.target - Timer Units. May 27 03:07:50.056569 systemd-networkd[795]: eth0: Gained IPv6LL May 27 03:07:50.057346 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 27 03:07:50.057500 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 03:07:50.059911 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 27 03:07:50.061695 systemd[1]: Stopped target basic.target - Basic System. May 27 03:07:50.063422 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 27 03:07:50.065191 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 27 03:07:50.066930 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 27 03:07:50.068852 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 27 03:07:50.070753 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 27 03:07:50.072520 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 27 03:07:50.074536 systemd[1]: Stopped target sysinit.target - System Initialization. May 27 03:07:50.076282 systemd[1]: Stopped target local-fs.target - Local File Systems. May 27 03:07:50.078206 systemd[1]: Stopped target swap.target - Swaps. May 27 03:07:50.079726 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 27 03:07:50.079850 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 27 03:07:50.082072 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 27 03:07:50.083203 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:07:50.085018 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 27 03:07:50.089519 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:07:50.090719 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 27 03:07:50.090829 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 27 03:07:50.093616 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 27 03:07:50.093741 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 27 03:07:50.095636 systemd[1]: Stopped target paths.target - Path Units. May 27 03:07:50.097180 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 27 03:07:50.102542 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:07:50.103793 systemd[1]: Stopped target slices.target - Slice Units. May 27 03:07:50.105798 systemd[1]: Stopped target sockets.target - Socket Units. May 27 03:07:50.107287 systemd[1]: iscsid.socket: Deactivated successfully. May 27 03:07:50.107366 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 27 03:07:50.108963 systemd[1]: iscsiuio.socket: Deactivated successfully. May 27 03:07:50.109040 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 03:07:50.110531 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 27 03:07:50.110650 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 03:07:50.112463 systemd[1]: ignition-files.service: Deactivated successfully. May 27 03:07:50.112581 systemd[1]: Stopped ignition-files.service - Ignition (files). May 27 03:07:50.114784 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 27 03:07:50.117242 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 27 03:07:50.118393 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 27 03:07:50.118531 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:07:50.120307 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 27 03:07:50.120422 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 27 03:07:50.125369 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 27 03:07:50.133637 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 27 03:07:50.141411 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 27 03:07:50.145909 ignition[1035]: INFO : Ignition 2.21.0 May 27 03:07:50.145909 ignition[1035]: INFO : Stage: umount May 27 03:07:50.148786 ignition[1035]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:07:50.148786 ignition[1035]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 27 03:07:50.148786 ignition[1035]: INFO : umount: umount passed May 27 03:07:50.148786 ignition[1035]: INFO : Ignition finished successfully May 27 03:07:50.146156 systemd[1]: sysroot-boot.service: Deactivated successfully. May 27 03:07:50.146236 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 27 03:07:50.148932 systemd[1]: ignition-mount.service: Deactivated successfully. May 27 03:07:50.149022 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 27 03:07:50.150708 systemd[1]: Stopped target network.target - Network. May 27 03:07:50.152271 systemd[1]: ignition-disks.service: Deactivated successfully. May 27 03:07:50.152321 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 27 03:07:50.153856 systemd[1]: ignition-kargs.service: Deactivated successfully. May 27 03:07:50.153900 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 27 03:07:50.155395 systemd[1]: ignition-setup.service: Deactivated successfully. May 27 03:07:50.155443 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 27 03:07:50.157039 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 27 03:07:50.157076 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 27 03:07:50.158678 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 27 03:07:50.158724 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 27 03:07:50.160457 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 27 03:07:50.162127 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 27 03:07:50.168584 systemd[1]: systemd-resolved.service: Deactivated successfully. May 27 03:07:50.168680 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 27 03:07:50.171819 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 27 03:07:50.172020 systemd[1]: systemd-networkd.service: Deactivated successfully. May 27 03:07:50.172103 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 27 03:07:50.175110 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 27 03:07:50.175618 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 27 03:07:50.176793 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 27 03:07:50.176825 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 27 03:07:50.179321 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 27 03:07:50.180182 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 27 03:07:50.180234 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 03:07:50.182233 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 27 03:07:50.182275 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 27 03:07:50.184984 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 27 03:07:50.185023 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 27 03:07:50.186850 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 27 03:07:50.186890 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:07:50.189787 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:07:50.209971 systemd[1]: systemd-udevd.service: Deactivated successfully. May 27 03:07:50.214623 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:07:50.216002 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 27 03:07:50.216037 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 27 03:07:50.217932 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 27 03:07:50.217960 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:07:50.219652 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 27 03:07:50.219694 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 27 03:07:50.222405 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 27 03:07:50.222446 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 27 03:07:50.224336 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 27 03:07:50.224391 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 03:07:50.227755 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 27 03:07:50.228951 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 27 03:07:50.229003 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:07:50.231784 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 27 03:07:50.231827 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:07:50.235096 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 27 03:07:50.235135 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 03:07:50.238337 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 27 03:07:50.238384 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:07:50.240500 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:07:50.240543 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:07:50.244066 systemd[1]: network-cleanup.service: Deactivated successfully. May 27 03:07:50.244167 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 27 03:07:50.245950 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 27 03:07:50.247505 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 27 03:07:50.249284 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 27 03:07:50.251104 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 27 03:07:50.266688 systemd[1]: Switching root. May 27 03:07:50.306425 systemd-journald[245]: Journal stopped May 27 03:07:50.950450 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). May 27 03:07:50.950534 kernel: SELinux: policy capability network_peer_controls=1 May 27 03:07:50.950547 kernel: SELinux: policy capability open_perms=1 May 27 03:07:50.950557 kernel: SELinux: policy capability extended_socket_class=1 May 27 03:07:50.950566 kernel: SELinux: policy capability always_check_network=0 May 27 03:07:50.950575 kernel: SELinux: policy capability cgroup_seclabel=1 May 27 03:07:50.950584 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 27 03:07:50.950594 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 27 03:07:50.950605 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 27 03:07:50.950614 kernel: SELinux: policy capability userspace_initial_context=0 May 27 03:07:50.950623 kernel: audit: type=1403 audit(1748315270.383:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 27 03:07:50.950635 systemd[1]: Successfully loaded SELinux policy in 44.023ms. May 27 03:07:50.950653 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.375ms. May 27 03:07:50.950664 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 03:07:50.950674 systemd[1]: Detected virtualization kvm. May 27 03:07:50.950684 systemd[1]: Detected architecture arm64. May 27 03:07:50.950695 systemd[1]: Detected first boot. May 27 03:07:50.950705 systemd[1]: Initializing machine ID from VM UUID. May 27 03:07:50.950714 kernel: NET: Registered PF_VSOCK protocol family May 27 03:07:50.950724 zram_generator::config[1082]: No configuration found. May 27 03:07:50.950734 systemd[1]: Populated /etc with preset unit settings. May 27 03:07:50.950744 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 27 03:07:50.950754 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 27 03:07:50.950765 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 27 03:07:50.950775 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 27 03:07:50.950786 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 27 03:07:50.950795 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 27 03:07:50.950805 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 27 03:07:50.950815 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 27 03:07:50.950825 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 27 03:07:50.950836 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 27 03:07:50.950846 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 27 03:07:50.950856 systemd[1]: Created slice user.slice - User and Session Slice. May 27 03:07:50.950865 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:07:50.950876 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:07:50.950892 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 27 03:07:50.950903 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 27 03:07:50.950913 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 27 03:07:50.950923 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 03:07:50.950935 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 27 03:07:50.950945 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:07:50.950958 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 03:07:50.950968 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 27 03:07:50.950978 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 27 03:07:50.950987 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 27 03:07:50.950997 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 27 03:07:50.951008 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:07:50.951022 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 03:07:50.951032 systemd[1]: Reached target slices.target - Slice Units. May 27 03:07:50.951041 systemd[1]: Reached target swap.target - Swaps. May 27 03:07:50.951052 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 27 03:07:50.951062 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 27 03:07:50.951072 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 27 03:07:50.951081 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 03:07:50.951091 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 03:07:50.951101 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:07:50.951113 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 27 03:07:50.951124 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 27 03:07:50.951133 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 27 03:07:50.951146 systemd[1]: Mounting media.mount - External Media Directory... May 27 03:07:50.951156 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 27 03:07:50.951165 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 27 03:07:50.951175 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 27 03:07:50.951186 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 03:07:50.951198 systemd[1]: Reached target machines.target - Containers. May 27 03:07:50.951208 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 27 03:07:50.951224 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:07:50.951235 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 03:07:50.951245 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 27 03:07:50.951255 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:07:50.951265 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 03:07:50.951276 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:07:50.951287 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 27 03:07:50.951299 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:07:50.951309 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 27 03:07:50.951319 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 27 03:07:50.951329 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 27 03:07:50.951339 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 27 03:07:50.951349 systemd[1]: Stopped systemd-fsck-usr.service. May 27 03:07:50.951360 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 03:07:50.951374 kernel: fuse: init (API version 7.41) May 27 03:07:50.951390 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 03:07:50.951401 kernel: loop: module loaded May 27 03:07:50.951412 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 03:07:50.951421 kernel: ACPI: bus type drm_connector registered May 27 03:07:50.951431 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 03:07:50.951441 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 27 03:07:50.951455 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 27 03:07:50.951465 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 03:07:50.951486 systemd[1]: verity-setup.service: Deactivated successfully. May 27 03:07:50.951499 systemd[1]: Stopped verity-setup.service. May 27 03:07:50.951528 systemd-journald[1154]: Collecting audit messages is disabled. May 27 03:07:50.951550 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 27 03:07:50.951560 systemd-journald[1154]: Journal started May 27 03:07:50.951582 systemd-journald[1154]: Runtime Journal (/run/log/journal/864159f589bb4522af33854fe347b1d5) is 6M, max 48.5M, 42.4M free. May 27 03:07:50.744981 systemd[1]: Queued start job for default target multi-user.target. May 27 03:07:50.765326 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 27 03:07:50.765679 systemd[1]: systemd-journald.service: Deactivated successfully. May 27 03:07:50.953842 systemd[1]: Started systemd-journald.service - Journal Service. May 27 03:07:50.954451 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 27 03:07:50.955699 systemd[1]: Mounted media.mount - External Media Directory. May 27 03:07:50.956751 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 27 03:07:50.957904 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 27 03:07:50.959082 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 27 03:07:50.960312 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 27 03:07:50.961765 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:07:50.963167 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 27 03:07:50.963329 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 27 03:07:50.964720 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:07:50.964898 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:07:50.966210 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 03:07:50.966363 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 03:07:50.967698 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:07:50.969512 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:07:50.970903 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 27 03:07:50.971051 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 27 03:07:50.972366 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:07:50.972561 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:07:50.973851 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 03:07:50.975191 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:07:50.976699 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 27 03:07:50.978176 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 27 03:07:50.992138 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 03:07:50.994566 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 27 03:07:50.996552 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 27 03:07:50.997622 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 27 03:07:50.997659 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 03:07:50.999526 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 27 03:07:51.002200 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 27 03:07:51.003347 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:07:51.004258 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 27 03:07:51.006105 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 27 03:07:51.007343 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 03:07:51.008228 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 27 03:07:51.010087 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 03:07:51.010948 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 03:07:51.014697 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 27 03:07:51.017806 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 03:07:51.020755 systemd-journald[1154]: Time spent on flushing to /var/log/journal/864159f589bb4522af33854fe347b1d5 is 22.432ms for 852 entries. May 27 03:07:51.020755 systemd-journald[1154]: System Journal (/var/log/journal/864159f589bb4522af33854fe347b1d5) is 8M, max 195.6M, 187.6M free. May 27 03:07:51.051792 systemd-journald[1154]: Received client request to flush runtime journal. May 27 03:07:51.051853 kernel: loop0: detected capacity change from 0 to 138376 May 27 03:07:51.051878 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 27 03:07:51.021511 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:07:51.023573 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 27 03:07:51.025745 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 27 03:07:51.027262 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 27 03:07:51.032146 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 27 03:07:51.034958 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 27 03:07:51.052832 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 03:07:51.054560 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 27 03:07:51.062879 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 27 03:07:51.062898 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 27 03:07:51.067024 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 03:07:51.069818 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 27 03:07:51.073269 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 27 03:07:51.080605 kernel: loop1: detected capacity change from 0 to 107312 May 27 03:07:51.100823 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 27 03:07:51.103493 kernel: loop2: detected capacity change from 0 to 138376 May 27 03:07:51.104900 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 03:07:51.123510 kernel: loop3: detected capacity change from 0 to 107312 May 27 03:07:51.124567 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. May 27 03:07:51.124820 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. May 27 03:07:51.128753 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:07:51.133814 (sd-merge)[1220]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 27 03:07:51.134149 (sd-merge)[1220]: Merged extensions into '/usr'. May 27 03:07:51.137506 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 27 03:07:51.142252 systemd[1]: Starting ensure-sysext.service... May 27 03:07:51.146608 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 03:07:51.163878 systemd[1]: Reload requested from client PID 1226 ('systemctl') (unit ensure-sysext.service)... May 27 03:07:51.163894 systemd[1]: Reloading... May 27 03:07:51.175755 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 27 03:07:51.175788 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 27 03:07:51.175983 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 27 03:07:51.176163 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 27 03:07:51.176875 systemd-tmpfiles[1227]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 27 03:07:51.177195 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. May 27 03:07:51.177277 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. May 27 03:07:51.180251 systemd-tmpfiles[1227]: Detected autofs mount point /boot during canonicalization of boot. May 27 03:07:51.180264 systemd-tmpfiles[1227]: Skipping /boot May 27 03:07:51.189590 systemd-tmpfiles[1227]: Detected autofs mount point /boot during canonicalization of boot. May 27 03:07:51.189624 systemd-tmpfiles[1227]: Skipping /boot May 27 03:07:51.222507 zram_generator::config[1255]: No configuration found. May 27 03:07:51.294868 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 03:07:51.297351 ldconfig[1193]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 03:07:51.356387 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 27 03:07:51.356519 systemd[1]: Reloading finished in 192 ms. May 27 03:07:51.392486 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 27 03:07:51.409519 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:07:51.416849 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 03:07:51.419168 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 27 03:07:51.433698 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 27 03:07:51.437420 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 03:07:51.439603 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 27 03:07:51.443603 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:07:51.444600 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:07:51.446878 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:07:51.459625 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:07:51.460843 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:07:51.460954 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 03:07:51.463181 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:07:51.464519 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:07:51.467391 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:07:51.468650 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:07:51.470494 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:07:51.470600 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 03:07:51.472176 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 27 03:07:51.474772 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 27 03:07:51.479019 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:07:51.479163 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:07:51.480801 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:07:51.480930 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:07:51.482452 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:07:51.482610 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:07:51.487787 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 27 03:07:51.493883 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:07:51.495151 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:07:51.497120 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 03:07:51.508296 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:07:51.514082 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:07:51.515408 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:07:51.515626 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 03:07:51.515856 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 03:07:51.518505 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 27 03:07:51.520296 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:07:51.520452 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:07:51.522385 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 03:07:51.522542 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 03:07:51.524040 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:07:51.524175 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:07:51.525766 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:07:51.525910 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:07:51.527317 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 27 03:07:51.532776 systemd[1]: Finished ensure-sysext.service. May 27 03:07:51.538262 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 03:07:51.538319 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 03:07:51.540460 augenrules[1341]: No rules May 27 03:07:51.542293 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 27 03:07:51.543873 systemd[1]: audit-rules.service: Deactivated successfully. May 27 03:07:51.544206 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 03:07:51.561959 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 27 03:07:51.565383 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:07:51.567687 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 27 03:07:51.586825 systemd-resolved[1295]: Positive Trust Anchors: May 27 03:07:51.587113 systemd-resolved[1295]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 03:07:51.587148 systemd-resolved[1295]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 27 03:07:51.598628 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 27 03:07:51.601088 systemd-resolved[1295]: Defaulting to hostname 'linux'. May 27 03:07:51.602549 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 03:07:51.603740 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 03:07:51.607602 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 27 03:07:51.608946 systemd[1]: Reached target time-set.target - System Time Set. May 27 03:07:51.614065 systemd-udevd[1350]: Using default interface naming scheme 'v255'. May 27 03:07:51.629180 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:07:51.630855 systemd[1]: Reached target sysinit.target - System Initialization. May 27 03:07:51.633043 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 27 03:07:51.634352 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 03:07:51.635823 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 27 03:07:51.638646 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 27 03:07:51.639923 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 27 03:07:51.641174 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 27 03:07:51.641210 systemd[1]: Reached target paths.target - Path Units. May 27 03:07:51.642100 systemd[1]: Reached target timers.target - Timer Units. May 27 03:07:51.644062 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 27 03:07:51.646552 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 03:07:51.649807 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 27 03:07:51.651824 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 27 03:07:51.653744 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 27 03:07:51.658833 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 27 03:07:51.660940 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 27 03:07:51.666977 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 03:07:51.668650 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 03:07:51.676058 systemd[1]: Reached target sockets.target - Socket Units. May 27 03:07:51.678305 systemd[1]: Reached target basic.target - Basic System. May 27 03:07:51.679377 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 27 03:07:51.679407 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 27 03:07:51.682662 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 27 03:07:51.686856 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 27 03:07:51.692645 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 27 03:07:51.696686 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 27 03:07:51.698541 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 03:07:51.699619 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 03:07:51.701724 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 27 03:07:51.706053 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 27 03:07:51.713735 systemd[1]: Starting systemd-logind.service - User Login Management... May 27 03:07:51.716436 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 27 03:07:51.716865 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 27 03:07:51.717666 systemd[1]: Starting update-engine.service - Update Engine... May 27 03:07:51.719627 jq[1388]: false May 27 03:07:51.719890 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 27 03:07:51.735708 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 27 03:07:51.737407 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 27 03:07:51.737615 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 27 03:07:51.742487 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 27 03:07:51.761936 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 27 03:07:51.763294 jq[1400]: true May 27 03:07:51.764998 systemd[1]: motdgen.service: Deactivated successfully. May 27 03:07:51.765200 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 03:07:51.768934 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 27 03:07:51.769128 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 27 03:07:51.784720 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 27 03:07:51.788762 extend-filesystems[1390]: Found loop2 May 27 03:07:51.789709 extend-filesystems[1390]: Found loop3 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda May 27 03:07:51.789709 extend-filesystems[1390]: Found vda1 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda2 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda3 May 27 03:07:51.789709 extend-filesystems[1390]: Found usr May 27 03:07:51.789709 extend-filesystems[1390]: Found vda4 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda6 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda7 May 27 03:07:51.789709 extend-filesystems[1390]: Found vda9 May 27 03:07:51.789709 extend-filesystems[1390]: Checking size of /dev/vda9 May 27 03:07:51.806491 dbus-daemon[1384]: [system] SELinux support is enabled May 27 03:07:51.809146 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 27 03:07:51.822559 update_engine[1399]: I20250527 03:07:51.808413 1399 main.cc:92] Flatcar Update Engine starting May 27 03:07:51.822559 update_engine[1399]: I20250527 03:07:51.821323 1399 update_check_scheduler.cc:74] Next update check in 11m16s May 27 03:07:51.822748 extend-filesystems[1390]: Old size kept for /dev/vda9 May 27 03:07:51.811836 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 03:07:51.823625 jq[1413]: true May 27 03:07:51.811856 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 27 03:07:51.816143 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 03:07:51.816157 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 27 03:07:51.818951 systemd[1]: extend-filesystems.service: Deactivated successfully. May 27 03:07:51.819151 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 27 03:07:51.823356 systemd[1]: Started update-engine.service - Update Engine. May 27 03:07:51.829403 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 27 03:07:51.833005 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 27 03:07:51.860531 bash[1441]: Updated "/home/core/.ssh/authorized_keys" May 27 03:07:51.862248 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 27 03:07:51.864717 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 27 03:07:51.886462 systemd-logind[1396]: New seat seat0. May 27 03:07:51.909392 systemd-networkd[1383]: lo: Link UP May 27 03:07:51.909399 systemd-networkd[1383]: lo: Gained carrier May 27 03:07:51.922276 systemd-networkd[1383]: Enumeration completed May 27 03:07:51.922385 systemd[1]: Started systemd-logind.service - User Login Management. May 27 03:07:51.923600 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 03:07:51.924811 systemd[1]: Reached target network.target - Network. May 27 03:07:51.926877 systemd-networkd[1383]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:07:51.926884 systemd-networkd[1383]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 03:07:51.927392 systemd-networkd[1383]: eth0: Link UP May 27 03:07:51.927596 systemd-networkd[1383]: eth0: Gained carrier May 27 03:07:51.927615 systemd-networkd[1383]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:07:51.928948 systemd[1]: Starting containerd.service - containerd container runtime... May 27 03:07:51.932721 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 27 03:07:51.944665 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 27 03:07:51.957758 (ntainerd)[1467]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 03:07:51.973542 systemd-networkd[1383]: eth0: DHCPv4 address 10.0.0.144/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 27 03:07:51.974146 systemd-timesyncd[1346]: Network configuration changed, trying to establish connection. May 27 03:07:51.976022 systemd-timesyncd[1346]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 27 03:07:51.976154 systemd-timesyncd[1346]: Initial clock synchronization to Tue 2025-05-27 03:07:51.892061 UTC. May 27 03:07:51.980641 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 27 03:07:52.008873 systemd-logind[1396]: Watching system buttons on /dev/input/event0 (Power Button) May 27 03:07:52.021593 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:07:52.050630 locksmithd[1430]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 27 03:07:52.080290 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:07:52.175324 containerd[1467]: time="2025-05-27T03:07: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 03:07:52.176636 containerd[1467]: time="2025-05-27T03:07:52.176595526Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 03:07:52.186965 containerd[1467]: time="2025-05-27T03:07:52.186918969Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.748µs" May 27 03:07:52.186965 containerd[1467]: time="2025-05-27T03:07:52.186954939Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 03:07:52.187031 containerd[1467]: time="2025-05-27T03:07:52.186971770Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 03:07:52.187131 containerd[1467]: time="2025-05-27T03:07:52.187102402Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 03:07:52.187131 containerd[1467]: time="2025-05-27T03:07:52.187123053Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 03:07:52.187185 containerd[1467]: time="2025-05-27T03:07:52.187145137Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 03:07:52.187205 containerd[1467]: time="2025-05-27T03:07:52.187190577Z" 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 03:07:52.187205 containerd[1467]: time="2025-05-27T03:07:52.187200764Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 03:07:52.187416 containerd[1467]: time="2025-05-27T03:07:52.187374170Z" 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 03:07:52.187416 containerd[1467]: time="2025-05-27T03:07:52.187404331Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 03:07:52.187454 containerd[1467]: time="2025-05-27T03:07:52.187418218Z" 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 03:07:52.187454 containerd[1467]: time="2025-05-27T03:07:52.187426335Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 03:07:52.187537 containerd[1467]: time="2025-05-27T03:07:52.187519007Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 03:07:52.187737 containerd[1467]: time="2025-05-27T03:07:52.187711075Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 03:07:52.187766 containerd[1467]: time="2025-05-27T03:07:52.187745454Z" 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 03:07:52.187766 containerd[1467]: time="2025-05-27T03:07:52.187755680Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 03:07:52.187813 containerd[1467]: time="2025-05-27T03:07:52.187800762Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 03:07:52.188088 containerd[1467]: time="2025-05-27T03:07:52.188050685Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 03:07:52.188162 containerd[1467]: time="2025-05-27T03:07:52.188146699Z" level=info msg="metadata content store policy set" policy=shared May 27 03:07:52.191449 containerd[1467]: time="2025-05-27T03:07:52.191416470Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 03:07:52.191496 containerd[1467]: time="2025-05-27T03:07:52.191462507Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 03:07:52.191496 containerd[1467]: time="2025-05-27T03:07:52.191488053Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 03:07:52.191532 containerd[1467]: time="2025-05-27T03:07:52.191500587Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 03:07:52.191532 containerd[1467]: time="2025-05-27T03:07:52.191513479Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 03:07:52.191532 containerd[1467]: time="2025-05-27T03:07:52.191523068Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 03:07:52.191580 containerd[1467]: time="2025-05-27T03:07:52.191535602Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 03:07:52.191580 containerd[1467]: time="2025-05-27T03:07:52.191548057Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 03:07:52.191580 containerd[1467]: time="2025-05-27T03:07:52.191559277Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 03:07:52.191580 containerd[1467]: time="2025-05-27T03:07:52.191569782Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 03:07:52.191580 containerd[1467]: time="2025-05-27T03:07:52.191579252Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 03:07:52.191656 containerd[1467]: time="2025-05-27T03:07:52.191591746Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 03:07:52.191725 containerd[1467]: time="2025-05-27T03:07:52.191695440Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 03:07:52.191748 containerd[1467]: time="2025-05-27T03:07:52.191725681Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 03:07:52.191748 containerd[1467]: time="2025-05-27T03:07:52.191740045Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 03:07:52.191783 containerd[1467]: time="2025-05-27T03:07:52.191751306Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 03:07:52.191783 containerd[1467]: time="2025-05-27T03:07:52.191762009Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 03:07:52.191783 containerd[1467]: time="2025-05-27T03:07:52.191772076Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 03:07:52.191783 containerd[1467]: time="2025-05-27T03:07:52.191782064Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 03:07:52.191848 containerd[1467]: time="2025-05-27T03:07:52.191792528Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 03:07:52.191848 containerd[1467]: time="2025-05-27T03:07:52.191803113Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 03:07:52.191848 containerd[1467]: time="2025-05-27T03:07:52.191814532Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 03:07:52.191848 containerd[1467]: time="2025-05-27T03:07:52.191831921Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 03:07:52.192032 containerd[1467]: time="2025-05-27T03:07:52.192011097Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 03:07:52.192060 containerd[1467]: time="2025-05-27T03:07:52.192051643Z" level=info msg="Start snapshots syncer" May 27 03:07:52.192088 containerd[1467]: time="2025-05-27T03:07:52.192077069Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 03:07:52.192490 containerd[1467]: time="2025-05-27T03:07:52.192393760Z" 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 03:07:52.192490 containerd[1467]: time="2025-05-27T03:07:52.192461523Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 03:07:52.192663 containerd[1467]: time="2025-05-27T03:07:52.192638352Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 03:07:52.192781 containerd[1467]: time="2025-05-27T03:07:52.192756608Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 03:07:52.192829 containerd[1467]: time="2025-05-27T03:07:52.192791902Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 03:07:52.192829 containerd[1467]: time="2025-05-27T03:07:52.192805272Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 03:07:52.192829 containerd[1467]: time="2025-05-27T03:07:52.192822024Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 03:07:52.192886 containerd[1467]: time="2025-05-27T03:07:52.192838974Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 03:07:52.192886 containerd[1467]: time="2025-05-27T03:07:52.192854055Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 03:07:52.192886 containerd[1467]: time="2025-05-27T03:07:52.192866310Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 03:07:52.192933 containerd[1467]: time="2025-05-27T03:07:52.192904071Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 03:07:52.192933 containerd[1467]: time="2025-05-27T03:07:52.192917719Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 03:07:52.192965 containerd[1467]: time="2025-05-27T03:07:52.192932561Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 03:07:52.192985 containerd[1467]: time="2025-05-27T03:07:52.192974102Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 03:07:52.193002 containerd[1467]: time="2025-05-27T03:07:52.192992088Z" 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 03:07:52.193020 containerd[1467]: time="2025-05-27T03:07:52.193006054Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 03:07:52.193038 containerd[1467]: time="2025-05-27T03:07:52.193016280Z" 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 03:07:52.193038 containerd[1467]: time="2025-05-27T03:07:52.193028058Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 03:07:52.193075 containerd[1467]: time="2025-05-27T03:07:52.193044611Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 03:07:52.193075 containerd[1467]: time="2025-05-27T03:07:52.193057105Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 03:07:52.193254 containerd[1467]: time="2025-05-27T03:07:52.193139391Z" level=info msg="runtime interface created" May 27 03:07:52.193254 containerd[1467]: time="2025-05-27T03:07:52.193149936Z" level=info msg="created NRI interface" May 27 03:07:52.193254 containerd[1467]: time="2025-05-27T03:07:52.193164380Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 03:07:52.193254 containerd[1467]: time="2025-05-27T03:07:52.193176556Z" level=info msg="Connect containerd service" May 27 03:07:52.193254 containerd[1467]: time="2025-05-27T03:07:52.193214356Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 03:07:52.196062 containerd[1467]: time="2025-05-27T03:07:52.196021644Z" 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 03:07:52.294875 containerd[1467]: time="2025-05-27T03:07:52.294811691Z" level=info msg="Start subscribing containerd event" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.294895012Z" level=info msg="Start recovering state" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.294985496Z" level=info msg="Start event monitor" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295000735Z" level=info msg="Start cni network conf syncer for default" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295008096Z" level=info msg="Start streaming server" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295021426Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295030658Z" level=info msg="runtime interface starting up..." May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295036109Z" level=info msg="starting plugins..." May 27 03:07:52.295045 containerd[1467]: time="2025-05-27T03:07:52.295049359Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 03:07:52.295421 containerd[1467]: time="2025-05-27T03:07:52.295400986Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 03:07:52.295458 containerd[1467]: time="2025-05-27T03:07:52.295446586Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 03:07:52.295607 systemd[1]: Started containerd.service - containerd container runtime. May 27 03:07:52.297013 containerd[1467]: time="2025-05-27T03:07:52.296981418Z" level=info msg="containerd successfully booted in 0.122099s" May 27 03:07:53.574638 systemd-networkd[1383]: eth0: Gained IPv6LL May 27 03:07:53.577363 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 27 03:07:53.579034 systemd[1]: Reached target network-online.target - Network is Online. May 27 03:07:53.581405 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 27 03:07:53.583325 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 27 03:07:53.607776 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 27 03:07:53.609305 systemd[1]: coreos-metadata.service: Deactivated successfully. May 27 03:07:53.609495 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 27 03:07:53.611267 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 27 03:07:54.079847 sshd_keygen[1405]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 27 03:07:54.098608 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 27 03:07:54.101347 systemd[1]: Starting issuegen.service - Generate /run/issue... May 27 03:07:54.120697 systemd[1]: issuegen.service: Deactivated successfully. May 27 03:07:54.120896 systemd[1]: Finished issuegen.service - Generate /run/issue. May 27 03:07:54.123375 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 27 03:07:54.147197 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 27 03:07:54.149869 systemd[1]: Started getty@tty1.service - Getty on tty1. May 27 03:07:54.151866 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 27 03:07:54.153117 systemd[1]: Reached target getty.target - Login Prompts. May 27 03:07:54.154149 systemd[1]: Reached target multi-user.target - Multi-User System. May 27 03:07:54.155247 systemd[1]: Startup finished in 2.135s (kernel) + 3.743s (initrd) + 3.821s (userspace) = 9.700s. May 27 03:07:59.954343 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 27 03:07:59.955431 systemd[1]: Started sshd@0-10.0.0.144:22-10.0.0.1:48390.service - OpenSSH per-connection server daemon (10.0.0.1:48390). May 27 03:08:00.017010 sshd[1537]: Accepted publickey for core from 10.0.0.1 port 48390 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.018737 sshd-session[1537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.024790 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 27 03:08:00.025818 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 27 03:08:00.032353 systemd-logind[1396]: New session 1 of user core. May 27 03:08:00.048508 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 27 03:08:00.051092 systemd[1]: Starting user@500.service - User Manager for UID 500... May 27 03:08:00.070364 (systemd)[1541]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 27 03:08:00.076186 systemd-logind[1396]: New session c1 of user core. May 27 03:08:00.191579 systemd[1541]: Queued start job for default target default.target. May 27 03:08:00.202426 systemd[1541]: Created slice app.slice - User Application Slice. May 27 03:08:00.202488 systemd[1541]: Reached target paths.target - Paths. May 27 03:08:00.202536 systemd[1541]: Reached target timers.target - Timers. May 27 03:08:00.203788 systemd[1541]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 03:08:00.213328 systemd[1541]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 03:08:00.213401 systemd[1541]: Reached target sockets.target - Sockets. May 27 03:08:00.213442 systemd[1541]: Reached target basic.target - Basic System. May 27 03:08:00.213490 systemd[1541]: Reached target default.target - Main User Target. May 27 03:08:00.213520 systemd[1541]: Startup finished in 131ms. May 27 03:08:00.213664 systemd[1]: Started user@500.service - User Manager for UID 500. May 27 03:08:00.215434 systemd[1]: Started session-1.scope - Session 1 of User core. May 27 03:08:00.279085 systemd[1]: Started sshd@1-10.0.0.144:22-10.0.0.1:48404.service - OpenSSH per-connection server daemon (10.0.0.1:48404). May 27 03:08:00.334746 sshd[1552]: Accepted publickey for core from 10.0.0.1 port 48404 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.336056 sshd-session[1552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.340313 systemd-logind[1396]: New session 2 of user core. May 27 03:08:00.352705 systemd[1]: Started session-2.scope - Session 2 of User core. May 27 03:08:00.404514 sshd[1554]: Connection closed by 10.0.0.1 port 48404 May 27 03:08:00.404322 sshd-session[1552]: pam_unix(sshd:session): session closed for user core May 27 03:08:00.415414 systemd[1]: sshd@1-10.0.0.144:22-10.0.0.1:48404.service: Deactivated successfully. May 27 03:08:00.416797 systemd[1]: session-2.scope: Deactivated successfully. May 27 03:08:00.418541 systemd-logind[1396]: Session 2 logged out. Waiting for processes to exit. May 27 03:08:00.420777 systemd[1]: Started sshd@2-10.0.0.144:22-10.0.0.1:48410.service - OpenSSH per-connection server daemon (10.0.0.1:48410). May 27 03:08:00.421781 systemd-logind[1396]: Removed session 2. May 27 03:08:00.470657 sshd[1560]: Accepted publickey for core from 10.0.0.1 port 48410 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.471787 sshd-session[1560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.476012 systemd-logind[1396]: New session 3 of user core. May 27 03:08:00.491680 systemd[1]: Started session-3.scope - Session 3 of User core. May 27 03:08:00.540537 sshd[1562]: Connection closed by 10.0.0.1 port 48410 May 27 03:08:00.540877 sshd-session[1560]: pam_unix(sshd:session): session closed for user core May 27 03:08:00.553006 systemd[1]: sshd@2-10.0.0.144:22-10.0.0.1:48410.service: Deactivated successfully. May 27 03:08:00.556043 systemd[1]: session-3.scope: Deactivated successfully. May 27 03:08:00.556694 systemd-logind[1396]: Session 3 logged out. Waiting for processes to exit. May 27 03:08:00.559075 systemd[1]: Started sshd@3-10.0.0.144:22-10.0.0.1:48412.service - OpenSSH per-connection server daemon (10.0.0.1:48412). May 27 03:08:00.560328 systemd-logind[1396]: Removed session 3. May 27 03:08:00.614651 sshd[1568]: Accepted publickey for core from 10.0.0.1 port 48412 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.615880 sshd-session[1568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.619866 systemd-logind[1396]: New session 4 of user core. May 27 03:08:00.630625 systemd[1]: Started session-4.scope - Session 4 of User core. May 27 03:08:00.682479 sshd[1570]: Connection closed by 10.0.0.1 port 48412 May 27 03:08:00.682783 sshd-session[1568]: pam_unix(sshd:session): session closed for user core May 27 03:08:00.701527 systemd[1]: sshd@3-10.0.0.144:22-10.0.0.1:48412.service: Deactivated successfully. May 27 03:08:00.702959 systemd[1]: session-4.scope: Deactivated successfully. May 27 03:08:00.704973 systemd-logind[1396]: Session 4 logged out. Waiting for processes to exit. May 27 03:08:00.707451 systemd[1]: Started sshd@4-10.0.0.144:22-10.0.0.1:48418.service - OpenSSH per-connection server daemon (10.0.0.1:48418). May 27 03:08:00.708771 systemd-logind[1396]: Removed session 4. May 27 03:08:00.759003 sshd[1576]: Accepted publickey for core from 10.0.0.1 port 48418 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.760342 sshd-session[1576]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.764969 systemd-logind[1396]: New session 5 of user core. May 27 03:08:00.775628 systemd[1]: Started session-5.scope - Session 5 of User core. May 27 03:08:00.833792 sudo[1579]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 27 03:08:00.834056 sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:08:00.857167 sudo[1579]: pam_unix(sudo:session): session closed for user root May 27 03:08:00.858755 sshd[1578]: Connection closed by 10.0.0.1 port 48418 May 27 03:08:00.859077 sshd-session[1576]: pam_unix(sshd:session): session closed for user core May 27 03:08:00.868707 systemd[1]: sshd@4-10.0.0.144:22-10.0.0.1:48418.service: Deactivated successfully. May 27 03:08:00.871041 systemd[1]: session-5.scope: Deactivated successfully. May 27 03:08:00.872094 systemd-logind[1396]: Session 5 logged out. Waiting for processes to exit. May 27 03:08:00.875212 systemd[1]: Started sshd@5-10.0.0.144:22-10.0.0.1:48434.service - OpenSSH per-connection server daemon (10.0.0.1:48434). May 27 03:08:00.875879 systemd-logind[1396]: Removed session 5. May 27 03:08:00.925698 sshd[1585]: Accepted publickey for core from 10.0.0.1 port 48434 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:00.926965 sshd-session[1585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:00.931023 systemd-logind[1396]: New session 6 of user core. May 27 03:08:00.945687 systemd[1]: Started session-6.scope - Session 6 of User core. May 27 03:08:00.996835 sudo[1589]: 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 03:08:00.997093 sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:08:01.001620 sudo[1589]: pam_unix(sudo:session): session closed for user root May 27 03:08:01.006312 sudo[1588]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 27 03:08:01.006874 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:08:01.015386 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 03:08:01.054488 augenrules[1611]: No rules May 27 03:08:01.055171 systemd[1]: audit-rules.service: Deactivated successfully. May 27 03:08:01.055517 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 03:08:01.056899 sudo[1588]: pam_unix(sudo:session): session closed for user root May 27 03:08:01.059227 sshd[1587]: Connection closed by 10.0.0.1 port 48434 May 27 03:08:01.059093 sshd-session[1585]: pam_unix(sshd:session): session closed for user core May 27 03:08:01.076551 systemd[1]: sshd@5-10.0.0.144:22-10.0.0.1:48434.service: Deactivated successfully. May 27 03:08:01.078185 systemd[1]: session-6.scope: Deactivated successfully. May 27 03:08:01.078952 systemd-logind[1396]: Session 6 logged out. Waiting for processes to exit. May 27 03:08:01.081500 systemd[1]: Started sshd@6-10.0.0.144:22-10.0.0.1:48442.service - OpenSSH per-connection server daemon (10.0.0.1:48442). May 27 03:08:01.082168 systemd-logind[1396]: Removed session 6. May 27 03:08:01.124933 sshd[1620]: Accepted publickey for core from 10.0.0.1 port 48442 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:01.126055 sshd-session[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:01.130445 systemd-logind[1396]: New session 7 of user core. May 27 03:08:01.146672 systemd[1]: Started session-7.scope - Session 7 of User core. May 27 03:08:01.201756 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 03:08:01.215822 (dockerd)[1624]: 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 03:08:01.508389 dockerd[1624]: time="2025-05-27T03:08:01.508325698Z" level=info msg="Starting up" May 27 03:08:01.510534 dockerd[1624]: time="2025-05-27T03:08:01.509800361Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 03:08:01.559206 dockerd[1624]: time="2025-05-27T03:08:01.559151742Z" level=info msg="Loading containers: start." May 27 03:08:01.567545 kernel: Initializing XFRM netlink socket May 27 03:08:01.767540 systemd-networkd[1383]: docker0: Link UP May 27 03:08:01.774861 dockerd[1624]: time="2025-05-27T03:08:01.774820234Z" level=info msg="Loading containers: done." May 27 03:08:01.786455 dockerd[1624]: time="2025-05-27T03:08:01.786408553Z" 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 03:08:01.786587 dockerd[1624]: time="2025-05-27T03:08:01.786498609Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 03:08:01.786613 dockerd[1624]: time="2025-05-27T03:08:01.786592772Z" level=info msg="Initializing buildkit" May 27 03:08:01.810540 dockerd[1624]: time="2025-05-27T03:08:01.810503492Z" level=info msg="Completed buildkit initialization" May 27 03:08:01.815051 dockerd[1624]: time="2025-05-27T03:08:01.815020527Z" level=info msg="Daemon has completed initialization" May 27 03:08:01.815171 dockerd[1624]: time="2025-05-27T03:08:01.815068147Z" level=info msg="API listen on /run/docker.sock" May 27 03:08:01.815376 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 03:08:01.822901 sshd[1622]: Connection closed by 10.0.0.1 port 48442 May 27 03:08:01.823216 sshd-session[1620]: pam_unix(sshd:session): session closed for user core May 27 03:08:01.836342 systemd[1]: sshd@6-10.0.0.144:22-10.0.0.1:48442.service: Deactivated successfully. May 27 03:08:01.837851 systemd[1]: session-7.scope: Deactivated successfully. May 27 03:08:01.839066 systemd-logind[1396]: Session 7 logged out. Waiting for processes to exit. May 27 03:08:01.842056 systemd[1]: Started sshd@7-10.0.0.144:22-10.0.0.1:48458.service - OpenSSH per-connection server daemon (10.0.0.1:48458). May 27 03:08:01.842665 systemd-logind[1396]: Removed session 7. May 27 03:08:01.889572 sshd[1830]: Accepted publickey for core from 10.0.0.1 port 48458 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:01.890747 sshd-session[1830]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:01.894430 systemd-logind[1396]: New session 8 of user core. May 27 03:08:01.902603 systemd[1]: Started session-8.scope - Session 8 of User core. May 27 03:08:03.025134 systemd[1]: var-lib-docker-overlay2-3a36c1bd51f90f166c47bca7a6c57c587ad4605ee559487eeea3625f96783cd6\x2dinit-merged.mount: Deactivated successfully. May 27 03:08:03.064116 containerd[1467]: time="2025-05-27T03:08:03.064073760Z" level=info msg="connecting to shim 0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a" address="unix:///run/containerd/s/f540614a1f32c038c0d4eed91da9ad64d3928daf8c2b083abe351b45a1eabd28" namespace=moby protocol=ttrpc version=3 May 27 03:08:03.086601 systemd[1]: Started docker-0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a.scope - libcontainer container 0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a. May 27 03:08:03.097410 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 03:08:03.116488 kernel: docker0: port 1(veth2071f98) entered blocking state May 27 03:08:03.116595 kernel: docker0: port 1(veth2071f98) entered disabled state May 27 03:08:03.116613 kernel: veth2071f98: entered allmulticast mode May 27 03:08:03.117730 kernel: veth2071f98: entered promiscuous mode May 27 03:08:03.118645 systemd-networkd[1383]: veth2071f98: Link UP May 27 03:08:03.126218 kernel: eth0: renamed from veth9263a4c May 27 03:08:03.126272 kernel: docker0: port 1(veth2071f98) entered blocking state May 27 03:08:03.126287 kernel: docker0: port 1(veth2071f98) entered forwarding state May 27 03:08:03.126977 systemd-networkd[1383]: veth2071f98: Gained carrier May 27 03:08:03.127318 systemd-networkd[1383]: docker0: Gained carrier May 27 03:08:03.146828 sshd[1832]: Connection closed by 10.0.0.1 port 48458 May 27 03:08:03.147134 sshd-session[1830]: pam_unix(sshd:session): session closed for user core May 27 03:08:03.162236 systemd[1]: sshd@7-10.0.0.144:22-10.0.0.1:48458.service: Deactivated successfully. May 27 03:08:03.163641 systemd[1]: session-8.scope: Deactivated successfully. May 27 03:08:03.164738 systemd-logind[1396]: Session 8 logged out. Waiting for processes to exit. May 27 03:08:03.166405 systemd[1]: Started sshd@8-10.0.0.144:22-10.0.0.1:33116.service - OpenSSH per-connection server daemon (10.0.0.1:33116). May 27 03:08:03.167135 systemd-logind[1396]: Removed session 8. May 27 03:08:03.222947 sshd[1906]: Accepted publickey for core from 10.0.0.1 port 33116 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:03.224016 sshd-session[1906]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:03.228309 systemd-logind[1396]: New session 9 of user core. May 27 03:08:03.238597 systemd[1]: Started session-9.scope - Session 9 of User core. May 27 03:08:03.290656 sshd[1908]: Connection closed by 10.0.0.1 port 33116 May 27 03:08:03.291015 sshd-session[1906]: pam_unix(sshd:session): session closed for user core May 27 03:08:03.305178 systemd[1]: sshd@8-10.0.0.144:22-10.0.0.1:33116.service: Deactivated successfully. May 27 03:08:03.306491 systemd[1]: session-9.scope: Deactivated successfully. May 27 03:08:03.307078 systemd-logind[1396]: Session 9 logged out. Waiting for processes to exit. May 27 03:08:03.309059 systemd[1]: Started sshd@9-10.0.0.144:22-10.0.0.1:33120.service - OpenSSH per-connection server daemon (10.0.0.1:33120). May 27 03:08:03.309994 systemd-logind[1396]: Removed session 9. May 27 03:08:03.357850 sshd[1914]: Accepted publickey for core from 10.0.0.1 port 33120 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:03.358854 sshd-session[1914]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:03.362758 systemd-logind[1396]: New session 10 of user core. May 27 03:08:03.374690 systemd[1]: Started session-10.scope - Session 10 of User core. May 27 03:08:03.423944 sudo[1917]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1467 May 27 03:08:03.424190 sudo[1917]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:08:03.428861 sudo[1917]: pam_unix(sudo:session): session closed for user root May 27 03:08:03.429657 containerd[1467]: time="2025-05-27T03:08:03.429319046Z" level=info msg="Stop CRI service" May 27 03:08:03.429825 dockerd[1624]: time="2025-05-27T03:08:03.429763019Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 27 03:08:03.429825 dockerd[1624]: time="2025-05-27T03:08:03.429802038Z" 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 03:08:03.430105 dockerd[1624]: time="2025-05-27T03:08:03.429827572Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 27 03:08:03.430105 dockerd[1624]: time="2025-05-27T03:08:03.429803834Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 27 03:08:03.430566 sshd[1916]: Connection closed by 10.0.0.1 port 33120 May 27 03:08:03.430859 sshd-session[1914]: pam_unix(sshd:session): session closed for user core May 27 03:08:03.444172 systemd[1]: containerd.service: Deactivated successfully. May 27 03:08:03.444261 systemd[1]: containerd.service: Unit process 1854 (containerd-shim) remains running after unit stopped. May 27 03:08:03.444490 systemd[1]: containerd.service: Consumed 357ms CPU time, 134.6M memory peak. May 27 03:08:03.444815 systemd[1]: sshd@9-10.0.0.144:22-10.0.0.1:33120.service: Deactivated successfully. May 27 03:08:03.446187 systemd[1]: session-10.scope: Deactivated successfully. May 27 03:08:03.446769 systemd-logind[1396]: Session 10 logged out. Waiting for processes to exit. May 27 03:08:03.449313 systemd[1]: Started sshd@10-10.0.0.144:22-10.0.0.1:33132.service - OpenSSH per-connection server daemon (10.0.0.1:33132). May 27 03:08:03.449957 systemd-logind[1396]: Removed session 10. May 27 03:08:03.496915 sshd[1924]: Accepted publickey for core from 10.0.0.1 port 33132 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:03.497956 sshd-session[1924]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:03.501540 systemd-logind[1396]: New session 11 of user core. May 27 03:08:03.517669 systemd[1]: Started session-11.scope - Session 11 of User core. May 27 03:08:03.569324 sshd[1926]: Connection closed by 10.0.0.1 port 33132 May 27 03:08:03.569215 sshd-session[1924]: pam_unix(sshd:session): session closed for user core May 27 03:08:03.571896 systemd[1]: sshd@10-10.0.0.144:22-10.0.0.1:33132.service: Deactivated successfully. May 27 03:08:03.573302 systemd[1]: session-11.scope: Deactivated successfully. May 27 03:08:03.573904 systemd-logind[1396]: Session 11 logged out. Waiting for processes to exit. May 27 03:08:03.575180 systemd-logind[1396]: Removed session 11. May 27 03:08:04.710617 systemd-networkd[1383]: docker0: Gained IPv6LL May 27 03:08:05.158628 systemd-networkd[1383]: veth2071f98: Gained IPv6LL May 27 03:08:08.523084 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. May 27 03:08:08.523506 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 27 03:08:08.524376 dockerd[1624]: time="2025-05-27T03:08:08.523589079Z" level=info msg="Processing signal 'terminated'" May 27 03:08:09.570520 systemd[1]: Started sshd@11-10.0.0.144:22-10.0.0.1:33144.service - OpenSSH per-connection server daemon (10.0.0.1:33144). May 27 03:08:09.613484 sshd[1932]: Accepted publickey for core from 10.0.0.1 port 33144 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:09.614732 sshd-session[1932]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:09.619260 systemd-logind[1396]: New session 12 of user core. May 27 03:08:09.630669 systemd[1]: Started session-12.scope - Session 12 of User core. May 27 03:08:09.685165 sshd[1934]: Connection closed by 10.0.0.1 port 33144 May 27 03:08:09.685488 sshd-session[1932]: pam_unix(sshd:session): session closed for user core May 27 03:08:09.688038 systemd[1]: sshd@11-10.0.0.144:22-10.0.0.1:33144.service: Deactivated successfully. May 27 03:08:09.689605 systemd[1]: session-12.scope: Deactivated successfully. May 27 03:08:09.692211 systemd-logind[1396]: Session 12 logged out. Waiting for processes to exit. May 27 03:08:09.693261 systemd-logind[1396]: Removed session 12. May 27 03:08:10.524396 dockerd[1624]: time="2025-05-27T03:08:10.524196823Z" level=error msg="Error sending stop (signal 15) to container" container=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a error="Cannot kill container 0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" May 27 03:08:10.524396 dockerd[1624]: time="2025-05-27T03:08:10.524247939Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a May 27 03:08:15.693780 systemd[1]: Started sshd@12-10.0.0.144:22-10.0.0.1:45684.service - OpenSSH per-connection server daemon (10.0.0.1:45684). May 27 03:08:15.743458 sshd[1940]: Accepted publickey for core from 10.0.0.1 port 45684 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:15.742125 sshd-session[1940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:15.747572 systemd-logind[1396]: New session 13 of user core. May 27 03:08:15.755669 systemd[1]: Started session-13.scope - Session 13 of User core. May 27 03:08:15.827063 sshd[1942]: Connection closed by 10.0.0.1 port 45684 May 27 03:08:15.826100 sshd-session[1940]: pam_unix(sshd:session): session closed for user core May 27 03:08:15.829409 systemd[1]: sshd@12-10.0.0.144:22-10.0.0.1:45684.service: Deactivated successfully. May 27 03:08:15.830903 systemd[1]: session-13.scope: Deactivated successfully. May 27 03:08:15.831590 systemd-logind[1396]: Session 13 logged out. Waiting for processes to exit. May 27 03:08:15.832870 systemd-logind[1396]: Removed session 13. May 27 03:08:20.526380 dockerd[1624]: time="2025-05-27T03:08:20.526319044Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a error="context deadline exceeded" May 27 03:08:20.531315 systemd[1]: docker-0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a.scope: Deactivated successfully. May 27 03:08:21.834505 systemd[1]: Started sshd@13-10.0.0.144:22-10.0.0.1:45688.service - OpenSSH per-connection server daemon (10.0.0.1:45688). May 27 03:08:21.902150 sshd[1948]: Accepted publickey for core from 10.0.0.1 port 45688 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:21.903401 sshd-session[1948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:21.907519 systemd-logind[1396]: New session 14 of user core. May 27 03:08:21.915628 systemd[1]: Started session-14.scope - Session 14 of User core. May 27 03:08:21.969880 sshd[1950]: Connection closed by 10.0.0.1 port 45688 May 27 03:08:21.970185 sshd-session[1948]: pam_unix(sshd:session): session closed for user core May 27 03:08:21.973227 systemd[1]: sshd@13-10.0.0.144:22-10.0.0.1:45688.service: Deactivated successfully. May 27 03:08:21.974672 systemd[1]: session-14.scope: Deactivated successfully. May 27 03:08:21.975348 systemd-logind[1396]: Session 14 logged out. Waiting for processes to exit. May 27 03:08:21.976485 systemd-logind[1396]: Removed session 14. May 27 03:08:23.524794 dockerd[1624]: time="2025-05-27T03:08:23.524722550Z" level=error msg="Force shutdown daemon" May 27 03:08:23.525140 dockerd[1624]: time="2025-05-27T03:08:23.524858174Z" level=info msg="Daemon shutdown complete" May 27 03:08:23.527886 systemd[1]: docker.service: Deactivated successfully. May 27 03:08:23.529508 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 27 03:08:23.529577 systemd[1]: docker.service: Consumed 802ms CPU time, 130.5M memory peak. May 27 03:08:23.529954 systemd[1]: docker.socket: Deactivated successfully. May 27 03:08:23.530211 systemd[1]: Closed docker.socket - Docker Socket for the API. May 27 03:08:23.530248 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 27 03:08:23.531693 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 03:08:23.531852 systemd[1]: containerd.service: Found left-over process 1854 (containerd-shim) in control group while starting unit. Ignoring. May 27 03:08:23.531860 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 03:08:23.539020 systemd[1]: Starting containerd.service - containerd container runtime... May 27 03:08:23.539272 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 03:08:23.549700 systemd[1]: containerd.service: Found left-over process 1854 (containerd-shim) in control group while starting unit. Ignoring. May 27 03:08:23.549713 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 03:08:23.553903 (ntainerd)[1961]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 03:08:23.567724 containerd[1961]: time="2025-05-27T03:08:23Z" 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 03:08:23.568385 containerd[1961]: time="2025-05-27T03:08:23.568341207Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 03:08:23.576742 containerd[1961]: time="2025-05-27T03:08:23.576700677Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.314µs" May 27 03:08:23.576742 containerd[1961]: time="2025-05-27T03:08:23.576735293Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 03:08:23.576838 containerd[1961]: time="2025-05-27T03:08:23.576753600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 03:08:23.576838 containerd[1961]: time="2025-05-27T03:08:23.576790773Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 03:08:23.576875 containerd[1961]: time="2025-05-27T03:08:23.576843256Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 03:08:23.577142 containerd[1961]: time="2025-05-27T03:08:23.577109107Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 03:08:23.577165 containerd[1961]: time="2025-05-27T03:08:23.577147280Z" 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 03:08:23.577165 containerd[1961]: time="2025-05-27T03:08:23.577158592Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 03:08:23.577355 containerd[1961]: time="2025-05-27T03:08:23.577331270Z" 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 03:08:23.577355 containerd[1961]: time="2025-05-27T03:08:23.577350376Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 03:08:23.577395 containerd[1961]: time="2025-05-27T03:08:23.577361089Z" 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 03:08:23.577395 containerd[1961]: time="2025-05-27T03:08:23.577369083Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 03:08:23.577395 containerd[1961]: time="2025-05-27T03:08:23.577387390Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 03:08:23.577495 containerd[1961]: time="2025-05-27T03:08:23.577462057Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 03:08:23.577516 containerd[1961]: time="2025-05-27T03:08:23.577499471Z" 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 03:08:23.577516 containerd[1961]: time="2025-05-27T03:08:23.577510263Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 03:08:23.577554 containerd[1961]: time="2025-05-27T03:08:23.577542560Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 03:08:23.577794 containerd[1961]: time="2025-05-27T03:08:23.577777114Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 03:08:23.577823 containerd[1961]: time="2025-05-27T03:08:23.577804854Z" level=info msg="metadata content store policy set" policy=shared May 27 03:08:23.578074 containerd[1961]: time="2025-05-27T03:08:23.578052998Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 03:08:23.578104 containerd[1961]: time="2025-05-27T03:08:23.578090052Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 03:08:23.578122 containerd[1961]: time="2025-05-27T03:08:23.578102523Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 03:08:23.578122 containerd[1961]: time="2025-05-27T03:08:23.578113715Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 03:08:23.578159 containerd[1961]: time="2025-05-27T03:08:23.578124587Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 03:08:23.578159 containerd[1961]: time="2025-05-27T03:08:23.578134980Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 03:08:23.578159 containerd[1961]: time="2025-05-27T03:08:23.578146212Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 03:08:23.578159 containerd[1961]: time="2025-05-27T03:08:23.578157084Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 03:08:23.578236 containerd[1961]: time="2025-05-27T03:08:23.578167756Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 03:08:23.578236 containerd[1961]: time="2025-05-27T03:08:23.578177869Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 03:08:23.578236 containerd[1961]: time="2025-05-27T03:08:23.578186383Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 03:08:23.578236 containerd[1961]: time="2025-05-27T03:08:23.578202612Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 03:08:23.578457 containerd[1961]: time="2025-05-27T03:08:23.578426773Z" level=info msg="connecting to shim 0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a" address="unix:///run/containerd/s/f540614a1f32c038c0d4eed91da9ad64d3928daf8c2b083abe351b45a1eabd28" namespace=moby protocol=ttrpc version=3 May 27 03:08:23.585004 containerd[1961]: time="2025-05-27T03:08:23.584962496Z" level=info msg="cleaning leaked shim process" id=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a namespace=moby May 27 03:08:23.593138 containerd[1961]: time="2025-05-27T03:08:23.592977091Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 27 03:08:23.593400 containerd[1961]: time="2025-05-27T03:08:23.593365615Z" level=info msg="shim disconnected" id=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a namespace=moby May 27 03:08:23.593534 containerd[1961]: time="2025-05-27T03:08:23.593460868Z" level=warning msg="cleaning up after shim disconnected" id=0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a namespace=moby May 27 03:08:23.593534 containerd[1961]: time="2025-05-27T03:08:23.593522144Z" level=info msg="cleaning up dead shim" namespace=moby May 27 03:08:23.605698 containerd[1961]: time="2025-05-27T03:08:23.605637909Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby May 27 03:08:23.605832 containerd[1961]: time="2025-05-27T03:08:23.605803032Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 03:08:23.605858 containerd[1961]: time="2025-05-27T03:08:23.605845282Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 03:08:23.605894 containerd[1961]: time="2025-05-27T03:08:23.605862150Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 03:08:23.605894 containerd[1961]: time="2025-05-27T03:08:23.605873942Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 03:08:23.605894 containerd[1961]: time="2025-05-27T03:08:23.605885374Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 03:08:23.605944 containerd[1961]: time="2025-05-27T03:08:23.605937737Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 03:08:23.605963 containerd[1961]: time="2025-05-27T03:08:23.605953286Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 03:08:23.605991 containerd[1961]: time="2025-05-27T03:08:23.605966356Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 03:08:23.606120 containerd[1961]: time="2025-05-27T03:08:23.606098423Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 03:08:23.606146 containerd[1961]: time="2025-05-27T03:08:23.606126043Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 03:08:23.606146 containerd[1961]: time="2025-05-27T03:08:23.606139354Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 03:08:23.606222 containerd[1961]: time="2025-05-27T03:08:23.606207465Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 03:08:23.606247 containerd[1961]: time="2025-05-27T03:08:23.606227051Z" level=info msg="Start snapshots syncer" May 27 03:08:23.606277 containerd[1961]: time="2025-05-27T03:08:23.606256910Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 03:08:23.607308 containerd[1961]: time="2025-05-27T03:08:23.607231219Z" 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 03:08:23.607533 containerd[1961]: time="2025-05-27T03:08:23.607351054Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 03:08:23.607533 containerd[1961]: time="2025-05-27T03:08:23.607516537Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 03:08:23.607595 containerd[1961]: time="2025-05-27T03:08:23.607575175Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 03:08:23.607632 containerd[1961]: time="2025-05-27T03:08:23.607618344Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 03:08:23.607676 containerd[1961]: time="2025-05-27T03:08:23.607644726Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 03:08:23.607784 containerd[1961]: time="2025-05-27T03:08:23.607660674Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 03:08:23.607996 containerd[1961]: time="2025-05-27T03:08:23.607967936Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 03:08:23.608023 containerd[1961]: time="2025-05-27T03:08:23.608000234Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 03:08:23.608023 containerd[1961]: time="2025-05-27T03:08:23.608013584Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 03:08:23.608056 containerd[1961]: time="2025-05-27T03:08:23.608041204Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 03:08:23.608056 containerd[1961]: time="2025-05-27T03:08:23.608052876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 03:08:23.608098 containerd[1961]: time="2025-05-27T03:08:23.608063629Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 03:08:23.608141 containerd[1961]: time="2025-05-27T03:08:23.608095686Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 03:08:23.608141 containerd[1961]: time="2025-05-27T03:08:23.608110755Z" 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 03:08:23.608141 containerd[1961]: time="2025-05-27T03:08:23.608119629Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 03:08:23.608141 containerd[1961]: time="2025-05-27T03:08:23.608128782Z" 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 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608146530Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608159800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608174710Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608199292Z" level=info msg="runtime interface created" May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608204329Z" level=info msg="created NRI interface" May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608212363Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608224195Z" level=info msg="Connect containerd service" May 27 03:08:23.608402 containerd[1961]: time="2025-05-27T03:08:23.608255452Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 03:08:23.608771 containerd[1961]: time="2025-05-27T03:08:23.608748623Z" 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 03:08:23.617372 containerd[1961]: time="2025-05-27T03:08:23.617313347Z" level=info msg="Start subscribing containerd event" May 27 03:08:23.617447 containerd[1961]: time="2025-05-27T03:08:23.617375982Z" level=info msg="Start recovering state" May 27 03:08:23.617489 containerd[1961]: time="2025-05-27T03:08:23.617446572Z" level=info msg="Start event monitor" May 27 03:08:23.617489 containerd[1961]: time="2025-05-27T03:08:23.617459563Z" level=info msg="Start cni network conf syncer for default" May 27 03:08:23.617489 containerd[1961]: time="2025-05-27T03:08:23.617475112Z" level=info msg="Start streaming server" May 27 03:08:23.617489 containerd[1961]: time="2025-05-27T03:08:23.617485065Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 03:08:23.617559 containerd[1961]: time="2025-05-27T03:08:23.617491780Z" level=info msg="runtime interface starting up..." May 27 03:08:23.617559 containerd[1961]: time="2025-05-27T03:08:23.617497376Z" level=info msg="starting plugins..." May 27 03:08:23.617559 containerd[1961]: time="2025-05-27T03:08:23.617507209Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 03:08:23.617743 containerd[1961]: time="2025-05-27T03:08:23.617604780Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 03:08:23.617800 containerd[1961]: time="2025-05-27T03:08:23.617780176Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 03:08:23.617889 containerd[1961]: time="2025-05-27T03:08:23.617836935Z" level=info msg="containerd successfully booted in 0.050293s" May 27 03:08:23.617954 systemd[1]: Started containerd.service - containerd container runtime. May 27 03:08:23.619666 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 03:08:23.638841 (dockerd)[2010]: 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 03:08:23.660803 dockerd[2010]: time="2025-05-27T03:08:23.660750333Z" level=info msg="Starting up" May 27 03:08:23.661273 dockerd[2010]: time="2025-05-27T03:08:23.661246221Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 03:08:23.687750 systemd[1]: var-lib-docker-overlay2-metacopy\x2dcheck3428552599-merged.mount: Deactivated successfully. May 27 03:08:23.689779 dockerd[2010]: time="2025-05-27T03:08:23.689734212Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 27 03:08:23.699522 dockerd[2010]: time="2025-05-27T03:08:23.699488612Z" level=info msg="Loading containers: start." May 27 03:08:23.710839 systemd[1]: var-lib-docker-overlay2-3a36c1bd51f90f166c47bca7a6c57c587ad4605ee559487eeea3625f96783cd6-merged.mount: Deactivated successfully. May 27 03:08:24.057536 kernel: docker0: port 1(veth2071f98) entered disabled state May 27 03:08:24.056761 systemd-networkd[1383]: veth2071f98: Link DOWN May 27 03:08:24.056766 systemd-networkd[1383]: veth2071f98: Lost carrier May 27 03:08:24.060174 kernel: veth2071f98 (unregistering): left allmulticast mode May 27 03:08:24.060237 kernel: veth2071f98 (unregistering): left promiscuous mode May 27 03:08:24.060257 kernel: docker0: port 1(veth2071f98) entered disabled state May 27 03:08:24.061736 systemd-networkd[1383]: docker0: Lost carrier May 27 03:08:24.062281 dockerd[2010]: time="2025-05-27T03:08:24.062228147Z" level=info msg="Removing stale sandbox c88fb3696c237249f908e26e438911dc137bda409b92cfe90826825e586a3312 (0783fe4963c8d63cce4e993650556142afdae617b19a17f794029182d6530b2a)" May 27 03:08:24.066893 dockerd[2010]: time="2025-05-27T03:08:24.066852912Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 27 03:08:24.066970 dockerd[2010]: time="2025-05-27T03:08:24.066901559Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 6475d02c69eb488e6ffc10c9a44e8e0e821204bc8c1167217875e4d7d4d68304 bf9923bf891563500a4bd32ad804f1b15b6537c623583cf3789c1c6c79b5c00b], retrying...." May 27 03:08:24.218210 dockerd[2010]: time="2025-05-27T03:08:24.218160808Z" level=warning msg="error locating sandbox id c88fb3696c237249f908e26e438911dc137bda409b92cfe90826825e586a3312: sandbox c88fb3696c237249f908e26e438911dc137bda409b92cfe90826825e586a3312 not found" May 27 03:08:24.218319 dockerd[2010]: time="2025-05-27T03:08:24.218241514Z" level=info msg="Loading containers: done." May 27 03:08:24.228096 dockerd[2010]: time="2025-05-27T03:08:24.227826460Z" 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 03:08:24.228096 dockerd[2010]: time="2025-05-27T03:08:24.227896773Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 03:08:24.228096 dockerd[2010]: time="2025-05-27T03:08:24.227932430Z" level=info msg="Initializing buildkit" May 27 03:08:24.239018 dockerd[2010]: time="2025-05-27T03:08:24.238977924Z" level=info msg="Completed buildkit initialization" May 27 03:08:24.245478 dockerd[2010]: time="2025-05-27T03:08:24.245389220Z" level=info msg="Daemon has completed initialization" May 27 03:08:24.245478 dockerd[2010]: time="2025-05-27T03:08:24.245437388Z" level=info msg="API listen on /run/docker.sock" May 27 03:08:24.245602 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 03:08:24.676420 systemd[1]: run-docker-netns-c88fb3696c23.mount: Deactivated successfully. May 27 03:08:27.984399 systemd[1]: Started sshd@14-10.0.0.144:22-10.0.0.1:49166.service - OpenSSH per-connection server daemon (10.0.0.1:49166). May 27 03:08:28.041621 sshd[2232]: Accepted publickey for core from 10.0.0.1 port 49166 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.042862 sshd-session[2232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.047533 systemd-logind[1396]: New session 15 of user core. May 27 03:08:28.061637 systemd[1]: Started session-15.scope - Session 15 of User core. May 27 03:08:28.113793 sshd[2234]: Connection closed by 10.0.0.1 port 49166 May 27 03:08:28.114041 sshd-session[2232]: pam_unix(sshd:session): session closed for user core May 27 03:08:28.130846 systemd[1]: sshd@14-10.0.0.144:22-10.0.0.1:49166.service: Deactivated successfully. May 27 03:08:28.133745 systemd[1]: session-15.scope: Deactivated successfully. May 27 03:08:28.134373 systemd-logind[1396]: Session 15 logged out. Waiting for processes to exit. May 27 03:08:28.136715 systemd[1]: Started sshd@15-10.0.0.144:22-10.0.0.1:49170.service - OpenSSH per-connection server daemon (10.0.0.1:49170). May 27 03:08:28.138959 systemd-logind[1396]: Removed session 15. May 27 03:08:28.189056 sshd[2240]: Accepted publickey for core from 10.0.0.1 port 49170 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.190137 sshd-session[2240]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.194598 systemd-logind[1396]: New session 16 of user core. May 27 03:08:28.206630 systemd[1]: Started session-16.scope - Session 16 of User core. May 27 03:08:28.259426 sshd[2242]: Connection closed by 10.0.0.1 port 49170 May 27 03:08:28.259877 sshd-session[2240]: pam_unix(sshd:session): session closed for user core May 27 03:08:28.276434 systemd[1]: sshd@15-10.0.0.144:22-10.0.0.1:49170.service: Deactivated successfully. May 27 03:08:28.279807 systemd[1]: session-16.scope: Deactivated successfully. May 27 03:08:28.280682 systemd-logind[1396]: Session 16 logged out. Waiting for processes to exit. May 27 03:08:28.283122 systemd[1]: Started sshd@16-10.0.0.144:22-10.0.0.1:49178.service - OpenSSH per-connection server daemon (10.0.0.1:49178). May 27 03:08:28.284026 systemd-logind[1396]: Removed session 16. May 27 03:08:28.349062 sshd[2248]: Accepted publickey for core from 10.0.0.1 port 49178 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.350361 sshd-session[2248]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.354531 systemd-logind[1396]: New session 17 of user core. May 27 03:08:28.372658 systemd[1]: Started session-17.scope - Session 17 of User core. May 27 03:08:28.428135 sshd[2250]: Connection closed by 10.0.0.1 port 49178 May 27 03:08:28.428580 sshd-session[2248]: pam_unix(sshd:session): session closed for user core May 27 03:08:28.437398 systemd[1]: sshd@16-10.0.0.144:22-10.0.0.1:49178.service: Deactivated successfully. May 27 03:08:28.439661 systemd[1]: session-17.scope: Deactivated successfully. May 27 03:08:28.441245 systemd-logind[1396]: Session 17 logged out. Waiting for processes to exit. May 27 03:08:28.442464 systemd[1]: Started sshd@17-10.0.0.144:22-10.0.0.1:49190.service - OpenSSH per-connection server daemon (10.0.0.1:49190). May 27 03:08:28.443586 systemd-logind[1396]: Removed session 17. May 27 03:08:28.492602 sshd[2263]: Accepted publickey for core from 10.0.0.1 port 49190 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.493686 sshd-session[2263]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.498392 systemd-logind[1396]: New session 18 of user core. May 27 03:08:28.507672 systemd[1]: Started session-18.scope - Session 18 of User core. May 27 03:08:28.629121 containerd[1961]: time="2025-05-27T03:08:28.629018357Z" level=info msg="connecting to shim 0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75" address="unix:///run/containerd/s/08fdabc350a9dce7bdb74613e4dd88f6a6c74c614f55777c4eba1f5d78e25124" namespace=moby protocol=ttrpc version=3 May 27 03:08:28.652645 systemd[1]: Started docker-0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75.scope - libcontainer container 0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75. May 27 03:08:28.663608 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 03:08:28.680833 kernel: docker0: port 1(veth16cd66c) entered blocking state May 27 03:08:28.680924 kernel: docker0: port 1(veth16cd66c) entered disabled state May 27 03:08:28.680945 kernel: veth16cd66c: entered allmulticast mode May 27 03:08:28.681533 kernel: veth16cd66c: entered promiscuous mode May 27 03:08:28.682204 systemd-networkd[1383]: veth16cd66c: Link UP May 27 03:08:28.687491 kernel: eth0: renamed from veth655e62a May 27 03:08:28.689948 kernel: docker0: port 1(veth16cd66c) entered blocking state May 27 03:08:28.690415 kernel: docker0: port 1(veth16cd66c) entered forwarding state May 27 03:08:28.689963 systemd-networkd[1383]: veth16cd66c: Gained carrier May 27 03:08:28.690582 systemd-networkd[1383]: docker0: Gained carrier May 27 03:08:28.707759 sshd[2265]: Connection closed by 10.0.0.1 port 49190 May 27 03:08:28.708171 sshd-session[2263]: pam_unix(sshd:session): session closed for user core May 27 03:08:28.715438 systemd[1]: sshd@17-10.0.0.144:22-10.0.0.1:49190.service: Deactivated successfully. May 27 03:08:28.717205 systemd[1]: session-18.scope: Deactivated successfully. May 27 03:08:28.717990 systemd-logind[1396]: Session 18 logged out. Waiting for processes to exit. May 27 03:08:28.721014 systemd[1]: Started sshd@18-10.0.0.144:22-10.0.0.1:49206.service - OpenSSH per-connection server daemon (10.0.0.1:49206). May 27 03:08:28.721789 systemd-logind[1396]: Removed session 18. May 27 03:08:28.779421 sshd[2333]: Accepted publickey for core from 10.0.0.1 port 49206 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.780792 sshd-session[2333]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.785521 systemd-logind[1396]: New session 19 of user core. May 27 03:08:28.802665 systemd[1]: Started session-19.scope - Session 19 of User core. May 27 03:08:28.855998 sshd[2335]: Connection closed by 10.0.0.1 port 49206 May 27 03:08:28.856528 sshd-session[2333]: pam_unix(sshd:session): session closed for user core May 27 03:08:28.868822 systemd[1]: sshd@18-10.0.0.144:22-10.0.0.1:49206.service: Deactivated successfully. May 27 03:08:28.870549 systemd[1]: session-19.scope: Deactivated successfully. May 27 03:08:28.871200 systemd-logind[1396]: Session 19 logged out. Waiting for processes to exit. May 27 03:08:28.873795 systemd[1]: Started sshd@19-10.0.0.144:22-10.0.0.1:49210.service - OpenSSH per-connection server daemon (10.0.0.1:49210). May 27 03:08:28.874755 systemd-logind[1396]: Removed session 19. May 27 03:08:28.928419 sshd[2341]: Accepted publickey for core from 10.0.0.1 port 49210 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:28.929609 sshd-session[2341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:28.934821 systemd-logind[1396]: New session 20 of user core. May 27 03:08:28.944634 systemd[1]: Started session-20.scope - Session 20 of User core. May 27 03:08:28.996720 sudo[2344]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1961 May 27 03:08:28.997010 sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:08:28.999758 sudo[2344]: pam_unix(sudo:session): session closed for user root May 27 03:08:29.003587 sshd[2343]: Connection closed by 10.0.0.1 port 49210 May 27 03:08:29.003831 dockerd[2010]: time="2025-05-27T03:08:29.003261149Z" 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 03:08:29.003831 dockerd[2010]: time="2025-05-27T03:08:29.003295053Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 27 03:08:29.003831 dockerd[2010]: time="2025-05-27T03:08:29.003372975Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 27 03:08:29.003831 dockerd[2010]: time="2025-05-27T03:08:29.003400042Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 27 03:08:29.004169 sshd-session[2341]: pam_unix(sshd:session): session closed for user core May 27 03:08:29.018613 systemd[1]: containerd.service: Deactivated successfully. May 27 03:08:29.018716 systemd[1]: containerd.service: Unit process 2283 (containerd-shim) remains running after unit stopped. May 27 03:08:29.018943 systemd[1]: containerd.service: Consumed 147ms CPU time, 137.6M memory peak. May 27 03:08:29.019306 systemd[1]: sshd@19-10.0.0.144:22-10.0.0.1:49210.service: Deactivated successfully. May 27 03:08:29.020954 systemd[1]: session-20.scope: Deactivated successfully. May 27 03:08:29.021657 systemd-logind[1396]: Session 20 logged out. Waiting for processes to exit. May 27 03:08:29.024857 systemd[1]: Started sshd@20-10.0.0.144:22-10.0.0.1:49222.service - OpenSSH per-connection server daemon (10.0.0.1:49222). May 27 03:08:29.025315 systemd-logind[1396]: Removed session 20. May 27 03:08:29.083095 sshd[2351]: Accepted publickey for core from 10.0.0.1 port 49222 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:29.084376 sshd-session[2351]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:29.088137 systemd-logind[1396]: New session 21 of user core. May 27 03:08:29.099639 systemd[1]: Started session-21.scope - Session 21 of User core. May 27 03:08:29.152672 sshd[2353]: Connection closed by 10.0.0.1 port 49222 May 27 03:08:29.153119 sshd-session[2351]: pam_unix(sshd:session): session closed for user core May 27 03:08:29.156806 systemd[1]: sshd@20-10.0.0.144:22-10.0.0.1:49222.service: Deactivated successfully. May 27 03:08:29.158887 systemd[1]: session-21.scope: Deactivated successfully. May 27 03:08:29.159554 systemd-logind[1396]: Session 21 logged out. Waiting for processes to exit. May 27 03:08:29.160606 systemd-logind[1396]: Removed session 21. May 27 03:08:30.182700 systemd-networkd[1383]: veth16cd66c: Gained IPv6LL May 27 03:08:34.023270 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. May 27 03:08:34.023792 dockerd[2010]: time="2025-05-27T03:08:34.023694609Z" level=info msg="Processing signal 'terminated'" May 27 03:08:34.023722 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 27 03:08:35.165680 systemd[1]: Started sshd@21-10.0.0.144:22-10.0.0.1:42456.service - OpenSSH per-connection server daemon (10.0.0.1:42456). May 27 03:08:35.208035 sshd[2359]: Accepted publickey for core from 10.0.0.1 port 42456 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:35.209150 sshd-session[2359]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:35.213383 systemd-logind[1396]: New session 22 of user core. May 27 03:08:35.223623 systemd[1]: Started session-22.scope - Session 22 of User core. May 27 03:08:35.275344 sshd[2361]: Connection closed by 10.0.0.1 port 42456 May 27 03:08:35.275606 sshd-session[2359]: pam_unix(sshd:session): session closed for user core May 27 03:08:35.279220 systemd[1]: sshd@21-10.0.0.144:22-10.0.0.1:42456.service: Deactivated successfully. May 27 03:08:35.280779 systemd[1]: session-22.scope: Deactivated successfully. May 27 03:08:35.281416 systemd-logind[1396]: Session 22 logged out. Waiting for processes to exit. May 27 03:08:35.282444 systemd-logind[1396]: Removed session 22. May 27 03:08:36.024254 dockerd[2010]: time="2025-05-27T03:08:36.024170347Z" level=error msg="Error sending stop (signal 15) to container" container=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 error="Cannot kill container 0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" May 27 03:08:36.024254 dockerd[2010]: time="2025-05-27T03:08:36.024242685Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 May 27 03:08:37.456815 update_engine[1399]: I20250527 03:08:37.456691 1399 update_attempter.cc:509] Updating boot flags... May 27 03:08:41.284493 systemd[1]: Started sshd@22-10.0.0.144:22-10.0.0.1:42478.service - OpenSSH per-connection server daemon (10.0.0.1:42478). May 27 03:08:41.335702 sshd[2383]: Accepted publickey for core from 10.0.0.1 port 42478 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:41.336926 sshd-session[2383]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:41.341586 systemd-logind[1396]: New session 23 of user core. May 27 03:08:41.356644 systemd[1]: Started session-23.scope - Session 23 of User core. May 27 03:08:41.410380 sshd[2385]: Connection closed by 10.0.0.1 port 42478 May 27 03:08:41.410310 sshd-session[2383]: pam_unix(sshd:session): session closed for user core May 27 03:08:41.413495 systemd[1]: sshd@22-10.0.0.144:22-10.0.0.1:42478.service: Deactivated successfully. May 27 03:08:41.416744 systemd[1]: session-23.scope: Deactivated successfully. May 27 03:08:41.417524 systemd-logind[1396]: Session 23 logged out. Waiting for processes to exit. May 27 03:08:41.418704 systemd-logind[1396]: Removed session 23. May 27 03:08:46.024630 dockerd[2010]: time="2025-05-27T03:08:46.024550219Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 error="context deadline exceeded" May 27 03:08:46.025418 systemd[1]: docker-0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75.scope: Deactivated successfully. May 27 03:08:47.423568 systemd[1]: Started sshd@23-10.0.0.144:22-10.0.0.1:50620.service - OpenSSH per-connection server daemon (10.0.0.1:50620). May 27 03:08:47.480157 sshd[2391]: Accepted publickey for core from 10.0.0.1 port 50620 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:47.481784 sshd-session[2391]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:47.485901 systemd-logind[1396]: New session 24 of user core. May 27 03:08:47.494619 systemd[1]: Started session-24.scope - Session 24 of User core. May 27 03:08:47.546513 sshd[2393]: Connection closed by 10.0.0.1 port 50620 May 27 03:08:47.546806 sshd-session[2391]: pam_unix(sshd:session): session closed for user core May 27 03:08:47.549227 systemd[1]: sshd@23-10.0.0.144:22-10.0.0.1:50620.service: Deactivated successfully. May 27 03:08:47.550657 systemd[1]: session-24.scope: Deactivated successfully. May 27 03:08:47.552522 systemd-logind[1396]: Session 24 logged out. Waiting for processes to exit. May 27 03:08:47.553777 systemd-logind[1396]: Removed session 24. May 27 03:08:49.024052 dockerd[2010]: time="2025-05-27T03:08:49.023938281Z" level=error msg="Force shutdown daemon" May 27 03:08:49.024695 dockerd[2010]: time="2025-05-27T03:08:49.024409499Z" level=info msg="Daemon shutdown complete" May 27 03:08:49.024695 dockerd[2010]: time="2025-05-27T03:08:49.024555920Z" 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 03:08:49.024695 dockerd[2010]: time="2025-05-27T03:08:49.024588475Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby May 27 03:08:49.024695 dockerd[2010]: time="2025-05-27T03:08:49.024557879Z" 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 03:08:49.024695 dockerd[2010]: time="2025-05-27T03:08:49.024613832Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=moby May 27 03:08:49.028852 systemd[1]: docker.service: Deactivated successfully. May 27 03:08:49.029251 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 27 03:08:49.029738 systemd[1]: docker.socket: Deactivated successfully. May 27 03:08:49.031520 systemd[1]: Closed docker.socket - Docker Socket for the API. May 27 03:08:49.031563 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 27 03:08:49.032946 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 03:08:49.033187 systemd[1]: containerd.service: Found left-over process 2283 (containerd-shim) in control group while starting unit. Ignoring. May 27 03:08:49.033199 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 03:08:49.036873 systemd[1]: Starting containerd.service - containerd container runtime... May 27 03:08:49.037393 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 03:08:49.047165 systemd[1]: containerd.service: Found left-over process 2283 (containerd-shim) in control group while starting unit. Ignoring. May 27 03:08:49.047177 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 27 03:08:49.051023 (ntainerd)[2404]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 03:08:49.063480 containerd[2404]: time="2025-05-27T03:08:49Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 27 03:08:49.064000 containerd[2404]: time="2025-05-27T03:08:49.063955423Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 03:08:49.072328 containerd[2404]: time="2025-05-27T03:08:49.072284360Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.039µs" May 27 03:08:49.072328 containerd[2404]: time="2025-05-27T03:08:49.072316876Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 03:08:49.072328 containerd[2404]: time="2025-05-27T03:08:49.072333394Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 03:08:49.072447 containerd[2404]: time="2025-05-27T03:08:49.072369429Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 03:08:49.072447 containerd[2404]: time="2025-05-27T03:08:49.072381708Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 03:08:49.072594 containerd[2404]: time="2025-05-27T03:08:49.072565123Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 03:08:49.072632 containerd[2404]: time="2025-05-27T03:08:49.072597799Z" 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 03:08:49.072632 containerd[2404]: time="2025-05-27T03:08:49.072608118Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 03:08:49.072810 containerd[2404]: time="2025-05-27T03:08:49.072784374Z" 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 03:08:49.072810 containerd[2404]: time="2025-05-27T03:08:49.072802372Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 03:08:49.072851 containerd[2404]: time="2025-05-27T03:08:49.072813170Z" 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 03:08:49.072851 containerd[2404]: time="2025-05-27T03:08:49.072820729Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 03:08:49.072851 containerd[2404]: time="2025-05-27T03:08:49.072847246Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 03:08:49.072929 containerd[2404]: time="2025-05-27T03:08:49.072916997Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 03:08:49.072952 containerd[2404]: time="2025-05-27T03:08:49.072939234Z" 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 03:08:49.072952 containerd[2404]: time="2025-05-27T03:08:49.072948872Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 03:08:49.072986 containerd[2404]: time="2025-05-27T03:08:49.072977469Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 03:08:49.073203 containerd[2404]: time="2025-05-27T03:08:49.073178602Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 03:08:49.073225 containerd[2404]: time="2025-05-27T03:08:49.073211238Z" level=info msg="metadata content store policy set" policy=shared May 27 03:08:49.073345 containerd[2404]: time="2025-05-27T03:08:49.073325303Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 03:08:49.073371 containerd[2404]: time="2025-05-27T03:08:49.073357418Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 03:08:49.073391 containerd[2404]: time="2025-05-27T03:08:49.073371017Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 03:08:49.073391 containerd[2404]: time="2025-05-27T03:08:49.073385535Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 03:08:49.073429 containerd[2404]: time="2025-05-27T03:08:49.073395813Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 03:08:49.073429 containerd[2404]: time="2025-05-27T03:08:49.073407012Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 03:08:49.073429 containerd[2404]: time="2025-05-27T03:08:49.073417050Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 03:08:49.073429 containerd[2404]: time="2025-05-27T03:08:49.073427609Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 03:08:49.073514 containerd[2404]: time="2025-05-27T03:08:49.073437168Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 03:08:49.073514 containerd[2404]: time="2025-05-27T03:08:49.073447126Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 03:08:49.073514 containerd[2404]: time="2025-05-27T03:08:49.073455565Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 03:08:49.073514 containerd[2404]: time="2025-05-27T03:08:49.073506399Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 03:08:49.073718 containerd[2404]: time="2025-05-27T03:08:49.073698613Z" level=info msg="connecting to shim 0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75" address="unix:///run/containerd/s/08fdabc350a9dce7bdb74613e4dd88f6a6c74c614f55777c4eba1f5d78e25124" namespace=moby protocol=ttrpc version=3 May 27 03:08:49.080119 containerd[2404]: time="2025-05-27T03:08:49.080089807Z" level=info msg="cleaning leaked shim process" id=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 namespace=moby May 27 03:08:49.087598 containerd[2404]: time="2025-05-27T03:08:49.087543380Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 27 03:08:49.087836 containerd[2404]: time="2025-05-27T03:08:49.087807785Z" level=info msg="shim disconnected" id=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 namespace=moby May 27 03:08:49.087894 containerd[2404]: time="2025-05-27T03:08:49.087830982Z" level=warning msg="cleaning up after shim disconnected" id=0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75 namespace=moby May 27 03:08:49.087894 containerd[2404]: time="2025-05-27T03:08:49.087859178Z" level=info msg="cleaning up dead shim" namespace=moby May 27 03:08:49.096098 containerd[2404]: time="2025-05-27T03:08:49.096044175Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby May 27 03:08:49.096214 containerd[2404]: time="2025-05-27T03:08:49.096187116Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 03:08:49.096263 containerd[2404]: time="2025-05-27T03:08:49.096225951Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 03:08:49.096263 containerd[2404]: time="2025-05-27T03:08:49.096241628Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 03:08:49.096263 containerd[2404]: time="2025-05-27T03:08:49.096252107Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 03:08:49.096319 containerd[2404]: time="2025-05-27T03:08:49.096272224Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 03:08:49.096319 containerd[2404]: time="2025-05-27T03:08:49.096283023Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 03:08:49.096319 containerd[2404]: time="2025-05-27T03:08:49.096295861Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 03:08:49.096319 containerd[2404]: time="2025-05-27T03:08:49.096306100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 03:08:49.096319 containerd[2404]: time="2025-05-27T03:08:49.096317378Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 03:08:49.096402 containerd[2404]: time="2025-05-27T03:08:49.096328817Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 03:08:49.096402 containerd[2404]: time="2025-05-27T03:08:49.096338816Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 03:08:49.096402 containerd[2404]: time="2025-05-27T03:08:49.096397688Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 03:08:49.096458 containerd[2404]: time="2025-05-27T03:08:49.096412326Z" level=info msg="Start snapshots syncer" May 27 03:08:49.096458 containerd[2404]: time="2025-05-27T03:08:49.096442122Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 03:08:49.096687 containerd[2404]: time="2025-05-27T03:08:49.096655214Z" 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 03:08:49.096835 containerd[2404]: time="2025-05-27T03:08:49.096813313Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 03:08:49.097073 containerd[2404]: time="2025-05-27T03:08:49.097045322Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 03:08:49.097129 containerd[2404]: time="2025-05-27T03:08:49.097111753Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 03:08:49.097154 containerd[2404]: time="2025-05-27T03:08:49.097143269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 03:08:49.097182 containerd[2404]: time="2025-05-27T03:08:49.097160747Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 03:08:49.097182 containerd[2404]: time="2025-05-27T03:08:49.097173345Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 03:08:49.097222 containerd[2404]: time="2025-05-27T03:08:49.097190103Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 03:08:49.097222 containerd[2404]: time="2025-05-27T03:08:49.097213460Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 03:08:49.097257 containerd[2404]: time="2025-05-27T03:08:49.097231697Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 03:08:49.097277 containerd[2404]: time="2025-05-27T03:08:49.097266413Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 03:08:49.097298 containerd[2404]: time="2025-05-27T03:08:49.097282651Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 03:08:49.097321 containerd[2404]: time="2025-05-27T03:08:49.097299488Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 03:08:49.097361 containerd[2404]: time="2025-05-27T03:08:49.097341763Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 03:08:49.097395 containerd[2404]: time="2025-05-27T03:08:49.097365000Z" 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 03:08:49.097395 containerd[2404]: time="2025-05-27T03:08:49.097379118Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 03:08:49.097430 containerd[2404]: time="2025-05-27T03:08:49.097393236Z" 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 03:08:49.097430 containerd[2404]: time="2025-05-27T03:08:49.097401795Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 03:08:49.097430 containerd[2404]: time="2025-05-27T03:08:49.097418793Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 03:08:49.097499 containerd[2404]: time="2025-05-27T03:08:49.097434191Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 03:08:49.097499 containerd[2404]: time="2025-05-27T03:08:49.097452188Z" level=info msg="runtime interface created" May 27 03:08:49.097499 containerd[2404]: time="2025-05-27T03:08:49.097460987Z" level=info msg="created NRI interface" May 27 03:08:49.097499 containerd[2404]: time="2025-05-27T03:08:49.097484784Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 03:08:49.097577 containerd[2404]: time="2025-05-27T03:08:49.097501342Z" level=info msg="Connect containerd service" May 27 03:08:49.097577 containerd[2404]: time="2025-05-27T03:08:49.097535617Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 03:08:49.098288 containerd[2404]: time="2025-05-27T03:08:49.098261481Z" 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 03:08:49.106384 containerd[2404]: time="2025-05-27T03:08:49.106319294Z" level=info msg="Start subscribing containerd event" May 27 03:08:49.106384 containerd[2404]: time="2025-05-27T03:08:49.106376727Z" level=info msg="Start recovering state" May 27 03:08:49.106486 containerd[2404]: time="2025-05-27T03:08:49.106435959Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 03:08:49.106486 containerd[2404]: time="2025-05-27T03:08:49.106439718Z" level=info msg="Start event monitor" May 27 03:08:49.106530 containerd[2404]: time="2025-05-27T03:08:49.106495631Z" level=info msg="Start cni network conf syncer for default" May 27 03:08:49.106530 containerd[2404]: time="2025-05-27T03:08:49.106502430Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 03:08:49.106530 containerd[2404]: time="2025-05-27T03:08:49.106505709Z" level=info msg="Start streaming server" May 27 03:08:49.106587 containerd[2404]: time="2025-05-27T03:08:49.106532266Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 03:08:49.106587 containerd[2404]: time="2025-05-27T03:08:49.106538545Z" level=info msg="runtime interface starting up..." May 27 03:08:49.106587 containerd[2404]: time="2025-05-27T03:08:49.106545024Z" level=info msg="starting plugins..." May 27 03:08:49.106587 containerd[2404]: time="2025-05-27T03:08:49.106554503Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 03:08:49.107298 containerd[2404]: time="2025-05-27T03:08:49.106697764Z" level=info msg="containerd successfully booted in 0.043399s" May 27 03:08:49.106753 systemd[1]: Started containerd.service - containerd container runtime. May 27 03:08:49.108200 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 03:08:49.123788 (dockerd)[2451]: 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 03:08:49.145878 dockerd[2451]: time="2025-05-27T03:08:49.145820984Z" level=info msg="Starting up" May 27 03:08:49.146319 dockerd[2451]: time="2025-05-27T03:08:49.146300161Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 03:08:49.170641 dockerd[2451]: time="2025-05-27T03:08:49.170555829Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 27 03:08:49.178030 dockerd[2451]: time="2025-05-27T03:08:49.178000204Z" level=info msg="Loading containers: start." May 27 03:08:49.190805 systemd[1]: var-lib-docker-overlay2-072e0f7525f658e8319ef8d55295d5bfd38b907a897828907c52d514dbaa53b4-merged.mount: Deactivated successfully. May 27 03:08:49.556421 systemd-networkd[1383]: veth16cd66c: Link DOWN May 27 03:08:49.556427 systemd-networkd[1383]: veth16cd66c: Lost carrier May 27 03:08:49.557530 kernel: docker0: port 1(veth16cd66c) entered disabled state May 27 03:08:49.559338 kernel: veth16cd66c (unregistering): left allmulticast mode May 27 03:08:49.559375 kernel: veth16cd66c (unregistering): left promiscuous mode May 27 03:08:49.559397 kernel: docker0: port 1(veth16cd66c) entered disabled state May 27 03:08:49.560900 systemd-networkd[1383]: docker0: Lost carrier May 27 03:08:49.561362 dockerd[2451]: time="2025-05-27T03:08:49.561199748Z" level=info msg="Removing stale sandbox 37004344b30dd4044d625a246d8e34e91785c48844ccc240ed516da3e1b28c6a (0ec4cf87ace7641a0a6284a587d96f03daaf030e8bc996b317203b9f2e5f1d75)" May 27 03:08:49.563739 dockerd[2451]: time="2025-05-27T03:08:49.563658702Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 27 03:08:49.563739 dockerd[2451]: time="2025-05-27T03:08:49.563701097Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint eef43ca861e17e8206b0c48d7b39c36b44ad529bb67eaad3fd68b77db080aa35 78758f78301ec7f2bdff88f48cd8c5d94f87f5f2531728296b427c08b5cf90f1], retrying...." May 27 03:08:49.726720 dockerd[2451]: time="2025-05-27T03:08:49.726655641Z" level=warning msg="error locating sandbox id c88fb3696c237249f908e26e438911dc137bda409b92cfe90826825e586a3312: sandbox c88fb3696c237249f908e26e438911dc137bda409b92cfe90826825e586a3312 not found" May 27 03:08:49.726720 dockerd[2451]: time="2025-05-27T03:08:49.726696796Z" level=warning msg="error locating sandbox id 37004344b30dd4044d625a246d8e34e91785c48844ccc240ed516da3e1b28c6a: sandbox 37004344b30dd4044d625a246d8e34e91785c48844ccc240ed516da3e1b28c6a not found" May 27 03:08:49.726993 dockerd[2451]: time="2025-05-27T03:08:49.726927325Z" level=info msg="Loading containers: done." May 27 03:08:49.737084 dockerd[2451]: time="2025-05-27T03:08:49.737038187Z" 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 03:08:49.737489 dockerd[2451]: time="2025-05-27T03:08:49.737295593Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 03:08:49.737489 dockerd[2451]: time="2025-05-27T03:08:49.737343346Z" level=info msg="Initializing buildkit" May 27 03:08:49.749095 dockerd[2451]: time="2025-05-27T03:08:49.749011801Z" level=info msg="Completed buildkit initialization" May 27 03:08:49.756254 dockerd[2451]: time="2025-05-27T03:08:49.756217847Z" level=info msg="Daemon has completed initialization" May 27 03:08:49.756502 dockerd[2451]: time="2025-05-27T03:08:49.756419101Z" level=info msg="API listen on /run/docker.sock" May 27 03:08:49.756584 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 03:08:50.160278 systemd[1]: run-docker-netns-37004344b30d.mount: Deactivated successfully. May 27 03:08:53.559450 systemd[1]: Started sshd@24-10.0.0.144:22-10.0.0.1:58238.service - OpenSSH per-connection server daemon (10.0.0.1:58238). May 27 03:08:53.609302 sshd[2675]: Accepted publickey for core from 10.0.0.1 port 58238 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:53.610383 sshd-session[2675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:53.613969 systemd-logind[1396]: New session 25 of user core. May 27 03:08:53.623622 systemd[1]: Started session-25.scope - Session 25 of User core. May 27 03:08:53.675297 sshd[2677]: Connection closed by 10.0.0.1 port 58238 May 27 03:08:53.675231 sshd-session[2675]: pam_unix(sshd:session): session closed for user core May 27 03:08:53.688359 systemd[1]: sshd@24-10.0.0.144:22-10.0.0.1:58238.service: Deactivated successfully. May 27 03:08:53.691689 systemd[1]: session-25.scope: Deactivated successfully. May 27 03:08:53.692559 systemd-logind[1396]: Session 25 logged out. Waiting for processes to exit. May 27 03:08:53.695229 systemd[1]: Started sshd@25-10.0.0.144:22-10.0.0.1:58242.service - OpenSSH per-connection server daemon (10.0.0.1:58242). May 27 03:08:53.695700 systemd-logind[1396]: Removed session 25. May 27 03:08:53.748798 sshd[2683]: Accepted publickey for core from 10.0.0.1 port 58242 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:53.749882 sshd-session[2683]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:53.753747 systemd-logind[1396]: New session 26 of user core. May 27 03:08:53.767694 systemd[1]: Started session-26.scope - Session 26 of User core. May 27 03:08:53.820680 sshd[2685]: Connection closed by 10.0.0.1 port 58242 May 27 03:08:53.820669 sshd-session[2683]: pam_unix(sshd:session): session closed for user core May 27 03:08:53.837288 systemd[1]: sshd@25-10.0.0.144:22-10.0.0.1:58242.service: Deactivated successfully. May 27 03:08:53.838611 systemd[1]: session-26.scope: Deactivated successfully. May 27 03:08:53.839231 systemd-logind[1396]: Session 26 logged out. Waiting for processes to exit. May 27 03:08:53.841266 systemd[1]: Started sshd@26-10.0.0.144:22-10.0.0.1:58254.service - OpenSSH per-connection server daemon (10.0.0.1:58254). May 27 03:08:53.842101 systemd-logind[1396]: Removed session 26. May 27 03:08:53.890721 sshd[2691]: Accepted publickey for core from 10.0.0.1 port 58254 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:53.891915 sshd-session[2691]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:53.895677 systemd-logind[1396]: New session 27 of user core. May 27 03:08:53.913453 systemd[1]: Started session-27.scope - Session 27 of User core. May 27 03:08:53.969728 sshd[2693]: Connection closed by 10.0.0.1 port 58254 May 27 03:08:53.970057 sshd-session[2691]: pam_unix(sshd:session): session closed for user core May 27 03:08:53.985585 systemd[1]: sshd@26-10.0.0.144:22-10.0.0.1:58254.service: Deactivated successfully. May 27 03:08:53.988009 systemd[1]: session-27.scope: Deactivated successfully. May 27 03:08:53.988843 systemd-logind[1396]: Session 27 logged out. Waiting for processes to exit. May 27 03:08:53.991414 systemd[1]: Started sshd@27-10.0.0.144:22-10.0.0.1:58256.service - OpenSSH per-connection server daemon (10.0.0.1:58256). May 27 03:08:53.992050 systemd-logind[1396]: Removed session 27. May 27 03:08:54.044808 sshd[2706]: Accepted publickey for core from 10.0.0.1 port 58256 ssh2: RSA SHA256:+Ok2qUkoQikU0DO7rksFgy8mCIIB6/JUg3lsMDZPwmg May 27 03:08:54.045919 sshd-session[2706]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:08:54.049591 systemd-logind[1396]: New session 28 of user core. May 27 03:08:54.060634 systemd[1]: Started session-28.scope - Session 28 of User core. May 27 03:08:54.129191 systemd[1]: var-lib-docker-overlay2-9d4cc57a9d47e22c02f6890ee67b04c20c3cf2850b03a92dec179253aef1aa1f\x2dinit-merged.mount: Deactivated successfully. May 27 03:08:54.163694 containerd[2404]: time="2025-05-27T03:08:54.163614328Z" level=info msg="connecting to shim 2ea9115460bf2f31ae21154191e97c20ed64ed024fb05f449ec18e2758a574bf" address="unix:///run/containerd/s/e5a0b3d66241f4b6c8a54e76d86b6b80242759d72f21be6c268f4295347e9ca2" namespace=moby protocol=ttrpc version=3 May 27 03:08:54.185608 systemd[1]: Started docker-2ea9115460bf2f31ae21154191e97c20ed64ed024fb05f449ec18e2758a574bf.scope - libcontainer container 2ea9115460bf2f31ae21154191e97c20ed64ed024fb05f449ec18e2758a574bf. May 27 03:08:54.195573 systemd-resolved[1295]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 27 03:08:54.211003 kernel: docker0: port 1(veth1a04101) entered blocking state May 27 03:08:54.211075 kernel: docker0: port 1(veth1a04101) entered disabled state May 27 03:08:54.211094 kernel: veth1a04101: entered allmulticast mode May 27 03:08:54.211110 kernel: veth1a04101: entered promiscuous mode May 27 03:08:54.211764 systemd-networkd[1383]: veth1a04101: Link UP May 27 03:08:54.216498 kernel: eth0: renamed from vetha631ba9 May 27 03:08:54.219562 kernel: docker0: port 1(veth1a04101) entered blocking state May 27 03:08:54.219662 kernel: docker0: port 1(veth1a04101) entered forwarding state May 27 03:08:54.220071 systemd-networkd[1383]: veth1a04101: Gained carrier May 27 03:08:54.220341 systemd-networkd[1383]: docker0: Gained carrier May 27 03:08:54.237494 sshd[2708]: Connection closed by 10.0.0.1 port 58256 May 27 03:08:54.237504 sshd-session[2706]: pam_unix(sshd:session): session closed for user core May 27 03:08:54.241203 systemd-logind[1396]: Session 28 logged out. Waiting for processes to exit. May 27 03:08:54.241453 systemd[1]: sshd@27-10.0.0.144:22-10.0.0.1:58256.service: Deactivated successfully. May 27 03:08:54.243024 systemd[1]: session-28.scope: Deactivated successfully. May 27 03:08:54.244463 systemd-logind[1396]: Removed session 28.