Jul 15 04:44:45.823238 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 15 04:44:45.823260 kernel: Linux version 6.12.36-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue Jul 15 03:28:41 -00 2025 Jul 15 04:44:45.823269 kernel: KASLR enabled Jul 15 04:44:45.823275 kernel: efi: EFI v2.7 by EDK II Jul 15 04:44:45.823280 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb221f18 Jul 15 04:44:45.823285 kernel: random: crng init done Jul 15 04:44:45.823292 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Jul 15 04:44:45.823298 kernel: secureboot: Secure boot enabled Jul 15 04:44:45.823303 kernel: ACPI: Early table checksum verification disabled Jul 15 04:44:45.823310 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) Jul 15 04:44:45.823316 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 15 04:44:45.823322 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823327 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823333 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823340 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823347 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823353 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823359 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823365 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823371 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 15 04:44:45.823377 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 15 04:44:45.823383 kernel: ACPI: Use ACPI SPCR as default console: Yes Jul 15 04:44:45.823389 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 04:44:45.823395 kernel: NODE_DATA(0) allocated [mem 0xdc737a00-0xdc73efff] Jul 15 04:44:45.823401 kernel: Zone ranges: Jul 15 04:44:45.823408 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 04:44:45.823414 kernel: DMA32 empty Jul 15 04:44:45.823420 kernel: Normal empty Jul 15 04:44:45.823426 kernel: Device empty Jul 15 04:44:45.823432 kernel: Movable zone start for each node Jul 15 04:44:45.823437 kernel: Early memory node ranges Jul 15 04:44:45.823443 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] Jul 15 04:44:45.823449 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] Jul 15 04:44:45.823455 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] Jul 15 04:44:45.823461 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] Jul 15 04:44:45.823467 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] Jul 15 04:44:45.823473 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] Jul 15 04:44:45.823480 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] Jul 15 04:44:45.823486 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Jul 15 04:44:45.823492 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jul 15 04:44:45.823500 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 15 04:44:45.823506 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 15 04:44:45.823513 kernel: cma: Reserved 16 MiB at 0x00000000d7a00000 on node -1 Jul 15 04:44:45.823530 kernel: psci: probing for conduit method from ACPI. Jul 15 04:44:45.823539 kernel: psci: PSCIv1.1 detected in firmware. Jul 15 04:44:45.823546 kernel: psci: Using standard PSCI v0.2 function IDs Jul 15 04:44:45.823552 kernel: psci: Trusted OS migration not required Jul 15 04:44:45.823558 kernel: psci: SMC Calling Convention v1.1 Jul 15 04:44:45.823565 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 15 04:44:45.823571 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jul 15 04:44:45.823577 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jul 15 04:44:45.823584 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 15 04:44:45.823590 kernel: Detected PIPT I-cache on CPU0 Jul 15 04:44:45.823598 kernel: CPU features: detected: GIC system register CPU interface Jul 15 04:44:45.823604 kernel: CPU features: detected: Spectre-v4 Jul 15 04:44:45.823610 kernel: CPU features: detected: Spectre-BHB Jul 15 04:44:45.823617 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 15 04:44:45.823623 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 15 04:44:45.823629 kernel: CPU features: detected: ARM erratum 1418040 Jul 15 04:44:45.823635 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 15 04:44:45.823642 kernel: alternatives: applying boot alternatives Jul 15 04:44:45.823649 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=71133d47dc7355ed63f3db64861b54679726ebf08c2975c3bf327e76b39a3acd Jul 15 04:44:45.823656 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 15 04:44:45.823662 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 15 04:44:45.823670 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 15 04:44:45.823676 kernel: Fallback order for Node 0: 0 Jul 15 04:44:45.823683 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Jul 15 04:44:45.823689 kernel: Policy zone: DMA Jul 15 04:44:45.823695 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 15 04:44:45.823702 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Jul 15 04:44:45.823708 kernel: software IO TLB: area num 4. Jul 15 04:44:45.823714 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Jul 15 04:44:45.823721 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) Jul 15 04:44:45.823728 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 15 04:44:45.823734 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 15 04:44:45.823741 kernel: rcu: RCU event tracing is enabled. Jul 15 04:44:45.823749 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 15 04:44:45.823756 kernel: Trampoline variant of Tasks RCU enabled. Jul 15 04:44:45.823762 kernel: Tracing variant of Tasks RCU enabled. Jul 15 04:44:45.823769 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 15 04:44:45.823775 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 15 04:44:45.823782 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 15 04:44:45.823789 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 15 04:44:45.823795 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 15 04:44:45.823802 kernel: GICv3: 256 SPIs implemented Jul 15 04:44:45.823808 kernel: GICv3: 0 Extended SPIs implemented Jul 15 04:44:45.823815 kernel: Root IRQ handler: gic_handle_irq Jul 15 04:44:45.823823 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jul 15 04:44:45.823829 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Jul 15 04:44:45.823836 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 15 04:44:45.823843 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 15 04:44:45.823849 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Jul 15 04:44:45.823856 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Jul 15 04:44:45.823862 kernel: GICv3: using LPI property table @0x0000000040130000 Jul 15 04:44:45.823869 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Jul 15 04:44:45.823875 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 15 04:44:45.823882 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 04:44:45.823888 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 15 04:44:45.823895 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 15 04:44:45.823903 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 15 04:44:45.823910 kernel: arm-pv: using stolen time PV Jul 15 04:44:45.823917 kernel: Console: colour dummy device 80x25 Jul 15 04:44:45.823923 kernel: ACPI: Core revision 20240827 Jul 15 04:44:45.823930 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 15 04:44:45.823937 kernel: pid_max: default: 32768 minimum: 301 Jul 15 04:44:45.823954 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 15 04:44:45.823962 kernel: landlock: Up and running. Jul 15 04:44:45.823968 kernel: SELinux: Initializing. Jul 15 04:44:45.823977 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 15 04:44:45.823984 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 15 04:44:45.823991 kernel: rcu: Hierarchical SRCU implementation. Jul 15 04:44:45.823998 kernel: rcu: Max phase no-delay instances is 400. Jul 15 04:44:45.824004 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 15 04:44:45.824011 kernel: Remapping and enabling EFI services. Jul 15 04:44:45.824018 kernel: smp: Bringing up secondary CPUs ... Jul 15 04:44:45.824024 kernel: Detected PIPT I-cache on CPU1 Jul 15 04:44:45.824031 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 15 04:44:45.824039 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Jul 15 04:44:45.824051 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 04:44:45.824058 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 15 04:44:45.824066 kernel: Detected PIPT I-cache on CPU2 Jul 15 04:44:45.824073 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 15 04:44:45.824080 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Jul 15 04:44:45.824087 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 04:44:45.824094 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 15 04:44:45.824101 kernel: Detected PIPT I-cache on CPU3 Jul 15 04:44:45.824109 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 15 04:44:45.824116 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Jul 15 04:44:45.824123 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 15 04:44:45.824130 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 15 04:44:45.824137 kernel: smp: Brought up 1 node, 4 CPUs Jul 15 04:44:45.824144 kernel: SMP: Total of 4 processors activated. Jul 15 04:44:45.824151 kernel: CPU: All CPU(s) started at EL1 Jul 15 04:44:45.824158 kernel: CPU features: detected: 32-bit EL0 Support Jul 15 04:44:45.824165 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 15 04:44:45.824173 kernel: CPU features: detected: Common not Private translations Jul 15 04:44:45.824180 kernel: CPU features: detected: CRC32 instructions Jul 15 04:44:45.824187 kernel: CPU features: detected: Enhanced Virtualization Traps Jul 15 04:44:45.824194 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 15 04:44:45.824201 kernel: CPU features: detected: LSE atomic instructions Jul 15 04:44:45.824208 kernel: CPU features: detected: Privileged Access Never Jul 15 04:44:45.824215 kernel: CPU features: detected: RAS Extension Support Jul 15 04:44:45.824222 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 15 04:44:45.824229 kernel: alternatives: applying system-wide alternatives Jul 15 04:44:45.824238 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jul 15 04:44:45.824245 kernel: Memory: 2421924K/2572288K available (11136K kernel code, 2436K rwdata, 9056K rodata, 39424K init, 1038K bss, 128028K reserved, 16384K cma-reserved) Jul 15 04:44:45.824252 kernel: devtmpfs: initialized Jul 15 04:44:45.824259 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 15 04:44:45.824266 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 15 04:44:45.824274 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 15 04:44:45.824281 kernel: 0 pages in range for non-PLT usage Jul 15 04:44:45.824288 kernel: 508448 pages in range for PLT usage Jul 15 04:44:45.824295 kernel: pinctrl core: initialized pinctrl subsystem Jul 15 04:44:45.824303 kernel: SMBIOS 3.0.0 present. Jul 15 04:44:45.824310 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Jul 15 04:44:45.824317 kernel: DMI: Memory slots populated: 1/1 Jul 15 04:44:45.824324 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 15 04:44:45.824331 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 15 04:44:45.824338 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 15 04:44:45.824345 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 15 04:44:45.824352 kernel: audit: initializing netlink subsys (disabled) Jul 15 04:44:45.824359 kernel: audit: type=2000 audit(0.027:1): state=initialized audit_enabled=0 res=1 Jul 15 04:44:45.824368 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 15 04:44:45.824375 kernel: cpuidle: using governor menu Jul 15 04:44:45.824382 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 15 04:44:45.824389 kernel: ASID allocator initialised with 32768 entries Jul 15 04:44:45.824396 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 15 04:44:45.824403 kernel: Serial: AMBA PL011 UART driver Jul 15 04:44:45.824410 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 15 04:44:45.824417 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jul 15 04:44:45.824424 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jul 15 04:44:45.824433 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jul 15 04:44:45.824440 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 15 04:44:45.824447 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jul 15 04:44:45.824453 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jul 15 04:44:45.824461 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jul 15 04:44:45.824468 kernel: ACPI: Added _OSI(Module Device) Jul 15 04:44:45.824475 kernel: ACPI: Added _OSI(Processor Device) Jul 15 04:44:45.824482 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 15 04:44:45.824489 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 15 04:44:45.824497 kernel: ACPI: Interpreter enabled Jul 15 04:44:45.824504 kernel: ACPI: Using GIC for interrupt routing Jul 15 04:44:45.824511 kernel: ACPI: MCFG table detected, 1 entries Jul 15 04:44:45.824523 kernel: ACPI: CPU0 has been hot-added Jul 15 04:44:45.824530 kernel: ACPI: CPU1 has been hot-added Jul 15 04:44:45.824537 kernel: ACPI: CPU2 has been hot-added Jul 15 04:44:45.824544 kernel: ACPI: CPU3 has been hot-added Jul 15 04:44:45.824551 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 15 04:44:45.824558 kernel: printk: legacy console [ttyAMA0] enabled Jul 15 04:44:45.824567 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 15 04:44:45.824708 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 15 04:44:45.824776 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 15 04:44:45.824836 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 15 04:44:45.824896 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 15 04:44:45.824971 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 15 04:44:45.824981 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 15 04:44:45.824991 kernel: PCI host bridge to bus 0000:00 Jul 15 04:44:45.825061 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 15 04:44:45.825117 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 15 04:44:45.825173 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 15 04:44:45.825227 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 15 04:44:45.825304 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Jul 15 04:44:45.825376 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jul 15 04:44:45.825441 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Jul 15 04:44:45.825502 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Jul 15 04:44:45.825574 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Jul 15 04:44:45.825634 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Jul 15 04:44:45.825693 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Jul 15 04:44:45.825753 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Jul 15 04:44:45.825808 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 15 04:44:45.825861 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 15 04:44:45.825914 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 15 04:44:45.825924 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 15 04:44:45.825931 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 15 04:44:45.825938 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 15 04:44:45.825954 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 15 04:44:45.825961 kernel: iommu: Default domain type: Translated Jul 15 04:44:45.825970 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 15 04:44:45.825977 kernel: efivars: Registered efivars operations Jul 15 04:44:45.825984 kernel: vgaarb: loaded Jul 15 04:44:45.825991 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 15 04:44:45.825998 kernel: VFS: Disk quotas dquot_6.6.0 Jul 15 04:44:45.826005 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 15 04:44:45.826012 kernel: pnp: PnP ACPI init Jul 15 04:44:45.826081 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 15 04:44:45.826090 kernel: pnp: PnP ACPI: found 1 devices Jul 15 04:44:45.826099 kernel: NET: Registered PF_INET protocol family Jul 15 04:44:45.826106 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 15 04:44:45.826113 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 15 04:44:45.826120 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 15 04:44:45.826127 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 15 04:44:45.826134 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 15 04:44:45.826141 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 15 04:44:45.826148 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 15 04:44:45.826155 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 15 04:44:45.826163 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 15 04:44:45.826170 kernel: PCI: CLS 0 bytes, default 64 Jul 15 04:44:45.826177 kernel: kvm [1]: HYP mode not available Jul 15 04:44:45.826184 kernel: Initialise system trusted keyrings Jul 15 04:44:45.826191 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 15 04:44:45.826198 kernel: Key type asymmetric registered Jul 15 04:44:45.826204 kernel: Asymmetric key parser 'x509' registered Jul 15 04:44:45.826211 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 15 04:44:45.826218 kernel: io scheduler mq-deadline registered Jul 15 04:44:45.826226 kernel: io scheduler kyber registered Jul 15 04:44:45.826233 kernel: io scheduler bfq registered Jul 15 04:44:45.826240 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 15 04:44:45.826247 kernel: ACPI: button: Power Button [PWRB] Jul 15 04:44:45.826255 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 15 04:44:45.826314 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 15 04:44:45.826323 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 15 04:44:45.826330 kernel: thunder_xcv, ver 1.0 Jul 15 04:44:45.826337 kernel: thunder_bgx, ver 1.0 Jul 15 04:44:45.826345 kernel: nicpf, ver 1.0 Jul 15 04:44:45.826352 kernel: nicvf, ver 1.0 Jul 15 04:44:45.826424 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 15 04:44:45.826480 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-15T04:44:45 UTC (1752554685) Jul 15 04:44:45.826489 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 15 04:44:45.826496 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jul 15 04:44:45.826503 kernel: watchdog: NMI not fully supported Jul 15 04:44:45.826510 kernel: watchdog: Hard watchdog permanently disabled Jul 15 04:44:45.826526 kernel: NET: Registered PF_INET6 protocol family Jul 15 04:44:45.826533 kernel: Segment Routing with IPv6 Jul 15 04:44:45.826540 kernel: In-situ OAM (IOAM) with IPv6 Jul 15 04:44:45.826547 kernel: NET: Registered PF_PACKET protocol family Jul 15 04:44:45.826554 kernel: Key type dns_resolver registered Jul 15 04:44:45.826560 kernel: registered taskstats version 1 Jul 15 04:44:45.826567 kernel: Loading compiled-in X.509 certificates Jul 15 04:44:45.826574 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.36-flatcar: b5c59c413839929aea5bd4b52ae6eaff0e245cd2' Jul 15 04:44:45.826581 kernel: Demotion targets for Node 0: null Jul 15 04:44:45.826590 kernel: Key type .fscrypt registered Jul 15 04:44:45.826597 kernel: Key type fscrypt-provisioning registered Jul 15 04:44:45.826604 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 15 04:44:45.826611 kernel: ima: Allocated hash algorithm: sha1 Jul 15 04:44:45.826618 kernel: ima: No architecture policies found Jul 15 04:44:45.826625 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 15 04:44:45.826632 kernel: clk: Disabling unused clocks Jul 15 04:44:45.826639 kernel: PM: genpd: Disabling unused power domains Jul 15 04:44:45.826646 kernel: Warning: unable to open an initial console. Jul 15 04:44:45.826654 kernel: Freeing unused kernel memory: 39424K Jul 15 04:44:45.826661 kernel: Run /init as init process Jul 15 04:44:45.826668 kernel: with arguments: Jul 15 04:44:45.826675 kernel: /init Jul 15 04:44:45.826681 kernel: with environment: Jul 15 04:44:45.826688 kernel: HOME=/ Jul 15 04:44:45.826695 kernel: TERM=linux Jul 15 04:44:45.826701 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 15 04:44:45.826709 systemd[1]: Successfully made /usr/ read-only. Jul 15 04:44:45.826720 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) Jul 15 04:44:45.826728 systemd[1]: Detected virtualization kvm. Jul 15 04:44:45.826735 systemd[1]: Detected architecture arm64. Jul 15 04:44:45.826743 systemd[1]: Running in initrd. Jul 15 04:44:45.826750 systemd[1]: No hostname configured, using default hostname. Jul 15 04:44:45.826757 systemd[1]: Hostname set to . Jul 15 04:44:45.826765 systemd[1]: Initializing machine ID from VM UUID. Jul 15 04:44:45.826773 systemd[1]: Queued start job for default target initrd.target. Jul 15 04:44:45.826781 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 04:44:45.826788 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 04:44:45.826796 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 15 04:44:45.826804 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 15 04:44:45.826811 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 15 04:44:45.826820 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 15 04:44:45.826829 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 15 04:44:45.826837 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 15 04:44:45.826844 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 04:44:45.826852 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 15 04:44:45.826859 systemd[1]: Reached target paths.target - Path Units. Jul 15 04:44:45.826866 systemd[1]: Reached target slices.target - Slice Units. Jul 15 04:44:45.826874 systemd[1]: Reached target swap.target - Swaps. Jul 15 04:44:45.826881 systemd[1]: Reached target timers.target - Timer Units. Jul 15 04:44:45.826890 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 15 04:44:45.826898 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 15 04:44:45.826905 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 15 04:44:45.826913 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 15 04:44:45.826920 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 15 04:44:45.826928 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 15 04:44:45.826935 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 04:44:45.826966 systemd[1]: Reached target sockets.target - Socket Units. Jul 15 04:44:45.826974 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 15 04:44:45.826983 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 15 04:44:45.826991 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 15 04:44:45.826999 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). Jul 15 04:44:45.827007 systemd[1]: Starting systemd-fsck-usr.service... Jul 15 04:44:45.827014 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 15 04:44:45.827021 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 15 04:44:45.827029 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 04:44:45.827036 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 15 04:44:45.827045 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 04:44:45.827053 systemd[1]: Finished systemd-fsck-usr.service. Jul 15 04:44:45.827060 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 15 04:44:45.827085 systemd-journald[245]: Collecting audit messages is disabled. Jul 15 04:44:45.827105 systemd-journald[245]: Journal started Jul 15 04:44:45.827123 systemd-journald[245]: Runtime Journal (/run/log/journal/bbffb35469cf4488ad68e0a72dba1552) is 6M, max 48.5M, 42.4M free. Jul 15 04:44:45.816433 systemd-modules-load[247]: Inserted module 'overlay' Jul 15 04:44:45.830777 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 04:44:45.834964 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 15 04:44:45.834991 systemd[1]: Started systemd-journald.service - Journal Service. Jul 15 04:44:45.837000 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 15 04:44:45.839666 kernel: Bridge firewalling registered Jul 15 04:44:45.837233 systemd-modules-load[247]: Inserted module 'br_netfilter' Jul 15 04:44:45.838558 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 15 04:44:45.843533 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 15 04:44:45.845278 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 15 04:44:45.847335 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 15 04:44:45.859658 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 15 04:44:45.866434 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 04:44:45.868977 systemd-tmpfiles[271]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 15 04:44:45.869552 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 15 04:44:45.873531 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 04:44:45.876509 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 15 04:44:45.877769 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 15 04:44:45.881663 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 15 04:44:45.900589 dracut-cmdline[289]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=71133d47dc7355ed63f3db64861b54679726ebf08c2975c3bf327e76b39a3acd Jul 15 04:44:45.918709 systemd-resolved[288]: Positive Trust Anchors: Jul 15 04:44:45.918728 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 04:44:45.918759 systemd-resolved[288]: 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 Jul 15 04:44:45.924614 systemd-resolved[288]: Defaulting to hostname 'linux'. Jul 15 04:44:45.927135 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 15 04:44:45.930442 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 15 04:44:45.980984 kernel: SCSI subsystem initialized Jul 15 04:44:45.988966 kernel: Loading iSCSI transport class v2.0-870. Jul 15 04:44:45.998974 kernel: iscsi: registered transport (tcp) Jul 15 04:44:46.013162 kernel: iscsi: registered transport (qla4xxx) Jul 15 04:44:46.013197 kernel: QLogic iSCSI HBA Driver Jul 15 04:44:46.030287 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 15 04:44:46.046590 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 04:44:46.048833 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 15 04:44:46.095144 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 15 04:44:46.097423 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 15 04:44:46.163988 kernel: raid6: neonx8 gen() 15783 MB/s Jul 15 04:44:46.180969 kernel: raid6: neonx4 gen() 15818 MB/s Jul 15 04:44:46.197963 kernel: raid6: neonx2 gen() 13201 MB/s Jul 15 04:44:46.214959 kernel: raid6: neonx1 gen() 10454 MB/s Jul 15 04:44:46.231973 kernel: raid6: int64x8 gen() 6897 MB/s Jul 15 04:44:46.248960 kernel: raid6: int64x4 gen() 7357 MB/s Jul 15 04:44:46.265959 kernel: raid6: int64x2 gen() 6108 MB/s Jul 15 04:44:46.283122 kernel: raid6: int64x1 gen() 5052 MB/s Jul 15 04:44:46.283150 kernel: raid6: using algorithm neonx4 gen() 15818 MB/s Jul 15 04:44:46.301076 kernel: raid6: .... xor() 12309 MB/s, rmw enabled Jul 15 04:44:46.301113 kernel: raid6: using neon recovery algorithm Jul 15 04:44:46.308334 kernel: xor: measuring software checksum speed Jul 15 04:44:46.308353 kernel: 8regs : 21596 MB/sec Jul 15 04:44:46.308995 kernel: 32regs : 21601 MB/sec Jul 15 04:44:46.310239 kernel: arm64_neon : 27993 MB/sec Jul 15 04:44:46.310250 kernel: xor: using function: arm64_neon (27993 MB/sec) Jul 15 04:44:46.366980 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 15 04:44:46.373665 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 15 04:44:46.376156 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 04:44:46.405785 systemd-udevd[499]: Using default interface naming scheme 'v255'. Jul 15 04:44:46.409851 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 04:44:46.412637 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 15 04:44:46.441623 dracut-pre-trigger[509]: rd.md=0: removing MD RAID activation Jul 15 04:44:46.464657 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 15 04:44:46.467027 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 15 04:44:46.522187 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 04:44:46.525334 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 15 04:44:46.570895 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jul 15 04:44:46.571076 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Jul 15 04:44:46.581966 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 04:44:46.583163 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 04:44:46.583283 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 04:44:46.586891 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 04:44:46.590419 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 04:44:46.619385 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 15 04:44:46.620939 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 04:44:46.623180 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 15 04:44:46.631368 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 15 04:44:46.640876 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 15 04:44:46.647126 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 15 04:44:46.648350 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 15 04:44:46.650647 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 15 04:44:46.653638 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 04:44:46.655787 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 15 04:44:46.658525 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 15 04:44:46.660400 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 15 04:44:46.687931 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 04:44:46.689822 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 15 04:44:47.699941 disk-uuid[592]: The operation has completed successfully. Jul 15 04:44:47.701301 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 15 04:44:47.726052 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 15 04:44:47.726184 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 15 04:44:47.751959 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 15 04:44:47.776966 sh[608]: Success Jul 15 04:44:47.796998 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 15 04:44:47.797051 kernel: device-mapper: uevent: version 1.0.3 Jul 15 04:44:47.798891 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 15 04:44:47.809977 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jul 15 04:44:47.840543 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 15 04:44:47.843494 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 15 04:44:47.857687 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 15 04:44:47.869567 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 15 04:44:47.869614 kernel: BTRFS: device fsid a7b7592d-2d1d-4236-b04f-dc58147b4692 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (620) Jul 15 04:44:47.874444 kernel: BTRFS info (device dm-0): first mount of filesystem a7b7592d-2d1d-4236-b04f-dc58147b4692 Jul 15 04:44:47.874480 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jul 15 04:44:47.874490 kernel: BTRFS info (device dm-0): using free-space-tree Jul 15 04:44:47.878620 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 15 04:44:47.879925 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 15 04:44:47.881353 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 15 04:44:47.882188 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 15 04:44:47.883800 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 15 04:44:47.906910 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (652) Jul 15 04:44:47.906976 kernel: BTRFS info (device vda6): first mount of filesystem 1ba6da34-80a1-4a8c-bd4d-0f30640013e8 Jul 15 04:44:47.906987 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 15 04:44:47.907969 kernel: BTRFS info (device vda6): using free-space-tree Jul 15 04:44:47.914846 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 15 04:44:47.916990 kernel: BTRFS info (device vda6): last unmount of filesystem 1ba6da34-80a1-4a8c-bd4d-0f30640013e8 Jul 15 04:44:47.917346 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 15 04:44:47.993471 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 15 04:44:47.996530 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 15 04:44:48.035720 systemd-networkd[802]: lo: Link UP Jul 15 04:44:48.035733 systemd-networkd[802]: lo: Gained carrier Jul 15 04:44:48.036549 systemd-networkd[802]: Enumeration completed Jul 15 04:44:48.036642 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 15 04:44:48.036973 systemd-networkd[802]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 04:44:48.036977 systemd-networkd[802]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 04:44:48.037702 systemd-networkd[802]: eth0: Link UP Jul 15 04:44:48.037705 systemd-networkd[802]: eth0: Gained carrier Jul 15 04:44:48.037712 systemd-networkd[802]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 04:44:48.038488 systemd[1]: Reached target network.target - Network. Jul 15 04:44:48.057455 ignition[697]: Ignition 2.21.0 Jul 15 04:44:48.057467 ignition[697]: Stage: fetch-offline Jul 15 04:44:48.058008 systemd-networkd[802]: eth0: DHCPv4 address 10.0.0.71/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 15 04:44:48.057495 ignition[697]: no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:48.057502 ignition[697]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:48.057699 ignition[697]: parsed url from cmdline: "" Jul 15 04:44:48.057702 ignition[697]: no config URL provided Jul 15 04:44:48.057706 ignition[697]: reading system config file "/usr/lib/ignition/user.ign" Jul 15 04:44:48.057713 ignition[697]: no config at "/usr/lib/ignition/user.ign" Jul 15 04:44:48.057731 ignition[697]: op(1): [started] loading QEMU firmware config module Jul 15 04:44:48.057735 ignition[697]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 15 04:44:48.066497 ignition[697]: op(1): [finished] loading QEMU firmware config module Jul 15 04:44:48.070213 ignition[697]: parsing config with SHA512: 5ff532f5305fadfbe94a1ba57439e792236a1f18b12c7da2c9ba5fef47c42c5fe88c361a3e73d7afc007661ade0b83afdaf2e832273e7d03e737e7bab0cb5b74 Jul 15 04:44:48.076996 unknown[697]: fetched base config from "system" Jul 15 04:44:48.077007 unknown[697]: fetched user config from "qemu" Jul 15 04:44:48.077171 ignition[697]: fetch-offline: fetch-offline passed Jul 15 04:44:48.079998 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 15 04:44:48.077248 ignition[697]: Ignition finished successfully Jul 15 04:44:48.081993 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 15 04:44:48.082822 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 15 04:44:48.112762 ignition[815]: Ignition 2.21.0 Jul 15 04:44:48.112778 ignition[815]: Stage: kargs Jul 15 04:44:48.112919 ignition[815]: no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:48.112928 ignition[815]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:48.113467 ignition[815]: kargs: kargs passed Jul 15 04:44:48.115632 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 15 04:44:48.113518 ignition[815]: Ignition finished successfully Jul 15 04:44:48.117881 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 15 04:44:48.142771 ignition[823]: Ignition 2.21.0 Jul 15 04:44:48.142787 ignition[823]: Stage: disks Jul 15 04:44:48.142909 ignition[823]: no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:48.142917 ignition[823]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:48.145610 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 15 04:44:48.143429 ignition[823]: disks: disks passed Jul 15 04:44:48.147225 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 15 04:44:48.143469 ignition[823]: Ignition finished successfully Jul 15 04:44:48.148975 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 15 04:44:48.150665 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 15 04:44:48.152486 systemd[1]: Reached target sysinit.target - System Initialization. Jul 15 04:44:48.154082 systemd[1]: Reached target basic.target - Basic System. Jul 15 04:44:48.156807 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 15 04:44:48.191254 systemd-fsck[833]: ROOT: clean, 15/553520 files, 52789/553472 blocks Jul 15 04:44:48.195536 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 15 04:44:48.197885 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 15 04:44:48.266973 kernel: EXT4-fs (vda9): mounted filesystem 4818953b-9d82-47bd-ab58-d0aa5641a19a r/w with ordered data mode. Quota mode: none. Jul 15 04:44:48.267331 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 15 04:44:48.268589 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 15 04:44:48.271886 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 15 04:44:48.273630 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 15 04:44:48.274618 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 15 04:44:48.274676 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 15 04:44:48.274698 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 15 04:44:48.293729 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 15 04:44:48.296520 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 15 04:44:48.301062 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (841) Jul 15 04:44:48.301093 kernel: BTRFS info (device vda6): first mount of filesystem 1ba6da34-80a1-4a8c-bd4d-0f30640013e8 Jul 15 04:44:48.301103 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 15 04:44:48.302975 kernel: BTRFS info (device vda6): using free-space-tree Jul 15 04:44:48.306520 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 15 04:44:48.365014 initrd-setup-root[865]: cut: /sysroot/etc/passwd: No such file or directory Jul 15 04:44:48.368552 initrd-setup-root[872]: cut: /sysroot/etc/group: No such file or directory Jul 15 04:44:48.373134 initrd-setup-root[879]: cut: /sysroot/etc/shadow: No such file or directory Jul 15 04:44:48.376480 initrd-setup-root[886]: cut: /sysroot/etc/gshadow: No such file or directory Jul 15 04:44:48.467132 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 15 04:44:48.469324 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 15 04:44:48.470914 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 15 04:44:48.486975 kernel: BTRFS info (device vda6): last unmount of filesystem 1ba6da34-80a1-4a8c-bd4d-0f30640013e8 Jul 15 04:44:48.502318 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 15 04:44:48.512293 ignition[954]: INFO : Ignition 2.21.0 Jul 15 04:44:48.512293 ignition[954]: INFO : Stage: mount Jul 15 04:44:48.514676 ignition[954]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:48.514676 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:48.514676 ignition[954]: INFO : mount: mount passed Jul 15 04:44:48.514676 ignition[954]: INFO : Ignition finished successfully Jul 15 04:44:48.514764 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 15 04:44:48.517544 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 15 04:44:48.867178 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 15 04:44:48.868754 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 15 04:44:48.889015 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (967) Jul 15 04:44:48.889061 kernel: BTRFS info (device vda6): first mount of filesystem 1ba6da34-80a1-4a8c-bd4d-0f30640013e8 Jul 15 04:44:48.889071 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 15 04:44:48.890954 kernel: BTRFS info (device vda6): using free-space-tree Jul 15 04:44:48.893243 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 15 04:44:48.926003 ignition[984]: INFO : Ignition 2.21.0 Jul 15 04:44:48.926003 ignition[984]: INFO : Stage: files Jul 15 04:44:48.928610 ignition[984]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:48.928610 ignition[984]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:48.928610 ignition[984]: DEBUG : files: compiled without relabeling support, skipping Jul 15 04:44:48.932097 ignition[984]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 15 04:44:48.932097 ignition[984]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 15 04:44:48.938583 ignition[984]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 15 04:44:48.940007 ignition[984]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 15 04:44:48.940007 ignition[984]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 15 04:44:48.939301 unknown[984]: wrote ssh authorized keys file for user: core Jul 15 04:44:48.946348 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 04:44:48.948184 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 04:44:48.948184 ignition[984]: INFO : files: op(4): [started] processing unit "docker.service" Jul 15 04:44:48.948184 ignition[984]: INFO : files: op(4): [finished] processing unit "docker.service" Jul 15 04:44:48.948184 ignition[984]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Jul 15 04:44:48.953707 ignition[984]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 15 04:44:48.957474 ignition[984]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 15 04:44:48.957474 ignition[984]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Jul 15 04:44:48.957474 ignition[984]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Jul 15 04:44:48.979081 ignition[984]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 15 04:44:48.982447 ignition[984]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 15 04:44:48.985083 ignition[984]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Jul 15 04:44:48.985083 ignition[984]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Jul 15 04:44:48.985083 ignition[984]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Jul 15 04:44:48.985083 ignition[984]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 15 04:44:48.985083 ignition[984]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 15 04:44:48.985083 ignition[984]: INFO : files: files passed Jul 15 04:44:48.985083 ignition[984]: INFO : Ignition finished successfully Jul 15 04:44:48.985889 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 15 04:44:48.988321 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 15 04:44:48.990260 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 15 04:44:49.005308 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 15 04:44:49.005411 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 15 04:44:49.010005 initrd-setup-root-after-ignition[1013]: grep: /sysroot/oem/oem-release: No such file or directory Jul 15 04:44:49.011455 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 15 04:44:49.011455 initrd-setup-root-after-ignition[1015]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 15 04:44:49.016935 initrd-setup-root-after-ignition[1019]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 15 04:44:49.012633 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 15 04:44:49.014489 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 15 04:44:49.016274 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 15 04:44:49.069784 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 15 04:44:49.069895 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 15 04:44:49.073272 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 15 04:44:49.075113 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 15 04:44:49.077044 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 15 04:44:49.077864 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 15 04:44:49.107418 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 15 04:44:49.110031 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 15 04:44:49.131245 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 15 04:44:49.133636 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 04:44:49.134992 systemd[1]: Stopped target timers.target - Timer Units. Jul 15 04:44:49.136967 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 15 04:44:49.137100 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 15 04:44:49.139750 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 15 04:44:49.140919 systemd[1]: Stopped target basic.target - Basic System. Jul 15 04:44:49.142910 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 15 04:44:49.144857 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 15 04:44:49.146793 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 15 04:44:49.148728 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 15 04:44:49.150738 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 15 04:44:49.152670 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 15 04:44:49.154809 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 15 04:44:49.156659 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 15 04:44:49.158687 systemd[1]: Stopped target swap.target - Swaps. Jul 15 04:44:49.160237 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 15 04:44:49.160372 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 15 04:44:49.162804 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 15 04:44:49.164852 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 04:44:49.166752 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 15 04:44:49.170012 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 04:44:49.171228 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 15 04:44:49.171346 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 15 04:44:49.174136 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 15 04:44:49.174255 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 15 04:44:49.176248 systemd[1]: Stopped target paths.target - Path Units. Jul 15 04:44:49.178034 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 15 04:44:49.183028 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 04:44:49.184342 systemd[1]: Stopped target slices.target - Slice Units. Jul 15 04:44:49.186539 systemd[1]: Stopped target sockets.target - Socket Units. Jul 15 04:44:49.188273 systemd[1]: iscsid.socket: Deactivated successfully. Jul 15 04:44:49.188365 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 15 04:44:49.190030 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 15 04:44:49.190106 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 15 04:44:49.191810 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 15 04:44:49.191931 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 15 04:44:49.193738 systemd[1]: ignition-files.service: Deactivated successfully. Jul 15 04:44:49.193838 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 15 04:44:49.196228 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 15 04:44:49.198772 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 15 04:44:49.200012 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 15 04:44:49.200132 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 04:44:49.202024 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 15 04:44:49.202130 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 15 04:44:49.207425 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 15 04:44:49.211136 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 15 04:44:49.223036 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 15 04:44:49.227047 ignition[1039]: INFO : Ignition 2.21.0 Jul 15 04:44:49.227047 ignition[1039]: INFO : Stage: umount Jul 15 04:44:49.227047 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 04:44:49.227047 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 15 04:44:49.227047 ignition[1039]: INFO : umount: umount passed Jul 15 04:44:49.227047 ignition[1039]: INFO : Ignition finished successfully Jul 15 04:44:49.227594 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 15 04:44:49.227696 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 15 04:44:49.229833 systemd[1]: Stopped target network.target - Network. Jul 15 04:44:49.231486 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 15 04:44:49.231561 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 15 04:44:49.233541 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 15 04:44:49.233592 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 15 04:44:49.235147 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 15 04:44:49.235194 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 15 04:44:49.236809 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 15 04:44:49.236851 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 15 04:44:49.238711 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 15 04:44:49.240563 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 15 04:44:49.242314 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 15 04:44:49.242401 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 15 04:44:49.243889 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 15 04:44:49.243936 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 15 04:44:49.250200 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 15 04:44:49.251053 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 15 04:44:49.254904 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 15 04:44:49.255158 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 15 04:44:49.255196 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 04:44:49.258105 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 15 04:44:49.261214 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 15 04:44:49.261305 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 15 04:44:49.263397 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 15 04:44:49.265502 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 15 04:44:49.265545 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 15 04:44:49.268047 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 15 04:44:49.269188 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 15 04:44:49.269264 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 15 04:44:49.271514 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 15 04:44:49.271565 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 15 04:44:49.274098 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 15 04:44:49.274140 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 15 04:44:49.276152 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 04:44:49.298765 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 15 04:44:49.298884 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 15 04:44:49.300933 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 15 04:44:49.302463 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 04:44:49.304179 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 15 04:44:49.304243 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 15 04:44:49.305825 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 15 04:44:49.305857 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 04:44:49.307714 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 15 04:44:49.307764 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 15 04:44:49.310583 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 15 04:44:49.310632 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 15 04:44:49.313208 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 15 04:44:49.313261 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 15 04:44:49.316202 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 15 04:44:49.317333 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 15 04:44:49.317399 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 04:44:49.320353 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 15 04:44:49.320417 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 04:44:49.323929 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 15 04:44:49.323991 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 15 04:44:49.327425 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 15 04:44:49.327468 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 04:44:49.329852 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 04:44:49.329898 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 04:44:49.333677 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 15 04:44:49.334975 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 15 04:44:49.336928 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 15 04:44:49.339396 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 15 04:44:49.353775 systemd[1]: Switching root. Jul 15 04:44:49.392337 systemd-journald[245]: Journal stopped Jul 15 04:44:50.121106 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Jul 15 04:44:50.121154 kernel: SELinux: policy capability network_peer_controls=1 Jul 15 04:44:50.121166 kernel: SELinux: policy capability open_perms=1 Jul 15 04:44:50.121175 kernel: SELinux: policy capability extended_socket_class=1 Jul 15 04:44:50.121188 kernel: SELinux: policy capability always_check_network=0 Jul 15 04:44:50.121197 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 15 04:44:50.121206 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 15 04:44:50.121219 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 15 04:44:50.121228 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 15 04:44:50.121237 kernel: SELinux: policy capability userspace_initial_context=0 Jul 15 04:44:50.121249 kernel: audit: type=1403 audit(1752554689.491:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 15 04:44:50.121264 systemd[1]: Successfully loaded SELinux policy in 55.176ms. Jul 15 04:44:50.121279 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.171ms. Jul 15 04:44:50.121292 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) Jul 15 04:44:50.121302 systemd[1]: Detected virtualization kvm. Jul 15 04:44:50.121312 systemd[1]: Detected architecture arm64. Jul 15 04:44:50.121322 systemd[1]: Detected first boot. Jul 15 04:44:50.121332 systemd[1]: Initializing machine ID from VM UUID. Jul 15 04:44:50.121342 zram_generator::config[1085]: No configuration found. Jul 15 04:44:50.121352 kernel: NET: Registered PF_VSOCK protocol family Jul 15 04:44:50.121361 systemd[1]: Populated /etc with preset unit settings. Jul 15 04:44:50.121373 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 15 04:44:50.121384 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 15 04:44:50.121394 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 15 04:44:50.121406 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 15 04:44:50.121416 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 15 04:44:50.121427 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 15 04:44:50.121437 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 15 04:44:50.121447 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 15 04:44:50.121457 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 15 04:44:50.121471 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 15 04:44:50.121481 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 15 04:44:50.121491 systemd[1]: Created slice user.slice - User and Session Slice. Jul 15 04:44:50.121501 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 04:44:50.121521 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 04:44:50.121533 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 15 04:44:50.121543 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 15 04:44:50.121553 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 15 04:44:50.121563 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 15 04:44:50.121573 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jul 15 04:44:50.121584 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 04:44:50.121594 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 15 04:44:50.121604 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 15 04:44:50.121615 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 15 04:44:50.121625 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 15 04:44:50.121635 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 15 04:44:50.121645 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 04:44:50.121655 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 15 04:44:50.121665 systemd[1]: Reached target slices.target - Slice Units. Jul 15 04:44:50.121674 systemd[1]: Reached target swap.target - Swaps. Jul 15 04:44:50.121686 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 15 04:44:50.121696 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 15 04:44:50.121707 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 15 04:44:50.121717 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 15 04:44:50.121727 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 15 04:44:50.121737 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 04:44:50.121747 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 15 04:44:50.121757 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 15 04:44:50.121767 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 15 04:44:50.121777 systemd[1]: Mounting media.mount - External Media Directory... Jul 15 04:44:50.121787 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 15 04:44:50.121799 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 15 04:44:50.121808 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 15 04:44:50.121819 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). Jul 15 04:44:50.121829 systemd[1]: Reached target machines.target - Containers. Jul 15 04:44:50.121839 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 15 04:44:50.121849 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 15 04:44:50.121858 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 15 04:44:50.121869 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 15 04:44:50.121882 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 15 04:44:50.121892 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 15 04:44:50.121901 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 15 04:44:50.121915 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 15 04:44:50.121924 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 15 04:44:50.121935 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 15 04:44:50.121954 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 15 04:44:50.121964 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 15 04:44:50.121975 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 15 04:44:50.121986 systemd[1]: Stopped systemd-fsck-usr.service. Jul 15 04:44:50.121997 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). Jul 15 04:44:50.122007 kernel: fuse: init (API version 7.41) Jul 15 04:44:50.122018 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 15 04:44:50.122029 kernel: loop: module loaded Jul 15 04:44:50.122039 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 15 04:44:50.122050 kernel: ACPI: bus type drm_connector registered Jul 15 04:44:50.122060 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 15 04:44:50.122071 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 15 04:44:50.122082 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 15 04:44:50.122093 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 15 04:44:50.122105 systemd[1]: verity-setup.service: Deactivated successfully. Jul 15 04:44:50.122114 systemd[1]: Stopped verity-setup.service. Jul 15 04:44:50.122124 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 15 04:44:50.122134 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 15 04:44:50.122144 systemd[1]: Mounted media.mount - External Media Directory. Jul 15 04:44:50.122177 systemd-journald[1153]: Collecting audit messages is disabled. Jul 15 04:44:50.122198 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 15 04:44:50.122209 systemd-journald[1153]: Journal started Jul 15 04:44:50.122228 systemd-journald[1153]: Runtime Journal (/run/log/journal/bbffb35469cf4488ad68e0a72dba1552) is 6M, max 48.5M, 42.4M free. Jul 15 04:44:49.879493 systemd[1]: Queued start job for default target multi-user.target. Jul 15 04:44:49.901087 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 15 04:44:49.901451 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 15 04:44:50.125967 systemd[1]: Started systemd-journald.service - Journal Service. Jul 15 04:44:50.126629 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 15 04:44:50.127859 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 15 04:44:50.130000 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 15 04:44:50.131468 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 04:44:50.132993 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 15 04:44:50.133175 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 15 04:44:50.134635 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 04:44:50.134817 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 15 04:44:50.136245 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 04:44:50.136413 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 15 04:44:50.137859 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 04:44:50.138048 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 15 04:44:50.139468 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 15 04:44:50.139659 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 15 04:44:50.141039 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 04:44:50.141210 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 15 04:44:50.142678 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 15 04:44:50.144103 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 04:44:50.145652 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 15 04:44:50.147254 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 15 04:44:50.158680 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 04:44:50.160788 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 15 04:44:50.163035 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 15 04:44:50.164924 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 15 04:44:50.166123 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 15 04:44:50.166178 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 15 04:44:50.168002 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 15 04:44:50.173978 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 15 04:44:50.175175 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 15 04:44:50.176137 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 15 04:44:50.178059 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 15 04:44:50.179335 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 04:44:50.180319 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 15 04:44:50.181614 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 15 04:44:50.184242 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 15 04:44:50.188188 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 15 04:44:50.190763 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 15 04:44:50.193034 systemd-journald[1153]: Time spent on flushing to /var/log/journal/bbffb35469cf4488ad68e0a72dba1552 is 13.403ms for 851 entries. Jul 15 04:44:50.193034 systemd-journald[1153]: System Journal (/var/log/journal/bbffb35469cf4488ad68e0a72dba1552) is 8M, max 195.6M, 187.6M free. Jul 15 04:44:50.213534 systemd-journald[1153]: Received client request to flush runtime journal. Jul 15 04:44:50.194639 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 15 04:44:50.196327 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 15 04:44:50.204999 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 15 04:44:50.206742 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 15 04:44:50.211382 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 15 04:44:50.216135 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 15 04:44:50.217746 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 15 04:44:50.226977 kernel: loop0: detected capacity change from 0 to 105936 Jul 15 04:44:50.237441 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Jul 15 04:44:50.237462 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Jul 15 04:44:50.238247 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 15 04:44:50.238890 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 15 04:44:50.247678 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 15 04:44:50.248157 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 15 04:44:50.250868 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 15 04:44:50.267025 kernel: loop1: detected capacity change from 0 to 134232 Jul 15 04:44:50.282283 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 15 04:44:50.284874 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 15 04:44:50.294296 kernel: loop2: detected capacity change from 0 to 105936 Jul 15 04:44:50.305989 kernel: loop3: detected capacity change from 0 to 134232 Jul 15 04:44:50.306831 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Jul 15 04:44:50.306850 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Jul 15 04:44:50.309897 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 04:44:50.328529 (sd-merge)[1224]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Jul 15 04:44:50.328882 (sd-merge)[1224]: Merged extensions into '/usr'. Jul 15 04:44:50.332039 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 15 04:44:50.334773 systemd[1]: Starting ensure-sysext.service... Jul 15 04:44:50.337188 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 15 04:44:50.358975 systemd[1]: Reload requested from client PID 1228 ('systemctl') (unit ensure-sysext.service)... Jul 15 04:44:50.358995 systemd[1]: Reloading... Jul 15 04:44:50.370072 systemd-tmpfiles[1229]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 15 04:44:50.370914 systemd-tmpfiles[1229]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 15 04:44:50.371218 systemd-tmpfiles[1229]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 15 04:44:50.371398 systemd-tmpfiles[1229]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 15 04:44:50.372031 systemd-tmpfiles[1229]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 15 04:44:50.372233 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. Jul 15 04:44:50.372274 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. Jul 15 04:44:50.375110 systemd-tmpfiles[1229]: Detected autofs mount point /boot during canonicalization of boot. Jul 15 04:44:50.375213 systemd-tmpfiles[1229]: Skipping /boot Jul 15 04:44:50.381203 systemd-tmpfiles[1229]: Detected autofs mount point /boot during canonicalization of boot. Jul 15 04:44:50.381317 systemd-tmpfiles[1229]: Skipping /boot Jul 15 04:44:50.415085 zram_generator::config[1256]: No configuration found. Jul 15 04:44:50.498367 ldconfig[1198]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 15 04:44:50.507530 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. Jul 15 04:44:50.569752 systemd[1]: Reloading finished in 210 ms. Jul 15 04:44:50.593603 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 15 04:44:50.613033 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 04:44:50.624807 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 15 04:44:50.641170 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 15 04:44:50.645170 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 15 04:44:50.650195 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 15 04:44:50.652367 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 15 04:44:50.654812 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 15 04:44:50.658588 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 15 04:44:50.660978 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 15 04:44:50.664304 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 15 04:44:50.666617 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 15 04:44:50.669019 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 15 04:44:50.669130 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). Jul 15 04:44:50.676417 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 04:44:50.679249 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 15 04:44:50.681697 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 04:44:50.682978 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 15 04:44:50.684626 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 04:44:50.684761 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 15 04:44:50.686592 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 04:44:50.686759 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 15 04:44:50.693513 augenrules[1325]: No rules Jul 15 04:44:50.694450 systemd[1]: audit-rules.service: Deactivated successfully. Jul 15 04:44:50.694666 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 15 04:44:50.698253 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 15 04:44:50.700123 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 15 04:44:50.704302 systemd-udevd[1318]: Using default interface naming scheme 'v255'. Jul 15 04:44:50.705568 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 15 04:44:50.706817 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 15 04:44:50.707823 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 15 04:44:50.717141 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 15 04:44:50.721139 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 15 04:44:50.723661 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 15 04:44:50.724814 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 15 04:44:50.724930 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). Jul 15 04:44:50.731484 augenrules[1334]: /sbin/augenrules: No change Jul 15 04:44:50.741690 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 15 04:44:50.744626 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 15 04:44:50.745640 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). Jul 15 04:44:50.748281 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 04:44:50.749251 augenrules[1384]: No rules Jul 15 04:44:50.750146 systemd[1]: audit-rules.service: Deactivated successfully. Jul 15 04:44:50.750320 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 15 04:44:50.751917 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 04:44:50.752100 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 15 04:44:50.754402 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 04:44:50.754569 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 15 04:44:50.756028 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 04:44:50.756173 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 15 04:44:50.757745 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 04:44:50.757888 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 15 04:44:50.759773 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 15 04:44:50.764598 systemd[1]: Finished ensure-sysext.service. Jul 15 04:44:50.774514 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 15 04:44:50.777142 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 04:44:50.777205 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 15 04:44:50.780698 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 15 04:44:50.795648 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jul 15 04:44:50.832475 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 15 04:44:50.834961 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 15 04:44:50.837109 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 15 04:44:50.859966 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 15 04:44:50.907285 systemd-networkd[1396]: lo: Link UP Jul 15 04:44:50.907292 systemd-networkd[1396]: lo: Gained carrier Jul 15 04:44:50.908169 systemd-networkd[1396]: Enumeration completed Jul 15 04:44:50.908267 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 15 04:44:50.908792 systemd-networkd[1396]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 04:44:50.908796 systemd-networkd[1396]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 04:44:50.909398 systemd-networkd[1396]: eth0: Link UP Jul 15 04:44:50.909526 systemd-networkd[1396]: eth0: Gained carrier Jul 15 04:44:50.909540 systemd-networkd[1396]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 04:44:50.910983 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 15 04:44:50.912646 systemd-resolved[1297]: Positive Trust Anchors: Jul 15 04:44:50.912890 systemd-resolved[1297]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 04:44:50.912977 systemd-resolved[1297]: 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 Jul 15 04:44:50.913629 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 15 04:44:50.915219 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 15 04:44:50.916757 systemd[1]: Reached target time-set.target - System Time Set. Jul 15 04:44:50.920240 systemd-resolved[1297]: Defaulting to hostname 'linux'. Jul 15 04:44:50.925011 systemd-networkd[1396]: eth0: DHCPv4 address 10.0.0.71/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 15 04:44:50.925558 systemd-timesyncd[1397]: Network configuration changed, trying to establish connection. Jul 15 04:44:50.925666 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 15 04:44:50.926305 systemd-timesyncd[1397]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 15 04:44:50.926346 systemd-timesyncd[1397]: Initial clock synchronization to Tue 2025-07-15 04:44:51.157263 UTC. Jul 15 04:44:50.927343 systemd[1]: Reached target network.target - Network. Jul 15 04:44:50.928236 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 15 04:44:50.930849 systemd[1]: Reached target sysinit.target - System Initialization. Jul 15 04:44:50.932059 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 15 04:44:50.933274 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 15 04:44:50.934642 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 15 04:44:50.935790 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 15 04:44:50.937023 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 15 04:44:50.938187 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 15 04:44:50.938216 systemd[1]: Reached target paths.target - Path Units. Jul 15 04:44:50.939077 systemd[1]: Reached target timers.target - Timer Units. Jul 15 04:44:50.940609 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 15 04:44:50.942838 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 15 04:44:50.945609 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 15 04:44:50.948232 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 15 04:44:50.949438 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 15 04:44:50.962525 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 15 04:44:50.964018 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 15 04:44:50.967785 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 15 04:44:50.969206 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 15 04:44:50.974915 systemd[1]: Reached target sockets.target - Socket Units. Jul 15 04:44:50.975933 systemd[1]: Reached target basic.target - Basic System. Jul 15 04:44:50.976931 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 15 04:44:50.977190 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 15 04:44:50.978268 systemd[1]: Starting containerd.service - containerd container runtime... Jul 15 04:44:50.981199 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 15 04:44:50.998442 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 15 04:44:51.000773 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 15 04:44:51.002831 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 15 04:44:51.004006 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). Jul 15 04:44:51.005081 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 15 04:44:51.009014 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 15 04:44:51.009605 jq[1439]: false Jul 15 04:44:51.011285 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 15 04:44:51.014746 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 15 04:44:51.016826 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 15 04:44:51.017328 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 15 04:44:51.018249 systemd[1]: Starting update-engine.service - Update Engine... Jul 15 04:44:51.025127 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 15 04:44:51.029675 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 15 04:44:51.031449 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 15 04:44:51.032998 jq[1451]: true Jul 15 04:44:51.034029 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 15 04:44:51.034614 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 15 04:44:51.034780 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 15 04:44:51.041330 extend-filesystems[1440]: Found /dev/vda6 Jul 15 04:44:51.043077 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 04:44:51.049178 jq[1459]: true Jul 15 04:44:51.056613 systemd[1]: motdgen.service: Deactivated successfully. Jul 15 04:44:51.056859 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 15 04:44:51.061420 (ntainerd)[1460]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 15 04:44:51.066546 extend-filesystems[1440]: Found /dev/vda9 Jul 15 04:44:51.071569 extend-filesystems[1440]: Checking size of /dev/vda9 Jul 15 04:44:51.092625 extend-filesystems[1440]: Old size kept for /dev/vda9 Jul 15 04:44:51.094080 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 15 04:44:51.096455 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 15 04:44:51.099223 dbus-daemon[1437]: [system] SELinux support is enabled Jul 15 04:44:51.114961 systemd-logind[1447]: Watching system buttons on /dev/input/event0 (Power Button) Jul 15 04:44:51.116232 systemd-logind[1447]: New seat seat0. Jul 15 04:44:51.121718 update_engine[1450]: I20250715 04:44:51.121513 1450 main.cc:92] Flatcar Update Engine starting Jul 15 04:44:51.126816 bash[1492]: Updated "/home/core/.ssh/authorized_keys" Jul 15 04:44:51.133266 update_engine[1450]: I20250715 04:44:51.133107 1450 update_check_scheduler.cc:74] Next update check in 7m1s Jul 15 04:44:51.153942 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 15 04:44:51.156913 systemd[1]: Started systemd-logind.service - User Login Management. Jul 15 04:44:51.159022 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 04:44:51.160455 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 15 04:44:51.166806 dbus-daemon[1437]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 15 04:44:51.174718 systemd[1]: Started update-engine.service - Update Engine. Jul 15 04:44:51.176930 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 15 04:44:51.177131 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). Jul 15 04:44:51.177256 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 15 04:44:51.178639 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). Jul 15 04:44:51.178754 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 15 04:44:51.182910 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 15 04:44:51.237297 locksmithd[1505]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 15 04:44:51.286893 containerd[1460]: time="2025-07-15T04:44:51Z" 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 Jul 15 04:44:51.288508 containerd[1460]: time="2025-07-15T04:44:51.288444071Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 15 04:44:51.298121 containerd[1460]: time="2025-07-15T04:44:51.298064698Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.247µs" Jul 15 04:44:51.298121 containerd[1460]: time="2025-07-15T04:44:51.298109887Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 15 04:44:51.298217 containerd[1460]: time="2025-07-15T04:44:51.298130752Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 15 04:44:51.298379 containerd[1460]: time="2025-07-15T04:44:51.298345500Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 15 04:44:51.298379 containerd[1460]: time="2025-07-15T04:44:51.298368300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 15 04:44:51.298427 containerd[1460]: time="2025-07-15T04:44:51.298394433Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298463 containerd[1460]: time="2025-07-15T04:44:51.298445918Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298485 containerd[1460]: time="2025-07-15T04:44:51.298461763Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298729 containerd[1460]: time="2025-07-15T04:44:51.298698981Z" 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 Jul 15 04:44:51.298729 containerd[1460]: time="2025-07-15T04:44:51.298721205Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298775 containerd[1460]: time="2025-07-15T04:44:51.298733469Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298775 containerd[1460]: time="2025-07-15T04:44:51.298742976Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 15 04:44:51.298827 containerd[1460]: time="2025-07-15T04:44:51.298811129Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 15 04:44:51.299045 containerd[1460]: time="2025-07-15T04:44:51.299025259Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 15 04:44:51.299070 containerd[1460]: time="2025-07-15T04:44:51.299061516Z" 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 Jul 15 04:44:51.299098 containerd[1460]: time="2025-07-15T04:44:51.299073122Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 15 04:44:51.299118 containerd[1460]: time="2025-07-15T04:44:51.299107651Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 15 04:44:51.300401 containerd[1460]: time="2025-07-15T04:44:51.299774447Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 15 04:44:51.300401 containerd[1460]: time="2025-07-15T04:44:51.299915897Z" level=info msg="metadata content store policy set" policy=shared Jul 15 04:44:51.321391 containerd[1460]: time="2025-07-15T04:44:51.321333022Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 15 04:44:51.321437 containerd[1460]: time="2025-07-15T04:44:51.321407760Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 15 04:44:51.321437 containerd[1460]: time="2025-07-15T04:44:51.321427802Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 15 04:44:51.321507 containerd[1460]: time="2025-07-15T04:44:51.321440272Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 15 04:44:51.321507 containerd[1460]: time="2025-07-15T04:44:51.321453154Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 15 04:44:51.321507 containerd[1460]: time="2025-07-15T04:44:51.321465171Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 15 04:44:51.321507 containerd[1460]: time="2025-07-15T04:44:51.321485831Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 15 04:44:51.321573 containerd[1460]: time="2025-07-15T04:44:51.321508425Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 15 04:44:51.321573 containerd[1460]: time="2025-07-15T04:44:51.321523076Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 15 04:44:51.321573 containerd[1460]: time="2025-07-15T04:44:51.321534559Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 15 04:44:51.321573 containerd[1460]: time="2025-07-15T04:44:51.321544847Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 15 04:44:51.321573 containerd[1460]: time="2025-07-15T04:44:51.321557276Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 15 04:44:51.321751 containerd[1460]: time="2025-07-15T04:44:51.321714324Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 15 04:44:51.321751 containerd[1460]: time="2025-07-15T04:44:51.321743544Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 15 04:44:51.321795 containerd[1460]: time="2025-07-15T04:44:51.321758731Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 15 04:44:51.321795 containerd[1460]: time="2025-07-15T04:44:51.321770871Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 15 04:44:51.321795 containerd[1460]: time="2025-07-15T04:44:51.321782148Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 15 04:44:51.321850 containerd[1460]: time="2025-07-15T04:44:51.321794165Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 15 04:44:51.321850 containerd[1460]: time="2025-07-15T04:44:51.321814743Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 15 04:44:51.321850 containerd[1460]: time="2025-07-15T04:44:51.321826595Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 15 04:44:51.321850 containerd[1460]: time="2025-07-15T04:44:51.321837707Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 15 04:44:51.321926 containerd[1460]: time="2025-07-15T04:44:51.321852770Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 15 04:44:51.321926 containerd[1460]: time="2025-07-15T04:44:51.321864458Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 15 04:44:51.322263 containerd[1460]: time="2025-07-15T04:44:51.322234360Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 15 04:44:51.322263 containerd[1460]: time="2025-07-15T04:44:51.322257489Z" level=info msg="Start snapshots syncer" Jul 15 04:44:51.322315 containerd[1460]: time="2025-07-15T04:44:51.322280577Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 15 04:44:51.322537 containerd[1460]: time="2025-07-15T04:44:51.322487505Z" 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\"}" Jul 15 04:44:51.322627 containerd[1460]: time="2025-07-15T04:44:51.322556440Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 15 04:44:51.322648 containerd[1460]: time="2025-07-15T04:44:51.322628462Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 15 04:44:51.322773 containerd[1460]: time="2025-07-15T04:44:51.322739539Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 15 04:44:51.322800 containerd[1460]: time="2025-07-15T04:44:51.322774069Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 15 04:44:51.322800 containerd[1460]: time="2025-07-15T04:44:51.322786950Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 15 04:44:51.322842 containerd[1460]: time="2025-07-15T04:44:51.322798515Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 15 04:44:51.322842 containerd[1460]: time="2025-07-15T04:44:51.322811190Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 15 04:44:51.322842 containerd[1460]: time="2025-07-15T04:44:51.322823331Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 15 04:44:51.322842 containerd[1460]: time="2025-07-15T04:44:51.322834772Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 15 04:44:51.322918 containerd[1460]: time="2025-07-15T04:44:51.322869631Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 15 04:44:51.322918 containerd[1460]: time="2025-07-15T04:44:51.322885434Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 15 04:44:51.322918 containerd[1460]: time="2025-07-15T04:44:51.322896711Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 15 04:44:51.322967 containerd[1460]: time="2025-07-15T04:44:51.322927495Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 15 04:44:51.322967 containerd[1460]: time="2025-07-15T04:44:51.322942105Z" 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 Jul 15 04:44:51.322967 containerd[1460]: time="2025-07-15T04:44:51.322951447Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:44:51.322967 containerd[1460]: time="2025-07-15T04:44:51.322960625Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:44:51.323070 containerd[1460]: time="2025-07-15T04:44:51.322969226Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 15 04:44:51.323070 containerd[1460]: time="2025-07-15T04:44:51.323006389Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 15 04:44:51.323070 containerd[1460]: time="2025-07-15T04:44:51.323017789Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 15 04:44:51.323162 containerd[1460]: time="2025-07-15T04:44:51.323148786Z" level=info msg="runtime interface created" Jul 15 04:44:51.323162 containerd[1460]: time="2025-07-15T04:44:51.323158210Z" level=info msg="created NRI interface" Jul 15 04:44:51.323202 containerd[1460]: time="2025-07-15T04:44:51.323170598Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 15 04:44:51.323202 containerd[1460]: time="2025-07-15T04:44:51.323182862Z" level=info msg="Connect containerd service" Jul 15 04:44:51.323236 containerd[1460]: time="2025-07-15T04:44:51.323208790Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 15 04:44:51.323956 containerd[1460]: time="2025-07-15T04:44:51.323912584Z" 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" Jul 15 04:44:51.437012 containerd[1460]: time="2025-07-15T04:44:51.436941215Z" level=info msg="Start subscribing containerd event" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437027435Z" level=info msg="Start recovering state" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437107975Z" level=info msg="Start event monitor" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437119581Z" level=info msg="Start cni network conf syncer for default" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437128923Z" level=info msg="Start streaming server" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437137936Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437146167Z" level=info msg="runtime interface starting up..." Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437152094Z" level=info msg="starting plugins..." Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437167486Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 15 04:44:51.437135 containerd[1460]: time="2025-07-15T04:44:51.437204690Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 15 04:44:51.438695 containerd[1460]: time="2025-07-15T04:44:51.437259797Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 15 04:44:51.438695 containerd[1460]: time="2025-07-15T04:44:51.437310335Z" level=info msg="containerd successfully booted in 0.150886s" Jul 15 04:44:51.437411 systemd[1]: Started containerd.service - containerd container runtime. Jul 15 04:44:51.942214 sshd_keygen[1458]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 15 04:44:51.961801 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 15 04:44:51.965954 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 15 04:44:51.989466 systemd[1]: issuegen.service: Deactivated successfully. Jul 15 04:44:51.989675 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 15 04:44:51.992944 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 15 04:44:52.020016 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 15 04:44:52.023641 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 15 04:44:52.026464 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jul 15 04:44:52.028026 systemd[1]: Reached target getty.target - Login Prompts. Jul 15 04:44:52.139295 systemd-networkd[1396]: eth0: Gained IPv6LL Jul 15 04:44:52.145505 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 15 04:44:52.147395 systemd[1]: Reached target network-online.target - Network is Online. Jul 15 04:44:52.149817 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 15 04:44:52.151952 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 15 04:44:52.184218 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 15 04:44:52.186202 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 15 04:44:52.186384 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 15 04:44:52.189959 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 15 04:44:52.190168 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 15 04:44:52.196065 systemd[1]: Startup finished in 2.111s (kernel) + 3.845s (initrd) + 2.771s (userspace) = 8.729s. Jul 15 04:44:59.021924 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 15 04:44:59.022969 systemd[1]: Started sshd@0-10.0.0.71:22-10.0.0.1:59950.service - OpenSSH per-connection server daemon (10.0.0.1:59950). Jul 15 04:44:59.098238 sshd[1563]: Accepted publickey for core from 10.0.0.1 port 59950 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:44:59.103026 sshd-session[1563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:44:59.109007 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 15 04:44:59.109979 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 15 04:44:59.118524 systemd-logind[1447]: New session 1 of user core. Jul 15 04:44:59.134902 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 15 04:44:59.137517 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 15 04:44:59.159111 (systemd)[1568]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 15 04:44:59.161329 systemd-logind[1447]: New session c1 of user core. Jul 15 04:44:59.271868 systemd[1568]: Queued start job for default target default.target. Jul 15 04:44:59.295021 systemd[1568]: Created slice app.slice - User Application Slice. Jul 15 04:44:59.295050 systemd[1568]: Reached target paths.target - Paths. Jul 15 04:44:59.295088 systemd[1568]: Reached target timers.target - Timers. Jul 15 04:44:59.296312 systemd[1568]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 15 04:44:59.307658 systemd[1568]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 15 04:44:59.307765 systemd[1568]: Reached target sockets.target - Sockets. Jul 15 04:44:59.307807 systemd[1568]: Reached target basic.target - Basic System. Jul 15 04:44:59.307836 systemd[1568]: Reached target default.target - Main User Target. Jul 15 04:44:59.307861 systemd[1568]: Startup finished in 141ms. Jul 15 04:44:59.307962 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 15 04:44:59.309494 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 15 04:44:59.380161 systemd[1]: Started sshd@1-10.0.0.71:22-10.0.0.1:59960.service - OpenSSH per-connection server daemon (10.0.0.1:59960). Jul 15 04:44:59.430650 sshd[1579]: Accepted publickey for core from 10.0.0.1 port 59960 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:44:59.431974 sshd-session[1579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:44:59.436968 systemd-logind[1447]: New session 2 of user core. Jul 15 04:44:59.451152 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 15 04:44:59.503851 sshd[1582]: Connection closed by 10.0.0.1 port 59960 Jul 15 04:44:59.504215 sshd-session[1579]: pam_unix(sshd:session): session closed for user core Jul 15 04:44:59.515576 systemd[1]: sshd@1-10.0.0.71:22-10.0.0.1:59960.service: Deactivated successfully. Jul 15 04:44:59.517246 systemd[1]: session-2.scope: Deactivated successfully. Jul 15 04:44:59.519227 systemd-logind[1447]: Session 2 logged out. Waiting for processes to exit. Jul 15 04:44:59.521919 systemd[1]: Started sshd@2-10.0.0.71:22-10.0.0.1:59970.service - OpenSSH per-connection server daemon (10.0.0.1:59970). Jul 15 04:44:59.523516 systemd-logind[1447]: Removed session 2. Jul 15 04:44:59.574452 sshd[1588]: Accepted publickey for core from 10.0.0.1 port 59970 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:44:59.575752 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:44:59.579694 systemd-logind[1447]: New session 3 of user core. Jul 15 04:44:59.595191 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 15 04:44:59.642866 sshd[1591]: Connection closed by 10.0.0.1 port 59970 Jul 15 04:44:59.642727 sshd-session[1588]: pam_unix(sshd:session): session closed for user core Jul 15 04:44:59.652176 systemd[1]: sshd@2-10.0.0.71:22-10.0.0.1:59970.service: Deactivated successfully. Jul 15 04:44:59.653899 systemd[1]: session-3.scope: Deactivated successfully. Jul 15 04:44:59.654574 systemd-logind[1447]: Session 3 logged out. Waiting for processes to exit. Jul 15 04:44:59.656452 systemd[1]: Started sshd@3-10.0.0.71:22-10.0.0.1:59980.service - OpenSSH per-connection server daemon (10.0.0.1:59980). Jul 15 04:44:59.657240 systemd-logind[1447]: Removed session 3. Jul 15 04:44:59.711804 sshd[1597]: Accepted publickey for core from 10.0.0.1 port 59980 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:44:59.713143 sshd-session[1597]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:44:59.717155 systemd-logind[1447]: New session 4 of user core. Jul 15 04:44:59.733148 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 15 04:44:59.785141 sshd[1601]: Connection closed by 10.0.0.1 port 59980 Jul 15 04:44:59.784630 sshd-session[1597]: pam_unix(sshd:session): session closed for user core Jul 15 04:44:59.792895 systemd[1]: sshd@3-10.0.0.71:22-10.0.0.1:59980.service: Deactivated successfully. Jul 15 04:44:59.795420 systemd[1]: session-4.scope: Deactivated successfully. Jul 15 04:44:59.796481 systemd-logind[1447]: Session 4 logged out. Waiting for processes to exit. Jul 15 04:44:59.798026 systemd[1]: Started sshd@4-10.0.0.71:22-10.0.0.1:59988.service - OpenSSH per-connection server daemon (10.0.0.1:59988). Jul 15 04:44:59.799344 systemd-logind[1447]: Removed session 4. Jul 15 04:44:59.860027 sshd[1607]: Accepted publickey for core from 10.0.0.1 port 59988 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:44:59.861246 sshd-session[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:44:59.865737 systemd-logind[1447]: New session 5 of user core. Jul 15 04:44:59.878162 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 15 04:44:59.938205 sudo[1611]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 15 04:44:59.938479 sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 15 04:44:59.959078 sudo[1611]: pam_unix(sudo:session): session closed for user root Jul 15 04:44:59.960626 sshd[1610]: Connection closed by 10.0.0.1 port 59988 Jul 15 04:44:59.961006 sshd-session[1607]: pam_unix(sshd:session): session closed for user core Jul 15 04:44:59.969137 systemd[1]: sshd@4-10.0.0.71:22-10.0.0.1:59988.service: Deactivated successfully. Jul 15 04:44:59.970658 systemd[1]: session-5.scope: Deactivated successfully. Jul 15 04:44:59.971402 systemd-logind[1447]: Session 5 logged out. Waiting for processes to exit. Jul 15 04:44:59.973662 systemd[1]: Started sshd@5-10.0.0.71:22-10.0.0.1:59998.service - OpenSSH per-connection server daemon (10.0.0.1:59998). Jul 15 04:44:59.974603 systemd-logind[1447]: Removed session 5. Jul 15 04:45:00.024289 sshd[1617]: Accepted publickey for core from 10.0.0.1 port 59998 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:00.025538 sshd-session[1617]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:00.030059 systemd-logind[1447]: New session 6 of user core. Jul 15 04:45:00.044186 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 15 04:45:00.101995 sudo[1622]: 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 Jul 15 04:45:00.102291 sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 15 04:45:00.112072 sudo[1622]: pam_unix(sudo:session): session closed for user root Jul 15 04:45:00.116996 sudo[1621]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jul 15 04:45:00.117277 sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 15 04:45:00.128828 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 15 04:45:00.167568 augenrules[1644]: No rules Jul 15 04:45:00.168891 systemd[1]: audit-rules.service: Deactivated successfully. Jul 15 04:45:00.169305 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 15 04:45:00.171183 sudo[1621]: pam_unix(sudo:session): session closed for user root Jul 15 04:45:00.172860 sshd[1620]: Connection closed by 10.0.0.1 port 59998 Jul 15 04:45:00.172781 sshd-session[1617]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:00.184336 systemd[1]: sshd@5-10.0.0.71:22-10.0.0.1:59998.service: Deactivated successfully. Jul 15 04:45:00.186028 systemd[1]: session-6.scope: Deactivated successfully. Jul 15 04:45:00.186689 systemd-logind[1447]: Session 6 logged out. Waiting for processes to exit. Jul 15 04:45:00.189116 systemd[1]: Started sshd@6-10.0.0.71:22-10.0.0.1:60014.service - OpenSSH per-connection server daemon (10.0.0.1:60014). Jul 15 04:45:00.190491 systemd-logind[1447]: Removed session 6. Jul 15 04:45:00.262941 sshd[1653]: Accepted publickey for core from 10.0.0.1 port 60014 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:00.264263 sshd-session[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:00.268442 systemd-logind[1447]: New session 7 of user core. Jul 15 04:45:00.286142 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 15 04:45:00.344353 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 15 04:45:00.358331 (dockerd)[1658]: 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 Jul 15 04:45:00.627099 dockerd[1658]: time="2025-07-15T04:45:00.626197920Z" level=info msg="Starting up" Jul 15 04:45:00.627628 dockerd[1658]: time="2025-07-15T04:45:00.627599989Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jul 15 04:45:00.638732 dockerd[1658]: time="2025-07-15T04:45:00.638691138Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jul 15 04:45:00.818788 dockerd[1658]: time="2025-07-15T04:45:00.818572659Z" level=info msg="Loading containers: start." Jul 15 04:45:00.834099 kernel: Initializing XFRM netlink socket Jul 15 04:45:01.087396 systemd-networkd[1396]: docker0: Link UP Jul 15 04:45:01.091544 dockerd[1658]: time="2025-07-15T04:45:01.091493643Z" level=info msg="Loading containers: done." Jul 15 04:45:01.104114 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3650796120-merged.mount: Deactivated successfully. Jul 15 04:45:01.106130 dockerd[1658]: time="2025-07-15T04:45:01.106082400Z" 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 Jul 15 04:45:01.106204 dockerd[1658]: time="2025-07-15T04:45:01.106181266Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jul 15 04:45:01.106291 dockerd[1658]: time="2025-07-15T04:45:01.106263606Z" level=info msg="Initializing buildkit" Jul 15 04:45:01.132415 dockerd[1658]: time="2025-07-15T04:45:01.132375082Z" level=info msg="Completed buildkit initialization" Jul 15 04:45:01.141868 dockerd[1658]: time="2025-07-15T04:45:01.141810141Z" level=info msg="Daemon has completed initialization" Jul 15 04:45:01.142024 dockerd[1658]: time="2025-07-15T04:45:01.141904976Z" level=info msg="API listen on /run/docker.sock" Jul 15 04:45:01.142975 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 15 04:45:01.152163 sshd[1656]: Connection closed by 10.0.0.1 port 60014 Jul 15 04:45:01.152079 sshd-session[1653]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:01.163918 systemd[1]: sshd@6-10.0.0.71:22-10.0.0.1:60014.service: Deactivated successfully. Jul 15 04:45:01.165812 systemd[1]: session-7.scope: Deactivated successfully. Jul 15 04:45:01.167085 systemd-logind[1447]: Session 7 logged out. Waiting for processes to exit. Jul 15 04:45:01.175381 systemd[1]: Started sshd@7-10.0.0.71:22-10.0.0.1:60026.service - OpenSSH per-connection server daemon (10.0.0.1:60026). Jul 15 04:45:01.175894 systemd-logind[1447]: Removed session 7. Jul 15 04:45:01.227658 sshd[1871]: Accepted publickey for core from 10.0.0.1 port 60026 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:01.228180 sshd-session[1871]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:01.232213 systemd-logind[1447]: New session 8 of user core. Jul 15 04:45:01.245119 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 15 04:45:02.543642 systemd[1]: var-lib-docker-overlay2-ceb93db240522c450625e9a7d970937a603290a8f0767a52d8c9609696a69f1e\x2dinit-merged.mount: Deactivated successfully. Jul 15 04:45:02.570012 systemd[1]: var-lib-docker-overlay2-ceb93db240522c450625e9a7d970937a603290a8f0767a52d8c9609696a69f1e-merged.mount: Deactivated successfully. Jul 15 04:45:02.606438 containerd[1460]: time="2025-07-15T04:45:02.606385703Z" level=info msg="connecting to shim 5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364" address="unix:///run/containerd/s/ef52deb4dd64fe72b19532b6c200dc03ccadc3b61f8ba7c60c5903dcf0dc27f8" namespace=moby protocol=ttrpc version=3 Jul 15 04:45:02.644153 systemd[1]: Started docker-5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364.scope - libcontainer container 5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364. Jul 15 04:45:02.661347 systemd-resolved[1297]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 04:45:02.683493 kernel: docker0: port 1(veth57ea7d6) entered blocking state Jul 15 04:45:02.683620 kernel: docker0: port 1(veth57ea7d6) entered disabled state Jul 15 04:45:02.683641 kernel: veth57ea7d6: entered allmulticast mode Jul 15 04:45:02.684990 kernel: veth57ea7d6: entered promiscuous mode Jul 15 04:45:02.685064 systemd-networkd[1396]: veth57ea7d6: Link UP Jul 15 04:45:02.694219 kernel: eth0: renamed from vethd83353c Jul 15 04:45:02.694305 kernel: docker0: port 1(veth57ea7d6) entered blocking state Jul 15 04:45:02.695669 kernel: docker0: port 1(veth57ea7d6) entered forwarding state Jul 15 04:45:02.695848 systemd-networkd[1396]: veth57ea7d6: Gained carrier Jul 15 04:45:02.696766 systemd-networkd[1396]: docker0: Gained carrier Jul 15 04:45:02.730974 sshd[1874]: Connection closed by 10.0.0.1 port 60026 Jul 15 04:45:02.731751 sshd-session[1871]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:02.747420 systemd[1]: sshd@7-10.0.0.71:22-10.0.0.1:60026.service: Deactivated successfully. Jul 15 04:45:02.749604 systemd[1]: session-8.scope: Deactivated successfully. Jul 15 04:45:02.751556 systemd-logind[1447]: Session 8 logged out. Waiting for processes to exit. Jul 15 04:45:02.754116 systemd[1]: Started sshd@8-10.0.0.71:22-10.0.0.1:55420.service - OpenSSH per-connection server daemon (10.0.0.1:55420). Jul 15 04:45:02.755242 systemd-logind[1447]: Removed session 8. Jul 15 04:45:02.810280 sshd[1951]: Accepted publickey for core from 10.0.0.1 port 55420 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:02.811685 sshd-session[1951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:02.816017 systemd-logind[1447]: New session 9 of user core. Jul 15 04:45:02.830152 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 15 04:45:02.884236 sshd[1954]: Connection closed by 10.0.0.1 port 55420 Jul 15 04:45:02.884750 sshd-session[1951]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:02.895998 systemd[1]: sshd@8-10.0.0.71:22-10.0.0.1:55420.service: Deactivated successfully. Jul 15 04:45:02.898518 systemd[1]: session-9.scope: Deactivated successfully. Jul 15 04:45:02.899259 systemd-logind[1447]: Session 9 logged out. Waiting for processes to exit. Jul 15 04:45:02.901418 systemd[1]: Started sshd@9-10.0.0.71:22-10.0.0.1:55424.service - OpenSSH per-connection server daemon (10.0.0.1:55424). Jul 15 04:45:02.903499 systemd-logind[1447]: Removed session 9. Jul 15 04:45:02.956147 sshd[1960]: Accepted publickey for core from 10.0.0.1 port 55424 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:02.957345 sshd-session[1960]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:02.961084 systemd-logind[1447]: New session 10 of user core. Jul 15 04:45:02.969091 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 15 04:45:03.019538 sudo[1964]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1460 Jul 15 04:45:03.019801 sudo[1964]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 15 04:45:03.023100 containerd[1460]: time="2025-07-15T04:45:03.023057023Z" level=info msg="Stop CRI service" Jul 15 04:45:03.023124 sudo[1964]: pam_unix(sudo:session): session closed for user root Jul 15 04:45:03.023215 dockerd[1658]: time="2025-07-15T04:45:03.023104417Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Jul 15 04:45:03.023215 dockerd[1658]: time="2025-07-15T04:45:03.023145133Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Jul 15 04:45:03.023423 dockerd[1658]: time="2025-07-15T04:45:03.023266153Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Jul 15 04:45:03.023423 dockerd[1658]: time="2025-07-15T04:45:03.023286510Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Jul 15 04:45:03.025019 sshd[1963]: Connection closed by 10.0.0.1 port 55424 Jul 15 04:45:03.024587 sshd-session[1960]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:03.042103 systemd[1]: containerd.service: Deactivated successfully. Jul 15 04:45:03.042220 systemd[1]: containerd.service: Unit process 1897 (containerd-shim) remains running after unit stopped. Jul 15 04:45:03.042455 systemd[1]: containerd.service: Consumed 378ms CPU time, 125.3M memory peak. Jul 15 04:45:03.042830 systemd[1]: sshd@9-10.0.0.71:22-10.0.0.1:55424.service: Deactivated successfully. Jul 15 04:45:03.045680 systemd[1]: session-10.scope: Deactivated successfully. Jul 15 04:45:03.047301 systemd-logind[1447]: Session 10 logged out. Waiting for processes to exit. Jul 15 04:45:03.049782 systemd-logind[1447]: Removed session 10. Jul 15 04:45:03.051049 systemd[1]: Started sshd@10-10.0.0.71:22-10.0.0.1:55432.service - OpenSSH per-connection server daemon (10.0.0.1:55432). Jul 15 04:45:03.107402 sshd[1972]: Accepted publickey for core from 10.0.0.1 port 55432 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:03.108714 sshd-session[1972]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:03.112826 systemd-logind[1447]: New session 11 of user core. Jul 15 04:45:03.123158 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 15 04:45:03.184558 sshd[1975]: Connection closed by 10.0.0.1 port 55432 Jul 15 04:45:03.184970 sshd-session[1972]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:03.188538 systemd[1]: sshd@10-10.0.0.71:22-10.0.0.1:55432.service: Deactivated successfully. Jul 15 04:45:03.190100 systemd[1]: session-11.scope: Deactivated successfully. Jul 15 04:45:03.191553 systemd-logind[1447]: Session 11 logged out. Waiting for processes to exit. Jul 15 04:45:03.192723 systemd-logind[1447]: Removed session 11. Jul 15 04:45:04.426152 systemd-networkd[1396]: veth57ea7d6: Gained IPv6LL Jul 15 04:45:04.493128 systemd-networkd[1396]: docker0: Gained IPv6LL Jul 15 04:45:08.293016 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Jul 15 04:45:08.293368 systemd[1]: Stopping docker.service - Docker Application Container Engine... Jul 15 04:45:08.294390 dockerd[1658]: time="2025-07-15T04:45:08.294349055Z" level=info msg="Processing signal 'terminated'" Jul 15 04:45:09.216821 systemd[1]: Started sshd@11-10.0.0.71:22-10.0.0.1:55470.service - OpenSSH per-connection server daemon (10.0.0.1:55470). Jul 15 04:45:09.258675 sshd[1981]: Accepted publickey for core from 10.0.0.1 port 55470 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:09.259835 sshd-session[1981]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:09.264427 systemd-logind[1447]: New session 12 of user core. Jul 15 04:45:09.278137 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 15 04:45:09.332765 sshd[1984]: Connection closed by 10.0.0.1 port 55470 Jul 15 04:45:09.333330 sshd-session[1981]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:09.337324 systemd[1]: sshd@11-10.0.0.71:22-10.0.0.1:55470.service: Deactivated successfully. Jul 15 04:45:09.340226 systemd[1]: session-12.scope: Deactivated successfully. Jul 15 04:45:09.341327 systemd-logind[1447]: Session 12 logged out. Waiting for processes to exit. Jul 15 04:45:09.342403 systemd-logind[1447]: Removed session 12. Jul 15 04:45:15.361563 systemd[1]: Started sshd@12-10.0.0.71:22-10.0.0.1:39878.service - OpenSSH per-connection server daemon (10.0.0.1:39878). Jul 15 04:45:15.406500 sshd[1990]: Accepted publickey for core from 10.0.0.1 port 39878 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:15.407681 sshd-session[1990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:15.412640 systemd-logind[1447]: New session 13 of user core. Jul 15 04:45:15.422505 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 15 04:45:15.479979 sshd[1993]: Connection closed by 10.0.0.1 port 39878 Jul 15 04:45:15.480363 sshd-session[1990]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:15.483422 systemd[1]: sshd@12-10.0.0.71:22-10.0.0.1:39878.service: Deactivated successfully. Jul 15 04:45:15.484840 systemd[1]: session-13.scope: Deactivated successfully. Jul 15 04:45:15.487995 systemd-logind[1447]: Session 13 logged out. Waiting for processes to exit. Jul 15 04:45:15.489228 systemd-logind[1447]: Removed session 13. Jul 15 04:45:21.506185 systemd[1]: Started sshd@13-10.0.0.71:22-10.0.0.1:39880.service - OpenSSH per-connection server daemon (10.0.0.1:39880). Jul 15 04:45:21.558323 sshd[1999]: Accepted publickey for core from 10.0.0.1 port 39880 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:21.559762 sshd-session[1999]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:21.564402 systemd-logind[1447]: New session 14 of user core. Jul 15 04:45:21.580151 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 15 04:45:21.636306 sshd[2002]: Connection closed by 10.0.0.1 port 39880 Jul 15 04:45:21.636858 sshd-session[1999]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:21.640212 systemd[1]: sshd@13-10.0.0.71:22-10.0.0.1:39880.service: Deactivated successfully. Jul 15 04:45:21.643467 systemd[1]: session-14.scope: Deactivated successfully. Jul 15 04:45:21.644161 systemd-logind[1447]: Session 14 logged out. Waiting for processes to exit. Jul 15 04:45:21.645495 systemd-logind[1447]: Removed session 14. Jul 15 04:45:23.295176 dockerd[1658]: time="2025-07-15T04:45:23.295124497Z" level=error msg="Force shutdown daemon" Jul 15 04:45:23.295532 dockerd[1658]: time="2025-07-15T04:45:23.295300777Z" level=info msg="Daemon shutdown complete" Jul 15 04:45:23.298290 systemd[1]: docker.service: Deactivated successfully. Jul 15 04:45:23.298617 systemd[1]: Stopped docker.service - Docker Application Container Engine. Jul 15 04:45:23.298680 systemd[1]: docker.service: Consumed 942ms CPU time, 121.6M memory peak. Jul 15 04:45:23.299074 systemd[1]: docker.socket: Deactivated successfully. Jul 15 04:45:23.299351 systemd[1]: Closed docker.socket - Docker Socket for the API. Jul 15 04:45:23.299393 systemd[1]: Stopping docker.socket - Docker Socket for the API... Jul 15 04:45:23.300731 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 15 04:45:23.300889 systemd[1]: containerd.service: Found left-over process 1897 (containerd-shim) in control group while starting unit. Ignoring. Jul 15 04:45:23.300892 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jul 15 04:45:23.307432 systemd[1]: Starting containerd.service - containerd container runtime... Jul 15 04:45:23.307688 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 15 04:45:23.317930 systemd[1]: containerd.service: Found left-over process 1897 (containerd-shim) in control group while starting unit. Ignoring. Jul 15 04:45:23.317962 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jul 15 04:45:23.321781 (ntainerd)[2013]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 15 04:45:23.335019 containerd[2013]: time="2025-07-15T04:45:23Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jul 15 04:45:23.335449 containerd[2013]: time="2025-07-15T04:45:23.335398601Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 15 04:45:23.343683 containerd[2013]: time="2025-07-15T04:45:23.343598791Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.925µs" Jul 15 04:45:23.343738 containerd[2013]: time="2025-07-15T04:45:23.343681669Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 15 04:45:23.343738 containerd[2013]: time="2025-07-15T04:45:23.343712683Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 15 04:45:23.343878 containerd[2013]: time="2025-07-15T04:45:23.343837139Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 15 04:45:23.343878 containerd[2013]: time="2025-07-15T04:45:23.343872235Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 15 04:45:23.344085 containerd[2013]: time="2025-07-15T04:45:23.344060440Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344115 containerd[2013]: time="2025-07-15T04:45:23.344093135Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344115 containerd[2013]: time="2025-07-15T04:45:23.344103820Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344310 containerd[2013]: time="2025-07-15T04:45:23.344283901Z" 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 Jul 15 04:45:23.344310 containerd[2013]: time="2025-07-15T04:45:23.344302310Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344358 containerd[2013]: time="2025-07-15T04:45:23.344312914Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344358 containerd[2013]: time="2025-07-15T04:45:23.344321158Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344358 containerd[2013]: time="2025-07-15T04:45:23.344339487Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344430 containerd[2013]: time="2025-07-15T04:45:23.344415841Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 15 04:45:23.344452 containerd[2013]: time="2025-07-15T04:45:23.344440932Z" 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 Jul 15 04:45:23.344474 containerd[2013]: time="2025-07-15T04:45:23.344450537Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 15 04:45:23.344495 containerd[2013]: time="2025-07-15T04:45:23.344481391Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 15 04:45:23.344702 containerd[2013]: time="2025-07-15T04:45:23.344679640Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 15 04:45:23.344727 containerd[2013]: time="2025-07-15T04:45:23.344705172Z" level=info msg="metadata content store policy set" policy=shared Jul 15 04:45:23.344846 containerd[2013]: time="2025-07-15T04:45:23.344832310Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 15 04:45:23.344868 containerd[2013]: time="2025-07-15T04:45:23.344861323Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 15 04:45:23.344890 containerd[2013]: time="2025-07-15T04:45:23.344874809Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 15 04:45:23.344910 containerd[2013]: time="2025-07-15T04:45:23.344889776Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 15 04:45:23.344910 containerd[2013]: time="2025-07-15T04:45:23.344901781Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 15 04:45:23.344961 containerd[2013]: time="2025-07-15T04:45:23.344912066Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 15 04:45:23.344961 containerd[2013]: time="2025-07-15T04:45:23.344923351Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 15 04:45:23.344961 containerd[2013]: time="2025-07-15T04:45:23.344934036Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 15 04:45:23.345060 containerd[2013]: time="2025-07-15T04:45:23.344963929Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 15 04:45:23.345060 containerd[2013]: time="2025-07-15T04:45:23.344975254Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 15 04:45:23.345060 containerd[2013]: time="2025-07-15T04:45:23.344984058Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 15 04:45:23.345060 containerd[2013]: time="2025-07-15T04:45:23.344995343Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 15 04:45:23.345201 containerd[2013]: time="2025-07-15T04:45:23.345178626Z" level=info msg="connecting to shim 5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364" address="unix:///run/containerd/s/ef52deb4dd64fe72b19532b6c200dc03ccadc3b61f8ba7c60c5903dcf0dc27f8" namespace=moby protocol=ttrpc version=3 Jul 15 04:45:23.351898 containerd[2013]: time="2025-07-15T04:45:23.351870094Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 15 04:45:23.351987 containerd[2013]: time="2025-07-15T04:45:23.351910792Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 15 04:45:23.351987 containerd[2013]: time="2025-07-15T04:45:23.351930321Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 15 04:45:23.351987 containerd[2013]: time="2025-07-15T04:45:23.351957934Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 15 04:45:23.351987 containerd[2013]: time="2025-07-15T04:45:23.351970099Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 15 04:45:23.351987 containerd[2013]: time="2025-07-15T04:45:23.351980624Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.351990989Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352000993Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352011838Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352021843Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352030967Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352083711Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352097237Z" level=info msg="Start snapshots syncer" Jul 15 04:45:23.352135 containerd[2013]: time="2025-07-15T04:45:23.352127330Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 15 04:45:23.352349 containerd[2013]: time="2025-07-15T04:45:23.352316816Z" 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\"}" Jul 15 04:45:23.352436 containerd[2013]: time="2025-07-15T04:45:23.352361116Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 15 04:45:23.352436 containerd[2013]: time="2025-07-15T04:45:23.352428907Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 15 04:45:23.352497 containerd[2013]: time="2025-07-15T04:45:23.352476368Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 15 04:45:23.352522 containerd[2013]: time="2025-07-15T04:45:23.352499939Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 15 04:45:23.352522 containerd[2013]: time="2025-07-15T04:45:23.352511864Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 15 04:45:23.352572 containerd[2013]: time="2025-07-15T04:45:23.352521909Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 15 04:45:23.352572 containerd[2013]: time="2025-07-15T04:45:23.352534995Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 15 04:45:23.352572 containerd[2013]: time="2025-07-15T04:45:23.352545560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 15 04:45:23.352572 containerd[2013]: time="2025-07-15T04:45:23.352555844Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352578615Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352595622Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352607948Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352640323Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352652808Z" 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 Jul 15 04:45:23.352664 containerd[2013]: time="2025-07-15T04:45:23.352661012Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352669096Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352677419Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352699990Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352711275Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352725761Z" level=info msg="runtime interface created" Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352730203Z" level=info msg="created NRI interface" Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352740128Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352750613Z" level=info msg="Connect containerd service" Jul 15 04:45:23.352786 containerd[2013]: time="2025-07-15T04:45:23.352776544Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 15 04:45:23.353277 containerd[2013]: time="2025-07-15T04:45:23.353255441Z" 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" Jul 15 04:45:23.362161 containerd[2013]: time="2025-07-15T04:45:23.362093840Z" level=info msg="Start subscribing containerd event" Jul 15 04:45:23.362254 containerd[2013]: time="2025-07-15T04:45:23.362169114Z" level=info msg="Start recovering state" Jul 15 04:45:23.362254 containerd[2013]: time="2025-07-15T04:45:23.362243468Z" level=info msg="Start event monitor" Jul 15 04:45:23.362301 containerd[2013]: time="2025-07-15T04:45:23.362253553Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 15 04:45:23.362301 containerd[2013]: time="2025-07-15T04:45:23.362256794Z" level=info msg="Start cni network conf syncer for default" Jul 15 04:45:23.362301 containerd[2013]: time="2025-07-15T04:45:23.362289529Z" level=info msg="Start streaming server" Jul 15 04:45:23.362301 containerd[2013]: time="2025-07-15T04:45:23.362295892Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 15 04:45:23.362373 containerd[2013]: time="2025-07-15T04:45:23.362297893Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 15 04:45:23.362373 containerd[2013]: time="2025-07-15T04:45:23.362342353Z" level=info msg="runtime interface starting up..." Jul 15 04:45:23.362373 containerd[2013]: time="2025-07-15T04:45:23.362348235Z" level=info msg="starting plugins..." Jul 15 04:45:23.362373 containerd[2013]: time="2025-07-15T04:45:23.362357400Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 15 04:45:23.362562 systemd[1]: Started containerd.service - containerd container runtime. Jul 15 04:45:23.363564 containerd[2013]: time="2025-07-15T04:45:23.362640528Z" level=info msg="containerd successfully booted in 0.027785s" Jul 15 04:45:23.364079 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 15 04:45:23.383232 (dockerd)[2041]: 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 Jul 15 04:45:23.405965 dockerd[2041]: time="2025-07-15T04:45:23.405909787Z" level=info msg="Starting up" Jul 15 04:45:23.406472 dockerd[2041]: time="2025-07-15T04:45:23.406452592Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jul 15 04:45:23.415509 dockerd[2041]: time="2025-07-15T04:45:23.415479877Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jul 15 04:45:23.435115 dockerd[2041]: time="2025-07-15T04:45:23.435075024Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Jul 15 04:45:23.442361 dockerd[2041]: time="2025-07-15T04:45:23.442333148Z" level=info msg="Loading containers: start." Jul 15 04:45:27.653517 systemd[1]: Started sshd@14-10.0.0.71:22-10.0.0.1:41828.service - OpenSSH per-connection server daemon (10.0.0.1:41828). Jul 15 04:45:27.706857 sshd[2072]: Accepted publickey for core from 10.0.0.1 port 41828 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:27.712290 sshd-session[2072]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:27.716899 systemd-logind[1447]: New session 15 of user core. Jul 15 04:45:27.726138 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 15 04:45:27.779476 sshd[2075]: Connection closed by 10.0.0.1 port 41828 Jul 15 04:45:27.780678 sshd-session[2072]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:27.789796 systemd[1]: sshd@14-10.0.0.71:22-10.0.0.1:41828.service: Deactivated successfully. Jul 15 04:45:27.793240 systemd[1]: session-15.scope: Deactivated successfully. Jul 15 04:45:27.794741 systemd-logind[1447]: Session 15 logged out. Waiting for processes to exit. Jul 15 04:45:27.799825 systemd[1]: Started sshd@15-10.0.0.71:22-10.0.0.1:41838.service - OpenSSH per-connection server daemon (10.0.0.1:41838). Jul 15 04:45:27.800440 systemd-logind[1447]: Removed session 15. Jul 15 04:45:27.856542 sshd[2081]: Accepted publickey for core from 10.0.0.1 port 41838 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:27.857738 sshd-session[2081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:27.862010 systemd-logind[1447]: New session 16 of user core. Jul 15 04:45:27.876117 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 15 04:45:27.928735 sshd[2084]: Connection closed by 10.0.0.1 port 41838 Jul 15 04:45:27.928972 sshd-session[2081]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:27.948824 systemd[1]: sshd@15-10.0.0.71:22-10.0.0.1:41838.service: Deactivated successfully. Jul 15 04:45:27.950548 systemd[1]: session-16.scope: Deactivated successfully. Jul 15 04:45:27.951413 systemd-logind[1447]: Session 16 logged out. Waiting for processes to exit. Jul 15 04:45:27.954524 systemd[1]: Started sshd@16-10.0.0.71:22-10.0.0.1:41854.service - OpenSSH per-connection server daemon (10.0.0.1:41854). Jul 15 04:45:27.955284 systemd-logind[1447]: Removed session 16. Jul 15 04:45:28.010468 sshd[2090]: Accepted publickey for core from 10.0.0.1 port 41854 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:28.010974 sshd-session[2090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:28.014998 systemd-logind[1447]: New session 17 of user core. Jul 15 04:45:28.025109 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 15 04:45:33.462068 dockerd[2041]: time="2025-07-15T04:45:33.461616865Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364 Jul 15 04:45:33.475104 systemd[1]: docker-5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364.scope: Deactivated successfully. Jul 15 04:45:33.488404 dockerd[2041]: time="2025-07-15T04:45:33.487756560Z" level=info msg="ignoring event" container=5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 15 04:45:33.488525 containerd[2013]: time="2025-07-15T04:45:33.488086210Z" level=info msg="shim disconnected" id=5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364 namespace=moby Jul 15 04:45:33.488525 containerd[2013]: time="2025-07-15T04:45:33.488121140Z" level=warning msg="cleaning up after shim disconnected" id=5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364 namespace=moby Jul 15 04:45:33.488525 containerd[2013]: time="2025-07-15T04:45:33.488156069Z" level=info msg="cleaning up dead shim" namespace=moby Jul 15 04:45:33.502795 systemd[1]: var-lib-docker-overlay2-ceb93db240522c450625e9a7d970937a603290a8f0767a52d8c9609696a69f1e-merged.mount: Deactivated successfully. Jul 15 04:45:33.962268 systemd[1]: run-docker-netns-176f14a21afc.mount: Deactivated successfully. Jul 15 04:45:33.963325 systemd-networkd[1396]: veth57ea7d6: Link DOWN Jul 15 04:45:33.963333 systemd-networkd[1396]: veth57ea7d6: Lost carrier Jul 15 04:45:33.966462 kernel: docker0: port 1(veth57ea7d6) entered disabled state Jul 15 04:45:33.966551 kernel: veth57ea7d6 (unregistering): left allmulticast mode Jul 15 04:45:33.966569 kernel: veth57ea7d6 (unregistering): left promiscuous mode Jul 15 04:45:33.967209 kernel: docker0: port 1(veth57ea7d6) entered disabled state Jul 15 04:45:33.971087 systemd-networkd[1396]: docker0: Lost carrier Jul 15 04:45:33.973201 dockerd[2041]: time="2025-07-15T04:45:33.971641098Z" level=info msg="Removing stale sandbox 176f14a21afccd486691a55274800dbeb8702a926cd13bea84c3abd0dbe53719 (5dc5881e9a11038e314363f2e592046a434a4d0b6eaf09ce452735a491c97364)" Jul 15 04:45:33.981514 dockerd[2041]: time="2025-07-15T04:45:33.981396376Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Jul 15 04:45:33.981514 dockerd[2041]: time="2025-07-15T04:45:33.981457433Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint e41f5ee1ed01eb92b0b27ddc5002c9f5c853e3f9a66e16c631f5dfd8cf36280c d523e2ee24febb6d8fdc29d93f8d237aab445f5159827ac96c619d0e20ff3959], retrying...." Jul 15 04:45:33.986460 systemd[1]: run-docker-netns-176f14a21afc.mount: Deactivated successfully. Jul 15 04:45:34.203129 dockerd[2041]: time="2025-07-15T04:45:34.203060427Z" level=warning msg="error locating sandbox id 176f14a21afccd486691a55274800dbeb8702a926cd13bea84c3abd0dbe53719: sandbox 176f14a21afccd486691a55274800dbeb8702a926cd13bea84c3abd0dbe53719 not found" Jul 15 04:45:34.203253 dockerd[2041]: time="2025-07-15T04:45:34.203159533Z" level=info msg="Loading containers: done." Jul 15 04:45:34.216654 dockerd[2041]: time="2025-07-15T04:45:34.216524774Z" 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 Jul 15 04:45:34.216654 dockerd[2041]: time="2025-07-15T04:45:34.216612717Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jul 15 04:45:34.216654 dockerd[2041]: time="2025-07-15T04:45:34.216649847Z" level=info msg="Initializing buildkit" Jul 15 04:45:34.229476 dockerd[2041]: time="2025-07-15T04:45:34.229421053Z" level=info msg="Completed buildkit initialization" Jul 15 04:45:34.236836 dockerd[2041]: time="2025-07-15T04:45:34.236777009Z" level=info msg="Daemon has completed initialization" Jul 15 04:45:34.237057 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 15 04:45:34.237659 dockerd[2041]: time="2025-07-15T04:45:34.237595903Z" level=info msg="API listen on /run/docker.sock" Jul 15 04:45:34.247214 sshd[2093]: Connection closed by 10.0.0.1 port 41854 Jul 15 04:45:34.246019 sshd-session[2090]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:34.259758 systemd[1]: sshd@16-10.0.0.71:22-10.0.0.1:41854.service: Deactivated successfully. Jul 15 04:45:34.261398 systemd[1]: session-17.scope: Deactivated successfully. Jul 15 04:45:34.262464 systemd-logind[1447]: Session 17 logged out. Waiting for processes to exit. Jul 15 04:45:34.264609 systemd-logind[1447]: Removed session 17. Jul 15 04:45:34.266332 systemd[1]: Started sshd@17-10.0.0.71:22-10.0.0.1:54648.service - OpenSSH per-connection server daemon (10.0.0.1:54648). Jul 15 04:45:34.335013 sshd[2343]: Accepted publickey for core from 10.0.0.1 port 54648 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:34.336414 sshd-session[2343]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:34.341188 systemd-logind[1447]: New session 18 of user core. Jul 15 04:45:34.354151 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 15 04:45:34.460216 containerd[2013]: time="2025-07-15T04:45:34.460155312Z" level=info msg="connecting to shim 83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833" address="unix:///run/containerd/s/b3c1299bab8ebbf6baed9b174687d6c36648aad8f0d424e599f2bdcad9a72c2b" namespace=moby protocol=ttrpc version=3 Jul 15 04:45:34.490165 systemd[1]: Started docker-83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833.scope - libcontainer container 83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833. Jul 15 04:45:34.520977 systemd-resolved[1297]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 04:45:34.540364 kernel: docker0: port 1(veth7db0322) entered blocking state Jul 15 04:45:34.540464 kernel: docker0: port 1(veth7db0322) entered disabled state Jul 15 04:45:34.541077 kernel: veth7db0322: entered allmulticast mode Jul 15 04:45:34.541960 kernel: veth7db0322: entered promiscuous mode Jul 15 04:45:34.542017 systemd-networkd[1396]: veth7db0322: Link UP Jul 15 04:45:34.554094 kernel: eth0: renamed from vethac7f98b Jul 15 04:45:34.554197 kernel: docker0: port 1(veth7db0322) entered blocking state Jul 15 04:45:34.554216 kernel: docker0: port 1(veth7db0322) entered forwarding state Jul 15 04:45:34.554157 systemd-networkd[1396]: veth7db0322: Gained carrier Jul 15 04:45:34.554902 systemd-networkd[1396]: docker0: Gained carrier Jul 15 04:45:34.574556 sshd[2346]: Connection closed by 10.0.0.1 port 54648 Jul 15 04:45:34.575562 sshd-session[2343]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:34.583539 systemd[1]: sshd@17-10.0.0.71:22-10.0.0.1:54648.service: Deactivated successfully. Jul 15 04:45:34.585489 systemd[1]: session-18.scope: Deactivated successfully. Jul 15 04:45:34.586266 systemd-logind[1447]: Session 18 logged out. Waiting for processes to exit. Jul 15 04:45:34.589248 systemd[1]: Started sshd@18-10.0.0.71:22-10.0.0.1:54662.service - OpenSSH per-connection server daemon (10.0.0.1:54662). Jul 15 04:45:34.590295 systemd-logind[1447]: Removed session 18. Jul 15 04:45:34.649093 sshd[2414]: Accepted publickey for core from 10.0.0.1 port 54662 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:34.653866 sshd-session[2414]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:34.659185 systemd-logind[1447]: New session 19 of user core. Jul 15 04:45:34.670130 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 15 04:45:34.724486 sshd[2417]: Connection closed by 10.0.0.1 port 54662 Jul 15 04:45:34.725025 sshd-session[2414]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:34.735009 systemd[1]: sshd@18-10.0.0.71:22-10.0.0.1:54662.service: Deactivated successfully. Jul 15 04:45:34.737447 systemd[1]: session-19.scope: Deactivated successfully. Jul 15 04:45:34.741022 systemd-logind[1447]: Session 19 logged out. Waiting for processes to exit. Jul 15 04:45:34.741869 systemd[1]: Started sshd@19-10.0.0.71:22-10.0.0.1:54676.service - OpenSSH per-connection server daemon (10.0.0.1:54676). Jul 15 04:45:34.744337 systemd-logind[1447]: Removed session 19. Jul 15 04:45:34.808761 sshd[2423]: Accepted publickey for core from 10.0.0.1 port 54676 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:34.810060 sshd-session[2423]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:34.814941 systemd-logind[1447]: New session 20 of user core. Jul 15 04:45:34.833425 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 15 04:45:34.883930 sudo[2427]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 2013 Jul 15 04:45:34.884242 sudo[2427]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 15 04:45:34.889137 sudo[2427]: pam_unix(sudo:session): session closed for user root Jul 15 04:45:34.889674 dockerd[2041]: time="2025-07-15T04:45:34.889632576Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Jul 15 04:45:34.890180 dockerd[2041]: time="2025-07-15T04:45:34.890029799Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Jul 15 04:45:34.890590 sshd[2426]: Connection closed by 10.0.0.1 port 54676 Jul 15 04:45:34.890849 sshd-session[2423]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:34.896277 dockerd[2041]: time="2025-07-15T04:45:34.896225293Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Jul 15 04:45:34.896277 dockerd[2041]: time="2025-07-15T04:45:34.896265023Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Jul 15 04:45:34.904017 systemd[1]: containerd.service: Deactivated successfully. Jul 15 04:45:34.904111 systemd[1]: containerd.service: Unit process 2366 (containerd-shim) remains running after unit stopped. Jul 15 04:45:34.904341 systemd[1]: containerd.service: Consumed 185ms CPU time, 126M memory peak. Jul 15 04:45:34.904697 systemd[1]: sshd@19-10.0.0.71:22-10.0.0.1:54676.service: Deactivated successfully. Jul 15 04:45:34.906576 systemd[1]: session-20.scope: Deactivated successfully. Jul 15 04:45:34.908307 systemd-logind[1447]: Session 20 logged out. Waiting for processes to exit. Jul 15 04:45:34.910760 systemd[1]: Started sshd@20-10.0.0.71:22-10.0.0.1:54684.service - OpenSSH per-connection server daemon (10.0.0.1:54684). Jul 15 04:45:34.911782 systemd-logind[1447]: Removed session 20. Jul 15 04:45:34.973127 sshd[2434]: Accepted publickey for core from 10.0.0.1 port 54684 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:34.974479 sshd-session[2434]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:34.978655 systemd-logind[1447]: New session 21 of user core. Jul 15 04:45:34.986128 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 15 04:45:35.040993 sshd[2437]: Connection closed by 10.0.0.1 port 54684 Jul 15 04:45:35.042060 sshd-session[2434]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:35.046333 systemd[1]: sshd@20-10.0.0.71:22-10.0.0.1:54684.service: Deactivated successfully. Jul 15 04:45:35.049536 systemd[1]: session-21.scope: Deactivated successfully. Jul 15 04:45:35.050294 systemd-logind[1447]: Session 21 logged out. Waiting for processes to exit. Jul 15 04:45:35.051700 systemd-logind[1447]: Removed session 21. Jul 15 04:45:35.850160 systemd-networkd[1396]: veth7db0322: Gained IPv6LL Jul 15 04:45:36.468494 update_engine[1450]: I20250715 04:45:36.468417 1450 update_attempter.cc:509] Updating boot flags... Jul 15 04:45:40.110596 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Jul 15 04:45:40.110935 systemd[1]: Stopping docker.service - Docker Application Container Engine... Jul 15 04:45:40.111200 dockerd[2041]: time="2025-07-15T04:45:40.111136065Z" level=info msg="Processing signal 'terminated'" Jul 15 04:45:41.056138 systemd[1]: Started sshd@21-10.0.0.71:22-10.0.0.1:54700.service - OpenSSH per-connection server daemon (10.0.0.1:54700). Jul 15 04:45:41.117851 sshd[2452]: Accepted publickey for core from 10.0.0.1 port 54700 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:41.118998 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:41.122566 systemd-logind[1447]: New session 22 of user core. Jul 15 04:45:41.133168 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 15 04:45:41.185554 sshd[2455]: Connection closed by 10.0.0.1 port 54700 Jul 15 04:45:41.185488 sshd-session[2452]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:41.188849 systemd[1]: sshd@21-10.0.0.71:22-10.0.0.1:54700.service: Deactivated successfully. Jul 15 04:45:41.192293 systemd[1]: session-22.scope: Deactivated successfully. Jul 15 04:45:41.192886 systemd-logind[1447]: Session 22 logged out. Waiting for processes to exit. Jul 15 04:45:41.193751 systemd-logind[1447]: Removed session 22. Jul 15 04:45:42.112331 dockerd[2041]: time="2025-07-15T04:45:42.112236846Z" level=error msg="Error sending stop (signal 15) to container" container=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 error="Cannot kill container 83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Jul 15 04:45:42.112331 dockerd[2041]: time="2025-07-15T04:45:42.112304418Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 Jul 15 04:45:47.198112 systemd[1]: Started sshd@22-10.0.0.71:22-10.0.0.1:51094.service - OpenSSH per-connection server daemon (10.0.0.1:51094). Jul 15 04:45:47.259329 sshd[2461]: Accepted publickey for core from 10.0.0.1 port 51094 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:47.260523 sshd-session[2461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:47.264910 systemd-logind[1447]: New session 23 of user core. Jul 15 04:45:47.281186 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 15 04:45:47.336031 sshd[2464]: Connection closed by 10.0.0.1 port 51094 Jul 15 04:45:47.336340 sshd-session[2461]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:47.339624 systemd[1]: sshd@22-10.0.0.71:22-10.0.0.1:51094.service: Deactivated successfully. Jul 15 04:45:47.341234 systemd[1]: session-23.scope: Deactivated successfully. Jul 15 04:45:47.342117 systemd-logind[1447]: Session 23 logged out. Waiting for processes to exit. Jul 15 04:45:47.343023 systemd-logind[1447]: Removed session 23. Jul 15 04:45:52.113336 dockerd[2041]: time="2025-07-15T04:45:52.113265250Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 error="context deadline exceeded" Jul 15 04:45:52.113959 systemd[1]: docker-83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833.scope: Deactivated successfully. Jul 15 04:45:53.352318 systemd[1]: Started sshd@23-10.0.0.71:22-10.0.0.1:45096.service - OpenSSH per-connection server daemon (10.0.0.1:45096). Jul 15 04:45:53.401951 sshd[2471]: Accepted publickey for core from 10.0.0.1 port 45096 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:53.403301 sshd-session[2471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:53.407154 systemd-logind[1447]: New session 24 of user core. Jul 15 04:45:53.425165 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 15 04:45:53.484384 sshd[2474]: Connection closed by 10.0.0.1 port 45096 Jul 15 04:45:53.484712 sshd-session[2471]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:53.487506 systemd[1]: sshd@23-10.0.0.71:22-10.0.0.1:45096.service: Deactivated successfully. Jul 15 04:45:53.489029 systemd[1]: session-24.scope: Deactivated successfully. Jul 15 04:45:53.490523 systemd-logind[1447]: Session 24 logged out. Waiting for processes to exit. Jul 15 04:45:53.493213 systemd-logind[1447]: Removed session 24. Jul 15 04:45:55.111558 dockerd[2041]: time="2025-07-15T04:45:55.111498829Z" level=error msg="Force shutdown daemon" Jul 15 04:45:55.111899 dockerd[2041]: time="2025-07-15T04:45:55.111666406Z" level=info msg="Daemon shutdown complete" Jul 15 04:45:55.115432 systemd[1]: docker.service: Deactivated successfully. Jul 15 04:45:55.115808 systemd[1]: Stopped docker.service - Docker Application Container Engine. Jul 15 04:45:55.116466 systemd[1]: docker.socket: Deactivated successfully. Jul 15 04:45:55.116779 systemd[1]: Closed docker.socket - Docker Socket for the API. Jul 15 04:45:55.116822 systemd[1]: Stopping docker.socket - Docker Socket for the API... Jul 15 04:45:55.118120 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 15 04:45:55.118287 systemd[1]: containerd.service: Found left-over process 2366 (containerd-shim) in control group while starting unit. Ignoring. Jul 15 04:45:55.118293 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jul 15 04:45:55.126362 systemd[1]: Starting containerd.service - containerd container runtime... Jul 15 04:45:55.126941 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 15 04:45:55.138097 systemd[1]: containerd.service: Found left-over process 2366 (containerd-shim) in control group while starting unit. Ignoring. Jul 15 04:45:55.138106 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Jul 15 04:45:55.142495 (ntainerd)[2485]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 15 04:45:55.158372 containerd[2485]: time="2025-07-15T04:45:55Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jul 15 04:45:55.158859 containerd[2485]: time="2025-07-15T04:45:55.158813008Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 15 04:45:55.168863 containerd[2485]: time="2025-07-15T04:45:55.168820372Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.641µs" Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.168992230Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169020393Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169050996Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169069998Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169241456Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169266979Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169283661Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169515125Z" 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 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169530287Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169541608Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169550249Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 15 04:45:55.169903 containerd[2485]: time="2025-07-15T04:45:55.169570291Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.169654419Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.169687583Z" 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 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.169697744Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.169738428Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.169991895Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.170036619Z" level=info msg="metadata content store policy set" policy=shared Jul 15 04:45:55.170186 containerd[2485]: time="2025-07-15T04:45:55.170181995Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170209317Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170221679Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170231800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170242721Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170253002Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170269084Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170280765Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170290526Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170300167Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 15 04:45:55.170312 containerd[2485]: time="2025-07-15T04:45:55.170309328Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 15 04:45:55.170485 containerd[2485]: time="2025-07-15T04:45:55.170325049Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 15 04:45:55.170559 containerd[2485]: time="2025-07-15T04:45:55.170531151Z" level=info msg="connecting to shim 83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833" address="unix:///run/containerd/s/b3c1299bab8ebbf6baed9b174687d6c36648aad8f0d424e599f2bdcad9a72c2b" namespace=moby protocol=ttrpc version=3 Jul 15 04:45:55.177987 containerd[2485]: time="2025-07-15T04:45:55.177957126Z" level=info msg="cleaning leaked shim process" id=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 namespace=moby Jul 15 04:45:55.186999 containerd[2485]: time="2025-07-15T04:45:55.186924982Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Jul 15 04:45:55.187371 containerd[2485]: time="2025-07-15T04:45:55.187275939Z" level=info msg="shim disconnected" id=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 namespace=moby Jul 15 04:45:55.187371 containerd[2485]: time="2025-07-15T04:45:55.187340346Z" level=warning msg="cleaning up after shim disconnected" id=83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833 namespace=moby Jul 15 04:45:55.187371 containerd[2485]: time="2025-07-15T04:45:55.187349067Z" level=info msg="cleaning up dead shim" namespace=moby Jul 15 04:45:55.198026 containerd[2485]: time="2025-07-15T04:45:55.197978136Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 15 04:45:55.198026 containerd[2485]: time="2025-07-15T04:45:55.198033822Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198059505Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198076026Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198088548Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198113790Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198129952Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198142353Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 15 04:45:55.198169 containerd[2485]: time="2025-07-15T04:45:55.198163555Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 15 04:45:55.198299 containerd[2485]: time="2025-07-15T04:45:55.198179357Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 15 04:45:55.198299 containerd[2485]: time="2025-07-15T04:45:55.198193839Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 15 04:45:55.198299 containerd[2485]: time="2025-07-15T04:45:55.198266406Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 15 04:45:55.198299 containerd[2485]: time="2025-07-15T04:45:55.198282488Z" level=info msg="Start snapshots syncer" Jul 15 04:45:55.198370 containerd[2485]: time="2025-07-15T04:45:55.198304850Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 15 04:45:55.198695 containerd[2485]: time="2025-07-15T04:45:55.198650686Z" 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\"}" Jul 15 04:45:55.198785 containerd[2485]: time="2025-07-15T04:45:55.198712333Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 15 04:45:55.198830 containerd[2485]: time="2025-07-15T04:45:55.198809623Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 15 04:45:55.198894 containerd[2485]: time="2025-07-15T04:45:55.198873470Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 15 04:45:55.198925 containerd[2485]: time="2025-07-15T04:45:55.198901712Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 15 04:45:55.198925 containerd[2485]: time="2025-07-15T04:45:55.198917154Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 15 04:45:55.198984 containerd[2485]: time="2025-07-15T04:45:55.198929035Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 15 04:45:55.198984 containerd[2485]: time="2025-07-15T04:45:55.198959318Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 15 04:45:55.198984 containerd[2485]: time="2025-07-15T04:45:55.198981601Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 15 04:45:55.199041 containerd[2485]: time="2025-07-15T04:45:55.199005603Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 15 04:45:55.199060 containerd[2485]: time="2025-07-15T04:45:55.199040687Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 15 04:45:55.199080 containerd[2485]: time="2025-07-15T04:45:55.199059609Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 15 04:45:55.199111 containerd[2485]: time="2025-07-15T04:45:55.199075371Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199140217Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199164220Z" 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 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199178221Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199192223Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199201384Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199215105Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199230347Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199264270Z" level=info msg="runtime interface created" Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199273751Z" level=info msg="created NRI interface" Jul 15 04:45:55.199289 containerd[2485]: time="2025-07-15T04:45:55.199290393Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 15 04:45:55.199513 containerd[2485]: time="2025-07-15T04:45:55.199307035Z" level=info msg="Connect containerd service" Jul 15 04:45:55.199513 containerd[2485]: time="2025-07-15T04:45:55.199340598Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 15 04:45:55.199997 containerd[2485]: time="2025-07-15T04:45:55.199904057Z" 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" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209417810Z" level=info msg="Start subscribing containerd event" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209481857Z" level=info msg="Start recovering state" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209551624Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209557705Z" level=info msg="Start event monitor" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209589468Z" level=info msg="Start cni network conf syncer for default" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209597309Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209597389Z" level=info msg="Start streaming server" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209616551Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209623112Z" level=info msg="runtime interface starting up..." Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209628592Z" level=info msg="starting plugins..." Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209638273Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 15 04:45:55.209887 containerd[2485]: time="2025-07-15T04:45:55.209763526Z" level=info msg="containerd successfully booted in 0.051657s" Jul 15 04:45:55.210283 systemd[1]: Started containerd.service - containerd container runtime. Jul 15 04:45:55.212550 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 15 04:45:55.226274 (dockerd)[2532]: 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 Jul 15 04:45:55.251910 dockerd[2532]: time="2025-07-15T04:45:55.251857320Z" level=info msg="Starting up" Jul 15 04:45:55.252391 dockerd[2532]: time="2025-07-15T04:45:55.252374214Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jul 15 04:45:55.261680 dockerd[2532]: time="2025-07-15T04:45:55.261645862Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jul 15 04:45:55.278117 dockerd[2532]: time="2025-07-15T04:45:55.278077617Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Jul 15 04:45:55.285854 dockerd[2532]: time="2025-07-15T04:45:55.285815585Z" level=info msg="Loading containers: start." Jul 15 04:45:55.302923 systemd[1]: var-lib-docker-overlay2-a996b1f7cc25307d9e255bd1f023dab4bc42ba4b9f8a9626cb06106e6cc915ae-merged.mount: Deactivated successfully. Jul 15 04:45:55.700013 systemd-networkd[1396]: veth7db0322: Link DOWN Jul 15 04:45:55.700019 systemd-networkd[1396]: veth7db0322: Lost carrier Jul 15 04:45:55.701169 kernel: docker0: port 1(veth7db0322) entered disabled state Jul 15 04:45:55.701224 kernel: veth7db0322 (unregistering): left allmulticast mode Jul 15 04:45:55.702395 kernel: veth7db0322 (unregistering): left promiscuous mode Jul 15 04:45:55.702427 kernel: docker0: port 1(veth7db0322) entered disabled state Jul 15 04:45:55.704042 systemd-networkd[1396]: docker0: Lost carrier Jul 15 04:45:55.704617 dockerd[2532]: time="2025-07-15T04:45:55.704498530Z" level=info msg="Removing stale sandbox c154938baf0be69430633f94fb9927556a6687f9755c4194684ae2ec9f12e3df (83ef96f1705ef2c2952dbb1dbbb74f8b6d5d1d0c3b814e777258c9b2149f8833)" Jul 15 04:45:55.707075 dockerd[2532]: time="2025-07-15T04:45:55.707049796Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Jul 15 04:45:55.707147 dockerd[2532]: time="2025-07-15T04:45:55.707096321Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 9a5f04d6302004073337c3974a922c593ce756e40da20d23346197bd847c002b 3e51c314b1f3d3b4b4382a7b0a0c684afecd96c2323dac8328ca35e4400cbcdc], retrying...." Jul 15 04:45:55.882670 dockerd[2532]: time="2025-07-15T04:45:55.882598161Z" level=warning msg="error locating sandbox id 176f14a21afccd486691a55274800dbeb8702a926cd13bea84c3abd0dbe53719: sandbox 176f14a21afccd486691a55274800dbeb8702a926cd13bea84c3abd0dbe53719 not found" Jul 15 04:45:55.882670 dockerd[2532]: time="2025-07-15T04:45:55.882649006Z" level=warning msg="error locating sandbox id c154938baf0be69430633f94fb9927556a6687f9755c4194684ae2ec9f12e3df: sandbox c154938baf0be69430633f94fb9927556a6687f9755c4194684ae2ec9f12e3df not found" Jul 15 04:45:55.882923 dockerd[2532]: time="2025-07-15T04:45:55.882814663Z" level=info msg="Loading containers: done." Jul 15 04:45:55.895598 dockerd[2532]: time="2025-07-15T04:45:55.894957171Z" 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 Jul 15 04:45:55.895598 dockerd[2532]: time="2025-07-15T04:45:55.895030259Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jul 15 04:45:55.895598 dockerd[2532]: time="2025-07-15T04:45:55.895062062Z" level=info msg="Initializing buildkit" Jul 15 04:45:55.908968 dockerd[2532]: time="2025-07-15T04:45:55.908882625Z" level=info msg="Completed buildkit initialization" Jul 15 04:45:55.915997 dockerd[2532]: time="2025-07-15T04:45:55.915967324Z" level=info msg="Daemon has completed initialization" Jul 15 04:45:55.916186 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 15 04:45:55.916903 dockerd[2532]: time="2025-07-15T04:45:55.916857577Z" level=info msg="API listen on /run/docker.sock" Jul 15 04:45:56.268459 systemd[1]: run-docker-netns-c154938baf0b.mount: Deactivated successfully. Jul 15 04:45:59.499696 systemd[1]: Started sshd@24-10.0.0.71:22-10.0.0.1:45102.service - OpenSSH per-connection server daemon (10.0.0.1:45102). Jul 15 04:45:59.558917 sshd[2767]: Accepted publickey for core from 10.0.0.1 port 45102 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:59.560071 sshd-session[2767]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:59.563772 systemd-logind[1447]: New session 25 of user core. Jul 15 04:45:59.572111 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 15 04:45:59.627297 sshd[2770]: Connection closed by 10.0.0.1 port 45102 Jul 15 04:45:59.627188 sshd-session[2767]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:59.637846 systemd[1]: sshd@24-10.0.0.71:22-10.0.0.1:45102.service: Deactivated successfully. Jul 15 04:45:59.639452 systemd[1]: session-25.scope: Deactivated successfully. Jul 15 04:45:59.640090 systemd-logind[1447]: Session 25 logged out. Waiting for processes to exit. Jul 15 04:45:59.642336 systemd[1]: Started sshd@25-10.0.0.71:22-10.0.0.1:45108.service - OpenSSH per-connection server daemon (10.0.0.1:45108). Jul 15 04:45:59.643467 systemd-logind[1447]: Removed session 25. Jul 15 04:45:59.695399 sshd[2776]: Accepted publickey for core from 10.0.0.1 port 45108 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:59.698134 sshd-session[2776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:59.702386 systemd-logind[1447]: New session 26 of user core. Jul 15 04:45:59.709520 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 15 04:45:59.764795 sshd[2779]: Connection closed by 10.0.0.1 port 45108 Jul 15 04:45:59.764303 sshd-session[2776]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:59.777872 systemd[1]: sshd@25-10.0.0.71:22-10.0.0.1:45108.service: Deactivated successfully. Jul 15 04:45:59.779321 systemd[1]: session-26.scope: Deactivated successfully. Jul 15 04:45:59.780029 systemd-logind[1447]: Session 26 logged out. Waiting for processes to exit. Jul 15 04:45:59.782137 systemd[1]: Started sshd@26-10.0.0.71:22-10.0.0.1:45120.service - OpenSSH per-connection server daemon (10.0.0.1:45120). Jul 15 04:45:59.782773 systemd-logind[1447]: Removed session 26. Jul 15 04:45:59.824513 sshd[2785]: Accepted publickey for core from 10.0.0.1 port 45120 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:59.825869 sshd-session[2785]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:59.830886 systemd-logind[1447]: New session 27 of user core. Jul 15 04:45:59.841127 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 15 04:45:59.898256 sshd[2788]: Connection closed by 10.0.0.1 port 45120 Jul 15 04:45:59.898580 sshd-session[2785]: pam_unix(sshd:session): session closed for user core Jul 15 04:45:59.909774 systemd[1]: sshd@26-10.0.0.71:22-10.0.0.1:45120.service: Deactivated successfully. Jul 15 04:45:59.912206 systemd[1]: session-27.scope: Deactivated successfully. Jul 15 04:45:59.912783 systemd-logind[1447]: Session 27 logged out. Waiting for processes to exit. Jul 15 04:45:59.916005 systemd[1]: Started sshd@27-10.0.0.71:22-10.0.0.1:45128.service - OpenSSH per-connection server daemon (10.0.0.1:45128). Jul 15 04:45:59.916615 systemd-logind[1447]: Removed session 27. Jul 15 04:45:59.988771 sshd[2801]: Accepted publickey for core from 10.0.0.1 port 45128 ssh2: RSA SHA256:sv36Sv5cF+dK4scc2r2cUvpDU+BCYvXiqSSRxSnX4+c Jul 15 04:45:59.990646 sshd-session[2801]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 04:45:59.997876 systemd-logind[1447]: New session 28 of user core. Jul 15 04:46:00.014136 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 15 04:46:00.083443 systemd[1]: var-lib-docker-overlay2-5cabb07af12dbb90b634dcddf202568bdd262247ec1095b187e886c08e675625\x2dinit-merged.mount: Deactivated successfully. Jul 15 04:46:00.120292 containerd[2485]: time="2025-07-15T04:46:00.120220491Z" level=info msg="connecting to shim 1630d1fb8892ed984cc69a2558a50287afe812b375be8e5b245bb635ff490324" address="unix:///run/containerd/s/266ec4fca4783424d60df55ed8d505ea90d783765fa9d2cd278885db64e82c29" namespace=moby protocol=ttrpc version=3 Jul 15 04:46:00.148103 systemd[1]: Started docker-1630d1fb8892ed984cc69a2558a50287afe812b375be8e5b245bb635ff490324.scope - libcontainer container 1630d1fb8892ed984cc69a2558a50287afe812b375be8e5b245bb635ff490324. Jul 15 04:46:00.160296 systemd-resolved[1297]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 15 04:46:00.185278 kernel: docker0: port 1(veth5ede6a8) entered blocking state Jul 15 04:46:00.185360 kernel: docker0: port 1(veth5ede6a8) entered disabled state Jul 15 04:46:00.185376 kernel: veth5ede6a8: entered allmulticast mode Jul 15 04:46:00.185390 kernel: veth5ede6a8: entered promiscuous mode Jul 15 04:46:00.185992 systemd-networkd[1396]: veth5ede6a8: Link UP Jul 15 04:46:00.197976 kernel: eth0: renamed from vethc7f149b Jul 15 04:46:00.202080 kernel: docker0: port 1(veth5ede6a8) entered blocking state Jul 15 04:46:00.202126 kernel: docker0: port 1(veth5ede6a8) entered forwarding state Jul 15 04:46:00.202141 systemd-networkd[1396]: veth5ede6a8: Gained carrier Jul 15 04:46:00.202425 systemd-networkd[1396]: docker0: Gained carrier Jul 15 04:46:00.223548 sshd[2804]: Connection closed by 10.0.0.1 port 45128 Jul 15 04:46:00.223863 sshd-session[2801]: pam_unix(sshd:session): session closed for user core Jul 15 04:46:00.227171 systemd[1]: sshd@27-10.0.0.71:22-10.0.0.1:45128.service: Deactivated successfully. Jul 15 04:46:00.230339 systemd[1]: session-28.scope: Deactivated successfully. Jul 15 04:46:00.232476 systemd-logind[1447]: Session 28 logged out. Waiting for processes to exit. Jul 15 04:46:00.233713 systemd-logind[1447]: Removed session 28. Jul 15 04:46:01.642111 systemd-networkd[1396]: veth5ede6a8: Gained IPv6LL