Jun 21 02:30:03.810540 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jun 21 02:30:03.810560 kernel: Linux version 6.12.34-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 Sat Jun 21 00:00:47 -00 2025 Jun 21 02:30:03.810569 kernel: KASLR enabled Jun 21 02:30:03.810574 kernel: efi: EFI v2.7 by EDK II Jun 21 02:30:03.810580 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Jun 21 02:30:03.810585 kernel: random: crng init done Jun 21 02:30:03.810592 kernel: secureboot: Secure boot disabled Jun 21 02:30:03.810597 kernel: ACPI: Early table checksum verification disabled Jun 21 02:30:03.810603 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Jun 21 02:30:03.810610 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Jun 21 02:30:03.810615 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810621 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810626 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810632 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810639 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810646 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810653 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810658 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810664 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jun 21 02:30:03.810670 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jun 21 02:30:03.810676 kernel: ACPI: Use ACPI SPCR as default console: Yes Jun 21 02:30:03.810682 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jun 21 02:30:03.810688 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Jun 21 02:30:03.810693 kernel: Zone ranges: Jun 21 02:30:03.810699 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jun 21 02:30:03.810706 kernel: DMA32 empty Jun 21 02:30:03.810712 kernel: Normal empty Jun 21 02:30:03.810718 kernel: Device empty Jun 21 02:30:03.810723 kernel: Movable zone start for each node Jun 21 02:30:03.810729 kernel: Early memory node ranges Jun 21 02:30:03.810735 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Jun 21 02:30:03.810741 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Jun 21 02:30:03.810747 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Jun 21 02:30:03.810753 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Jun 21 02:30:03.810759 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Jun 21 02:30:03.810765 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Jun 21 02:30:03.810771 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Jun 21 02:30:03.810778 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Jun 21 02:30:03.810784 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Jun 21 02:30:03.810790 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Jun 21 02:30:03.810799 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Jun 21 02:30:03.810805 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Jun 21 02:30:03.810811 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jun 21 02:30:03.810819 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jun 21 02:30:03.810825 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jun 21 02:30:03.810831 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Jun 21 02:30:03.810838 kernel: psci: probing for conduit method from ACPI. Jun 21 02:30:03.810849 kernel: psci: PSCIv1.1 detected in firmware. Jun 21 02:30:03.810856 kernel: psci: Using standard PSCI v0.2 function IDs Jun 21 02:30:03.810862 kernel: psci: Trusted OS migration not required Jun 21 02:30:03.810868 kernel: psci: SMC Calling Convention v1.1 Jun 21 02:30:03.810875 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jun 21 02:30:03.810881 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jun 21 02:30:03.810889 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jun 21 02:30:03.810895 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jun 21 02:30:03.810902 kernel: Detected PIPT I-cache on CPU0 Jun 21 02:30:03.810908 kernel: CPU features: detected: GIC system register CPU interface Jun 21 02:30:03.810914 kernel: CPU features: detected: Spectre-v4 Jun 21 02:30:03.810921 kernel: CPU features: detected: Spectre-BHB Jun 21 02:30:03.810927 kernel: CPU features: kernel page table isolation forced ON by KASLR Jun 21 02:30:03.810933 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jun 21 02:30:03.810940 kernel: CPU features: detected: ARM erratum 1418040 Jun 21 02:30:03.810946 kernel: CPU features: detected: SSBS not fully self-synchronizing Jun 21 02:30:03.810952 kernel: alternatives: applying boot alternatives Jun 21 02:30:03.810959 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=cb99487be08e9decec94bac26681ba79a4365c210ec86e0c6fe47991cb7f77db Jun 21 02:30:03.810967 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 21 02:30:03.810974 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 21 02:30:03.810980 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 21 02:30:03.810986 kernel: Fallback order for Node 0: 0 Jun 21 02:30:03.810996 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Jun 21 02:30:03.811003 kernel: Policy zone: DMA Jun 21 02:30:03.811009 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 21 02:30:03.811015 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Jun 21 02:30:03.811021 kernel: software IO TLB: area num 4. Jun 21 02:30:03.811027 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Jun 21 02:30:03.811034 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Jun 21 02:30:03.811041 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jun 21 02:30:03.811048 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 21 02:30:03.811054 kernel: rcu: RCU event tracing is enabled. Jun 21 02:30:03.811061 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jun 21 02:30:03.811067 kernel: Trampoline variant of Tasks RCU enabled. Jun 21 02:30:03.811074 kernel: Tracing variant of Tasks RCU enabled. Jun 21 02:30:03.811098 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 21 02:30:03.811106 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jun 21 02:30:03.811112 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 21 02:30:03.811119 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jun 21 02:30:03.811125 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jun 21 02:30:03.811137 kernel: GICv3: 256 SPIs implemented Jun 21 02:30:03.811143 kernel: GICv3: 0 Extended SPIs implemented Jun 21 02:30:03.811149 kernel: Root IRQ handler: gic_handle_irq Jun 21 02:30:03.811156 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jun 21 02:30:03.811162 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Jun 21 02:30:03.811168 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jun 21 02:30:03.811175 kernel: ITS [mem 0x08080000-0x0809ffff] Jun 21 02:30:03.811181 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Jun 21 02:30:03.811188 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Jun 21 02:30:03.811194 kernel: GICv3: using LPI property table @0x00000000400f0000 Jun 21 02:30:03.811200 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040110000 Jun 21 02:30:03.811207 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 21 02:30:03.811214 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 21 02:30:03.811226 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jun 21 02:30:03.811233 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jun 21 02:30:03.811239 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jun 21 02:30:03.811246 kernel: arm-pv: using stolen time PV Jun 21 02:30:03.811252 kernel: Console: colour dummy device 80x25 Jun 21 02:30:03.811259 kernel: ACPI: Core revision 20240827 Jun 21 02:30:03.811266 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jun 21 02:30:03.811272 kernel: pid_max: default: 32768 minimum: 301 Jun 21 02:30:03.811279 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jun 21 02:30:03.811287 kernel: landlock: Up and running. Jun 21 02:30:03.811293 kernel: SELinux: Initializing. Jun 21 02:30:03.811300 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 21 02:30:03.811306 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 21 02:30:03.811313 kernel: rcu: Hierarchical SRCU implementation. Jun 21 02:30:03.811320 kernel: rcu: Max phase no-delay instances is 400. Jun 21 02:30:03.811326 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 21 02:30:03.811333 kernel: Remapping and enabling EFI services. Jun 21 02:30:03.811340 kernel: smp: Bringing up secondary CPUs ... Jun 21 02:30:03.811353 kernel: Detected PIPT I-cache on CPU1 Jun 21 02:30:03.811360 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jun 21 02:30:03.811367 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040120000 Jun 21 02:30:03.811375 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 21 02:30:03.811382 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jun 21 02:30:03.811389 kernel: Detected PIPT I-cache on CPU2 Jun 21 02:30:03.811396 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jun 21 02:30:03.811403 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040130000 Jun 21 02:30:03.811412 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 21 02:30:03.811419 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jun 21 02:30:03.811426 kernel: Detected PIPT I-cache on CPU3 Jun 21 02:30:03.811433 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jun 21 02:30:03.811440 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040140000 Jun 21 02:30:03.811446 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 21 02:30:03.811453 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jun 21 02:30:03.811460 kernel: smp: Brought up 1 node, 4 CPUs Jun 21 02:30:03.811467 kernel: SMP: Total of 4 processors activated. Jun 21 02:30:03.811475 kernel: CPU: All CPU(s) started at EL1 Jun 21 02:30:03.811482 kernel: CPU features: detected: 32-bit EL0 Support Jun 21 02:30:03.811489 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jun 21 02:30:03.811496 kernel: CPU features: detected: Common not Private translations Jun 21 02:30:03.811503 kernel: CPU features: detected: CRC32 instructions Jun 21 02:30:03.811509 kernel: CPU features: detected: Enhanced Virtualization Traps Jun 21 02:30:03.811516 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jun 21 02:30:03.811524 kernel: CPU features: detected: LSE atomic instructions Jun 21 02:30:03.811530 kernel: CPU features: detected: Privileged Access Never Jun 21 02:30:03.811537 kernel: CPU features: detected: RAS Extension Support Jun 21 02:30:03.811546 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jun 21 02:30:03.811553 kernel: alternatives: applying system-wide alternatives Jun 21 02:30:03.811560 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jun 21 02:30:03.811567 kernel: Memory: 2424408K/2572288K available (11136K kernel code, 2284K rwdata, 8980K rodata, 39488K init, 1037K bss, 125728K reserved, 16384K cma-reserved) Jun 21 02:30:03.811574 kernel: devtmpfs: initialized Jun 21 02:30:03.811581 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 21 02:30:03.811588 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jun 21 02:30:03.811595 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jun 21 02:30:03.811603 kernel: 0 pages in range for non-PLT usage Jun 21 02:30:03.811610 kernel: 508496 pages in range for PLT usage Jun 21 02:30:03.811616 kernel: pinctrl core: initialized pinctrl subsystem Jun 21 02:30:03.811623 kernel: SMBIOS 3.0.0 present. Jun 21 02:30:03.811630 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Jun 21 02:30:03.811636 kernel: DMI: Memory slots populated: 1/1 Jun 21 02:30:03.811643 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 21 02:30:03.811650 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jun 21 02:30:03.811657 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jun 21 02:30:03.811665 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jun 21 02:30:03.811672 kernel: audit: initializing netlink subsys (disabled) Jun 21 02:30:03.811679 kernel: audit: type=2000 audit(0.021:1): state=initialized audit_enabled=0 res=1 Jun 21 02:30:03.811685 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 21 02:30:03.811693 kernel: cpuidle: using governor menu Jun 21 02:30:03.811699 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jun 21 02:30:03.811706 kernel: ASID allocator initialised with 32768 entries Jun 21 02:30:03.811714 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 21 02:30:03.811720 kernel: Serial: AMBA PL011 UART driver Jun 21 02:30:03.811728 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 21 02:30:03.811735 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jun 21 02:30:03.811742 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jun 21 02:30:03.811749 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jun 21 02:30:03.811756 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 21 02:30:03.811763 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jun 21 02:30:03.811770 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jun 21 02:30:03.811777 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jun 21 02:30:03.811784 kernel: ACPI: Added _OSI(Module Device) Jun 21 02:30:03.811791 kernel: ACPI: Added _OSI(Processor Device) Jun 21 02:30:03.811799 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 21 02:30:03.811806 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 21 02:30:03.811812 kernel: ACPI: Interpreter enabled Jun 21 02:30:03.811819 kernel: ACPI: Using GIC for interrupt routing Jun 21 02:30:03.811826 kernel: ACPI: MCFG table detected, 1 entries Jun 21 02:30:03.811833 kernel: ACPI: CPU0 has been hot-added Jun 21 02:30:03.811840 kernel: ACPI: CPU1 has been hot-added Jun 21 02:30:03.811849 kernel: ACPI: CPU2 has been hot-added Jun 21 02:30:03.811856 kernel: ACPI: CPU3 has been hot-added Jun 21 02:30:03.811864 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jun 21 02:30:03.811871 kernel: printk: legacy console [ttyAMA0] enabled Jun 21 02:30:03.811878 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jun 21 02:30:03.812011 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jun 21 02:30:03.812076 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jun 21 02:30:03.812217 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jun 21 02:30:03.812287 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jun 21 02:30:03.812348 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jun 21 02:30:03.812358 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jun 21 02:30:03.812365 kernel: PCI host bridge to bus 0000:00 Jun 21 02:30:03.812432 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jun 21 02:30:03.812489 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jun 21 02:30:03.812543 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jun 21 02:30:03.812597 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 21 02:30:03.812684 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Jun 21 02:30:03.812760 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jun 21 02:30:03.812823 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Jun 21 02:30:03.812892 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Jun 21 02:30:03.812953 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Jun 21 02:30:03.813019 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Jun 21 02:30:03.813097 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Jun 21 02:30:03.813174 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Jun 21 02:30:03.813237 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jun 21 02:30:03.813291 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jun 21 02:30:03.813344 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jun 21 02:30:03.813353 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jun 21 02:30:03.813361 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jun 21 02:30:03.813368 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jun 21 02:30:03.813377 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jun 21 02:30:03.813384 kernel: iommu: Default domain type: Translated Jun 21 02:30:03.813391 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jun 21 02:30:03.813398 kernel: efivars: Registered efivars operations Jun 21 02:30:03.813405 kernel: vgaarb: loaded Jun 21 02:30:03.813412 kernel: clocksource: Switched to clocksource arch_sys_counter Jun 21 02:30:03.813419 kernel: VFS: Disk quotas dquot_6.6.0 Jun 21 02:30:03.813426 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 21 02:30:03.813433 kernel: pnp: PnP ACPI init Jun 21 02:30:03.813500 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jun 21 02:30:03.813509 kernel: pnp: PnP ACPI: found 1 devices Jun 21 02:30:03.813517 kernel: NET: Registered PF_INET protocol family Jun 21 02:30:03.813524 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 21 02:30:03.813531 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jun 21 02:30:03.813538 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 21 02:30:03.813545 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jun 21 02:30:03.813552 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jun 21 02:30:03.813561 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jun 21 02:30:03.813568 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 21 02:30:03.813575 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 21 02:30:03.813583 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 21 02:30:03.813590 kernel: PCI: CLS 0 bytes, default 64 Jun 21 02:30:03.813597 kernel: kvm [1]: HYP mode not available Jun 21 02:30:03.813604 kernel: Initialise system trusted keyrings Jun 21 02:30:03.813611 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jun 21 02:30:03.813618 kernel: Key type asymmetric registered Jun 21 02:30:03.813625 kernel: Asymmetric key parser 'x509' registered Jun 21 02:30:03.813634 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jun 21 02:30:03.813641 kernel: io scheduler mq-deadline registered Jun 21 02:30:03.813648 kernel: io scheduler kyber registered Jun 21 02:30:03.813655 kernel: io scheduler bfq registered Jun 21 02:30:03.813662 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jun 21 02:30:03.813669 kernel: ACPI: button: Power Button [PWRB] Jun 21 02:30:03.813676 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jun 21 02:30:03.813735 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jun 21 02:30:03.813745 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 21 02:30:03.813753 kernel: thunder_xcv, ver 1.0 Jun 21 02:30:03.813760 kernel: thunder_bgx, ver 1.0 Jun 21 02:30:03.813767 kernel: nicpf, ver 1.0 Jun 21 02:30:03.813774 kernel: nicvf, ver 1.0 Jun 21 02:30:03.813843 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jun 21 02:30:03.813920 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-06-21T02:30:03 UTC (1750473003) Jun 21 02:30:03.813930 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 21 02:30:03.813937 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jun 21 02:30:03.813947 kernel: watchdog: NMI not fully supported Jun 21 02:30:03.813954 kernel: watchdog: Hard watchdog permanently disabled Jun 21 02:30:03.813961 kernel: NET: Registered PF_INET6 protocol family Jun 21 02:30:03.813968 kernel: Segment Routing with IPv6 Jun 21 02:30:03.813975 kernel: In-situ OAM (IOAM) with IPv6 Jun 21 02:30:03.813983 kernel: NET: Registered PF_PACKET protocol family Jun 21 02:30:03.813990 kernel: Key type dns_resolver registered Jun 21 02:30:03.813997 kernel: registered taskstats version 1 Jun 21 02:30:03.814004 kernel: Loading compiled-in X.509 certificates Jun 21 02:30:03.814012 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: 0d4b619b81572779adc2f9dd5f1325c23c2a41ec' Jun 21 02:30:03.814019 kernel: Demotion targets for Node 0: null Jun 21 02:30:03.814026 kernel: Key type .fscrypt registered Jun 21 02:30:03.814033 kernel: Key type fscrypt-provisioning registered Jun 21 02:30:03.814040 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 21 02:30:03.814048 kernel: ima: Allocated hash algorithm: sha1 Jun 21 02:30:03.814055 kernel: ima: No architecture policies found Jun 21 02:30:03.814062 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jun 21 02:30:03.814070 kernel: clk: Disabling unused clocks Jun 21 02:30:03.814077 kernel: PM: genpd: Disabling unused power domains Jun 21 02:30:03.814110 kernel: Warning: unable to open an initial console. Jun 21 02:30:03.814119 kernel: Freeing unused kernel memory: 39488K Jun 21 02:30:03.814130 kernel: Run /init as init process Jun 21 02:30:03.814137 kernel: with arguments: Jun 21 02:30:03.814144 kernel: /init Jun 21 02:30:03.814151 kernel: with environment: Jun 21 02:30:03.814158 kernel: HOME=/ Jun 21 02:30:03.814165 kernel: TERM=linux Jun 21 02:30:03.814174 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 21 02:30:03.814202 systemd[1]: Successfully made /usr/ read-only. Jun 21 02:30:03.814213 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) Jun 21 02:30:03.814227 systemd[1]: Detected virtualization kvm. Jun 21 02:30:03.814235 systemd[1]: Detected architecture arm64. Jun 21 02:30:03.814242 systemd[1]: Running in initrd. Jun 21 02:30:03.814250 systemd[1]: No hostname configured, using default hostname. Jun 21 02:30:03.814260 systemd[1]: Hostname set to . Jun 21 02:30:03.814267 systemd[1]: Initializing machine ID from VM UUID. Jun 21 02:30:03.814275 systemd[1]: Queued start job for default target initrd.target. Jun 21 02:30:03.814282 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 02:30:03.814290 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 02:30:03.814298 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 21 02:30:03.814306 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 21 02:30:03.814314 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 21 02:30:03.814324 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 21 02:30:03.814333 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 21 02:30:03.814341 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 21 02:30:03.814349 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 02:30:03.814356 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 21 02:30:03.814374 systemd[1]: Reached target paths.target - Path Units. Jun 21 02:30:03.814382 systemd[1]: Reached target slices.target - Slice Units. Jun 21 02:30:03.814391 systemd[1]: Reached target swap.target - Swaps. Jun 21 02:30:03.814400 systemd[1]: Reached target timers.target - Timer Units. Jun 21 02:30:03.814408 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 21 02:30:03.814415 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 21 02:30:03.814423 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 21 02:30:03.814430 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 21 02:30:03.814438 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 21 02:30:03.814446 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 21 02:30:03.814454 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 02:30:03.814462 systemd[1]: Reached target sockets.target - Socket Units. Jun 21 02:30:03.814470 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 21 02:30:03.814477 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 21 02:30:03.814485 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 21 02:30:03.814493 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). Jun 21 02:30:03.814500 systemd[1]: Starting systemd-fsck-usr.service... Jun 21 02:30:03.814507 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 21 02:30:03.814515 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 21 02:30:03.814524 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 02:30:03.814532 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 02:30:03.814540 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 21 02:30:03.814547 systemd[1]: Finished systemd-fsck-usr.service. Jun 21 02:30:03.814556 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 21 02:30:03.814563 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 02:30:03.814571 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 21 02:30:03.814598 systemd-journald[245]: Collecting audit messages is disabled. Jun 21 02:30:03.814618 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 21 02:30:03.814627 systemd-journald[245]: Journal started Jun 21 02:30:03.814645 systemd-journald[245]: Runtime Journal (/run/log/journal/0b2596c681cc41caa8fd2c2c63adf06f) is 6M, max 48.5M, 42.4M free. Jun 21 02:30:03.794853 systemd-modules-load[247]: Inserted module 'overlay' Jun 21 02:30:03.819110 systemd[1]: Started systemd-journald.service - Journal Service. Jun 21 02:30:03.819144 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 21 02:30:03.822635 systemd-modules-load[247]: Inserted module 'br_netfilter' Jun 21 02:30:03.823964 kernel: Bridge firewalling registered Jun 21 02:30:03.823557 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 21 02:30:03.829822 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 21 02:30:03.831502 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 21 02:30:03.842675 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 21 02:30:03.844211 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 21 02:30:03.847891 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 21 02:30:03.854487 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 21 02:30:03.855974 systemd-tmpfiles[276]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jun 21 02:30:03.857343 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 02:30:03.858828 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 02:30:03.864334 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 21 02:30:03.873046 dracut-cmdline[283]: 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=cb99487be08e9decec94bac26681ba79a4365c210ec86e0c6fe47991cb7f77db Jun 21 02:30:03.907763 systemd-resolved[296]: Positive Trust Anchors: Jun 21 02:30:03.908898 systemd-resolved[296]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 21 02:30:03.908935 systemd-resolved[296]: 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 Jun 21 02:30:03.916409 systemd-resolved[296]: Defaulting to hostname 'linux'. Jun 21 02:30:03.917507 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 21 02:30:03.918681 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 21 02:30:03.971117 kernel: SCSI subsystem initialized Jun 21 02:30:03.976100 kernel: Loading iSCSI transport class v2.0-870. Jun 21 02:30:03.989143 kernel: iscsi: registered transport (tcp) Jun 21 02:30:04.006113 kernel: iscsi: registered transport (qla4xxx) Jun 21 02:30:04.006153 kernel: QLogic iSCSI HBA Driver Jun 21 02:30:04.025653 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 21 02:30:04.046487 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 02:30:04.048206 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 21 02:30:04.097128 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 21 02:30:04.099621 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 21 02:30:04.165121 kernel: raid6: neonx8 gen() 15791 MB/s Jun 21 02:30:04.182103 kernel: raid6: neonx4 gen() 15791 MB/s Jun 21 02:30:04.199109 kernel: raid6: neonx2 gen() 13182 MB/s Jun 21 02:30:04.216103 kernel: raid6: neonx1 gen() 10410 MB/s Jun 21 02:30:04.233115 kernel: raid6: int64x8 gen() 6883 MB/s Jun 21 02:30:04.250107 kernel: raid6: int64x4 gen() 7337 MB/s Jun 21 02:30:04.267106 kernel: raid6: int64x2 gen() 6099 MB/s Jun 21 02:30:04.284436 kernel: raid6: int64x1 gen() 5044 MB/s Jun 21 02:30:04.284462 kernel: raid6: using algorithm neonx8 gen() 15791 MB/s Jun 21 02:30:04.302253 kernel: raid6: .... xor() 12035 MB/s, rmw enabled Jun 21 02:30:04.302281 kernel: raid6: using neon recovery algorithm Jun 21 02:30:04.308105 kernel: xor: measuring software checksum speed Jun 21 02:30:04.309396 kernel: 8regs : 18239 MB/sec Jun 21 02:30:04.309409 kernel: 32regs : 21658 MB/sec Jun 21 02:30:04.310690 kernel: arm64_neon : 28013 MB/sec Jun 21 02:30:04.310704 kernel: xor: using function: arm64_neon (28013 MB/sec) Jun 21 02:30:04.376110 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 21 02:30:04.382460 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 21 02:30:04.385069 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 02:30:04.412741 systemd-udevd[498]: Using default interface naming scheme 'v255'. Jun 21 02:30:04.416961 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 02:30:04.419918 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 21 02:30:04.450724 dracut-pre-trigger[506]: rd.md=0: removing MD RAID activation Jun 21 02:30:04.474745 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 21 02:30:04.477160 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 21 02:30:04.526625 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 02:30:04.530064 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 21 02:30:04.578328 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jun 21 02:30:04.580899 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 21 02:30:04.586743 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Jun 21 02:30:04.585906 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 02:30:04.588421 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 02:30:04.590633 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 21 02:30:04.592453 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 02:30:04.620313 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jun 21 02:30:04.623114 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 02:30:04.630996 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jun 21 02:30:04.632499 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 21 02:30:04.644573 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jun 21 02:30:04.645781 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jun 21 02:30:04.655275 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 21 02:30:04.656513 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 21 02:30:04.658610 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 02:30:04.660724 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 21 02:30:04.663417 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 21 02:30:04.665174 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 21 02:30:04.686108 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 21 02:30:04.690118 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 21 02:30:05.704109 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 21 02:30:05.704598 disk-uuid[594]: The operation has completed successfully. Jun 21 02:30:05.732072 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 21 02:30:05.732183 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 21 02:30:05.759067 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 21 02:30:05.778060 sh[611]: Success Jun 21 02:30:05.795188 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 21 02:30:05.795227 kernel: device-mapper: uevent: version 1.0.3 Jun 21 02:30:05.796895 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jun 21 02:30:05.803106 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jun 21 02:30:05.832673 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 21 02:30:05.835419 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 21 02:30:05.852159 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 21 02:30:05.860298 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jun 21 02:30:05.860336 kernel: BTRFS: device fsid 750e5bb7-0e5c-4b2e-87f6-233588ea3c64 devid 1 transid 51 /dev/mapper/usr (253:0) scanned by mount (623) Jun 21 02:30:05.861758 kernel: BTRFS info (device dm-0): first mount of filesystem 750e5bb7-0e5c-4b2e-87f6-233588ea3c64 Jun 21 02:30:05.861785 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jun 21 02:30:05.863411 kernel: BTRFS info (device dm-0): using free-space-tree Jun 21 02:30:05.866724 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 21 02:30:05.868041 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jun 21 02:30:05.869594 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 21 02:30:05.870440 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 21 02:30:05.872018 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 21 02:30:05.902220 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (654) Jun 21 02:30:05.904590 kernel: BTRFS info (device vda6): first mount of filesystem 3419b9f8-2562-4f16-b892-4960d53a6e77 Jun 21 02:30:05.904625 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 21 02:30:05.904641 kernel: BTRFS info (device vda6): using free-space-tree Jun 21 02:30:05.912135 kernel: BTRFS info (device vda6): last unmount of filesystem 3419b9f8-2562-4f16-b892-4960d53a6e77 Jun 21 02:30:05.912593 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 21 02:30:05.915166 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 21 02:30:05.990194 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 21 02:30:05.993567 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 21 02:30:06.053317 systemd-networkd[797]: lo: Link UP Jun 21 02:30:06.053327 systemd-networkd[797]: lo: Gained carrier Jun 21 02:30:06.054000 systemd-networkd[797]: Enumeration completed Jun 21 02:30:06.054862 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 02:30:06.054866 systemd-networkd[797]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 21 02:30:06.055588 systemd-networkd[797]: eth0: Link UP Jun 21 02:30:06.055591 systemd-networkd[797]: eth0: Gained carrier Jun 21 02:30:06.055599 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 02:30:06.056695 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 21 02:30:06.058095 systemd[1]: Reached target network.target - Network. Jun 21 02:30:06.075131 systemd-networkd[797]: eth0: DHCPv4 address 10.0.0.138/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 21 02:30:06.104304 ignition[697]: Ignition 2.21.0 Jun 21 02:30:06.104316 ignition[697]: Stage: fetch-offline Jun 21 02:30:06.104356 ignition[697]: no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:06.104364 ignition[697]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:06.104552 ignition[697]: parsed url from cmdline: "" Jun 21 02:30:06.104555 ignition[697]: no config URL provided Jun 21 02:30:06.104559 ignition[697]: reading system config file "/usr/lib/ignition/user.ign" Jun 21 02:30:06.104566 ignition[697]: no config at "/usr/lib/ignition/user.ign" Jun 21 02:30:06.104588 ignition[697]: op(1): [started] loading QEMU firmware config module Jun 21 02:30:06.104592 ignition[697]: op(1): executing: "modprobe" "qemu_fw_cfg" Jun 21 02:30:06.115811 ignition[697]: op(1): [finished] loading QEMU firmware config module Jun 21 02:30:06.119052 ignition[697]: parsing config with SHA512: 19403bf674a595df2c65dd9e9d4ef4451fca9d68e47cc149e31d69cbbcb0260bf2544f5f1844abf1a241c88a7874c22bb2eb5fe2bdfbfbe4fb189f136d48b044 Jun 21 02:30:06.123979 unknown[697]: fetched base config from "system" Jun 21 02:30:06.123990 unknown[697]: fetched user config from "qemu" Jun 21 02:30:06.124188 ignition[697]: fetch-offline: fetch-offline passed Jun 21 02:30:06.124285 ignition[697]: Ignition finished successfully Jun 21 02:30:06.126865 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 21 02:30:06.128980 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jun 21 02:30:06.130312 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 21 02:30:06.172322 ignition[811]: Ignition 2.21.0 Jun 21 02:30:06.172334 ignition[811]: Stage: kargs Jun 21 02:30:06.172471 ignition[811]: no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:06.172480 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:06.175259 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 21 02:30:06.172999 ignition[811]: kargs: kargs passed Jun 21 02:30:06.173040 ignition[811]: Ignition finished successfully Jun 21 02:30:06.177636 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 21 02:30:06.208707 ignition[819]: Ignition 2.21.0 Jun 21 02:30:06.208725 ignition[819]: Stage: disks Jun 21 02:30:06.208859 ignition[819]: no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:06.208869 ignition[819]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:06.210960 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 21 02:30:06.209390 ignition[819]: disks: disks passed Jun 21 02:30:06.213281 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 21 02:30:06.209428 ignition[819]: Ignition finished successfully Jun 21 02:30:06.214972 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 21 02:30:06.216632 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 21 02:30:06.218461 systemd[1]: Reached target sysinit.target - System Initialization. Jun 21 02:30:06.219996 systemd[1]: Reached target basic.target - Basic System. Jun 21 02:30:06.222696 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 21 02:30:06.256932 systemd-fsck[829]: ROOT: clean, 15/553520 files, 52789/553472 blocks Jun 21 02:30:06.261588 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 21 02:30:06.265520 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 21 02:30:06.336125 kernel: EXT4-fs (vda9): mounted filesystem 9ad072e4-7680-4e5b-adc0-72c770c20c86 r/w with ordered data mode. Quota mode: none. Jun 21 02:30:06.336751 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 21 02:30:06.338045 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 21 02:30:06.340459 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 21 02:30:06.342077 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 21 02:30:06.343065 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jun 21 02:30:06.343125 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 21 02:30:06.343147 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 21 02:30:06.361117 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 21 02:30:06.363947 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 21 02:30:06.368116 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (837) Jun 21 02:30:06.371212 kernel: BTRFS info (device vda6): first mount of filesystem 3419b9f8-2562-4f16-b892-4960d53a6e77 Jun 21 02:30:06.371230 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 21 02:30:06.371240 kernel: BTRFS info (device vda6): using free-space-tree Jun 21 02:30:06.374968 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 21 02:30:06.419605 initrd-setup-root[861]: cut: /sysroot/etc/passwd: No such file or directory Jun 21 02:30:06.422630 initrd-setup-root[868]: cut: /sysroot/etc/group: No such file or directory Jun 21 02:30:06.425470 initrd-setup-root[875]: cut: /sysroot/etc/shadow: No such file or directory Jun 21 02:30:06.428147 initrd-setup-root[882]: cut: /sysroot/etc/gshadow: No such file or directory Jun 21 02:30:06.496552 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 21 02:30:06.498718 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 21 02:30:06.500349 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 21 02:30:06.516105 kernel: BTRFS info (device vda6): last unmount of filesystem 3419b9f8-2562-4f16-b892-4960d53a6e77 Jun 21 02:30:06.534498 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 21 02:30:06.536289 ignition[950]: INFO : Ignition 2.21.0 Jun 21 02:30:06.536289 ignition[950]: INFO : Stage: mount Jun 21 02:30:06.536289 ignition[950]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:06.536289 ignition[950]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:06.539793 ignition[950]: INFO : mount: mount passed Jun 21 02:30:06.539793 ignition[950]: INFO : Ignition finished successfully Jun 21 02:30:06.539747 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 21 02:30:06.541709 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 21 02:30:06.859050 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 21 02:30:06.860521 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 21 02:30:06.878095 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 (254:6) scanned by mount (963) Jun 21 02:30:06.880381 kernel: BTRFS info (device vda6): first mount of filesystem 3419b9f8-2562-4f16-b892-4960d53a6e77 Jun 21 02:30:06.880408 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 21 02:30:06.880419 kernel: BTRFS info (device vda6): using free-space-tree Jun 21 02:30:06.883969 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 21 02:30:06.913807 ignition[980]: INFO : Ignition 2.21.0 Jun 21 02:30:06.913807 ignition[980]: INFO : Stage: files Jun 21 02:30:06.915472 ignition[980]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:06.915472 ignition[980]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:06.915472 ignition[980]: DEBUG : files: compiled without relabeling support, skipping Jun 21 02:30:06.918875 ignition[980]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 21 02:30:06.918875 ignition[980]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 21 02:30:06.922671 ignition[980]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 21 02:30:06.924137 ignition[980]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 21 02:30:06.924137 ignition[980]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 21 02:30:06.923241 unknown[980]: wrote ssh authorized keys file for user: core Jun 21 02:30:06.930421 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 21 02:30:06.932176 ignition[980]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 21 02:30:06.932176 ignition[980]: INFO : files: op(4): [started] processing unit "docker.service" Jun 21 02:30:06.932176 ignition[980]: INFO : files: op(4): [finished] processing unit "docker.service" Jun 21 02:30:06.932176 ignition[980]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Jun 21 02:30:06.937891 ignition[980]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 21 02:30:06.940419 ignition[980]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 21 02:30:06.940419 ignition[980]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Jun 21 02:30:06.940419 ignition[980]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Jun 21 02:30:06.958418 ignition[980]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Jun 21 02:30:06.961633 ignition[980]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jun 21 02:30:06.964116 ignition[980]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Jun 21 02:30:06.964116 ignition[980]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Jun 21 02:30:06.964116 ignition[980]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Jun 21 02:30:06.964116 ignition[980]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 21 02:30:06.964116 ignition[980]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 21 02:30:06.964116 ignition[980]: INFO : files: files passed Jun 21 02:30:06.964116 ignition[980]: INFO : Ignition finished successfully Jun 21 02:30:06.964800 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 21 02:30:06.967314 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 21 02:30:06.969413 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 21 02:30:06.987072 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 21 02:30:06.987179 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 21 02:30:06.990331 initrd-setup-root-after-ignition[1009]: grep: /sysroot/oem/oem-release: No such file or directory Jun 21 02:30:06.992925 initrd-setup-root-after-ignition[1011]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 21 02:30:06.992925 initrd-setup-root-after-ignition[1011]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 21 02:30:06.996222 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 21 02:30:06.995767 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 21 02:30:06.997695 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 21 02:30:07.000758 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 21 02:30:07.033662 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 21 02:30:07.033786 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 21 02:30:07.036229 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 21 02:30:07.038120 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 21 02:30:07.040035 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 21 02:30:07.040854 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 21 02:30:07.057120 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 21 02:30:07.059541 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 21 02:30:07.080314 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 21 02:30:07.081598 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 02:30:07.083634 systemd[1]: Stopped target timers.target - Timer Units. Jun 21 02:30:07.085391 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 21 02:30:07.085511 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 21 02:30:07.087997 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 21 02:30:07.090021 systemd[1]: Stopped target basic.target - Basic System. Jun 21 02:30:07.091682 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 21 02:30:07.093371 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 21 02:30:07.095306 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 21 02:30:07.097201 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jun 21 02:30:07.099192 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 21 02:30:07.101031 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 21 02:30:07.103030 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 21 02:30:07.105021 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 21 02:30:07.106766 systemd[1]: Stopped target swap.target - Swaps. Jun 21 02:30:07.108369 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 21 02:30:07.108498 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 21 02:30:07.110789 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 21 02:30:07.112002 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 02:30:07.114017 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 21 02:30:07.114174 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 02:30:07.116110 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 21 02:30:07.116240 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 21 02:30:07.118907 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 21 02:30:07.119010 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 21 02:30:07.121376 systemd[1]: Stopped target paths.target - Path Units. Jun 21 02:30:07.122872 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 21 02:30:07.122970 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 02:30:07.124976 systemd[1]: Stopped target slices.target - Slice Units. Jun 21 02:30:07.126825 systemd[1]: Stopped target sockets.target - Socket Units. Jun 21 02:30:07.128725 systemd[1]: iscsid.socket: Deactivated successfully. Jun 21 02:30:07.128808 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 21 02:30:07.130373 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 21 02:30:07.130503 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 21 02:30:07.132330 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 21 02:30:07.132488 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 21 02:30:07.134695 systemd[1]: ignition-files.service: Deactivated successfully. Jun 21 02:30:07.134800 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 21 02:30:07.137119 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 21 02:30:07.138647 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 21 02:30:07.138769 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 02:30:07.159700 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 21 02:30:07.160552 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 21 02:30:07.160665 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 02:30:07.162586 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 21 02:30:07.162683 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 21 02:30:07.168276 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 21 02:30:07.169021 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 21 02:30:07.173272 ignition[1036]: INFO : Ignition 2.21.0 Jun 21 02:30:07.173272 ignition[1036]: INFO : Stage: umount Jun 21 02:30:07.175448 ignition[1036]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 21 02:30:07.175448 ignition[1036]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 21 02:30:07.177611 ignition[1036]: INFO : umount: umount passed Jun 21 02:30:07.177611 ignition[1036]: INFO : Ignition finished successfully Jun 21 02:30:07.181167 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 21 02:30:07.181648 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 21 02:30:07.181758 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 21 02:30:07.183552 systemd[1]: Stopped target network.target - Network. Jun 21 02:30:07.184886 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 21 02:30:07.184945 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 21 02:30:07.186671 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 21 02:30:07.186711 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 21 02:30:07.188270 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 21 02:30:07.188313 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 21 02:30:07.189953 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 21 02:30:07.189991 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 21 02:30:07.191854 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 21 02:30:07.193645 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 21 02:30:07.195496 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 21 02:30:07.195577 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 21 02:30:07.197324 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 21 02:30:07.197399 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 21 02:30:07.199628 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 21 02:30:07.199736 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 21 02:30:07.203522 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 21 02:30:07.203729 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 21 02:30:07.203761 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 02:30:07.206616 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 21 02:30:07.206824 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 21 02:30:07.206906 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 21 02:30:07.209212 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jun 21 02:30:07.210937 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 21 02:30:07.210971 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 21 02:30:07.213533 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 21 02:30:07.214561 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 21 02:30:07.214615 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 21 02:30:07.216790 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 21 02:30:07.216835 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 21 02:30:07.219737 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 21 02:30:07.219805 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 21 02:30:07.222022 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 02:30:07.232421 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 21 02:30:07.232533 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 21 02:30:07.247688 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 21 02:30:07.248735 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 02:30:07.251526 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 21 02:30:07.252184 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 21 02:30:07.253560 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 21 02:30:07.253593 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 02:30:07.255379 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 21 02:30:07.255431 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 21 02:30:07.258099 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 21 02:30:07.258146 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 21 02:30:07.260828 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 21 02:30:07.260884 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 21 02:30:07.264493 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 21 02:30:07.265820 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jun 21 02:30:07.265884 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 02:30:07.269326 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 21 02:30:07.269369 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 02:30:07.272714 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 21 02:30:07.272760 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 02:30:07.282270 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 21 02:30:07.282368 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 21 02:30:07.284645 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 21 02:30:07.287177 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 21 02:30:07.311423 systemd[1]: Switching root. Jun 21 02:30:07.351549 systemd-journald[245]: Journal stopped Jun 21 02:30:08.039853 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Jun 21 02:30:08.039902 kernel: SELinux: policy capability network_peer_controls=1 Jun 21 02:30:08.039914 kernel: SELinux: policy capability open_perms=1 Jun 21 02:30:08.039923 kernel: SELinux: policy capability extended_socket_class=1 Jun 21 02:30:08.039932 kernel: SELinux: policy capability always_check_network=0 Jun 21 02:30:08.039941 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 21 02:30:08.039953 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 21 02:30:08.039963 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 21 02:30:08.039973 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 21 02:30:08.039982 kernel: SELinux: policy capability userspace_initial_context=0 Jun 21 02:30:08.039994 kernel: audit: type=1403 audit(1750473007.420:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 21 02:30:08.040009 systemd[1]: Successfully loaded SELinux policy in 33.675ms. Jun 21 02:30:08.040035 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.357ms. Jun 21 02:30:08.040046 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) Jun 21 02:30:08.040057 systemd[1]: Detected virtualization kvm. Jun 21 02:30:08.040066 systemd[1]: Detected architecture arm64. Jun 21 02:30:08.040076 systemd[1]: Detected first boot. Jun 21 02:30:08.040121 systemd[1]: Initializing machine ID from VM UUID. Jun 21 02:30:08.040136 zram_generator::config[1081]: No configuration found. Jun 21 02:30:08.040146 kernel: NET: Registered PF_VSOCK protocol family Jun 21 02:30:08.040155 systemd[1]: Populated /etc with preset unit settings. Jun 21 02:30:08.040165 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 21 02:30:08.040176 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 21 02:30:08.040186 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 21 02:30:08.040196 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 21 02:30:08.040215 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 21 02:30:08.040228 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 21 02:30:08.040238 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 21 02:30:08.040262 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 21 02:30:08.040274 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 21 02:30:08.040285 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 21 02:30:08.040301 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 21 02:30:08.040311 systemd[1]: Created slice user.slice - User and Session Slice. Jun 21 02:30:08.040320 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 21 02:30:08.040330 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 21 02:30:08.040340 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 21 02:30:08.040350 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 21 02:30:08.040360 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 21 02:30:08.040369 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 21 02:30:08.040380 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jun 21 02:30:08.040392 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 21 02:30:08.040402 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 21 02:30:08.040433 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 21 02:30:08.040443 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 21 02:30:08.040453 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 21 02:30:08.040462 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 21 02:30:08.040472 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 21 02:30:08.040483 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 21 02:30:08.040493 systemd[1]: Reached target slices.target - Slice Units. Jun 21 02:30:08.040503 systemd[1]: Reached target swap.target - Swaps. Jun 21 02:30:08.040512 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 21 02:30:08.040523 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 21 02:30:08.040533 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 21 02:30:08.040543 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 21 02:30:08.040552 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 21 02:30:08.040562 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 21 02:30:08.040572 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 21 02:30:08.040583 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 21 02:30:08.040593 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 21 02:30:08.040603 systemd[1]: Mounting media.mount - External Media Directory... Jun 21 02:30:08.040613 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 21 02:30:08.040622 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 21 02:30:08.040632 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 21 02:30:08.040643 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). Jun 21 02:30:08.040652 systemd[1]: Reached target machines.target - Containers. Jun 21 02:30:08.040664 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 21 02:30:08.040674 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 02:30:08.040683 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 21 02:30:08.040693 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 21 02:30:08.040703 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 02:30:08.040713 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 21 02:30:08.040727 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 02:30:08.040737 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 21 02:30:08.040747 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 02:30:08.040758 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 21 02:30:08.040768 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 21 02:30:08.040777 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 21 02:30:08.040786 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 21 02:30:08.040796 systemd[1]: Stopped systemd-fsck-usr.service. Jun 21 02:30:08.040807 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). Jun 21 02:30:08.040817 kernel: fuse: init (API version 7.41) Jun 21 02:30:08.040827 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 21 02:30:08.040837 kernel: loop: module loaded Jun 21 02:30:08.040847 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 21 02:30:08.040856 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 21 02:30:08.040866 kernel: ACPI: bus type drm_connector registered Jun 21 02:30:08.040875 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 21 02:30:08.040885 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 21 02:30:08.040917 systemd-journald[1153]: Collecting audit messages is disabled. Jun 21 02:30:08.040938 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 21 02:30:08.040949 systemd-journald[1153]: Journal started Jun 21 02:30:08.040970 systemd-journald[1153]: Runtime Journal (/run/log/journal/0b2596c681cc41caa8fd2c2c63adf06f) is 6M, max 48.5M, 42.4M free. Jun 21 02:30:07.808418 systemd[1]: Queued start job for default target multi-user.target. Jun 21 02:30:07.833029 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jun 21 02:30:07.833415 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 21 02:30:08.042610 systemd[1]: verity-setup.service: Deactivated successfully. Jun 21 02:30:08.042641 systemd[1]: Stopped verity-setup.service. Jun 21 02:30:08.050904 systemd[1]: Started systemd-journald.service - Journal Service. Jun 21 02:30:08.048216 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 21 02:30:08.049363 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 21 02:30:08.050577 systemd[1]: Mounted media.mount - External Media Directory. Jun 21 02:30:08.051740 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 21 02:30:08.052971 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 21 02:30:08.054288 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 21 02:30:08.055602 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 21 02:30:08.058281 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 21 02:30:08.059896 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 21 02:30:08.060094 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 21 02:30:08.061607 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 02:30:08.061788 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 02:30:08.063266 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 21 02:30:08.063429 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 21 02:30:08.064840 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 02:30:08.064993 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 02:30:08.066629 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 21 02:30:08.066786 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 21 02:30:08.068211 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 02:30:08.068380 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 02:30:08.069859 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 21 02:30:08.071348 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 21 02:30:08.074120 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 21 02:30:08.075832 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 21 02:30:08.088466 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 21 02:30:08.090969 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 21 02:30:08.093102 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 21 02:30:08.094257 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 21 02:30:08.094286 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 21 02:30:08.096177 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 21 02:30:08.100834 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 21 02:30:08.101991 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 02:30:08.103212 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 21 02:30:08.105020 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 21 02:30:08.106300 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 02:30:08.112723 systemd-journald[1153]: Time spent on flushing to /var/log/journal/0b2596c681cc41caa8fd2c2c63adf06f is 12.876ms for 846 entries. Jun 21 02:30:08.112723 systemd-journald[1153]: System Journal (/var/log/journal/0b2596c681cc41caa8fd2c2c63adf06f) is 8M, max 195.6M, 187.6M free. Jun 21 02:30:08.137217 systemd-journald[1153]: Received client request to flush runtime journal. Jun 21 02:30:08.112791 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 21 02:30:08.115149 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 02:30:08.117267 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 21 02:30:08.122238 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 21 02:30:08.124292 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 21 02:30:08.126839 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 21 02:30:08.128414 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 21 02:30:08.129628 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 21 02:30:08.139378 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 21 02:30:08.141289 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 21 02:30:08.147541 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 21 02:30:08.151243 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 21 02:30:08.156019 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 21 02:30:08.161133 kernel: loop0: detected capacity change from 0 to 107312 Jun 21 02:30:08.182165 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 21 02:30:08.182810 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 21 02:30:08.183778 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 21 02:30:08.185986 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 21 02:30:08.191225 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 21 02:30:08.215033 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Jun 21 02:30:08.215353 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Jun 21 02:30:08.217119 kernel: loop1: detected capacity change from 0 to 138376 Jun 21 02:30:08.219782 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 21 02:30:08.255117 kernel: loop2: detected capacity change from 0 to 107312 Jun 21 02:30:08.261139 kernel: loop3: detected capacity change from 0 to 138376 Jun 21 02:30:08.268821 (sd-merge)[1221]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Jun 21 02:30:08.269162 (sd-merge)[1221]: Merged extensions into '/usr'. Jun 21 02:30:08.271984 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 21 02:30:08.275229 systemd[1]: Starting ensure-sysext.service... Jun 21 02:30:08.276843 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 21 02:30:08.302667 systemd[1]: Reload requested from client PID 1223 ('systemctl') (unit ensure-sysext.service)... Jun 21 02:30:08.302689 systemd[1]: Reloading... Jun 21 02:30:08.305581 systemd-tmpfiles[1224]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jun 21 02:30:08.305612 systemd-tmpfiles[1224]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jun 21 02:30:08.305835 systemd-tmpfiles[1224]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 21 02:30:08.306021 systemd-tmpfiles[1224]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 21 02:30:08.306740 systemd-tmpfiles[1224]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 21 02:30:08.306948 systemd-tmpfiles[1224]: ACLs are not supported, ignoring. Jun 21 02:30:08.306994 systemd-tmpfiles[1224]: ACLs are not supported, ignoring. Jun 21 02:30:08.309502 systemd-tmpfiles[1224]: Detected autofs mount point /boot during canonicalization of boot. Jun 21 02:30:08.309513 systemd-tmpfiles[1224]: Skipping /boot Jun 21 02:30:08.318122 systemd-tmpfiles[1224]: Detected autofs mount point /boot during canonicalization of boot. Jun 21 02:30:08.318132 systemd-tmpfiles[1224]: Skipping /boot Jun 21 02:30:08.355181 zram_generator::config[1249]: No configuration found. Jun 21 02:30:08.441745 ldconfig[1192]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 21 02:30:08.445939 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. Jun 21 02:30:08.509824 systemd[1]: Reloading finished in 206 ms. Jun 21 02:30:08.532678 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 21 02:30:08.544805 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 21 02:30:08.552741 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 21 02:30:08.555218 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 21 02:30:08.565992 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 21 02:30:08.568892 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 21 02:30:08.570878 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 21 02:30:08.574571 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 02:30:08.576321 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 02:30:08.582393 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 02:30:08.585390 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 02:30:08.586522 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 02:30:08.586656 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). Jun 21 02:30:08.588772 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 02:30:08.588963 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 02:30:08.590822 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 21 02:30:08.598790 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 02:30:08.601613 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 02:30:08.603105 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 02:30:08.603303 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). Jun 21 02:30:08.606293 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 21 02:30:08.608456 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 21 02:30:08.611662 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 02:30:08.612099 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 02:30:08.614564 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 02:30:08.615171 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 02:30:08.617067 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 02:30:08.617257 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 02:30:08.625338 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 21 02:30:08.626713 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 21 02:30:08.630843 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 21 02:30:08.641132 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 21 02:30:08.644479 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 21 02:30:08.647246 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 21 02:30:08.647368 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). Jun 21 02:30:08.647835 augenrules[1327]: No rules Jun 21 02:30:08.648624 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 21 02:30:08.652503 systemd[1]: audit-rules.service: Deactivated successfully. Jun 21 02:30:08.652686 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 21 02:30:08.654319 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 21 02:30:08.656048 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 21 02:30:08.657592 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 21 02:30:08.657740 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 21 02:30:08.659456 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 21 02:30:08.659626 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 21 02:30:08.661196 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 21 02:30:08.661366 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 21 02:30:08.663068 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 21 02:30:08.663276 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 21 02:30:08.666653 systemd[1]: Finished ensure-sysext.service. Jun 21 02:30:08.672765 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 21 02:30:08.672827 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 21 02:30:08.674647 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jun 21 02:30:08.676924 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 21 02:30:08.679330 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 21 02:30:08.682341 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). Jun 21 02:30:08.698569 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 21 02:30:08.708986 systemd-resolved[1291]: Positive Trust Anchors: Jun 21 02:30:08.709005 systemd-resolved[1291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 21 02:30:08.709039 systemd-resolved[1291]: 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 Jun 21 02:30:08.711775 systemd-udevd[1346]: Using default interface naming scheme 'v255'. Jun 21 02:30:08.715229 systemd-resolved[1291]: Defaulting to hostname 'linux'. Jun 21 02:30:08.716826 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 21 02:30:08.718093 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 21 02:30:08.733963 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 21 02:30:08.735390 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jun 21 02:30:08.738414 systemd[1]: Reached target sysinit.target - System Initialization. Jun 21 02:30:08.740317 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 21 02:30:08.742101 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 21 02:30:08.744256 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 21 02:30:08.746185 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 21 02:30:08.746225 systemd[1]: Reached target paths.target - Path Units. Jun 21 02:30:08.747134 systemd[1]: Reached target time-set.target - System Time Set. Jun 21 02:30:08.748478 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 21 02:30:08.750160 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 21 02:30:08.751903 systemd[1]: Reached target timers.target - Timer Units. Jun 21 02:30:08.753963 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 21 02:30:08.756392 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 21 02:30:08.761970 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 21 02:30:08.763418 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 21 02:30:08.769313 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 21 02:30:08.784185 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 21 02:30:08.787661 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 21 02:30:08.790879 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 21 02:30:08.793792 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 21 02:30:08.804596 systemd[1]: Reached target sockets.target - Socket Units. Jun 21 02:30:08.807284 systemd[1]: Reached target basic.target - Basic System. Jun 21 02:30:08.810616 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 21 02:30:08.810646 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 21 02:30:08.812318 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 21 02:30:08.815324 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 21 02:30:08.818299 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 21 02:30:08.820931 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 21 02:30:08.823169 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). Jun 21 02:30:08.825338 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 21 02:30:08.829343 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 21 02:30:08.837316 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 21 02:30:08.846366 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 21 02:30:08.848381 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 21 02:30:08.848902 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 21 02:30:08.853017 jq[1388]: false Jun 21 02:30:08.853541 systemd[1]: Starting update-engine.service - Update Engine... Jun 21 02:30:08.856006 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 21 02:30:08.860332 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 21 02:30:08.861827 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 21 02:30:08.862001 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 21 02:30:08.862296 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 21 02:30:08.862477 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 21 02:30:08.864062 systemd[1]: motdgen.service: Deactivated successfully. Jun 21 02:30:08.864253 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 21 02:30:08.870984 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jun 21 02:30:08.875422 jq[1405]: true Jun 21 02:30:08.887830 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 21 02:30:08.894718 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 21 02:30:08.896304 jq[1412]: true Jun 21 02:30:08.901118 extend-filesystems[1389]: Found /dev/vda6 Jun 21 02:30:08.909027 extend-filesystems[1389]: Found /dev/vda9 Jun 21 02:30:08.912680 extend-filesystems[1389]: Checking size of /dev/vda9 Jun 21 02:30:08.920707 dbus-daemon[1386]: [system] SELinux support is enabled Jun 21 02:30:08.920863 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 21 02:30:08.923849 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). Jun 21 02:30:08.923885 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 21 02:30:08.925393 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). Jun 21 02:30:08.925421 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 21 02:30:08.932190 extend-filesystems[1389]: Old size kept for /dev/vda9 Jun 21 02:30:08.933659 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 21 02:30:08.934322 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 21 02:30:08.937425 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 21 02:30:08.961720 bash[1440]: Updated "/home/core/.ssh/authorized_keys" Jun 21 02:30:08.961172 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 21 02:30:08.962907 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 21 02:30:08.972683 systemd-logind[1394]: New seat seat0. Jun 21 02:30:08.978495 update_engine[1400]: I20250621 02:30:08.978346 1400 main.cc:92] Flatcar Update Engine starting Jun 21 02:30:08.983957 systemd[1]: Started update-engine.service - Update Engine. Jun 21 02:30:08.984060 update_engine[1400]: I20250621 02:30:08.983997 1400 update_check_scheduler.cc:74] Next update check in 7m48s Jun 21 02:30:08.996319 systemd-networkd[1379]: lo: Link UP Jun 21 02:30:08.996324 systemd-networkd[1379]: lo: Gained carrier Jun 21 02:30:09.004239 systemd-networkd[1379]: Enumeration completed Jun 21 02:30:09.005364 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 21 02:30:09.006534 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 02:30:09.006544 systemd-networkd[1379]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 21 02:30:09.006648 systemd[1]: Started systemd-logind.service - User Login Management. Jun 21 02:30:09.007062 systemd-networkd[1379]: eth0: Link UP Jun 21 02:30:09.007234 systemd-networkd[1379]: eth0: Gained carrier Jun 21 02:30:09.007254 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 21 02:30:09.008975 systemd[1]: Reached target network.target - Network. Jun 21 02:30:09.011454 systemd[1]: Starting containerd.service - containerd container runtime... Jun 21 02:30:09.017047 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 21 02:30:09.033271 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 21 02:30:09.037370 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 21 02:30:09.042242 systemd-networkd[1379]: eth0: DHCPv4 address 10.0.0.138/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 21 02:30:09.050212 systemd-timesyncd[1345]: Network configuration changed, trying to establish connection. Jun 21 02:30:09.050837 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 21 02:30:09.052026 systemd-timesyncd[1345]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jun 21 02:30:09.052096 systemd-timesyncd[1345]: Initial clock synchronization to Sat 2025-06-21 02:30:09.099749 UTC. Jun 21 02:30:09.071345 (ntainerd)[1465]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 21 02:30:09.073160 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 21 02:30:09.077688 systemd-logind[1394]: Watching system buttons on /dev/input/event0 (Power Button) Jun 21 02:30:09.157419 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 21 02:30:09.158139 locksmithd[1460]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 21 02:30:09.266431 containerd[1465]: time="2025-06-21T02:30:09Z" 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 Jun 21 02:30:09.269524 containerd[1465]: time="2025-06-21T02:30:09.269479400Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 21 02:30:09.278575 containerd[1465]: time="2025-06-21T02:30:09.278534080Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.6µs" Jun 21 02:30:09.278575 containerd[1465]: time="2025-06-21T02:30:09.278565360Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 21 02:30:09.278654 containerd[1465]: time="2025-06-21T02:30:09.278586840Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 21 02:30:09.278755 containerd[1465]: time="2025-06-21T02:30:09.278728520Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 21 02:30:09.278755 containerd[1465]: time="2025-06-21T02:30:09.278750720Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 21 02:30:09.278812 containerd[1465]: time="2025-06-21T02:30:09.278773760Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:30:09.278839 containerd[1465]: time="2025-06-21T02:30:09.278822640Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:30:09.278858 containerd[1465]: time="2025-06-21T02:30:09.278837120Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279053 containerd[1465]: time="2025-06-21T02:30:09.279022000Z" 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 Jun 21 02:30:09.279053 containerd[1465]: time="2025-06-21T02:30:09.279043200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279106 containerd[1465]: time="2025-06-21T02:30:09.279054080Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279106 containerd[1465]: time="2025-06-21T02:30:09.279063000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279174 containerd[1465]: time="2025-06-21T02:30:09.279156160Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279398 containerd[1465]: time="2025-06-21T02:30:09.279367560Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 21 02:30:09.279421 containerd[1465]: time="2025-06-21T02:30:09.279408240Z" 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 Jun 21 02:30:09.279421 containerd[1465]: time="2025-06-21T02:30:09.279418760Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 21 02:30:09.279467 containerd[1465]: time="2025-06-21T02:30:09.279451080Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 21 02:30:09.279698 containerd[1465]: time="2025-06-21T02:30:09.279681240Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 21 02:30:09.279768 containerd[1465]: time="2025-06-21T02:30:09.279752440Z" level=info msg="metadata content store policy set" policy=shared Jun 21 02:30:09.282553 containerd[1465]: time="2025-06-21T02:30:09.282513640Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 21 02:30:09.282590 containerd[1465]: time="2025-06-21T02:30:09.282564720Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 21 02:30:09.282590 containerd[1465]: time="2025-06-21T02:30:09.282577560Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 21 02:30:09.282623 containerd[1465]: time="2025-06-21T02:30:09.282588880Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 21 02:30:09.282623 containerd[1465]: time="2025-06-21T02:30:09.282601800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 21 02:30:09.282623 containerd[1465]: time="2025-06-21T02:30:09.282612960Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282623560Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282637320Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282651360Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282661560Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282671160Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 21 02:30:09.282684 containerd[1465]: time="2025-06-21T02:30:09.282682680Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 21 02:30:09.282825 containerd[1465]: time="2025-06-21T02:30:09.282795080Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 21 02:30:09.282825 containerd[1465]: time="2025-06-21T02:30:09.282823040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282838600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282849760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282861200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282872360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282883160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282893160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 21 02:30:09.282907 containerd[1465]: time="2025-06-21T02:30:09.282903920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 21 02:30:09.283027 containerd[1465]: time="2025-06-21T02:30:09.282914560Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 21 02:30:09.283027 containerd[1465]: time="2025-06-21T02:30:09.282939560Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 21 02:30:09.283175 containerd[1465]: time="2025-06-21T02:30:09.283142440Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 21 02:30:09.283175 containerd[1465]: time="2025-06-21T02:30:09.283165120Z" level=info msg="Start snapshots syncer" Jun 21 02:30:09.283236 containerd[1465]: time="2025-06-21T02:30:09.283192520Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 21 02:30:09.283459 containerd[1465]: time="2025-06-21T02:30:09.283406200Z" 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\"}" Jun 21 02:30:09.283572 containerd[1465]: time="2025-06-21T02:30:09.283461920Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 21 02:30:09.283572 containerd[1465]: time="2025-06-21T02:30:09.283541160Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 21 02:30:09.283669 containerd[1465]: time="2025-06-21T02:30:09.283647480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 21 02:30:09.283716 containerd[1465]: time="2025-06-21T02:30:09.283675200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 21 02:30:09.283716 containerd[1465]: time="2025-06-21T02:30:09.283687120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 21 02:30:09.283716 containerd[1465]: time="2025-06-21T02:30:09.283700160Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 21 02:30:09.283781 containerd[1465]: time="2025-06-21T02:30:09.283718480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 21 02:30:09.283781 containerd[1465]: time="2025-06-21T02:30:09.283730000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 21 02:30:09.283781 containerd[1465]: time="2025-06-21T02:30:09.283741520Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 21 02:30:09.283781 containerd[1465]: time="2025-06-21T02:30:09.283770560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 21 02:30:09.283847 containerd[1465]: time="2025-06-21T02:30:09.283781600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 21 02:30:09.283847 containerd[1465]: time="2025-06-21T02:30:09.283795080Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 21 02:30:09.283847 containerd[1465]: time="2025-06-21T02:30:09.283820760Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 21 02:30:09.283847 containerd[1465]: time="2025-06-21T02:30:09.283833640Z" 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 Jun 21 02:30:09.283847 containerd[1465]: time="2025-06-21T02:30:09.283842640Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:30:09.283928 containerd[1465]: time="2025-06-21T02:30:09.283852400Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:30:09.283928 containerd[1465]: time="2025-06-21T02:30:09.283859880Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 21 02:30:09.283928 containerd[1465]: time="2025-06-21T02:30:09.283869080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 21 02:30:09.283928 containerd[1465]: time="2025-06-21T02:30:09.283878480Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 21 02:30:09.283990 containerd[1465]: time="2025-06-21T02:30:09.283953240Z" level=info msg="runtime interface created" Jun 21 02:30:09.283990 containerd[1465]: time="2025-06-21T02:30:09.283958760Z" level=info msg="created NRI interface" Jun 21 02:30:09.283990 containerd[1465]: time="2025-06-21T02:30:09.283966400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 21 02:30:09.283990 containerd[1465]: time="2025-06-21T02:30:09.283976960Z" level=info msg="Connect containerd service" Jun 21 02:30:09.284053 containerd[1465]: time="2025-06-21T02:30:09.284002120Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 21 02:30:09.284719 containerd[1465]: time="2025-06-21T02:30:09.284683560Z" 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" Jun 21 02:30:09.391160 containerd[1465]: time="2025-06-21T02:30:09.391103200Z" level=info msg="Start subscribing containerd event" Jun 21 02:30:09.391160 containerd[1465]: time="2025-06-21T02:30:09.391165320Z" level=info msg="Start recovering state" Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391390240Z" level=info msg="Start event monitor" Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391410080Z" level=info msg="Start cni network conf syncer for default" Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391418120Z" level=info msg="Start streaming server" Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391427240Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391435560Z" level=info msg="runtime interface starting up..." Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391441080Z" level=info msg="starting plugins..." Jun 21 02:30:09.391474 containerd[1465]: time="2025-06-21T02:30:09.391455480Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 21 02:30:09.391868 containerd[1465]: time="2025-06-21T02:30:09.391831240Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 21 02:30:09.392019 containerd[1465]: time="2025-06-21T02:30:09.391998960Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 21 02:30:09.395224 containerd[1465]: time="2025-06-21T02:30:09.395188320Z" level=info msg="containerd successfully booted in 0.129184s" Jun 21 02:30:09.395297 systemd[1]: Started containerd.service - containerd container runtime. Jun 21 02:30:09.758243 sshd_keygen[1411]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 21 02:30:09.776687 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 21 02:30:09.779474 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 21 02:30:09.806194 systemd[1]: issuegen.service: Deactivated successfully. Jun 21 02:30:09.806397 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 21 02:30:09.808848 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 21 02:30:09.818774 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 21 02:30:09.821334 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 21 02:30:09.823289 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jun 21 02:30:09.824581 systemd[1]: Reached target getty.target - Login Prompts. Jun 21 02:30:10.150296 systemd-networkd[1379]: eth0: Gained IPv6LL Jun 21 02:30:10.152491 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 21 02:30:10.154256 systemd[1]: Reached target network-online.target - Network is Online. Jun 21 02:30:10.156626 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jun 21 02:30:10.158607 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 21 02:30:10.186976 systemd[1]: coreos-metadata.service: Deactivated successfully. Jun 21 02:30:10.187218 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jun 21 02:30:10.188808 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 21 02:30:10.194344 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 21 02:30:10.195834 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 21 02:30:10.198171 systemd[1]: Startup finished in 2.156s (kernel) + 3.815s (initrd) + 2.811s (userspace) = 8.784s. Jun 21 02:30:16.831520 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 21 02:30:16.832945 systemd[1]: Started sshd@0-10.0.0.138:22-10.0.0.1:45684.service - OpenSSH per-connection server daemon (10.0.0.1:45684). Jun 21 02:30:16.895063 sshd[1536]: Accepted publickey for core from 10.0.0.1 port 45684 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:16.897015 sshd-session[1536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:16.908204 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 21 02:30:16.910290 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 21 02:30:16.916862 systemd-logind[1394]: New session 1 of user core. Jun 21 02:30:16.928919 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 21 02:30:16.933324 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 21 02:30:16.957044 (systemd)[1540]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 21 02:30:16.959396 systemd-logind[1394]: New session c1 of user core. Jun 21 02:30:17.066376 systemd[1540]: Queued start job for default target default.target. Jun 21 02:30:17.084961 systemd[1540]: Created slice app.slice - User Application Slice. Jun 21 02:30:17.084990 systemd[1540]: Reached target paths.target - Paths. Jun 21 02:30:17.085026 systemd[1540]: Reached target timers.target - Timers. Jun 21 02:30:17.086255 systemd[1540]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 21 02:30:17.094686 systemd[1540]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 21 02:30:17.094742 systemd[1540]: Reached target sockets.target - Sockets. Jun 21 02:30:17.094775 systemd[1540]: Reached target basic.target - Basic System. Jun 21 02:30:17.094813 systemd[1540]: Reached target default.target - Main User Target. Jun 21 02:30:17.094840 systemd[1540]: Startup finished in 129ms. Jun 21 02:30:17.095006 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 21 02:30:17.096536 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 21 02:30:17.154046 systemd[1]: Started sshd@1-10.0.0.138:22-10.0.0.1:45694.service - OpenSSH per-connection server daemon (10.0.0.1:45694). Jun 21 02:30:17.209202 sshd[1551]: Accepted publickey for core from 10.0.0.1 port 45694 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:17.210217 sshd-session[1551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:17.215870 systemd-logind[1394]: New session 2 of user core. Jun 21 02:30:17.223226 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 21 02:30:17.274705 sshd[1553]: Connection closed by 10.0.0.1 port 45694 Jun 21 02:30:17.275302 sshd-session[1551]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:17.287065 systemd[1]: sshd@1-10.0.0.138:22-10.0.0.1:45694.service: Deactivated successfully. Jun 21 02:30:17.289266 systemd[1]: session-2.scope: Deactivated successfully. Jun 21 02:30:17.289959 systemd-logind[1394]: Session 2 logged out. Waiting for processes to exit. Jun 21 02:30:17.291931 systemd[1]: Started sshd@2-10.0.0.138:22-10.0.0.1:45700.service - OpenSSH per-connection server daemon (10.0.0.1:45700). Jun 21 02:30:17.293218 systemd-logind[1394]: Removed session 2. Jun 21 02:30:17.346152 sshd[1559]: Accepted publickey for core from 10.0.0.1 port 45700 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:17.347611 sshd-session[1559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:17.354096 systemd-logind[1394]: New session 3 of user core. Jun 21 02:30:17.372324 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 21 02:30:17.420484 sshd[1561]: Connection closed by 10.0.0.1 port 45700 Jun 21 02:30:17.421036 sshd-session[1559]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:17.430882 systemd[1]: sshd@2-10.0.0.138:22-10.0.0.1:45700.service: Deactivated successfully. Jun 21 02:30:17.433462 systemd[1]: session-3.scope: Deactivated successfully. Jun 21 02:30:17.435929 systemd-logind[1394]: Session 3 logged out. Waiting for processes to exit. Jun 21 02:30:17.439462 systemd[1]: Started sshd@3-10.0.0.138:22-10.0.0.1:45704.service - OpenSSH per-connection server daemon (10.0.0.1:45704). Jun 21 02:30:17.440588 systemd-logind[1394]: Removed session 3. Jun 21 02:30:17.491483 sshd[1567]: Accepted publickey for core from 10.0.0.1 port 45704 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:17.492632 sshd-session[1567]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:17.496513 systemd-logind[1394]: New session 4 of user core. Jun 21 02:30:17.515252 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 21 02:30:17.568079 sshd[1569]: Connection closed by 10.0.0.1 port 45704 Jun 21 02:30:17.568529 sshd-session[1567]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:17.584282 systemd[1]: sshd@3-10.0.0.138:22-10.0.0.1:45704.service: Deactivated successfully. Jun 21 02:30:17.587161 systemd[1]: session-4.scope: Deactivated successfully. Jun 21 02:30:17.588962 systemd-logind[1394]: Session 4 logged out. Waiting for processes to exit. Jun 21 02:30:17.591345 systemd[1]: Started sshd@4-10.0.0.138:22-10.0.0.1:45708.service - OpenSSH per-connection server daemon (10.0.0.1:45708). Jun 21 02:30:17.592416 systemd-logind[1394]: Removed session 4. Jun 21 02:30:17.653694 sshd[1575]: Accepted publickey for core from 10.0.0.1 port 45708 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:17.658248 sshd-session[1575]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:17.662025 systemd-logind[1394]: New session 5 of user core. Jun 21 02:30:17.669217 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 21 02:30:17.736054 sudo[1578]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 21 02:30:17.738671 sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 02:30:17.749657 sudo[1578]: pam_unix(sudo:session): session closed for user root Jun 21 02:30:17.752846 sshd[1577]: Connection closed by 10.0.0.1 port 45708 Jun 21 02:30:17.753402 sshd-session[1575]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:17.766562 systemd[1]: sshd@4-10.0.0.138:22-10.0.0.1:45708.service: Deactivated successfully. Jun 21 02:30:17.769362 systemd[1]: session-5.scope: Deactivated successfully. Jun 21 02:30:17.770495 systemd-logind[1394]: Session 5 logged out. Waiting for processes to exit. Jun 21 02:30:17.773339 systemd[1]: Started sshd@5-10.0.0.138:22-10.0.0.1:45724.service - OpenSSH per-connection server daemon (10.0.0.1:45724). Jun 21 02:30:17.775602 systemd-logind[1394]: Removed session 5. Jun 21 02:30:17.843866 sshd[1584]: Accepted publickey for core from 10.0.0.1 port 45724 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:17.848354 sshd-session[1584]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:17.852478 systemd-logind[1394]: New session 6 of user core. Jun 21 02:30:17.859253 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 21 02:30:17.910027 sudo[1588]: 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 Jun 21 02:30:17.910302 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 02:30:17.933432 sudo[1588]: pam_unix(sudo:session): session closed for user root Jun 21 02:30:17.938213 sudo[1587]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jun 21 02:30:17.938477 sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 02:30:17.947770 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 21 02:30:17.981748 augenrules[1610]: No rules Jun 21 02:30:17.982376 systemd[1]: audit-rules.service: Deactivated successfully. Jun 21 02:30:17.982569 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 21 02:30:17.984647 sudo[1587]: pam_unix(sudo:session): session closed for user root Jun 21 02:30:17.985792 sshd[1586]: Connection closed by 10.0.0.1 port 45724 Jun 21 02:30:17.986140 sshd-session[1584]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:17.997032 systemd[1]: sshd@5-10.0.0.138:22-10.0.0.1:45724.service: Deactivated successfully. Jun 21 02:30:18.002214 systemd[1]: session-6.scope: Deactivated successfully. Jun 21 02:30:18.002901 systemd-logind[1394]: Session 6 logged out. Waiting for processes to exit. Jun 21 02:30:18.006656 systemd[1]: Started sshd@6-10.0.0.138:22-10.0.0.1:45736.service - OpenSSH per-connection server daemon (10.0.0.1:45736). Jun 21 02:30:18.007462 systemd-logind[1394]: Removed session 6. Jun 21 02:30:18.061786 sshd[1620]: Accepted publickey for core from 10.0.0.1 port 45736 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:18.062543 sshd-session[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:18.066275 systemd-logind[1394]: New session 7 of user core. Jun 21 02:30:18.075576 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 21 02:30:18.128991 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 21 02:30:18.138476 (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 Jun 21 02:30:18.399537 dockerd[1624]: time="2025-06-21T02:30:18.399414325Z" level=info msg="Starting up" Jun 21 02:30:18.400832 dockerd[1624]: time="2025-06-21T02:30:18.400157987Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jun 21 02:30:18.424611 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport2530728482-merged.mount: Deactivated successfully. Jun 21 02:30:18.446072 dockerd[1624]: time="2025-06-21T02:30:18.446030941Z" level=info msg="Loading containers: start." Jun 21 02:30:18.456113 kernel: Initializing XFRM netlink socket Jun 21 02:30:18.728763 systemd-networkd[1379]: docker0: Link UP Jun 21 02:30:18.735483 dockerd[1624]: time="2025-06-21T02:30:18.735438672Z" level=info msg="Loading containers: done." Jun 21 02:30:18.749674 dockerd[1624]: time="2025-06-21T02:30:18.749626379Z" 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 Jun 21 02:30:18.749812 dockerd[1624]: time="2025-06-21T02:30:18.749707714Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jun 21 02:30:18.749846 dockerd[1624]: time="2025-06-21T02:30:18.749816840Z" level=info msg="Initializing buildkit" Jun 21 02:30:18.769679 dockerd[1624]: time="2025-06-21T02:30:18.769631943Z" level=info msg="Completed buildkit initialization" Jun 21 02:30:18.776993 dockerd[1624]: time="2025-06-21T02:30:18.776949936Z" level=info msg="Daemon has completed initialization" Jun 21 02:30:18.777177 dockerd[1624]: time="2025-06-21T02:30:18.777108920Z" level=info msg="API listen on /run/docker.sock" Jun 21 02:30:18.777353 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 21 02:30:18.787681 sshd[1622]: Connection closed by 10.0.0.1 port 45736 Jun 21 02:30:18.787552 sshd-session[1620]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:18.795934 systemd[1]: sshd@6-10.0.0.138:22-10.0.0.1:45736.service: Deactivated successfully. Jun 21 02:30:18.800031 systemd[1]: session-7.scope: Deactivated successfully. Jun 21 02:30:18.802040 systemd-logind[1394]: Session 7 logged out. Waiting for processes to exit. Jun 21 02:30:18.804314 systemd[1]: Started sshd@7-10.0.0.138:22-10.0.0.1:45738.service - OpenSSH per-connection server daemon (10.0.0.1:45738). Jun 21 02:30:18.805583 systemd-logind[1394]: Removed session 7. Jun 21 02:30:18.859242 sshd[1828]: Accepted publickey for core from 10.0.0.1 port 45738 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:18.863434 sshd-session[1828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:18.867767 systemd-logind[1394]: New session 8 of user core. Jun 21 02:30:18.878229 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 21 02:30:19.422519 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3556978617-merged.mount: Deactivated successfully. Jun 21 02:30:20.046874 systemd[1]: var-lib-docker-overlay2-5c60e6eda7216cf5b7249b68acb1b5618e83a38c76741ef4b907734f34c826e4\x2dinit-merged.mount: Deactivated successfully. Jun 21 02:30:20.082287 containerd[1465]: time="2025-06-21T02:30:20.082170837Z" level=info msg="connecting to shim 02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a" address="unix:///run/containerd/s/5b28143899707c050b4c78f8ec64c1160527d705265f1f98d8e887c416aae356" namespace=moby protocol=ttrpc version=3 Jun 21 02:30:20.105246 systemd[1]: Started docker-02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a.scope - libcontainer container 02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a. Jun 21 02:30:20.116611 systemd-resolved[1291]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jun 21 02:30:20.137108 kernel: docker0: port 1(vethbca3a31) entered blocking state Jun 21 02:30:20.137183 kernel: docker0: port 1(vethbca3a31) entered disabled state Jun 21 02:30:20.137199 kernel: vethbca3a31: entered allmulticast mode Jun 21 02:30:20.138024 systemd-networkd[1379]: vethbca3a31: Link UP Jun 21 02:30:20.138304 kernel: vethbca3a31: entered promiscuous mode Jun 21 02:30:20.151124 kernel: eth0: renamed from vethee6ee7c Jun 21 02:30:20.157340 kernel: docker0: port 1(vethbca3a31) entered blocking state Jun 21 02:30:20.157407 kernel: docker0: port 1(vethbca3a31) entered forwarding state Jun 21 02:30:20.157472 systemd-networkd[1379]: vethbca3a31: Gained carrier Jun 21 02:30:20.157870 systemd-networkd[1379]: docker0: Gained carrier Jun 21 02:30:20.185507 sshd[1830]: Connection closed by 10.0.0.1 port 45738 Jun 21 02:30:20.186586 sshd-session[1828]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:20.192962 systemd[1]: sshd@7-10.0.0.138:22-10.0.0.1:45738.service: Deactivated successfully. Jun 21 02:30:20.195293 systemd[1]: session-8.scope: Deactivated successfully. Jun 21 02:30:20.196246 systemd-logind[1394]: Session 8 logged out. Waiting for processes to exit. Jun 21 02:30:20.199237 systemd[1]: Started sshd@8-10.0.0.138:22-10.0.0.1:45740.service - OpenSSH per-connection server daemon (10.0.0.1:45740). Jun 21 02:30:20.199666 systemd-logind[1394]: Removed session 8. Jun 21 02:30:20.245221 sshd[1903]: Accepted publickey for core from 10.0.0.1 port 45740 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:20.246659 sshd-session[1903]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:20.251902 systemd-logind[1394]: New session 9 of user core. Jun 21 02:30:20.259246 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 21 02:30:20.313384 sshd[1905]: Connection closed by 10.0.0.1 port 45740 Jun 21 02:30:20.314219 sshd-session[1903]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:20.324623 systemd[1]: sshd@8-10.0.0.138:22-10.0.0.1:45740.service: Deactivated successfully. Jun 21 02:30:20.326181 systemd[1]: session-9.scope: Deactivated successfully. Jun 21 02:30:20.326951 systemd-logind[1394]: Session 9 logged out. Waiting for processes to exit. Jun 21 02:30:20.329508 systemd[1]: Started sshd@9-10.0.0.138:22-10.0.0.1:45742.service - OpenSSH per-connection server daemon (10.0.0.1:45742). Jun 21 02:30:20.330307 systemd-logind[1394]: Removed session 9. Jun 21 02:30:20.390725 sshd[1911]: Accepted publickey for core from 10.0.0.1 port 45742 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:20.392039 sshd-session[1911]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:20.396071 systemd-logind[1394]: New session 10 of user core. Jun 21 02:30:20.408227 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 21 02:30:20.458284 sudo[1914]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1465 Jun 21 02:30:20.458872 sudo[1914]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 02:30:20.465059 sudo[1914]: pam_unix(sudo:session): session closed for user root Jun 21 02:30:20.465523 containerd[1465]: time="2025-06-21T02:30:20.465445259Z" level=info msg="Stop CRI service" Jun 21 02:30:20.465726 dockerd[1624]: time="2025-06-21T02:30:20.465643434Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Jun 21 02:30:20.465726 dockerd[1624]: time="2025-06-21T02:30:20.465692207Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Jun 21 02:30:20.466245 dockerd[1624]: time="2025-06-21T02:30:20.465665779Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Jun 21 02:30:20.466245 dockerd[1624]: time="2025-06-21T02:30:20.465797442Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Jun 21 02:30:20.466292 containerd[1465]: time="2025-06-21T02:30:20.465962541Z" level=info msg="Stop CRI service" Jun 21 02:30:20.467391 sshd[1913]: Connection closed by 10.0.0.1 port 45742 Jun 21 02:30:20.466852 sshd-session[1911]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:20.476962 systemd[1]: containerd.service: Deactivated successfully. Jun 21 02:30:20.477052 systemd[1]: containerd.service: Unit process 1854 (containerd-shim) remains running after unit stopped. Jun 21 02:30:20.477271 systemd[1]: containerd.service: Consumed 349ms CPU time, 135.8M memory peak. Jun 21 02:30:20.477598 systemd[1]: sshd@9-10.0.0.138:22-10.0.0.1:45742.service: Deactivated successfully. Jun 21 02:30:20.478920 systemd[1]: session-10.scope: Deactivated successfully. Jun 21 02:30:20.479511 systemd-logind[1394]: Session 10 logged out. Waiting for processes to exit. Jun 21 02:30:20.482386 systemd[1]: Started sshd@10-10.0.0.138:22-10.0.0.1:45746.service - OpenSSH per-connection server daemon (10.0.0.1:45746). Jun 21 02:30:20.482928 systemd-logind[1394]: Removed session 10. Jun 21 02:30:20.533777 sshd[1921]: Accepted publickey for core from 10.0.0.1 port 45746 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:20.534971 sshd-session[1921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:20.538799 systemd-logind[1394]: New session 11 of user core. Jun 21 02:30:20.549219 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 21 02:30:20.601700 sshd[1923]: Connection closed by 10.0.0.1 port 45746 Jun 21 02:30:20.601935 sshd-session[1921]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:20.605366 systemd[1]: sshd@10-10.0.0.138:22-10.0.0.1:45746.service: Deactivated successfully. Jun 21 02:30:20.606913 systemd[1]: session-11.scope: Deactivated successfully. Jun 21 02:30:20.609625 systemd-logind[1394]: Session 11 logged out. Waiting for processes to exit. Jun 21 02:30:20.610654 systemd-logind[1394]: Removed session 11. Jun 21 02:30:21.798345 systemd-networkd[1379]: docker0: Gained IPv6LL Jun 21 02:30:22.182271 systemd-networkd[1379]: vethbca3a31: Gained IPv6LL Jun 21 02:30:25.727635 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Jun 21 02:30:25.728006 systemd[1]: Stopping docker.service - Docker Application Container Engine... Jun 21 02:30:25.728214 dockerd[1624]: time="2025-06-21T02:30:25.728160656Z" level=info msg="Processing signal 'terminated'" Jun 21 02:30:26.623368 systemd[1]: Started sshd@11-10.0.0.138:22-10.0.0.1:47102.service - OpenSSH per-connection server daemon (10.0.0.1:47102). Jun 21 02:30:26.679318 sshd[1929]: Accepted publickey for core from 10.0.0.1 port 47102 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:26.680972 sshd-session[1929]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:26.685171 systemd-logind[1394]: New session 12 of user core. Jun 21 02:30:26.701360 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 21 02:30:26.759620 sshd[1931]: Connection closed by 10.0.0.1 port 47102 Jun 21 02:30:26.759760 sshd-session[1929]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:26.764454 systemd[1]: sshd@11-10.0.0.138:22-10.0.0.1:47102.service: Deactivated successfully. Jun 21 02:30:26.765937 systemd[1]: session-12.scope: Deactivated successfully. Jun 21 02:30:26.766586 systemd-logind[1394]: Session 12 logged out. Waiting for processes to exit. Jun 21 02:30:26.767526 systemd-logind[1394]: Removed session 12. Jun 21 02:30:27.729159 dockerd[1624]: time="2025-06-21T02:30:27.729071486Z" level=error msg="Error sending stop (signal 15) to container" container=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a error="Cannot kill container 02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" Jun 21 02:30:27.729159 dockerd[1624]: time="2025-06-21T02:30:27.729141627Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a Jun 21 02:30:32.787096 systemd[1]: Started sshd@12-10.0.0.138:22-10.0.0.1:33326.service - OpenSSH per-connection server daemon (10.0.0.1:33326). Jun 21 02:30:32.831770 sshd[1937]: Accepted publickey for core from 10.0.0.1 port 33326 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:32.832917 sshd-session[1937]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:32.837436 systemd-logind[1394]: New session 13 of user core. Jun 21 02:30:32.853253 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 21 02:30:32.905560 sshd[1939]: Connection closed by 10.0.0.1 port 33326 Jun 21 02:30:32.905876 sshd-session[1937]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:32.908482 systemd[1]: sshd@12-10.0.0.138:22-10.0.0.1:33326.service: Deactivated successfully. Jun 21 02:30:32.911407 systemd[1]: session-13.scope: Deactivated successfully. Jun 21 02:30:32.913251 systemd-logind[1394]: Session 13 logged out. Waiting for processes to exit. Jun 21 02:30:32.914429 systemd-logind[1394]: Removed session 13. Jun 21 02:30:37.731819 dockerd[1624]: time="2025-06-21T02:30:37.731742220Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a error="context deadline exceeded" Jun 21 02:30:37.732466 systemd[1]: docker-02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a.scope: Deactivated successfully. Jun 21 02:30:38.929216 systemd[1]: Started sshd@13-10.0.0.138:22-10.0.0.1:33340.service - OpenSSH per-connection server daemon (10.0.0.1:33340). Jun 21 02:30:38.978982 sshd[1945]: Accepted publickey for core from 10.0.0.1 port 33340 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:38.980321 sshd-session[1945]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:38.984653 systemd-logind[1394]: New session 14 of user core. Jun 21 02:30:38.998305 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 21 02:30:39.051716 sshd[1947]: Connection closed by 10.0.0.1 port 33340 Jun 21 02:30:39.052177 sshd-session[1945]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:39.055949 systemd[1]: sshd@13-10.0.0.138:22-10.0.0.1:33340.service: Deactivated successfully. Jun 21 02:30:39.058667 systemd[1]: session-14.scope: Deactivated successfully. Jun 21 02:30:39.059514 systemd-logind[1394]: Session 14 logged out. Waiting for processes to exit. Jun 21 02:30:39.060854 systemd-logind[1394]: Removed session 14. Jun 21 02:30:40.729430 dockerd[1624]: time="2025-06-21T02:30:40.729347243Z" level=error msg="Force shutdown daemon" Jun 21 02:30:40.729771 dockerd[1624]: time="2025-06-21T02:30:40.729524385Z" level=info msg="Daemon shutdown complete" Jun 21 02:30:40.732643 systemd[1]: docker.service: Deactivated successfully. Jun 21 02:30:40.732988 systemd[1]: Stopped docker.service - Docker Application Container Engine. Jun 21 02:30:40.733047 systemd[1]: docker.service: Consumed 819ms CPU time, 129.3M memory peak. Jun 21 02:30:40.733430 systemd[1]: docker.socket: Deactivated successfully. Jun 21 02:30:40.733699 systemd[1]: Closed docker.socket - Docker Socket for the API. Jun 21 02:30:40.733730 systemd[1]: Stopping docker.socket - Docker Socket for the API... Jun 21 02:30:40.735322 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 21 02:30:40.735485 systemd[1]: containerd.service: Found left-over process 1854 (containerd-shim) in control group while starting unit. Ignoring. Jun 21 02:30:40.735494 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jun 21 02:30:40.740420 systemd[1]: Starting containerd.service - containerd container runtime... Jun 21 02:30:40.740862 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 21 02:30:40.751601 systemd[1]: containerd.service: Found left-over process 1854 (containerd-shim) in control group while starting unit. Ignoring. Jun 21 02:30:40.751612 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jun 21 02:30:40.758689 (ntainerd)[1957]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 21 02:30:40.777936 containerd[1957]: time="2025-06-21T02:30:40Z" 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 Jun 21 02:30:40.778525 containerd[1957]: time="2025-06-21T02:30:40.778480873Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 21 02:30:40.786349 containerd[1957]: time="2025-06-21T02:30:40.786304301Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.844µs" Jun 21 02:30:40.786349 containerd[1957]: time="2025-06-21T02:30:40.786336759Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 21 02:30:40.786425 containerd[1957]: time="2025-06-21T02:30:40.786355570Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 21 02:30:40.786425 containerd[1957]: time="2025-06-21T02:30:40.786392031Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 21 02:30:40.786425 containerd[1957]: time="2025-06-21T02:30:40.786404038Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 21 02:30:40.786608 containerd[1957]: time="2025-06-21T02:30:40.786577458Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786641 containerd[1957]: time="2025-06-21T02:30:40.786606595Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786641 containerd[1957]: time="2025-06-21T02:30:40.786618321Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786816 containerd[1957]: time="2025-06-21T02:30:40.786788420Z" 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 Jun 21 02:30:40.786816 containerd[1957]: time="2025-06-21T02:30:40.786804869Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786856 containerd[1957]: time="2025-06-21T02:30:40.786815435Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786856 containerd[1957]: time="2025-06-21T02:30:40.786823240Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786856 containerd[1957]: time="2025-06-21T02:30:40.786848734Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786961 containerd[1957]: time="2025-06-21T02:30:40.786945110Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 21 02:30:40.786982 containerd[1957]: time="2025-06-21T02:30:40.786969644Z" 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 Jun 21 02:30:40.787005 containerd[1957]: time="2025-06-21T02:30:40.786980130Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 21 02:30:40.787023 containerd[1957]: time="2025-06-21T02:30:40.787010748Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 21 02:30:40.788131 containerd[1957]: time="2025-06-21T02:30:40.788100215Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 21 02:30:40.788181 containerd[1957]: time="2025-06-21T02:30:40.788141679Z" level=info msg="metadata content store policy set" policy=shared Jun 21 02:30:40.788286 containerd[1957]: time="2025-06-21T02:30:40.788269993Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 21 02:30:40.788309 containerd[1957]: time="2025-06-21T02:30:40.788301451Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 21 02:30:40.788336 containerd[1957]: time="2025-06-21T02:30:40.788315219Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 21 02:30:40.788336 containerd[1957]: time="2025-06-21T02:30:40.788326266Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 21 02:30:40.788378 containerd[1957]: time="2025-06-21T02:30:40.788338112Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 21 02:30:40.788378 containerd[1957]: time="2025-06-21T02:30:40.788350399Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 21 02:30:40.788378 containerd[1957]: time="2025-06-21T02:30:40.788370131Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 21 02:30:40.788430 containerd[1957]: time="2025-06-21T02:30:40.788381577Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 21 02:30:40.788430 containerd[1957]: time="2025-06-21T02:30:40.788394145Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 21 02:30:40.788430 containerd[1957]: time="2025-06-21T02:30:40.788404431Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 21 02:30:40.788430 containerd[1957]: time="2025-06-21T02:30:40.788414236Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 21 02:30:40.788430 containerd[1957]: time="2025-06-21T02:30:40.788425603Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 21 02:30:40.788632 containerd[1957]: time="2025-06-21T02:30:40.788614312Z" level=info msg="connecting to shim 02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a" address="unix:///run/containerd/s/5b28143899707c050b4c78f8ec64c1160527d705265f1f98d8e887c416aae356" namespace=moby protocol=ttrpc version=3 Jun 21 02:30:40.795562 containerd[1957]: time="2025-06-21T02:30:40.795527255Z" level=info msg="cleaning leaked shim process" id=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a namespace=moby Jun 21 02:30:40.804519 containerd[1957]: time="2025-06-21T02:30:40.804451197Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Jun 21 02:30:40.804837 containerd[1957]: time="2025-06-21T02:30:40.804804640Z" level=info msg="shim disconnected" id=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a namespace=moby Jun 21 02:30:40.804891 containerd[1957]: time="2025-06-21T02:30:40.804833857Z" level=warning msg="cleaning up after shim disconnected" id=02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a namespace=moby Jun 21 02:30:40.804891 containerd[1957]: time="2025-06-21T02:30:40.804882165Z" level=info msg="cleaning up dead shim" namespace=moby Jun 21 02:30:40.814507 containerd[1957]: time="2025-06-21T02:30:40.814408654Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/6: file already closed" namespace=moby Jun 21 02:30:40.814607 containerd[1957]: time="2025-06-21T02:30:40.814587077Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 21 02:30:40.814631 containerd[1957]: time="2025-06-21T02:30:40.814623538Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 21 02:30:40.814650 containerd[1957]: time="2025-06-21T02:30:40.814645070Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 21 02:30:40.814669 containerd[1957]: time="2025-06-21T02:30:40.814656997Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 21 02:30:40.814686 containerd[1957]: time="2025-06-21T02:30:40.814667683Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 21 02:30:40.814686 containerd[1957]: time="2025-06-21T02:30:40.814679290Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 21 02:30:40.814719 containerd[1957]: time="2025-06-21T02:30:40.814691857Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 21 02:30:40.814719 containerd[1957]: time="2025-06-21T02:30:40.814702423Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 21 02:30:40.814719 containerd[1957]: time="2025-06-21T02:30:40.814714190Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 21 02:30:40.814774 containerd[1957]: time="2025-06-21T02:30:40.814726717Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 21 02:30:40.814774 containerd[1957]: time="2025-06-21T02:30:40.814737283Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 21 02:30:40.814827 containerd[1957]: time="2025-06-21T02:30:40.814802801Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 21 02:30:40.814850 containerd[1957]: time="2025-06-21T02:30:40.814830177Z" level=info msg="Start snapshots syncer" Jun 21 02:30:40.814867 containerd[1957]: time="2025-06-21T02:30:40.814856432Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 21 02:30:40.815207 containerd[1957]: time="2025-06-21T02:30:40.815071316Z" 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\"}" Jun 21 02:30:40.815295 containerd[1957]: time="2025-06-21T02:30:40.815212157Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 21 02:30:40.815295 containerd[1957]: time="2025-06-21T02:30:40.815287600Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 21 02:30:40.815356 containerd[1957]: time="2025-06-21T02:30:40.815339350Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 21 02:30:40.815376 containerd[1957]: time="2025-06-21T02:30:40.815363044Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 21 02:30:40.815406 containerd[1957]: time="2025-06-21T02:30:40.815374210Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 21 02:30:40.815406 containerd[1957]: time="2025-06-21T02:30:40.815384056Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 21 02:30:40.815406 containerd[1957]: time="2025-06-21T02:30:40.815395382Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 21 02:30:40.815406 containerd[1957]: time="2025-06-21T02:30:40.815405508Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 21 02:30:40.815476 containerd[1957]: time="2025-06-21T02:30:40.815415834Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 21 02:30:40.815476 containerd[1957]: time="2025-06-21T02:30:40.815440168Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 21 02:30:40.815476 containerd[1957]: time="2025-06-21T02:30:40.815450934Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 21 02:30:40.815476 containerd[1957]: time="2025-06-21T02:30:40.815470186Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 21 02:30:40.815543 containerd[1957]: time="2025-06-21T02:30:40.815502964Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 21 02:30:40.815543 containerd[1957]: time="2025-06-21T02:30:40.815518293Z" 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 Jun 21 02:30:40.815543 containerd[1957]: time="2025-06-21T02:30:40.815527579Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:30:40.815543 containerd[1957]: time="2025-06-21T02:30:40.815536544Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815544108Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815554114Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815567362Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815582530Z" level=info msg="runtime interface created" Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815587373Z" level=info msg="created NRI interface" Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815595378Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 21 02:30:40.815610 containerd[1957]: time="2025-06-21T02:30:40.815606064Z" level=info msg="Connect containerd service" Jun 21 02:30:40.815723 containerd[1957]: time="2025-06-21T02:30:40.815631799Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 21 02:30:40.816175 containerd[1957]: time="2025-06-21T02:30:40.816143454Z" 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" Jun 21 02:30:40.824945 containerd[1957]: time="2025-06-21T02:30:40.824865719Z" level=info msg="Start subscribing containerd event" Jun 21 02:30:40.825059 containerd[1957]: time="2025-06-21T02:30:40.824941003Z" level=info msg="Start recovering state" Jun 21 02:30:40.825170 containerd[1957]: time="2025-06-21T02:30:40.825144320Z" level=info msg="Start event monitor" Jun 21 02:30:40.825170 containerd[1957]: time="2025-06-21T02:30:40.825165932Z" level=info msg="Start cni network conf syncer for default" Jun 21 02:30:40.825212 containerd[1957]: time="2025-06-21T02:30:40.825174337Z" level=info msg="Start streaming server" Jun 21 02:30:40.825212 containerd[1957]: time="2025-06-21T02:30:40.825188185Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 21 02:30:40.825212 containerd[1957]: time="2025-06-21T02:30:40.825197310Z" level=info msg="runtime interface starting up..." Jun 21 02:30:40.825212 containerd[1957]: time="2025-06-21T02:30:40.825203954Z" level=info msg="starting plugins..." Jun 21 02:30:40.825295 containerd[1957]: time="2025-06-21T02:30:40.825217642Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 21 02:30:40.825314 containerd[1957]: time="2025-06-21T02:30:40.825034296Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 21 02:30:40.825359 containerd[1957]: time="2025-06-21T02:30:40.825345636Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 21 02:30:40.825439 containerd[1957]: time="2025-06-21T02:30:40.825408912Z" level=info msg="containerd successfully booted in 0.048443s" Jun 21 02:30:40.825665 systemd[1]: Started containerd.service - containerd container runtime. Jun 21 02:30:40.827741 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 21 02:30:40.837413 (dockerd)[2006]: 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 Jun 21 02:30:40.861010 dockerd[2006]: time="2025-06-21T02:30:40.860713534Z" level=info msg="Starting up" Jun 21 02:30:40.862219 dockerd[2006]: time="2025-06-21T02:30:40.862167172Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jun 21 02:30:40.882334 systemd[1]: var-lib-docker-overlay2-check\x2doverlayfs\x2dsupport2209691529-merged.mount: Deactivated successfully. Jun 21 02:30:40.893109 dockerd[2006]: time="2025-06-21T02:30:40.892841406Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Jun 21 02:30:40.902193 dockerd[2006]: time="2025-06-21T02:30:40.902150129Z" level=info msg="Loading containers: start." Jun 21 02:30:40.915345 systemd[1]: var-lib-docker-overlay2-5c60e6eda7216cf5b7249b68acb1b5618e83a38c76741ef4b907734f34c826e4-merged.mount: Deactivated successfully. Jun 21 02:30:41.246224 kernel: docker0: port 1(vethbca3a31) entered disabled state Jun 21 02:30:41.246247 systemd-networkd[1379]: vethbca3a31: Link DOWN Jun 21 02:30:41.246254 systemd-networkd[1379]: vethbca3a31: Lost carrier Jun 21 02:30:41.248172 kernel: vethbca3a31 (unregistering): left allmulticast mode Jun 21 02:30:41.248235 kernel: vethbca3a31 (unregistering): left promiscuous mode Jun 21 02:30:41.249137 kernel: docker0: port 1(vethbca3a31) entered disabled state Jun 21 02:30:41.249661 systemd-networkd[1379]: docker0: Lost carrier Jun 21 02:30:41.250297 dockerd[2006]: time="2025-06-21T02:30:41.250255059Z" level=info msg="Removing stale sandbox 843a55584f26292b37ba032b5c9217d37bbe1771937bca1ca070a47fca3027d5 (02fc2883bcfd2f3d4a547fccfee1835f8c313c6c50ce4377c6aa1af5d83b8f9a)" Jun 21 02:30:41.253072 dockerd[2006]: time="2025-06-21T02:30:41.253021363Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Jun 21 02:30:41.253072 dockerd[2006]: time="2025-06-21T02:30:41.253069630Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 74d93b85a273e2173c5fc51cae364eb19ff03fdebd4c7f24e121b9fed027b003 cda96298884aa6d0f98458b07395dab870395de6f35181eda5071b75f8d741e9], retrying...." Jun 21 02:30:41.470659 dockerd[2006]: time="2025-06-21T02:30:41.470563353Z" level=warning msg="error locating sandbox id 843a55584f26292b37ba032b5c9217d37bbe1771937bca1ca070a47fca3027d5: sandbox 843a55584f26292b37ba032b5c9217d37bbe1771937bca1ca070a47fca3027d5 not found" Jun 21 02:30:41.471407 dockerd[2006]: time="2025-06-21T02:30:41.470839067Z" level=info msg="Loading containers: done." Jun 21 02:30:41.481862 dockerd[2006]: time="2025-06-21T02:30:41.481810431Z" 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 Jun 21 02:30:41.481980 dockerd[2006]: time="2025-06-21T02:30:41.481889996Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jun 21 02:30:41.481980 dockerd[2006]: time="2025-06-21T02:30:41.481929178Z" level=info msg="Initializing buildkit" Jun 21 02:30:41.493611 dockerd[2006]: time="2025-06-21T02:30:41.493572957Z" level=info msg="Completed buildkit initialization" Jun 21 02:30:41.500076 dockerd[2006]: time="2025-06-21T02:30:41.499022079Z" level=info msg="Daemon has completed initialization" Jun 21 02:30:41.500076 dockerd[2006]: time="2025-06-21T02:30:41.499069145Z" level=info msg="API listen on /run/docker.sock" Jun 21 02:30:41.499950 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 21 02:30:41.879107 systemd[1]: run-docker-netns-843a55584f26.mount: Deactivated successfully. Jun 21 02:30:45.071294 systemd[1]: Started sshd@14-10.0.0.138:22-10.0.0.1:56706.service - OpenSSH per-connection server daemon (10.0.0.1:56706). Jun 21 02:30:45.122926 sshd[2227]: Accepted publickey for core from 10.0.0.1 port 56706 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:45.124134 sshd-session[2227]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:45.128590 systemd-logind[1394]: New session 15 of user core. Jun 21 02:30:45.139239 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 21 02:30:45.193162 sshd[2229]: Connection closed by 10.0.0.1 port 56706 Jun 21 02:30:45.193656 sshd-session[2227]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:45.213348 systemd[1]: sshd@14-10.0.0.138:22-10.0.0.1:56706.service: Deactivated successfully. Jun 21 02:30:45.214812 systemd[1]: session-15.scope: Deactivated successfully. Jun 21 02:30:45.217408 systemd-logind[1394]: Session 15 logged out. Waiting for processes to exit. Jun 21 02:30:45.218585 systemd[1]: Started sshd@15-10.0.0.138:22-10.0.0.1:56710.service - OpenSSH per-connection server daemon (10.0.0.1:56710). Jun 21 02:30:45.222838 systemd-logind[1394]: Removed session 15. Jun 21 02:30:45.272195 sshd[2235]: Accepted publickey for core from 10.0.0.1 port 56710 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:45.272157 sshd-session[2235]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:45.276813 systemd-logind[1394]: New session 16 of user core. Jun 21 02:30:45.288283 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 21 02:30:45.341047 sshd[2237]: Connection closed by 10.0.0.1 port 56710 Jun 21 02:30:45.342587 sshd-session[2235]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:45.354141 systemd[1]: sshd@15-10.0.0.138:22-10.0.0.1:56710.service: Deactivated successfully. Jun 21 02:30:45.355619 systemd[1]: session-16.scope: Deactivated successfully. Jun 21 02:30:45.356278 systemd-logind[1394]: Session 16 logged out. Waiting for processes to exit. Jun 21 02:30:45.358191 systemd[1]: Started sshd@16-10.0.0.138:22-10.0.0.1:56714.service - OpenSSH per-connection server daemon (10.0.0.1:56714). Jun 21 02:30:45.359665 systemd-logind[1394]: Removed session 16. Jun 21 02:30:45.413156 sshd[2243]: Accepted publickey for core from 10.0.0.1 port 56714 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:45.414441 sshd-session[2243]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:45.419319 systemd-logind[1394]: New session 17 of user core. Jun 21 02:30:45.432264 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 21 02:30:45.489987 sshd[2245]: Connection closed by 10.0.0.1 port 56714 Jun 21 02:30:45.491727 sshd-session[2243]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:45.504368 systemd[1]: sshd@16-10.0.0.138:22-10.0.0.1:56714.service: Deactivated successfully. Jun 21 02:30:45.505924 systemd[1]: session-17.scope: Deactivated successfully. Jun 21 02:30:45.506575 systemd-logind[1394]: Session 17 logged out. Waiting for processes to exit. Jun 21 02:30:45.508880 systemd[1]: Started sshd@17-10.0.0.138:22-10.0.0.1:56728.service - OpenSSH per-connection server daemon (10.0.0.1:56728). Jun 21 02:30:45.509495 systemd-logind[1394]: Removed session 17. Jun 21 02:30:45.579893 sshd[2258]: Accepted publickey for core from 10.0.0.1 port 56728 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:45.581249 sshd-session[2258]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:45.585139 systemd-logind[1394]: New session 18 of user core. Jun 21 02:30:45.599249 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 21 02:30:45.670556 systemd[1]: var-lib-docker-overlay2-303e5a8f6c2087c5927524359c11f95b743376cb92d1c4bc3dfd3b42f4853c17\x2dinit-merged.mount: Deactivated successfully. Jun 21 02:30:45.702026 containerd[1957]: time="2025-06-21T02:30:45.701979753Z" level=info msg="connecting to shim fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53" address="unix:///run/containerd/s/7b395848dc8c61be17e76c0842adac71dd051c8945f39af34c805836fa582831" namespace=moby protocol=ttrpc version=3 Jun 21 02:30:45.724239 systemd[1]: Started docker-fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53.scope - libcontainer container fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53. Jun 21 02:30:45.734673 systemd-resolved[1291]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jun 21 02:30:45.749482 kernel: docker0: port 1(veth1c02a82) entered blocking state Jun 21 02:30:45.749586 kernel: docker0: port 1(veth1c02a82) entered disabled state Jun 21 02:30:45.749616 kernel: veth1c02a82: entered allmulticast mode Jun 21 02:30:45.749632 kernel: veth1c02a82: entered promiscuous mode Jun 21 02:30:45.750375 systemd-networkd[1379]: veth1c02a82: Link UP Jun 21 02:30:45.757114 kernel: eth0: renamed from veth8995662 Jun 21 02:30:45.757193 kernel: docker0: port 1(veth1c02a82) entered blocking state Jun 21 02:30:45.757209 kernel: docker0: port 1(veth1c02a82) entered forwarding state Jun 21 02:30:45.759197 systemd-networkd[1379]: veth1c02a82: Gained carrier Jun 21 02:30:45.759432 systemd-networkd[1379]: docker0: Gained carrier Jun 21 02:30:45.779472 sshd[2260]: Connection closed by 10.0.0.1 port 56728 Jun 21 02:30:45.780656 sshd-session[2258]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:45.792189 systemd[1]: sshd@17-10.0.0.138:22-10.0.0.1:56728.service: Deactivated successfully. Jun 21 02:30:45.793624 systemd[1]: session-18.scope: Deactivated successfully. Jun 21 02:30:45.795156 systemd-logind[1394]: Session 18 logged out. Waiting for processes to exit. Jun 21 02:30:45.796961 systemd[1]: Started sshd@18-10.0.0.138:22-10.0.0.1:56730.service - OpenSSH per-connection server daemon (10.0.0.1:56730). Jun 21 02:30:45.798030 systemd-logind[1394]: Removed session 18. Jun 21 02:30:45.859758 sshd[2330]: Accepted publickey for core from 10.0.0.1 port 56730 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:45.860948 sshd-session[2330]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:45.864750 systemd-logind[1394]: New session 19 of user core. Jun 21 02:30:45.872222 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 21 02:30:45.924412 sshd[2332]: Connection closed by 10.0.0.1 port 56730 Jun 21 02:30:45.924746 sshd-session[2330]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:45.940059 systemd[1]: sshd@18-10.0.0.138:22-10.0.0.1:56730.service: Deactivated successfully. Jun 21 02:30:45.943457 systemd[1]: session-19.scope: Deactivated successfully. Jun 21 02:30:45.944102 systemd-logind[1394]: Session 19 logged out. Waiting for processes to exit. Jun 21 02:30:45.947315 systemd[1]: Started sshd@19-10.0.0.138:22-10.0.0.1:56742.service - OpenSSH per-connection server daemon (10.0.0.1:56742). Jun 21 02:30:45.948311 systemd-logind[1394]: Removed session 19. Jun 21 02:30:45.999179 sshd[2338]: Accepted publickey for core from 10.0.0.1 port 56742 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:46.000436 sshd-session[2338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:46.004897 systemd-logind[1394]: New session 20 of user core. Jun 21 02:30:46.012249 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 21 02:30:46.064268 sudo[2341]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1957 Jun 21 02:30:46.064538 sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 21 02:30:46.067723 sudo[2341]: pam_unix(sudo:session): session closed for user root Jun 21 02:30:46.069189 sshd[2340]: Connection closed by 10.0.0.1 port 56742 Jun 21 02:30:46.070062 dockerd[2006]: time="2025-06-21T02:30:46.069874573Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Jun 21 02:30:46.070062 dockerd[2006]: time="2025-06-21T02:30:46.069912111Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Jun 21 02:30:46.070062 dockerd[2006]: time="2025-06-21T02:30:46.069886299Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Jun 21 02:30:46.070062 dockerd[2006]: time="2025-06-21T02:30:46.070034609Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Jun 21 02:30:46.070267 sshd-session[2338]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:46.083886 systemd[1]: containerd.service: Deactivated successfully. Jun 21 02:30:46.084000 systemd[1]: containerd.service: Unit process 2279 (containerd-shim) remains running after unit stopped. Jun 21 02:30:46.084416 systemd[1]: containerd.service: Consumed 142ms CPU time, 135.8M memory peak. Jun 21 02:30:46.085197 systemd[1]: sshd@19-10.0.0.138:22-10.0.0.1:56742.service: Deactivated successfully. Jun 21 02:30:46.087468 systemd[1]: session-20.scope: Deactivated successfully. Jun 21 02:30:46.088213 systemd-logind[1394]: Session 20 logged out. Waiting for processes to exit. Jun 21 02:30:46.091426 systemd[1]: Started sshd@20-10.0.0.138:22-10.0.0.1:56746.service - OpenSSH per-connection server daemon (10.0.0.1:56746). Jun 21 02:30:46.092167 systemd-logind[1394]: Removed session 20. Jun 21 02:30:46.153377 sshd[2348]: Accepted publickey for core from 10.0.0.1 port 56746 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:46.154840 sshd-session[2348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:46.159147 systemd-logind[1394]: New session 21 of user core. Jun 21 02:30:46.170265 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 21 02:30:46.223150 sshd[2350]: Connection closed by 10.0.0.1 port 56746 Jun 21 02:30:46.223645 sshd-session[2348]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:46.227066 systemd[1]: sshd@20-10.0.0.138:22-10.0.0.1:56746.service: Deactivated successfully. Jun 21 02:30:46.229454 systemd[1]: session-21.scope: Deactivated successfully. Jun 21 02:30:46.230437 systemd-logind[1394]: Session 21 logged out. Waiting for processes to exit. Jun 21 02:30:46.231618 systemd-logind[1394]: Removed session 21. Jun 21 02:30:47.206370 systemd-networkd[1379]: veth1c02a82: Gained IPv6LL Jun 21 02:30:51.211364 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Jun 21 02:30:51.211685 systemd[1]: Stopping docker.service - Docker Application Container Engine... Jun 21 02:30:51.211920 dockerd[2006]: time="2025-06-21T02:30:51.211832371Z" level=info msg="Processing signal 'terminated'" Jun 21 02:30:52.237538 systemd[1]: Started sshd@21-10.0.0.138:22-10.0.0.1:56748.service - OpenSSH per-connection server daemon (10.0.0.1:56748). Jun 21 02:30:52.315533 sshd[2356]: Accepted publickey for core from 10.0.0.1 port 56748 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:52.316992 sshd-session[2356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:52.324683 systemd-logind[1394]: New session 22 of user core. Jun 21 02:30:52.335295 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 21 02:30:52.391937 sshd[2358]: Connection closed by 10.0.0.1 port 56748 Jun 21 02:30:52.392421 sshd-session[2356]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:52.396324 systemd-logind[1394]: Session 22 logged out. Waiting for processes to exit. Jun 21 02:30:52.396474 systemd[1]: sshd@21-10.0.0.138:22-10.0.0.1:56748.service: Deactivated successfully. Jun 21 02:30:52.399398 systemd[1]: session-22.scope: Deactivated successfully. Jun 21 02:30:52.401249 systemd-logind[1394]: Removed session 22. Jun 21 02:30:53.213249 dockerd[2006]: time="2025-06-21T02:30:53.213162327Z" level=error msg="Error sending stop (signal 15) to container" container=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 error="Cannot kill container fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Jun 21 02:30:53.213249 dockerd[2006]: time="2025-06-21T02:30:53.213219629Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 Jun 21 02:30:54.416337 update_engine[1400]: I20250621 02:30:54.416237 1400 update_attempter.cc:509] Updating boot flags... Jun 21 02:30:58.408987 systemd[1]: Started sshd@22-10.0.0.138:22-10.0.0.1:56506.service - OpenSSH per-connection server daemon (10.0.0.1:56506). Jun 21 02:30:58.454019 sshd[2380]: Accepted publickey for core from 10.0.0.1 port 56506 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:30:58.455150 sshd-session[2380]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:30:58.459139 systemd-logind[1394]: New session 23 of user core. Jun 21 02:30:58.467227 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 21 02:30:58.519343 sshd[2382]: Connection closed by 10.0.0.1 port 56506 Jun 21 02:30:58.519658 sshd-session[2380]: pam_unix(sshd:session): session closed for user core Jun 21 02:30:58.522726 systemd[1]: sshd@22-10.0.0.138:22-10.0.0.1:56506.service: Deactivated successfully. Jun 21 02:30:58.524320 systemd[1]: session-23.scope: Deactivated successfully. Jun 21 02:30:58.525946 systemd-logind[1394]: Session 23 logged out. Waiting for processes to exit. Jun 21 02:30:58.526908 systemd-logind[1394]: Removed session 23. Jun 21 02:31:03.213580 dockerd[2006]: time="2025-06-21T02:31:03.213472895Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 error="context deadline exceeded" Jun 21 02:31:03.215603 systemd[1]: docker-fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53.scope: Deactivated successfully. Jun 21 02:31:04.537390 systemd[1]: Started sshd@23-10.0.0.138:22-10.0.0.1:36994.service - OpenSSH per-connection server daemon (10.0.0.1:36994). Jun 21 02:31:04.583290 sshd[2388]: Accepted publickey for core from 10.0.0.1 port 36994 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:31:04.584535 sshd-session[2388]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:31:04.589264 systemd-logind[1394]: New session 24 of user core. Jun 21 02:31:04.607263 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 21 02:31:04.660302 sshd[2390]: Connection closed by 10.0.0.1 port 36994 Jun 21 02:31:04.660795 sshd-session[2388]: pam_unix(sshd:session): session closed for user core Jun 21 02:31:04.664520 systemd[1]: sshd@23-10.0.0.138:22-10.0.0.1:36994.service: Deactivated successfully. Jun 21 02:31:04.666020 systemd[1]: session-24.scope: Deactivated successfully. Jun 21 02:31:04.666772 systemd-logind[1394]: Session 24 logged out. Waiting for processes to exit. Jun 21 02:31:04.668186 systemd-logind[1394]: Removed session 24. Jun 21 02:31:06.212985 dockerd[2006]: time="2025-06-21T02:31:06.212911386Z" level=error msg="Force shutdown daemon" Jun 21 02:31:06.213468 dockerd[2006]: time="2025-06-21T02:31:06.213425996Z" level=info msg="Daemon shutdown complete" Jun 21 02:31:06.216095 dockerd[2006]: time="2025-06-21T02:31:06.213537064Z" 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\"" Jun 21 02:31:06.216095 dockerd[2006]: time="2025-06-21T02:31:06.213573474Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=moby Jun 21 02:31:06.217760 systemd[1]: docker.service: Deactivated successfully. Jun 21 02:31:06.218105 systemd[1]: Stopped docker.service - Docker Application Container Engine. Jun 21 02:31:06.218529 systemd[1]: docker.socket: Deactivated successfully. Jun 21 02:31:06.218806 systemd[1]: Closed docker.socket - Docker Socket for the API. Jun 21 02:31:06.218840 systemd[1]: Stopping docker.socket - Docker Socket for the API... Jun 21 02:31:06.220270 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 21 02:31:06.220422 systemd[1]: containerd.service: Found left-over process 2279 (containerd-shim) in control group while starting unit. Ignoring. Jun 21 02:31:06.220425 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jun 21 02:31:06.226447 systemd[1]: Starting containerd.service - containerd container runtime... Jun 21 02:31:06.227246 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 21 02:31:06.241790 systemd[1]: containerd.service: Found left-over process 2279 (containerd-shim) in control group while starting unit. Ignoring. Jun 21 02:31:06.241793 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jun 21 02:31:06.247155 (ntainerd)[2400]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 21 02:31:06.264477 containerd[2400]: time="2025-06-21T02:31:06Z" 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 Jun 21 02:31:06.264820 containerd[2400]: time="2025-06-21T02:31:06.264781242Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 21 02:31:06.272736 containerd[2400]: time="2025-06-21T02:31:06.272686278Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.002µs" Jun 21 02:31:06.272736 containerd[2400]: time="2025-06-21T02:31:06.272721327Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 21 02:31:06.272736 containerd[2400]: time="2025-06-21T02:31:06.272738251Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 21 02:31:06.272852 containerd[2400]: time="2025-06-21T02:31:06.272772139Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 21 02:31:06.272852 containerd[2400]: time="2025-06-21T02:31:06.272783582Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 21 02:31:06.273139 containerd[2400]: time="2025-06-21T02:31:06.273099262Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273173 containerd[2400]: time="2025-06-21T02:31:06.273137272Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273173 containerd[2400]: time="2025-06-21T02:31:06.273148394Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273349 containerd[2400]: time="2025-06-21T02:31:06.273316237Z" 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 Jun 21 02:31:06.273349 containerd[2400]: time="2025-06-21T02:31:06.273333881Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273349 containerd[2400]: time="2025-06-21T02:31:06.273343884Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273419 containerd[2400]: time="2025-06-21T02:31:06.273352006Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273419 containerd[2400]: time="2025-06-21T02:31:06.273375052Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273456 containerd[2400]: time="2025-06-21T02:31:06.273444709Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 21 02:31:06.273475 containerd[2400]: time="2025-06-21T02:31:06.273464234Z" 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 Jun 21 02:31:06.273495 containerd[2400]: time="2025-06-21T02:31:06.273474117Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 21 02:31:06.273517 containerd[2400]: time="2025-06-21T02:31:06.273507045Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 21 02:31:06.273715 containerd[2400]: time="2025-06-21T02:31:06.273689771Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 21 02:31:06.273737 containerd[2400]: time="2025-06-21T02:31:06.273714137Z" level=info msg="metadata content store policy set" policy=shared Jun 21 02:31:06.273857 containerd[2400]: time="2025-06-21T02:31:06.273831447Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 21 02:31:06.273882 containerd[2400]: time="2025-06-21T02:31:06.273864735Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 21 02:31:06.273882 containerd[2400]: time="2025-06-21T02:31:06.273877739Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273893183Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273904345Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273916148Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273925791Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273936073Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273945956Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273955398Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 21 02:31:06.273995 containerd[2400]: time="2025-06-21T02:31:06.273964360Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 21 02:31:06.274198 containerd[2400]: time="2025-06-21T02:31:06.274070467Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 21 02:31:06.274373 containerd[2400]: time="2025-06-21T02:31:06.274344256Z" level=info msg="connecting to shim fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53" address="unix:///run/containerd/s/7b395848dc8c61be17e76c0842adac71dd051c8945f39af34c805836fa582831" namespace=moby protocol=ttrpc version=3 Jun 21 02:31:06.281696 containerd[2400]: time="2025-06-21T02:31:06.281653422Z" level=info msg="cleaning leaked shim process" id=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 namespace=moby Jun 21 02:31:06.293547 containerd[2400]: time="2025-06-21T02:31:06.293126438Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Jun 21 02:31:06.294924 containerd[2400]: time="2025-06-21T02:31:06.294884242Z" level=info msg="shim disconnected" id=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 namespace=moby Jun 21 02:31:06.295268 containerd[2400]: time="2025-06-21T02:31:06.295200482Z" level=warning msg="cleaning up after shim disconnected" id=fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53 namespace=moby Jun 21 02:31:06.295429 containerd[2400]: time="2025-06-21T02:31:06.295414376Z" level=info msg="cleaning up dead shim" namespace=moby Jun 21 02:31:06.305171 containerd[2400]: time="2025-06-21T02:31:06.305058251Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Jun 21 02:31:06.305352 containerd[2400]: time="2025-06-21T02:31:06.305334360Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 21 02:31:06.305437 containerd[2400]: time="2025-06-21T02:31:06.305423543Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 21 02:31:06.305509 containerd[2400]: time="2025-06-21T02:31:06.305486479Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 21 02:31:06.305565 containerd[2400]: time="2025-06-21T02:31:06.305553496Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 21 02:31:06.305624 containerd[2400]: time="2025-06-21T02:31:06.305612151Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 21 02:31:06.305678 containerd[2400]: time="2025-06-21T02:31:06.305665804Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 21 02:31:06.305743 containerd[2400]: time="2025-06-21T02:31:06.305730060Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 21 02:31:06.305794 containerd[2400]: time="2025-06-21T02:31:06.305782554Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 21 02:31:06.305862 containerd[2400]: time="2025-06-21T02:31:06.305850171Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 21 02:31:06.305921 containerd[2400]: time="2025-06-21T02:31:06.305908145Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 21 02:31:06.305977 containerd[2400]: time="2025-06-21T02:31:06.305965480Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 21 02:31:06.306130 containerd[2400]: time="2025-06-21T02:31:06.306112637Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 21 02:31:06.306190 containerd[2400]: time="2025-06-21T02:31:06.306179214Z" level=info msg="Start snapshots syncer" Jun 21 02:31:06.306320 containerd[2400]: time="2025-06-21T02:31:06.306280799Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 21 02:31:06.306625 containerd[2400]: time="2025-06-21T02:31:06.306580315Z" 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\"}" Jun 21 02:31:06.306787 containerd[2400]: time="2025-06-21T02:31:06.306771203Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 21 02:31:06.306987 containerd[2400]: time="2025-06-21T02:31:06.306929323Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 21 02:31:06.307116 containerd[2400]: time="2025-06-21T02:31:06.307076960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 21 02:31:06.307202 containerd[2400]: time="2025-06-21T02:31:06.307186748Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 21 02:31:06.307256 containerd[2400]: time="2025-06-21T02:31:06.307244483Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 21 02:31:06.307313 containerd[2400]: time="2025-06-21T02:31:06.307300737Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 21 02:31:06.307363 containerd[2400]: time="2025-06-21T02:31:06.307352510Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 21 02:31:06.307413 containerd[2400]: time="2025-06-21T02:31:06.307401522Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 21 02:31:06.307467 containerd[2400]: time="2025-06-21T02:31:06.307453976Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 21 02:31:06.307531 containerd[2400]: time="2025-06-21T02:31:06.307519432Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 21 02:31:06.307593 containerd[2400]: time="2025-06-21T02:31:06.307579327Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 21 02:31:06.307644 containerd[2400]: time="2025-06-21T02:31:06.307632221Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 21 02:31:06.307734 containerd[2400]: time="2025-06-21T02:31:06.307719923Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 21 02:31:06.307815 containerd[2400]: time="2025-06-21T02:31:06.307788580Z" 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 Jun 21 02:31:06.308017 containerd[2400]: time="2025-06-21T02:31:06.307999713Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:31:06.308075 containerd[2400]: time="2025-06-21T02:31:06.308062169Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 21 02:31:06.308181 containerd[2400]: time="2025-06-21T02:31:06.308148351Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 21 02:31:06.308207 containerd[2400]: time="2025-06-21T02:31:06.308183800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 21 02:31:06.308207 containerd[2400]: time="2025-06-21T02:31:06.308197003Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 21 02:31:06.308248 containerd[2400]: time="2025-06-21T02:31:06.308215528Z" level=info msg="runtime interface created" Jun 21 02:31:06.308248 containerd[2400]: time="2025-06-21T02:31:06.308221409Z" level=info msg="created NRI interface" Jun 21 02:31:06.308248 containerd[2400]: time="2025-06-21T02:31:06.308231052Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 21 02:31:06.308248 containerd[2400]: time="2025-06-21T02:31:06.308244495Z" level=info msg="Connect containerd service" Jun 21 02:31:06.308419 containerd[2400]: time="2025-06-21T02:31:06.308278704Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 21 02:31:06.308770 containerd[2400]: time="2025-06-21T02:31:06.308729578Z" 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" Jun 21 02:31:06.319059 containerd[2400]: time="2025-06-21T02:31:06.318953719Z" level=info msg="Start subscribing containerd event" Jun 21 02:31:06.319059 containerd[2400]: time="2025-06-21T02:31:06.319018455Z" level=info msg="Start recovering state" Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319109078Z" level=info msg="Start event monitor" Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319124362Z" level=info msg="Start cni network conf syncer for default" Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319131124Z" level=info msg="Start streaming server" Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319163172Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319170454Z" level=info msg="runtime interface starting up..." Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319175655Z" level=info msg="starting plugins..." Jun 21 02:31:06.319188 containerd[2400]: time="2025-06-21T02:31:06.319183737Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 21 02:31:06.319317 containerd[2400]: time="2025-06-21T02:31:06.319019456Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 21 02:31:06.319317 containerd[2400]: time="2025-06-21T02:31:06.319294445Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 21 02:31:06.319454 containerd[2400]: time="2025-06-21T02:31:06.319351699Z" level=info msg="containerd successfully booted in 0.055034s" Jun 21 02:31:06.319453 systemd[1]: Started containerd.service - containerd container runtime. Jun 21 02:31:06.321106 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 21 02:31:06.336478 (dockerd)[2450]: 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 Jun 21 02:31:06.366118 dockerd[2450]: time="2025-06-21T02:31:06.365811829Z" level=info msg="Starting up" Jun 21 02:31:06.368205 dockerd[2450]: time="2025-06-21T02:31:06.368172185Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jun 21 02:31:06.396307 dockerd[2450]: time="2025-06-21T02:31:06.396258116Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Jun 21 02:31:06.403836 dockerd[2450]: time="2025-06-21T02:31:06.403802021Z" level=info msg="Loading containers: start." Jun 21 02:31:06.418841 systemd[1]: var-lib-docker-overlay2-303e5a8f6c2087c5927524359c11f95b743376cb92d1c4bc3dfd3b42f4853c17-merged.mount: Deactivated successfully. Jun 21 02:31:06.755454 systemd-networkd[1379]: veth1c02a82: Link DOWN Jun 21 02:31:06.755463 systemd-networkd[1379]: veth1c02a82: Lost carrier Jun 21 02:31:06.757162 kernel: docker0: port 1(veth1c02a82) entered disabled state Jun 21 02:31:06.757211 kernel: veth1c02a82 (unregistering): left allmulticast mode Jun 21 02:31:06.757227 kernel: veth1c02a82 (unregistering): left promiscuous mode Jun 21 02:31:06.758531 kernel: docker0: port 1(veth1c02a82) entered disabled state Jun 21 02:31:06.759634 systemd-networkd[1379]: docker0: Lost carrier Jun 21 02:31:06.760219 dockerd[2450]: time="2025-06-21T02:31:06.760184316Z" level=info msg="Removing stale sandbox cac3c41d8a2a8abe41ea45c4c1ffc09e36121178e6295bf04ece741ca962fc9f (fa51c83fe82d6cba26008a3f7c4b1bcbbb59620b1a22cafaa3ce852f10758a53)" Jun 21 02:31:06.763040 dockerd[2450]: time="2025-06-21T02:31:06.762988024Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Jun 21 02:31:06.763040 dockerd[2450]: time="2025-06-21T02:31:06.763041277Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 502f649fdba2c4f144427b88cf57aaa698f05152b286009c68b49ed2b77fc5ec b1a1c4d112b9ca8e23914deab00708c5de8d4b5be1f4ccd4471c0c9bf617d94e], retrying...." Jun 21 02:31:06.930064 dockerd[2450]: time="2025-06-21T02:31:06.930012833Z" level=warning msg="error locating sandbox id cac3c41d8a2a8abe41ea45c4c1ffc09e36121178e6295bf04ece741ca962fc9f: sandbox cac3c41d8a2a8abe41ea45c4c1ffc09e36121178e6295bf04ece741ca962fc9f not found" Jun 21 02:31:06.930064 dockerd[2450]: time="2025-06-21T02:31:06.930056404Z" level=warning msg="error locating sandbox id 843a55584f26292b37ba032b5c9217d37bbe1771937bca1ca070a47fca3027d5: sandbox 843a55584f26292b37ba032b5c9217d37bbe1771937bca1ca070a47fca3027d5 not found" Jun 21 02:31:06.930307 dockerd[2450]: time="2025-06-21T02:31:06.930153068Z" level=info msg="Loading containers: done." Jun 21 02:31:06.939529 dockerd[2450]: time="2025-06-21T02:31:06.939482944Z" 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 Jun 21 02:31:06.939661 dockerd[2450]: time="2025-06-21T02:31:06.939556762Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jun 21 02:31:06.939661 dockerd[2450]: time="2025-06-21T02:31:06.939593692Z" level=info msg="Initializing buildkit" Jun 21 02:31:06.950781 dockerd[2450]: time="2025-06-21T02:31:06.950744707Z" level=info msg="Completed buildkit initialization" Jun 21 02:31:06.955354 dockerd[2450]: time="2025-06-21T02:31:06.955318342Z" level=info msg="Daemon has completed initialization" Jun 21 02:31:06.955412 dockerd[2450]: time="2025-06-21T02:31:06.955366034Z" level=info msg="API listen on /run/docker.sock" Jun 21 02:31:06.955542 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 21 02:31:07.384192 systemd[1]: run-docker-netns-cac3c41d8a2a.mount: Deactivated successfully. Jun 21 02:31:10.684018 systemd[1]: Started sshd@24-10.0.0.138:22-10.0.0.1:37010.service - OpenSSH per-connection server daemon (10.0.0.1:37010). Jun 21 02:31:10.736434 sshd[2671]: Accepted publickey for core from 10.0.0.1 port 37010 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:31:10.737844 sshd-session[2671]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:31:10.741792 systemd-logind[1394]: New session 25 of user core. Jun 21 02:31:10.751278 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 21 02:31:10.806423 sshd[2673]: Connection closed by 10.0.0.1 port 37010 Jun 21 02:31:10.807575 sshd-session[2671]: pam_unix(sshd:session): session closed for user core Jun 21 02:31:10.820147 systemd[1]: sshd@24-10.0.0.138:22-10.0.0.1:37010.service: Deactivated successfully. Jun 21 02:31:10.822397 systemd[1]: session-25.scope: Deactivated successfully. Jun 21 02:31:10.823053 systemd-logind[1394]: Session 25 logged out. Waiting for processes to exit. Jun 21 02:31:10.825269 systemd[1]: Started sshd@25-10.0.0.138:22-10.0.0.1:37012.service - OpenSSH per-connection server daemon (10.0.0.1:37012). Jun 21 02:31:10.826018 systemd-logind[1394]: Removed session 25. Jun 21 02:31:10.908321 sshd[2679]: Accepted publickey for core from 10.0.0.1 port 37012 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:31:10.909581 sshd-session[2679]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:31:10.913461 systemd-logind[1394]: New session 26 of user core. Jun 21 02:31:10.930278 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 21 02:31:10.984355 sshd[2681]: Connection closed by 10.0.0.1 port 37012 Jun 21 02:31:10.984963 sshd-session[2679]: pam_unix(sshd:session): session closed for user core Jun 21 02:31:10.995110 systemd[1]: sshd@25-10.0.0.138:22-10.0.0.1:37012.service: Deactivated successfully. Jun 21 02:31:10.997422 systemd[1]: session-26.scope: Deactivated successfully. Jun 21 02:31:11.000754 systemd-logind[1394]: Session 26 logged out. Waiting for processes to exit. Jun 21 02:31:11.002567 systemd[1]: Started sshd@26-10.0.0.138:22-10.0.0.1:37028.service - OpenSSH per-connection server daemon (10.0.0.1:37028). Jun 21 02:31:11.003935 systemd-logind[1394]: Removed session 26. Jun 21 02:31:11.076793 sshd[2687]: Accepted publickey for core from 10.0.0.1 port 37028 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:31:11.078155 sshd-session[2687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:31:11.083579 systemd-logind[1394]: New session 27 of user core. Jun 21 02:31:11.097354 systemd[1]: Started session-27.scope - Session 27 of User core. Jun 21 02:31:11.154378 sshd[2689]: Connection closed by 10.0.0.1 port 37028 Jun 21 02:31:11.155420 sshd-session[2687]: pam_unix(sshd:session): session closed for user core Jun 21 02:31:11.162042 systemd[1]: sshd@26-10.0.0.138:22-10.0.0.1:37028.service: Deactivated successfully. Jun 21 02:31:11.163483 systemd[1]: session-27.scope: Deactivated successfully. Jun 21 02:31:11.165614 systemd-logind[1394]: Session 27 logged out. Waiting for processes to exit. Jun 21 02:31:11.167936 systemd[1]: Started sshd@27-10.0.0.138:22-10.0.0.1:37044.service - OpenSSH per-connection server daemon (10.0.0.1:37044). Jun 21 02:31:11.169193 systemd-logind[1394]: Removed session 27. Jun 21 02:31:11.222758 sshd[2703]: Accepted publickey for core from 10.0.0.1 port 37044 ssh2: RSA SHA256:7C6jsm4BYxuBnpyGUGr+M8fttrfZ+ALeZqEnZbBdE/w Jun 21 02:31:11.224127 sshd-session[2703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 21 02:31:11.228885 systemd-logind[1394]: New session 28 of user core. Jun 21 02:31:11.244302 systemd[1]: Started session-28.scope - Session 28 of User core. Jun 21 02:31:11.323689 systemd[1]: var-lib-docker-overlay2-b88fed717b1657fad2df8999cf958f141fc5020516a0758a991d83f56ea142a2\x2dinit-merged.mount: Deactivated successfully. Jun 21 02:31:11.340964 systemd[1]: var-lib-docker-overlay2-b88fed717b1657fad2df8999cf958f141fc5020516a0758a991d83f56ea142a2-merged.mount: Deactivated successfully. Jun 21 02:31:11.369685 containerd[2400]: time="2025-06-21T02:31:11.369598130Z" level=info msg="connecting to shim f61d27c6c99426d5cbe29f5beec7da052c1dbbf9e55bf26f05b3404ca38f2146" address="unix:///run/containerd/s/70a7dab8ac54ced78522545f2649e5b2dc95f999227c2c6a873110925f13cbea" namespace=moby protocol=ttrpc version=3 Jun 21 02:31:11.398249 systemd[1]: Started docker-f61d27c6c99426d5cbe29f5beec7da052c1dbbf9e55bf26f05b3404ca38f2146.scope - libcontainer container f61d27c6c99426d5cbe29f5beec7da052c1dbbf9e55bf26f05b3404ca38f2146. Jun 21 02:31:11.409285 systemd-resolved[1291]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jun 21 02:31:11.423462 kernel: docker0: port 1(vethc2cf540) entered blocking state Jun 21 02:31:11.423554 kernel: docker0: port 1(vethc2cf540) entered disabled state Jun 21 02:31:11.423577 kernel: vethc2cf540: entered allmulticast mode Jun 21 02:31:11.425142 kernel: vethc2cf540: entered promiscuous mode Jun 21 02:31:11.425232 systemd-networkd[1379]: vethc2cf540: Link UP Jun 21 02:31:11.430119 kernel: eth0: renamed from vethebdf350 Jun 21 02:31:11.432617 kernel: docker0: port 1(vethc2cf540) entered blocking state Jun 21 02:31:11.432692 kernel: docker0: port 1(vethc2cf540) entered forwarding state Jun 21 02:31:11.432712 systemd-networkd[1379]: vethc2cf540: Gained carrier Jun 21 02:31:11.433263 systemd-networkd[1379]: docker0: Gained carrier Jun 21 02:31:11.457520 sshd[2705]: Connection closed by 10.0.0.1 port 37044 Jun 21 02:31:11.457831 sshd-session[2703]: pam_unix(sshd:session): session closed for user core Jun 21 02:31:11.461497 systemd[1]: sshd@27-10.0.0.138:22-10.0.0.1:37044.service: Deactivated successfully. Jun 21 02:31:11.463344 systemd[1]: session-28.scope: Deactivated successfully. Jun 21 02:31:11.465044 systemd-logind[1394]: Session 28 logged out. Waiting for processes to exit. Jun 21 02:31:11.467675 systemd-logind[1394]: Removed session 28.