May 9 04:48:46.869911 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 9 04:48:46.869933 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Fri May 9 03:42:00 -00 2025 May 9 04:48:46.869943 kernel: KASLR enabled May 9 04:48:46.869948 kernel: efi: EFI v2.7 by EDK II May 9 04:48:46.869954 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 May 9 04:48:46.869959 kernel: random: crng init done May 9 04:48:46.869966 kernel: secureboot: Secure boot disabled May 9 04:48:46.869971 kernel: ACPI: Early table checksum verification disabled May 9 04:48:46.869977 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) May 9 04:48:46.869984 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 9 04:48:46.869990 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.869996 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870001 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870007 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870014 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870021 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870028 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870033 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870039 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 9 04:48:46.870045 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 9 04:48:46.870051 kernel: NUMA: Failed to initialise from firmware May 9 04:48:46.870057 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 9 04:48:46.870063 kernel: NUMA: NODE_DATA [mem 0xdc955e00-0xdc95cfff] May 9 04:48:46.870069 kernel: Zone ranges: May 9 04:48:46.870075 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 9 04:48:46.870082 kernel: DMA32 empty May 9 04:48:46.870088 kernel: Normal empty May 9 04:48:46.870094 kernel: Device empty May 9 04:48:46.870099 kernel: Movable zone start for each node May 9 04:48:46.870105 kernel: Early memory node ranges May 9 04:48:46.870111 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] May 9 04:48:46.870117 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] May 9 04:48:46.870123 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] May 9 04:48:46.870129 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] May 9 04:48:46.870135 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] May 9 04:48:46.870141 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] May 9 04:48:46.870147 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] May 9 04:48:46.870153 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] May 9 04:48:46.870160 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] May 9 04:48:46.870166 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 9 04:48:46.870174 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 9 04:48:46.870181 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 9 04:48:46.870187 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 9 04:48:46.870195 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 9 04:48:46.870201 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 9 04:48:46.870207 kernel: psci: probing for conduit method from ACPI. May 9 04:48:46.870214 kernel: psci: PSCIv1.1 detected in firmware. May 9 04:48:46.870220 kernel: psci: Using standard PSCI v0.2 function IDs May 9 04:48:46.870226 kernel: psci: Trusted OS migration not required May 9 04:48:46.870232 kernel: psci: SMC Calling Convention v1.1 May 9 04:48:46.870239 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 9 04:48:46.870245 kernel: percpu: Embedded 31 pages/cpu s87016 r8192 d31768 u126976 May 9 04:48:46.870251 kernel: pcpu-alloc: s87016 r8192 d31768 u126976 alloc=31*4096 May 9 04:48:46.870258 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 9 04:48:46.870266 kernel: Detected PIPT I-cache on CPU0 May 9 04:48:46.870272 kernel: CPU features: detected: GIC system register CPU interface May 9 04:48:46.870278 kernel: CPU features: detected: Hardware dirty bit management May 9 04:48:46.870284 kernel: CPU features: detected: Spectre-v4 May 9 04:48:46.870291 kernel: CPU features: detected: Spectre-BHB May 9 04:48:46.870297 kernel: CPU features: kernel page table isolation forced ON by KASLR May 9 04:48:46.870303 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 9 04:48:46.870310 kernel: CPU features: detected: ARM erratum 1418040 May 9 04:48:46.870316 kernel: CPU features: detected: SSBS not fully self-synchronizing May 9 04:48:46.870322 kernel: alternatives: applying boot alternatives May 9 04:48:46.870329 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=180634d3e256b1dbb5700949694cb34c82ca79af028365e078744f4de51d78d8 May 9 04:48:46.870337 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 9 04:48:46.870344 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 9 04:48:46.870350 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 9 04:48:46.870356 kernel: Fallback order for Node 0: 0 May 9 04:48:46.870363 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 9 04:48:46.870369 kernel: Policy zone: DMA May 9 04:48:46.870375 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 9 04:48:46.870381 kernel: software IO TLB: area num 4. May 9 04:48:46.870387 kernel: software IO TLB: mapped [mem 0x00000000d5000000-0x00000000d9000000] (64MB) May 9 04:48:46.870394 kernel: Memory: 2386500K/2572288K available (10432K kernel code, 2202K rwdata, 8168K rodata, 39040K init, 993K bss, 185788K reserved, 0K cma-reserved) May 9 04:48:46.870401 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 9 04:48:46.870408 kernel: rcu: Preemptible hierarchical RCU implementation. May 9 04:48:46.870415 kernel: rcu: RCU event tracing is enabled. May 9 04:48:46.870422 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 9 04:48:46.870428 kernel: Trampoline variant of Tasks RCU enabled. May 9 04:48:46.870435 kernel: Tracing variant of Tasks RCU enabled. May 9 04:48:46.870441 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 9 04:48:46.870448 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 9 04:48:46.870454 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 9 04:48:46.870460 kernel: GICv3: 256 SPIs implemented May 9 04:48:46.870467 kernel: GICv3: 0 Extended SPIs implemented May 9 04:48:46.870473 kernel: Root IRQ handler: gic_handle_irq May 9 04:48:46.870479 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 9 04:48:46.870486 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 9 04:48:46.870493 kernel: ITS [mem 0x08080000-0x0809ffff] May 9 04:48:46.870499 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) May 9 04:48:46.870506 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) May 9 04:48:46.870512 kernel: GICv3: using LPI property table @0x00000000400f0000 May 9 04:48:46.870518 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 May 9 04:48:46.870525 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 9 04:48:46.870531 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 04:48:46.870537 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 9 04:48:46.870544 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 9 04:48:46.870550 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 9 04:48:46.870558 kernel: arm-pv: using stolen time PV May 9 04:48:46.870564 kernel: Console: colour dummy device 80x25 May 9 04:48:46.870571 kernel: ACPI: Core revision 20230628 May 9 04:48:46.870578 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 9 04:48:46.870584 kernel: pid_max: default: 32768 minimum: 301 May 9 04:48:46.870591 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 9 04:48:46.870597 kernel: landlock: Up and running. May 9 04:48:46.870604 kernel: SELinux: Initializing. May 9 04:48:46.870610 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 04:48:46.870618 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 04:48:46.870624 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 9 04:48:46.870631 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 04:48:46.870638 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 04:48:46.870645 kernel: rcu: Hierarchical SRCU implementation. May 9 04:48:46.870651 kernel: rcu: Max phase no-delay instances is 400. May 9 04:48:46.870658 kernel: Platform MSI: ITS@0x8080000 domain created May 9 04:48:46.870664 kernel: PCI/MSI: ITS@0x8080000 domain created May 9 04:48:46.870670 kernel: Remapping and enabling EFI services. May 9 04:48:46.870678 kernel: smp: Bringing up secondary CPUs ... May 9 04:48:46.870689 kernel: Detected PIPT I-cache on CPU1 May 9 04:48:46.870696 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 9 04:48:46.870704 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 May 9 04:48:46.870711 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 04:48:46.870877 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 9 04:48:46.870886 kernel: Detected PIPT I-cache on CPU2 May 9 04:48:46.870893 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 9 04:48:46.870900 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 May 9 04:48:46.870912 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 04:48:46.870919 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 9 04:48:46.870926 kernel: Detected PIPT I-cache on CPU3 May 9 04:48:46.870933 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 9 04:48:46.870939 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 May 9 04:48:46.870946 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 04:48:46.870953 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 9 04:48:46.870960 kernel: smp: Brought up 1 node, 4 CPUs May 9 04:48:46.870966 kernel: SMP: Total of 4 processors activated. May 9 04:48:46.870975 kernel: CPU features: detected: 32-bit EL0 Support May 9 04:48:46.870982 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 9 04:48:46.870988 kernel: CPU features: detected: Common not Private translations May 9 04:48:46.870995 kernel: CPU features: detected: CRC32 instructions May 9 04:48:46.871002 kernel: CPU features: detected: Enhanced Virtualization Traps May 9 04:48:46.871009 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 9 04:48:46.871015 kernel: CPU features: detected: LSE atomic instructions May 9 04:48:46.871022 kernel: CPU features: detected: Privileged Access Never May 9 04:48:46.871029 kernel: CPU features: detected: RAS Extension Support May 9 04:48:46.871038 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 9 04:48:46.871044 kernel: CPU: All CPU(s) started at EL1 May 9 04:48:46.871051 kernel: alternatives: applying system-wide alternatives May 9 04:48:46.871058 kernel: devtmpfs: initialized May 9 04:48:46.871065 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 9 04:48:46.871072 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 9 04:48:46.871079 kernel: pinctrl core: initialized pinctrl subsystem May 9 04:48:46.871086 kernel: SMBIOS 3.0.0 present. May 9 04:48:46.871092 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 9 04:48:46.871100 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 9 04:48:46.871107 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 9 04:48:46.871114 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 9 04:48:46.871121 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 9 04:48:46.871128 kernel: audit: initializing netlink subsys (disabled) May 9 04:48:46.871135 kernel: audit: type=2000 audit(0.026:1): state=initialized audit_enabled=0 res=1 May 9 04:48:46.871142 kernel: thermal_sys: Registered thermal governor 'step_wise' May 9 04:48:46.871149 kernel: cpuidle: using governor menu May 9 04:48:46.871156 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 9 04:48:46.871164 kernel: ASID allocator initialised with 32768 entries May 9 04:48:46.871171 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 9 04:48:46.871177 kernel: Serial: AMBA PL011 UART driver May 9 04:48:46.871184 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 9 04:48:46.871191 kernel: Modules: 0 pages in range for non-PLT usage May 9 04:48:46.871198 kernel: Modules: 509024 pages in range for PLT usage May 9 04:48:46.871205 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 9 04:48:46.871211 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 9 04:48:46.871218 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 9 04:48:46.871227 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 9 04:48:46.871234 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 9 04:48:46.871240 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 9 04:48:46.871247 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 9 04:48:46.871254 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 9 04:48:46.871260 kernel: ACPI: Added _OSI(Module Device) May 9 04:48:46.871267 kernel: ACPI: Added _OSI(Processor Device) May 9 04:48:46.871274 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 9 04:48:46.871281 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 9 04:48:46.871289 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 9 04:48:46.871296 kernel: ACPI: Interpreter enabled May 9 04:48:46.871303 kernel: ACPI: Using GIC for interrupt routing May 9 04:48:46.871313 kernel: ACPI: MCFG table detected, 1 entries May 9 04:48:46.871320 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 9 04:48:46.871327 kernel: printk: console [ttyAMA0] enabled May 9 04:48:46.871334 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 9 04:48:46.871468 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 9 04:48:46.871541 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 9 04:48:46.871603 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 9 04:48:46.871663 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 9 04:48:46.871734 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 9 04:48:46.871744 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 9 04:48:46.871751 kernel: PCI host bridge to bus 0000:00 May 9 04:48:46.871820 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 9 04:48:46.871979 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 9 04:48:46.872045 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 9 04:48:46.872101 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 9 04:48:46.872179 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 9 04:48:46.872253 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 9 04:48:46.872316 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 9 04:48:46.872377 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 9 04:48:46.872444 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 9 04:48:46.872506 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 9 04:48:46.872568 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 9 04:48:46.872629 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 9 04:48:46.872685 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 9 04:48:46.872758 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 9 04:48:46.872817 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 9 04:48:46.872829 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 9 04:48:46.872836 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 9 04:48:46.872843 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 9 04:48:46.872914 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 9 04:48:46.872922 kernel: iommu: Default domain type: Translated May 9 04:48:46.872929 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 9 04:48:46.872936 kernel: efivars: Registered efivars operations May 9 04:48:46.873045 kernel: vgaarb: loaded May 9 04:48:46.873054 kernel: clocksource: Switched to clocksource arch_sys_counter May 9 04:48:46.873066 kernel: VFS: Disk quotas dquot_6.6.0 May 9 04:48:46.873073 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 9 04:48:46.873080 kernel: pnp: PnP ACPI init May 9 04:48:46.873185 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 9 04:48:46.873196 kernel: pnp: PnP ACPI: found 1 devices May 9 04:48:46.873203 kernel: NET: Registered PF_INET protocol family May 9 04:48:46.873210 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 9 04:48:46.873217 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 9 04:48:46.873227 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 9 04:48:46.873234 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 9 04:48:46.873241 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 9 04:48:46.873248 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 9 04:48:46.873255 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 04:48:46.873262 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 04:48:46.873268 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 9 04:48:46.873275 kernel: PCI: CLS 0 bytes, default 64 May 9 04:48:46.873282 kernel: kvm [1]: HYP mode not available May 9 04:48:46.873290 kernel: Initialise system trusted keyrings May 9 04:48:46.873297 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 9 04:48:46.873304 kernel: Key type asymmetric registered May 9 04:48:46.873310 kernel: Asymmetric key parser 'x509' registered May 9 04:48:46.873317 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 9 04:48:46.873324 kernel: io scheduler mq-deadline registered May 9 04:48:46.873331 kernel: io scheduler kyber registered May 9 04:48:46.873338 kernel: io scheduler bfq registered May 9 04:48:46.873345 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 9 04:48:46.873353 kernel: ACPI: button: Power Button [PWRB] May 9 04:48:46.873361 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 9 04:48:46.873425 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 9 04:48:46.873434 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 9 04:48:46.873441 kernel: thunder_xcv, ver 1.0 May 9 04:48:46.873448 kernel: thunder_bgx, ver 1.0 May 9 04:48:46.873455 kernel: nicpf, ver 1.0 May 9 04:48:46.873461 kernel: nicvf, ver 1.0 May 9 04:48:46.873530 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 9 04:48:46.873593 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-09T04:48:46 UTC (1746766126) May 9 04:48:46.873602 kernel: hid: raw HID events driver (C) Jiri Kosina May 9 04:48:46.873609 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 9 04:48:46.873616 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 9 04:48:46.873623 kernel: watchdog: Hard watchdog permanently disabled May 9 04:48:46.873630 kernel: NET: Registered PF_INET6 protocol family May 9 04:48:46.873637 kernel: Segment Routing with IPv6 May 9 04:48:46.873643 kernel: In-situ OAM (IOAM) with IPv6 May 9 04:48:46.873652 kernel: NET: Registered PF_PACKET protocol family May 9 04:48:46.873659 kernel: Key type dns_resolver registered May 9 04:48:46.873666 kernel: registered taskstats version 1 May 9 04:48:46.873672 kernel: Loading compiled-in X.509 certificates May 9 04:48:46.873679 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: aad33ee745b4b133d332bac6576e33058e4e0478' May 9 04:48:46.873686 kernel: Key type .fscrypt registered May 9 04:48:46.873693 kernel: Key type fscrypt-provisioning registered May 9 04:48:46.873700 kernel: ima: No TPM chip found, activating TPM-bypass! May 9 04:48:46.873707 kernel: ima: Allocated hash algorithm: sha1 May 9 04:48:46.873725 kernel: ima: No architecture policies found May 9 04:48:46.873733 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 9 04:48:46.873740 kernel: clk: Disabling unused clocks May 9 04:48:46.873746 kernel: Warning: unable to open an initial console. May 9 04:48:46.873753 kernel: Freeing unused kernel memory: 39040K May 9 04:48:46.873760 kernel: Run /init as init process May 9 04:48:46.873767 kernel: with arguments: May 9 04:48:46.873774 kernel: /init May 9 04:48:46.873780 kernel: with environment: May 9 04:48:46.873789 kernel: HOME=/ May 9 04:48:46.873796 kernel: TERM=linux May 9 04:48:46.873802 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 9 04:48:46.873810 systemd[1]: Successfully made /usr/ read-only. May 9 04:48:46.873820 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 9 04:48:46.873828 systemd[1]: Detected virtualization kvm. May 9 04:48:46.873835 systemd[1]: Detected architecture arm64. May 9 04:48:46.873844 systemd[1]: Running in initrd. May 9 04:48:46.873868 systemd[1]: No hostname configured, using default hostname. May 9 04:48:46.873876 systemd[1]: Hostname set to . May 9 04:48:46.873883 systemd[1]: Initializing machine ID from VM UUID. May 9 04:48:46.873891 systemd[1]: Queued start job for default target initrd.target. May 9 04:48:46.873898 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 04:48:46.873905 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 04:48:46.873913 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 9 04:48:46.873923 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 04:48:46.873931 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 9 04:48:46.873939 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 9 04:48:46.873947 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 9 04:48:46.873955 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 9 04:48:46.873963 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 04:48:46.873970 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 04:48:46.873979 systemd[1]: Reached target paths.target - Path Units. May 9 04:48:46.873986 systemd[1]: Reached target slices.target - Slice Units. May 9 04:48:46.873994 systemd[1]: Reached target swap.target - Swaps. May 9 04:48:46.874001 systemd[1]: Reached target timers.target - Timer Units. May 9 04:48:46.874008 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 9 04:48:46.874016 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 04:48:46.874023 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 9 04:48:46.874031 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 9 04:48:46.874038 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 04:48:46.874047 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 04:48:46.874054 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 04:48:46.874062 systemd[1]: Reached target sockets.target - Socket Units. May 9 04:48:46.874069 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 9 04:48:46.874077 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 04:48:46.874084 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 9 04:48:46.874092 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 9 04:48:46.874099 systemd[1]: Starting systemd-fsck-usr.service... May 9 04:48:46.874108 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 04:48:46.874115 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 04:48:46.874123 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 04:48:46.874130 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 9 04:48:46.874138 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 04:48:46.874147 systemd[1]: Finished systemd-fsck-usr.service. May 9 04:48:46.874155 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 9 04:48:46.874180 systemd-journald[238]: Collecting audit messages is disabled. May 9 04:48:46.874200 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 04:48:46.874208 systemd-journald[238]: Journal started May 9 04:48:46.874226 systemd-journald[238]: Runtime Journal (/run/log/journal/82abb1c92bea4285be987f32bd913f04) is 5.9M, max 47.3M, 41.4M free. May 9 04:48:46.877435 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 04:48:46.860814 systemd-modules-load[239]: Inserted module 'overlay' May 9 04:48:46.882865 systemd[1]: Started systemd-journald.service - Journal Service. May 9 04:48:46.884904 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 9 04:48:46.887874 kernel: Bridge firewalling registered May 9 04:48:46.886918 systemd-modules-load[239]: Inserted module 'br_netfilter' May 9 04:48:46.893088 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 04:48:46.894125 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 04:48:46.895927 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 04:48:46.902073 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 04:48:46.904623 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 9 04:48:46.906361 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 04:48:46.907195 systemd-tmpfiles[258]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 9 04:48:46.909926 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 04:48:46.911078 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 04:48:46.917340 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 04:48:46.918483 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 04:48:46.922052 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 04:48:46.927150 dracut-cmdline[273]: 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=180634d3e256b1dbb5700949694cb34c82ca79af028365e078744f4de51d78d8 May 9 04:48:46.961739 systemd-resolved[290]: Positive Trust Anchors: May 9 04:48:46.961755 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 04:48:46.961785 systemd-resolved[290]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 9 04:48:46.966436 systemd-resolved[290]: Defaulting to hostname 'linux'. May 9 04:48:46.967418 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 04:48:46.969701 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 04:48:47.006873 kernel: SCSI subsystem initialized May 9 04:48:47.011863 kernel: Loading iSCSI transport class v2.0-870. May 9 04:48:47.018866 kernel: iscsi: registered transport (tcp) May 9 04:48:47.031878 kernel: iscsi: registered transport (qla4xxx) May 9 04:48:47.031922 kernel: QLogic iSCSI HBA Driver May 9 04:48:47.047449 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 9 04:48:47.063865 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 9 04:48:47.065050 systemd[1]: Reached target network-pre.target - Preparation for Network. May 9 04:48:47.108563 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 9 04:48:47.110543 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 9 04:48:47.169876 kernel: raid6: neonx8 gen() 15750 MB/s May 9 04:48:47.186874 kernel: raid6: neonx4 gen() 15766 MB/s May 9 04:48:47.203874 kernel: raid6: neonx2 gen() 13167 MB/s May 9 04:48:47.220870 kernel: raid6: neonx1 gen() 10529 MB/s May 9 04:48:47.237872 kernel: raid6: int64x8 gen() 6777 MB/s May 9 04:48:47.254873 kernel: raid6: int64x4 gen() 7330 MB/s May 9 04:48:47.271873 kernel: raid6: int64x2 gen() 6104 MB/s May 9 04:48:47.288874 kernel: raid6: int64x1 gen() 5037 MB/s May 9 04:48:47.288915 kernel: raid6: using algorithm neonx4 gen() 15766 MB/s May 9 04:48:47.305867 kernel: raid6: .... xor() 12346 MB/s, rmw enabled May 9 04:48:47.305880 kernel: raid6: using neon recovery algorithm May 9 04:48:47.313000 kernel: xor: measuring software checksum speed May 9 04:48:47.313029 kernel: 8regs : 21641 MB/sec May 9 04:48:47.313048 kernel: 32regs : 21687 MB/sec May 9 04:48:47.315863 kernel: arm64_neon : 1707 MB/sec May 9 04:48:47.315876 kernel: xor: using function: 32regs (21687 MB/sec) May 9 04:48:47.366875 kernel: Btrfs loaded, zoned=no, fsverity=no May 9 04:48:47.373188 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 9 04:48:47.375365 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 04:48:47.405009 systemd-udevd[493]: Using default interface naming scheme 'v255'. May 9 04:48:47.409521 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 04:48:47.411892 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 9 04:48:47.431953 dracut-pre-trigger[501]: rd.md=0: removing MD RAID activation May 9 04:48:47.453911 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 9 04:48:47.455765 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 04:48:47.504122 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 04:48:47.506343 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 9 04:48:47.548170 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 9 04:48:47.548347 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 9 04:48:47.565899 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 04:48:47.566743 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 04:48:47.566815 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 04:48:47.569714 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 9 04:48:47.571348 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 04:48:47.588274 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (537) May 9 04:48:47.588321 kernel: BTRFS: device fsid 40f1eae7-2721-4eea-912a-4692becebc68 devid 1 transid 40 /dev/vda3 scanned by (udev-worker) (554) May 9 04:48:47.595958 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 04:48:47.603878 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 9 04:48:47.611546 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 9 04:48:47.618863 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 9 04:48:47.626063 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 04:48:47.631916 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 9 04:48:47.632774 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 9 04:48:47.634586 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 9 04:48:47.636658 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 04:48:47.638269 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 04:48:47.640445 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 9 04:48:47.641979 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 9 04:48:47.661723 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 04:48:47.663907 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 9 04:48:47.673866 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 04:48:48.678876 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 04:48:48.679224 disk-uuid[587]: The operation has completed successfully. May 9 04:48:48.700658 systemd[1]: disk-uuid.service: Deactivated successfully. May 9 04:48:48.700769 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 9 04:48:48.729432 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 9 04:48:48.743706 sh[604]: Success May 9 04:48:48.760629 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 9 04:48:48.760675 kernel: device-mapper: uevent: version 1.0.3 May 9 04:48:48.760695 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 9 04:48:48.771890 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 9 04:48:48.796658 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 9 04:48:48.817402 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 9 04:48:48.819700 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 9 04:48:48.829860 kernel: BTRFS info (device dm-0): first mount of filesystem 40f1eae7-2721-4eea-912a-4692becebc68 May 9 04:48:48.829895 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 9 04:48:48.829905 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 9 04:48:48.829915 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 9 04:48:48.829929 kernel: BTRFS info (device dm-0): using free space tree May 9 04:48:48.833034 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 9 04:48:48.834028 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 9 04:48:48.835007 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 9 04:48:48.835613 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 9 04:48:48.836819 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 9 04:48:48.864117 kernel: BTRFS info (device vda6): first mount of filesystem 43f5fbf3-70bc-4d67-8861-0fe39cce4ad6 May 9 04:48:48.864158 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 04:48:48.864168 kernel: BTRFS info (device vda6): using free space tree May 9 04:48:48.866869 kernel: BTRFS info (device vda6): auto enabling async discard May 9 04:48:48.870877 kernel: BTRFS info (device vda6): last unmount of filesystem 43f5fbf3-70bc-4d67-8861-0fe39cce4ad6 May 9 04:48:48.872790 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 9 04:48:48.874639 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 9 04:48:48.938043 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 04:48:48.940978 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 04:48:48.988788 systemd-networkd[791]: lo: Link UP May 9 04:48:48.988799 systemd-networkd[791]: lo: Gained carrier May 9 04:48:48.989556 systemd-networkd[791]: Enumeration completed May 9 04:48:48.989661 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 04:48:48.990060 systemd-networkd[791]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 04:48:48.990064 systemd-networkd[791]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 04:48:48.990618 systemd-networkd[791]: eth0: Link UP May 9 04:48:48.990620 systemd-networkd[791]: eth0: Gained carrier May 9 04:48:48.990628 systemd-networkd[791]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 04:48:48.991065 systemd[1]: Reached target network.target - Network. May 9 04:48:49.012311 ignition[697]: Ignition 2.21.0 May 9 04:48:49.012339 ignition[697]: Stage: fetch-offline May 9 04:48:49.012892 systemd-networkd[791]: eth0: DHCPv4 address 10.0.0.29/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 04:48:49.012368 ignition[697]: no configs at "/usr/lib/ignition/base.d" May 9 04:48:49.012376 ignition[697]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:49.012659 ignition[697]: parsed url from cmdline: "" May 9 04:48:49.012662 ignition[697]: no config URL provided May 9 04:48:49.012667 ignition[697]: reading system config file "/usr/lib/ignition/user.ign" May 9 04:48:49.012675 ignition[697]: no config at "/usr/lib/ignition/user.ign" May 9 04:48:49.012701 ignition[697]: op(1): [started] loading QEMU firmware config module May 9 04:48:49.012705 ignition[697]: op(1): executing: "modprobe" "qemu_fw_cfg" May 9 04:48:49.023829 ignition[697]: op(1): [finished] loading QEMU firmware config module May 9 04:48:49.026820 ignition[697]: parsing config with SHA512: 4a111b738b414a01e43644e3bc801fd414e01c1b36313fb4f88dc15f952d0ef98f9111774d4dd0ffcf4ed7c97258798b5c04507af4bd6c7873c97c03d735113a May 9 04:48:49.032732 unknown[697]: fetched base config from "system" May 9 04:48:49.032744 unknown[697]: fetched user config from "qemu" May 9 04:48:49.032957 ignition[697]: fetch-offline: fetch-offline passed May 9 04:48:49.033039 ignition[697]: Ignition finished successfully May 9 04:48:49.035590 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 9 04:48:49.036874 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 9 04:48:49.037582 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 9 04:48:49.063658 ignition[804]: Ignition 2.21.0 May 9 04:48:49.063678 ignition[804]: Stage: kargs May 9 04:48:49.063810 ignition[804]: no configs at "/usr/lib/ignition/base.d" May 9 04:48:49.063821 ignition[804]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:49.064815 ignition[804]: kargs: kargs passed May 9 04:48:49.064880 ignition[804]: Ignition finished successfully May 9 04:48:49.067616 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 9 04:48:49.069296 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 9 04:48:49.096743 ignition[812]: Ignition 2.21.0 May 9 04:48:49.096758 ignition[812]: Stage: disks May 9 04:48:49.096919 ignition[812]: no configs at "/usr/lib/ignition/base.d" May 9 04:48:49.096930 ignition[812]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:49.097405 ignition[812]: disks: disks passed May 9 04:48:49.099821 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 9 04:48:49.097541 ignition[812]: Ignition finished successfully May 9 04:48:49.101459 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 9 04:48:49.102258 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 9 04:48:49.103112 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 04:48:49.104154 systemd[1]: Reached target sysinit.target - System Initialization. May 9 04:48:49.105508 systemd[1]: Reached target basic.target - Basic System. May 9 04:48:49.107519 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 9 04:48:49.130720 systemd-fsck[822]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 9 04:48:49.134724 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 9 04:48:49.136470 systemd[1]: Mounting sysroot.mount - /sysroot... May 9 04:48:49.191879 kernel: EXT4-fs (vda9): mounted filesystem 6dc42008-f956-4b63-8173-09d769f43317 r/w with ordered data mode. Quota mode: none. May 9 04:48:49.191867 systemd[1]: Mounted sysroot.mount - /sysroot. May 9 04:48:49.192892 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 9 04:48:49.194698 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 04:48:49.196137 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 9 04:48:49.196938 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 9 04:48:49.196976 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 9 04:48:49.196999 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 9 04:48:49.208099 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 9 04:48:49.209762 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 9 04:48:49.213869 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (830) May 9 04:48:49.216054 kernel: BTRFS info (device vda6): first mount of filesystem 43f5fbf3-70bc-4d67-8861-0fe39cce4ad6 May 9 04:48:49.216077 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 04:48:49.216088 kernel: BTRFS info (device vda6): using free space tree May 9 04:48:49.218868 kernel: BTRFS info (device vda6): auto enabling async discard May 9 04:48:49.219665 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 04:48:49.251452 initrd-setup-root[854]: cut: /sysroot/etc/passwd: No such file or directory May 9 04:48:49.255274 initrd-setup-root[861]: cut: /sysroot/etc/group: No such file or directory May 9 04:48:49.258389 initrd-setup-root[868]: cut: /sysroot/etc/shadow: No such file or directory May 9 04:48:49.261378 initrd-setup-root[875]: cut: /sysroot/etc/gshadow: No such file or directory May 9 04:48:49.327252 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 9 04:48:49.328924 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 9 04:48:49.330261 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 9 04:48:49.343872 kernel: BTRFS info (device vda6): last unmount of filesystem 43f5fbf3-70bc-4d67-8861-0fe39cce4ad6 May 9 04:48:49.361034 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 9 04:48:49.372576 ignition[944]: INFO : Ignition 2.21.0 May 9 04:48:49.372576 ignition[944]: INFO : Stage: mount May 9 04:48:49.374015 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 04:48:49.374015 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:49.374015 ignition[944]: INFO : mount: mount passed May 9 04:48:49.374015 ignition[944]: INFO : Ignition finished successfully May 9 04:48:49.376406 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 9 04:48:49.378124 systemd[1]: Starting ignition-files.service - Ignition (files)... May 9 04:48:49.959880 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 9 04:48:49.961328 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 04:48:49.976602 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (956) May 9 04:48:49.976639 kernel: BTRFS info (device vda6): first mount of filesystem 43f5fbf3-70bc-4d67-8861-0fe39cce4ad6 May 9 04:48:49.976657 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 04:48:49.977277 kernel: BTRFS info (device vda6): using free space tree May 9 04:48:49.979862 kernel: BTRFS info (device vda6): auto enabling async discard May 9 04:48:49.980786 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 04:48:50.004214 ignition[973]: INFO : Ignition 2.21.0 May 9 04:48:50.004214 ignition[973]: INFO : Stage: files May 9 04:48:50.005493 ignition[973]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 04:48:50.005493 ignition[973]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:50.005493 ignition[973]: DEBUG : files: compiled without relabeling support, skipping May 9 04:48:50.007741 ignition[973]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 9 04:48:50.007741 ignition[973]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 9 04:48:50.009868 ignition[973]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 9 04:48:50.009868 ignition[973]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 9 04:48:50.009868 ignition[973]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 9 04:48:50.009277 unknown[973]: wrote ssh authorized keys file for user: core May 9 04:48:50.013779 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 9 04:48:50.013779 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 9 04:48:50.013779 ignition[973]: INFO : files: op(4): [started] processing unit "coreos-metadata.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(4): [finished] processing unit "coreos-metadata.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(6): [started] processing unit "docker.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(6): [finished] processing unit "docker.service" May 9 04:48:50.013779 ignition[973]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" May 9 04:48:50.028440 ignition[973]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" May 9 04:48:50.031463 ignition[973]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 9 04:48:50.033747 ignition[973]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" May 9 04:48:50.033747 ignition[973]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" May 9 04:48:50.033747 ignition[973]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" May 9 04:48:50.033747 ignition[973]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" May 9 04:48:50.033747 ignition[973]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" May 9 04:48:50.033747 ignition[973]: INFO : files: files passed May 9 04:48:50.033747 ignition[973]: INFO : Ignition finished successfully May 9 04:48:50.034471 systemd[1]: Finished ignition-files.service - Ignition (files). May 9 04:48:50.036316 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 9 04:48:50.037694 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 9 04:48:50.051792 systemd[1]: ignition-quench.service: Deactivated successfully. May 9 04:48:50.051909 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 9 04:48:50.054034 initrd-setup-root-after-ignition[1002]: grep: /sysroot/oem/oem-release: No such file or directory May 9 04:48:50.055019 initrd-setup-root-after-ignition[1005]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 04:48:50.055019 initrd-setup-root-after-ignition[1005]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 9 04:48:50.057271 initrd-setup-root-after-ignition[1009]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 04:48:50.057931 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 04:48:50.059419 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 9 04:48:50.061444 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 9 04:48:50.104483 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 9 04:48:50.104605 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 9 04:48:50.106231 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 9 04:48:50.107525 systemd[1]: Reached target initrd.target - Initrd Default Target. May 9 04:48:50.108811 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 9 04:48:50.109486 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 9 04:48:50.132579 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 04:48:50.134563 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 9 04:48:50.152950 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 9 04:48:50.153832 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 04:48:50.155372 systemd[1]: Stopped target timers.target - Timer Units. May 9 04:48:50.156668 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 9 04:48:50.156782 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 04:48:50.158585 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 9 04:48:50.160030 systemd[1]: Stopped target basic.target - Basic System. May 9 04:48:50.161210 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 9 04:48:50.162478 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 9 04:48:50.163984 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 9 04:48:50.165495 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 9 04:48:50.168603 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 9 04:48:50.169893 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 9 04:48:50.171336 systemd[1]: Stopped target sysinit.target - System Initialization. May 9 04:48:50.172708 systemd[1]: Stopped target local-fs.target - Local File Systems. May 9 04:48:50.173963 systemd[1]: Stopped target swap.target - Swaps. May 9 04:48:50.175050 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 9 04:48:50.175164 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 9 04:48:50.176862 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 9 04:48:50.178414 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 04:48:50.179785 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 9 04:48:50.179885 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 04:48:50.181440 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 9 04:48:50.181544 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 9 04:48:50.183586 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 9 04:48:50.183714 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 9 04:48:50.185061 systemd[1]: Stopped target paths.target - Path Units. May 9 04:48:50.186271 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 9 04:48:50.190907 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 04:48:50.191827 systemd[1]: Stopped target slices.target - Slice Units. May 9 04:48:50.193460 systemd[1]: Stopped target sockets.target - Socket Units. May 9 04:48:50.194572 systemd[1]: iscsid.socket: Deactivated successfully. May 9 04:48:50.194652 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 9 04:48:50.195768 systemd[1]: iscsiuio.socket: Deactivated successfully. May 9 04:48:50.195841 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 04:48:50.197047 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 9 04:48:50.197147 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 04:48:50.198410 systemd[1]: ignition-files.service: Deactivated successfully. May 9 04:48:50.198503 systemd[1]: Stopped ignition-files.service - Ignition (files). May 9 04:48:50.200283 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 9 04:48:50.201503 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 9 04:48:50.201619 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 9 04:48:50.210313 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 9 04:48:50.210963 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 9 04:48:50.211069 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 9 04:48:50.212418 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 9 04:48:50.212515 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 9 04:48:50.217397 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 9 04:48:50.217481 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 9 04:48:50.222766 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 9 04:48:50.225184 systemd[1]: sysroot-boot.service: Deactivated successfully. May 9 04:48:50.225839 ignition[1030]: INFO : Ignition 2.21.0 May 9 04:48:50.225839 ignition[1030]: INFO : Stage: umount May 9 04:48:50.225839 ignition[1030]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 04:48:50.225839 ignition[1030]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 04:48:50.229096 ignition[1030]: INFO : umount: umount passed May 9 04:48:50.229096 ignition[1030]: INFO : Ignition finished successfully May 9 04:48:50.225904 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 9 04:48:50.228021 systemd[1]: ignition-mount.service: Deactivated successfully. May 9 04:48:50.228097 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 9 04:48:50.231375 systemd[1]: Stopped target network.target - Network. May 9 04:48:50.232053 systemd[1]: ignition-disks.service: Deactivated successfully. May 9 04:48:50.232120 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 9 04:48:50.233311 systemd[1]: ignition-kargs.service: Deactivated successfully. May 9 04:48:50.233349 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 9 04:48:50.234567 systemd[1]: ignition-setup.service: Deactivated successfully. May 9 04:48:50.234604 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 9 04:48:50.235799 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 9 04:48:50.235836 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 9 04:48:50.237086 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 9 04:48:50.237130 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 9 04:48:50.238508 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 9 04:48:50.239801 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 9 04:48:50.245550 systemd[1]: systemd-resolved.service: Deactivated successfully. May 9 04:48:50.245680 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 9 04:48:50.248771 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 9 04:48:50.249019 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 9 04:48:50.249053 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 04:48:50.251456 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 9 04:48:50.254278 systemd[1]: systemd-networkd.service: Deactivated successfully. May 9 04:48:50.254375 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 9 04:48:50.256700 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 9 04:48:50.257544 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 9 04:48:50.257576 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 9 04:48:50.259530 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 9 04:48:50.260195 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 9 04:48:50.260244 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 04:48:50.261757 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 9 04:48:50.261793 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 9 04:48:50.263796 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 9 04:48:50.263874 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 9 04:48:50.265406 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 04:48:50.277812 systemd[1]: systemd-udevd.service: Deactivated successfully. May 9 04:48:50.278752 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 04:48:50.279836 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 9 04:48:50.279910 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 9 04:48:50.281506 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 9 04:48:50.281534 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 9 04:48:50.282780 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 9 04:48:50.282817 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 9 04:48:50.284873 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 9 04:48:50.284916 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 9 04:48:50.286779 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 9 04:48:50.286818 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 04:48:50.289541 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 9 04:48:50.290331 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 9 04:48:50.290399 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 9 04:48:50.292742 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 9 04:48:50.292796 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 04:48:50.295046 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 04:48:50.295085 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 04:48:50.304061 systemd[1]: network-cleanup.service: Deactivated successfully. May 9 04:48:50.304177 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 9 04:48:50.308330 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 9 04:48:50.308437 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 9 04:48:50.310033 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 9 04:48:50.312044 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 9 04:48:50.329015 systemd[1]: Switching root. May 9 04:48:50.346560 systemd-journald[238]: Journal stopped May 9 04:48:50.972132 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). May 9 04:48:50.972181 kernel: SELinux: policy capability network_peer_controls=1 May 9 04:48:50.972193 kernel: SELinux: policy capability open_perms=1 May 9 04:48:50.972202 kernel: SELinux: policy capability extended_socket_class=1 May 9 04:48:50.972213 kernel: SELinux: policy capability always_check_network=0 May 9 04:48:50.972222 kernel: SELinux: policy capability cgroup_seclabel=1 May 9 04:48:50.972232 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 9 04:48:50.972244 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 9 04:48:50.972253 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 9 04:48:50.972262 kernel: audit: type=1403 audit(1746766130.407:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 9 04:48:50.972272 systemd[1]: Successfully loaded SELinux policy in 29.132ms. May 9 04:48:50.972292 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.449ms. May 9 04:48:50.972305 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 9 04:48:50.972315 systemd[1]: Detected virtualization kvm. May 9 04:48:50.972325 systemd[1]: Detected architecture arm64. May 9 04:48:50.972336 systemd[1]: Detected first boot. May 9 04:48:50.972346 systemd[1]: Initializing machine ID from VM UUID. May 9 04:48:50.972356 zram_generator::config[1077]: No configuration found. May 9 04:48:50.972367 kernel: NET: Registered PF_VSOCK protocol family May 9 04:48:50.972377 systemd[1]: Populated /etc with preset unit settings. May 9 04:48:50.972391 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 9 04:48:50.972401 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 9 04:48:50.972411 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 9 04:48:50.972421 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 9 04:48:50.972434 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 9 04:48:50.972445 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 9 04:48:50.972455 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 9 04:48:50.972466 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 9 04:48:50.972476 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 9 04:48:50.972487 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 9 04:48:50.972497 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 9 04:48:50.972507 systemd[1]: Created slice user.slice - User and Session Slice. May 9 04:48:50.972517 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 04:48:50.972527 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 04:48:50.972538 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 9 04:48:50.972548 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 9 04:48:50.972558 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 9 04:48:50.972571 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 04:48:50.972581 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 9 04:48:50.972592 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 04:48:50.972602 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 04:48:50.972614 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 9 04:48:50.972624 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 9 04:48:50.972634 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 9 04:48:50.972644 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 9 04:48:50.972656 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 04:48:50.972677 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 04:48:50.972689 systemd[1]: Reached target slices.target - Slice Units. May 9 04:48:50.972700 systemd[1]: Reached target swap.target - Swaps. May 9 04:48:50.972710 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 9 04:48:50.972720 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 9 04:48:50.972730 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 9 04:48:50.972740 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 04:48:50.972750 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 04:48:50.972760 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 04:48:50.972772 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 9 04:48:50.972782 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 9 04:48:50.972792 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 9 04:48:50.972802 systemd[1]: Mounting media.mount - External Media Directory... May 9 04:48:50.972812 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 9 04:48:50.972822 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 9 04:48:50.972832 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 9 04:48:50.972844 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 9 04:48:50.972869 systemd[1]: Reached target machines.target - Containers. May 9 04:48:50.972880 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 9 04:48:50.972890 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 04:48:50.972900 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 04:48:50.972910 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 9 04:48:50.972921 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 04:48:50.972931 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 04:48:50.972941 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 04:48:50.972951 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 9 04:48:50.972962 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 04:48:50.972972 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 9 04:48:50.972982 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 9 04:48:50.972992 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 9 04:48:50.973002 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 9 04:48:50.973012 systemd[1]: Stopped systemd-fsck-usr.service. May 9 04:48:50.973023 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 9 04:48:50.973033 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 04:48:50.973044 kernel: fuse: init (API version 7.39) May 9 04:48:50.973054 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 04:48:50.973064 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 9 04:48:50.973075 kernel: loop: module loaded May 9 04:48:50.973086 kernel: ACPI: bus type drm_connector registered May 9 04:48:50.973097 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 9 04:48:50.973107 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 9 04:48:50.973118 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 04:48:50.973130 systemd[1]: verity-setup.service: Deactivated successfully. May 9 04:48:50.973140 systemd[1]: Stopped verity-setup.service. May 9 04:48:50.973150 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 9 04:48:50.973160 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 9 04:48:50.973170 systemd[1]: Mounted media.mount - External Media Directory. May 9 04:48:50.973180 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 9 04:48:50.973190 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 9 04:48:50.973200 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 9 04:48:50.973233 systemd-journald[1143]: Collecting audit messages is disabled. May 9 04:48:50.973254 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 9 04:48:50.973264 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 04:48:50.973275 systemd-journald[1143]: Journal started May 9 04:48:50.973295 systemd-journald[1143]: Runtime Journal (/run/log/journal/82abb1c92bea4285be987f32bd913f04) is 5.9M, max 47.3M, 41.4M free. May 9 04:48:50.781352 systemd[1]: Queued start job for default target multi-user.target. May 9 04:48:50.794654 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 9 04:48:50.795007 systemd[1]: systemd-journald.service: Deactivated successfully. May 9 04:48:50.976237 systemd[1]: Started systemd-journald.service - Journal Service. May 9 04:48:50.976999 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 9 04:48:50.977176 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 9 04:48:50.978414 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 04:48:50.978573 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 04:48:50.979788 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 04:48:50.979958 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 04:48:50.982020 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 04:48:50.982198 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 04:48:50.983440 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 9 04:48:50.983599 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 9 04:48:50.984701 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 04:48:50.984885 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 04:48:50.985954 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 04:48:50.987087 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 9 04:48:50.989886 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 9 04:48:50.991059 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 9 04:48:51.003306 systemd[1]: Reached target network-pre.target - Preparation for Network. May 9 04:48:51.005395 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 9 04:48:51.007151 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 9 04:48:51.008073 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 9 04:48:51.008107 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 04:48:51.009687 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 9 04:48:51.027707 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 9 04:48:51.028660 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 04:48:51.030006 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 9 04:48:51.031710 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 9 04:48:51.032774 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 04:48:51.035999 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 9 04:48:51.037020 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 04:48:51.037991 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 04:48:51.038626 systemd-journald[1143]: Time spent on flushing to /var/log/journal/82abb1c92bea4285be987f32bd913f04 is 22.850ms for 845 entries. May 9 04:48:51.038626 systemd-journald[1143]: System Journal (/var/log/journal/82abb1c92bea4285be987f32bd913f04) is 8M, max 195.6M, 187.6M free. May 9 04:48:51.071790 systemd-journald[1143]: Received client request to flush runtime journal. May 9 04:48:51.072034 kernel: loop0: detected capacity change from 0 to 107312 May 9 04:48:51.040710 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 9 04:48:51.044033 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 9 04:48:51.051429 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 04:48:51.053066 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 9 04:48:51.054420 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 9 04:48:51.072239 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 9 04:48:51.074013 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 9 04:48:51.079283 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 9 04:48:51.079913 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 9 04:48:51.083007 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 9 04:48:51.084345 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 04:48:51.097536 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 9 04:48:51.099824 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 04:48:51.110976 kernel: loop1: detected capacity change from 0 to 138376 May 9 04:48:51.114426 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 9 04:48:51.130034 systemd-tmpfiles[1210]: ACLs are not supported, ignoring. May 9 04:48:51.130050 systemd-tmpfiles[1210]: ACLs are not supported, ignoring. May 9 04:48:51.136887 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 04:48:51.139884 kernel: loop2: detected capacity change from 0 to 107312 May 9 04:48:51.144870 kernel: loop3: detected capacity change from 0 to 138376 May 9 04:48:51.149811 (sd-merge)[1214]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 9 04:48:51.150168 (sd-merge)[1214]: Merged extensions into '/usr'. May 9 04:48:51.153892 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 9 04:48:51.156469 systemd[1]: Starting ensure-sysext.service... May 9 04:48:51.158286 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 04:48:51.182780 systemd[1]: Reload requested from client PID 1217 ('systemctl') (unit ensure-sysext.service)... May 9 04:48:51.182792 systemd[1]: Reloading... May 9 04:48:51.190720 systemd-tmpfiles[1218]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 9 04:48:51.190753 systemd-tmpfiles[1218]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 9 04:48:51.191008 systemd-tmpfiles[1218]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 9 04:48:51.191193 systemd-tmpfiles[1218]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 9 04:48:51.191819 systemd-tmpfiles[1218]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 9 04:48:51.192038 systemd-tmpfiles[1218]: ACLs are not supported, ignoring. May 9 04:48:51.192088 systemd-tmpfiles[1218]: ACLs are not supported, ignoring. May 9 04:48:51.194989 systemd-tmpfiles[1218]: Detected autofs mount point /boot during canonicalization of boot. May 9 04:48:51.194999 systemd-tmpfiles[1218]: Skipping /boot May 9 04:48:51.207642 systemd-tmpfiles[1218]: Detected autofs mount point /boot during canonicalization of boot. May 9 04:48:51.207661 systemd-tmpfiles[1218]: Skipping /boot May 9 04:48:51.245878 zram_generator::config[1242]: No configuration found. May 9 04:48:51.319614 ldconfig[1188]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 9 04:48:51.344122 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 9 04:48:51.405181 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 9 04:48:51.405545 systemd[1]: Reloading finished in 222 ms. May 9 04:48:51.420344 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 9 04:48:51.433883 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 04:48:51.441450 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 9 04:48:51.443743 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 9 04:48:51.455085 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 9 04:48:51.459887 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 04:48:51.461654 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 9 04:48:51.464529 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 04:48:51.465572 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 04:48:51.472408 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 04:48:51.474162 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 04:48:51.474958 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 04:48:51.475064 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 9 04:48:51.475861 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 04:48:51.476045 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 04:48:51.478796 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 04:48:51.478995 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 04:48:51.482308 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 04:48:51.482460 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 04:48:51.486142 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 04:48:51.487919 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 04:48:51.489620 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 04:48:51.494043 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 04:48:51.494978 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 04:48:51.495085 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 9 04:48:51.501018 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 9 04:48:51.504713 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 04:48:51.504871 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 04:48:51.506210 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 9 04:48:51.507700 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 04:48:51.507835 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 04:48:51.513670 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 04:48:51.516028 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 04:48:51.518153 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 04:48:51.521189 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 04:48:51.522082 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 04:48:51.522187 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 9 04:48:51.523075 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 9 04:48:51.531048 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 04:48:51.531222 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 04:48:51.533884 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 9 04:48:51.535375 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 04:48:51.535983 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 04:48:51.537288 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 04:48:51.537416 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 04:48:51.542710 systemd[1]: Finished ensure-sysext.service. May 9 04:48:51.543678 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 04:48:51.543824 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 04:48:51.545920 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 9 04:48:51.552894 augenrules[1329]: No rules May 9 04:48:51.553198 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 04:48:51.553300 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 04:48:51.555395 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 9 04:48:51.559988 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 04:48:51.564965 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 9 04:48:51.565700 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 9 04:48:51.573974 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 9 04:48:51.575095 systemd[1]: audit-rules.service: Deactivated successfully. May 9 04:48:51.575287 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 9 04:48:51.591184 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 9 04:48:51.606273 systemd-udevd[1339]: Using default interface naming scheme 'v255'. May 9 04:48:51.623218 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 9 04:48:51.624339 systemd[1]: Reached target time-set.target - System Time Set. May 9 04:48:51.625189 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 04:48:51.628721 systemd-resolved[1286]: Positive Trust Anchors: May 9 04:48:51.628740 systemd-resolved[1286]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 04:48:51.628771 systemd-resolved[1286]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 9 04:48:51.628879 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 04:48:51.639120 systemd-resolved[1286]: Defaulting to hostname 'linux'. May 9 04:48:51.640478 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 04:48:51.641767 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 04:48:51.643238 systemd[1]: Reached target sysinit.target - System Initialization. May 9 04:48:51.644409 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 9 04:48:51.645772 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 9 04:48:51.647474 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 9 04:48:51.649092 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 9 04:48:51.650948 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 9 04:48:51.652074 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 9 04:48:51.652108 systemd[1]: Reached target paths.target - Path Units. May 9 04:48:51.653921 systemd[1]: Reached target timers.target - Timer Units. May 9 04:48:51.655574 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 9 04:48:51.658795 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 04:48:51.663993 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 9 04:48:51.665228 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 9 04:48:51.666181 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 9 04:48:51.670950 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 9 04:48:51.672292 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 9 04:48:51.673793 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 04:48:51.678022 systemd[1]: Reached target sockets.target - Socket Units. May 9 04:48:51.678974 systemd[1]: Reached target basic.target - Basic System. May 9 04:48:51.679685 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 9 04:48:51.679716 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 9 04:48:51.682044 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 9 04:48:51.683845 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 9 04:48:51.688688 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 9 04:48:51.690479 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 9 04:48:51.691538 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 9 04:48:51.699049 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 9 04:48:51.705287 jq[1379]: false May 9 04:48:51.706283 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 9 04:48:51.710860 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 9 04:48:51.722615 systemd[1]: Starting systemd-logind.service - User Login Management... May 9 04:48:51.725023 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 9 04:48:51.725559 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 9 04:48:51.728181 systemd[1]: Starting update-engine.service - Update Engine... May 9 04:48:51.734386 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 9 04:48:51.736050 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 9 04:48:51.738393 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 9 04:48:51.738916 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 9 04:48:51.739191 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 9 04:48:51.739353 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 9 04:48:51.741603 jq[1397]: true May 9 04:48:51.742913 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1354) May 9 04:48:51.743240 extend-filesystems[1380]: Found loop2 May 9 04:48:51.744515 extend-filesystems[1380]: Found loop3 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda May 9 04:48:51.744515 extend-filesystems[1380]: Found vda1 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda2 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda3 May 9 04:48:51.744515 extend-filesystems[1380]: Found usr May 9 04:48:51.744515 extend-filesystems[1380]: Found vda4 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda6 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda7 May 9 04:48:51.744515 extend-filesystems[1380]: Found vda9 May 9 04:48:51.751934 jq[1401]: true May 9 04:48:51.752182 systemd[1]: extend-filesystems.service: Deactivated successfully. May 9 04:48:51.756866 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 9 04:48:51.758162 systemd[1]: motdgen.service: Deactivated successfully. May 9 04:48:51.758340 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 9 04:48:51.760521 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 9 04:48:51.811964 update_engine[1395]: I20250509 04:48:51.810278 1395 main.cc:92] Flatcar Update Engine starting May 9 04:48:51.819318 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 04:48:51.824675 dbus-daemon[1377]: [system] SELinux support is enabled May 9 04:48:51.825086 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 9 04:48:51.826080 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 9 04:48:51.829914 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 9 04:48:51.829940 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 9 04:48:51.830903 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 9 04:48:51.830925 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 9 04:48:51.842562 systemd[1]: Started update-engine.service - Update Engine. May 9 04:48:51.843034 update_engine[1395]: I20250509 04:48:51.842988 1395 update_check_scheduler.cc:74] Next update check in 3m44s May 9 04:48:51.845148 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 9 04:48:51.852706 systemd-logind[1390]: New seat seat0. May 9 04:48:51.853239 systemd[1]: Started systemd-logind.service - User Login Management. May 9 04:48:51.855461 bash[1424]: Updated "/home/core/.ssh/authorized_keys" May 9 04:48:51.857403 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 9 04:48:51.861543 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 9 04:48:51.863803 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 9 04:48:51.884560 systemd-networkd[1355]: lo: Link UP May 9 04:48:51.884567 systemd-networkd[1355]: lo: Gained carrier May 9 04:48:51.889055 systemd-networkd[1355]: Enumeration completed May 9 04:48:51.889157 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 04:48:51.889492 systemd-networkd[1355]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 04:48:51.889500 systemd-networkd[1355]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 04:48:51.889993 systemd-networkd[1355]: eth0: Link UP May 9 04:48:51.889996 systemd-networkd[1355]: eth0: Gained carrier May 9 04:48:51.890009 systemd-networkd[1355]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 04:48:51.890390 systemd[1]: Reached target network.target - Network. May 9 04:48:51.894107 systemd[1]: Starting containerd.service - containerd container runtime... May 9 04:48:51.896136 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 9 04:48:51.931144 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 9 04:48:51.939343 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 04:48:51.944972 systemd-networkd[1355]: eth0: DHCPv4 address 10.0.0.29/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 04:48:51.945559 systemd-timesyncd[1335]: Network configuration changed, trying to establish connection. May 9 04:48:51.947012 systemd-timesyncd[1335]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 9 04:48:51.947065 systemd-timesyncd[1335]: Initial clock synchronization to Fri 2025-05-09 04:48:51.825795 UTC. May 9 04:48:51.949351 (ntainerd)[1452]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 04:48:51.957488 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 9 04:48:51.995549 systemd-logind[1390]: Watching system buttons on /dev/input/event0 (Power Button) May 9 04:48:51.999736 locksmithd[1426]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 9 04:48:52.030049 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 04:48:52.146085 containerd[1452]: time="2025-05-09T04:48:52Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 9 04:48:52.146673 containerd[1452]: time="2025-05-09T04:48:52.146628531Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 9 04:48:52.155051 containerd[1452]: time="2025-05-09T04:48:52.155011911Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.249µs" May 9 04:48:52.155051 containerd[1452]: time="2025-05-09T04:48:52.155042994Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 9 04:48:52.155132 containerd[1452]: time="2025-05-09T04:48:52.155064351Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 9 04:48:52.155258 containerd[1452]: time="2025-05-09T04:48:52.155228222Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 9 04:48:52.155284 containerd[1452]: time="2025-05-09T04:48:52.155257915Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 9 04:48:52.155301 containerd[1452]: time="2025-05-09T04:48:52.155282845Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:48:52.155358 containerd[1452]: time="2025-05-09T04:48:52.155343185Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:48:52.155376 containerd[1452]: time="2025-05-09T04:48:52.155357992Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:48:52.155599 containerd[1452]: time="2025-05-09T04:48:52.155566363Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:48:52.155599 containerd[1452]: time="2025-05-09T04:48:52.155587243Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:48:52.155648 containerd[1452]: time="2025-05-09T04:48:52.155598676Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:48:52.155648 containerd[1452]: time="2025-05-09T04:48:52.155606655Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 9 04:48:52.155711 containerd[1452]: time="2025-05-09T04:48:52.155692679Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 9 04:48:52.155960 containerd[1452]: time="2025-05-09T04:48:52.155931458Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:48:52.155990 containerd[1452]: time="2025-05-09T04:48:52.155975800Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:48:52.155990 containerd[1452]: time="2025-05-09T04:48:52.155987233Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 9 04:48:52.156587 containerd[1452]: time="2025-05-09T04:48:52.156544264Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 9 04:48:52.156850 containerd[1452]: time="2025-05-09T04:48:52.156826234Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 9 04:48:52.156947 containerd[1452]: time="2025-05-09T04:48:52.156919840Z" level=info msg="metadata content store policy set" policy=shared May 9 04:48:52.160342 containerd[1452]: time="2025-05-09T04:48:52.160305260Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 9 04:48:52.160406 containerd[1452]: time="2025-05-09T04:48:52.160363892Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 9 04:48:52.160406 containerd[1452]: time="2025-05-09T04:48:52.160378382Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 9 04:48:52.160406 containerd[1452]: time="2025-05-09T04:48:52.160391839Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160403351Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160454164Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160465755Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160478816Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160490685Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160500927Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160510574Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 9 04:48:52.160527 containerd[1452]: time="2025-05-09T04:48:52.160522364Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 9 04:48:52.160667 containerd[1452]: time="2025-05-09T04:48:52.160642368Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 9 04:48:52.160667 containerd[1452]: time="2025-05-09T04:48:52.160661899Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 9 04:48:52.160700 containerd[1452]: time="2025-05-09T04:48:52.160676865Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 9 04:48:52.160700 containerd[1452]: time="2025-05-09T04:48:52.160688258Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 9 04:48:52.160731 containerd[1452]: time="2025-05-09T04:48:52.160698500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 9 04:48:52.160731 containerd[1452]: time="2025-05-09T04:48:52.160709933Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 9 04:48:52.160731 containerd[1452]: time="2025-05-09T04:48:52.160720453Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 9 04:48:52.160782 containerd[1452]: time="2025-05-09T04:48:52.160730258Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 9 04:48:52.160782 containerd[1452]: time="2025-05-09T04:48:52.160741532Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 9 04:48:52.160782 containerd[1452]: time="2025-05-09T04:48:52.160751933Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 9 04:48:52.160782 containerd[1452]: time="2025-05-09T04:48:52.160761420Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 9 04:48:52.160997 containerd[1452]: time="2025-05-09T04:48:52.160962924Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 9 04:48:52.160997 containerd[1452]: time="2025-05-09T04:48:52.160985273Z" level=info msg="Start snapshots syncer" May 9 04:48:52.161057 containerd[1452]: time="2025-05-09T04:48:52.161015165Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 9 04:48:52.161866 containerd[1452]: time="2025-05-09T04:48:52.161673941Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 9 04:48:52.161866 containerd[1452]: time="2025-05-09T04:48:52.161755360Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 9 04:48:52.162021 containerd[1452]: time="2025-05-09T04:48:52.161992948Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 9 04:48:52.162159 containerd[1452]: time="2025-05-09T04:48:52.162120296Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 9 04:48:52.162188 containerd[1452]: time="2025-05-09T04:48:52.162159478Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 9 04:48:52.162188 containerd[1452]: time="2025-05-09T04:48:52.162176825Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 9 04:48:52.162248 containerd[1452]: time="2025-05-09T04:48:52.162188695Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 9 04:48:52.162248 containerd[1452]: time="2025-05-09T04:48:52.162216006Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 9 04:48:52.162248 containerd[1452]: time="2025-05-09T04:48:52.162231409Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 9 04:48:52.162296 containerd[1452]: time="2025-05-09T04:48:52.162265826Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 9 04:48:52.162313 containerd[1452]: time="2025-05-09T04:48:52.162303102Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 9 04:48:52.162332 containerd[1452]: time="2025-05-09T04:48:52.162318941Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 9 04:48:52.162350 containerd[1452]: time="2025-05-09T04:48:52.162334384Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 9 04:48:52.162391 containerd[1452]: time="2025-05-09T04:48:52.162373009Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:48:52.162414 containerd[1452]: time="2025-05-09T04:48:52.162394287Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:48:52.162414 containerd[1452]: time="2025-05-09T04:48:52.162407069Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:48:52.162487 containerd[1452]: time="2025-05-09T04:48:52.162470386Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:48:52.162513 containerd[1452]: time="2025-05-09T04:48:52.162489600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 9 04:48:52.162513 containerd[1452]: time="2025-05-09T04:48:52.162502223Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 9 04:48:52.162546 containerd[1452]: time="2025-05-09T04:48:52.162530329Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 9 04:48:52.162645 containerd[1452]: time="2025-05-09T04:48:52.162624094Z" level=info msg="runtime interface created" May 9 04:48:52.162645 containerd[1452]: time="2025-05-09T04:48:52.162636440Z" level=info msg="created NRI interface" May 9 04:48:52.162684 containerd[1452]: time="2025-05-09T04:48:52.162646205Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 9 04:48:52.162684 containerd[1452]: time="2025-05-09T04:48:52.162662997Z" level=info msg="Connect containerd service" May 9 04:48:52.162721 containerd[1452]: time="2025-05-09T04:48:52.162698804Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 04:48:52.163813 containerd[1452]: time="2025-05-09T04:48:52.163778569Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 04:48:52.269314 containerd[1452]: time="2025-05-09T04:48:52.269251655Z" level=info msg="Start subscribing containerd event" May 9 04:48:52.269410 containerd[1452]: time="2025-05-09T04:48:52.269329978Z" level=info msg="Start recovering state" May 9 04:48:52.269430 containerd[1452]: time="2025-05-09T04:48:52.269416121Z" level=info msg="Start event monitor" May 9 04:48:52.269448 containerd[1452]: time="2025-05-09T04:48:52.269428586Z" level=info msg="Start cni network conf syncer for default" May 9 04:48:52.269448 containerd[1452]: time="2025-05-09T04:48:52.269435414Z" level=info msg="Start streaming server" May 9 04:48:52.269448 containerd[1452]: time="2025-05-09T04:48:52.269443115Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 9 04:48:52.269521 containerd[1452]: time="2025-05-09T04:48:52.269449983Z" level=info msg="runtime interface starting up..." May 9 04:48:52.269521 containerd[1452]: time="2025-05-09T04:48:52.269456096Z" level=info msg="starting plugins..." May 9 04:48:52.269788 containerd[1452]: time="2025-05-09T04:48:52.269563477Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 9 04:48:52.269964 containerd[1452]: time="2025-05-09T04:48:52.269940760Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 04:48:52.270082 containerd[1452]: time="2025-05-09T04:48:52.270056437Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 04:48:52.270213 containerd[1452]: time="2025-05-09T04:48:52.270185453Z" level=info msg="containerd successfully booted in 0.124525s" May 9 04:48:52.270292 systemd[1]: Started containerd.service - containerd container runtime. May 9 04:48:52.455633 sshd_keygen[1398]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 9 04:48:52.473918 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 9 04:48:52.476195 systemd[1]: Starting issuegen.service - Generate /run/issue... May 9 04:48:52.493982 systemd[1]: issuegen.service: Deactivated successfully. May 9 04:48:52.495888 systemd[1]: Finished issuegen.service - Generate /run/issue. May 9 04:48:52.498005 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 9 04:48:52.519720 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 9 04:48:52.522040 systemd[1]: Started getty@tty1.service - Getty on tty1. May 9 04:48:52.523747 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 9 04:48:52.524882 systemd[1]: Reached target getty.target - Login Prompts. May 9 04:48:53.734957 systemd-networkd[1355]: eth0: Gained IPv6LL May 9 04:48:53.737669 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 9 04:48:53.739142 systemd[1]: Reached target network-online.target - Network is Online. May 9 04:48:53.741319 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 9 04:48:53.743268 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 9 04:48:53.771562 systemd[1]: coreos-metadata.service: Deactivated successfully. May 9 04:48:53.771790 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 9 04:48:53.773021 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 9 04:48:53.775867 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 9 04:48:53.777012 systemd[1]: Reached target multi-user.target - Multi-User System. May 9 04:48:53.778009 systemd[1]: Startup finished in 2.173s (kernel) + 3.715s (initrd) + 3.401s (userspace) = 9.291s. May 9 04:48:58.985531 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 9 04:48:58.986701 systemd[1]: Started sshd@0-10.0.0.29:22-10.0.0.1:52508.service - OpenSSH per-connection server daemon (10.0.0.1:52508). May 9 04:48:59.056218 sshd[1521]: Accepted publickey for core from 10.0.0.1 port 52508 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.058058 sshd-session[1521]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.063868 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 9 04:48:59.064885 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 9 04:48:59.070189 systemd-logind[1390]: New session 1 of user core. May 9 04:48:59.091916 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 9 04:48:59.094409 systemd[1]: Starting user@500.service - User Manager for UID 500... May 9 04:48:59.109794 (systemd)[1525]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 9 04:48:59.111792 systemd-logind[1390]: New session c1 of user core. May 9 04:48:59.222819 systemd[1525]: Queued start job for default target default.target. May 9 04:48:59.236767 systemd[1525]: Created slice app.slice - User Application Slice. May 9 04:48:59.236797 systemd[1525]: Reached target paths.target - Paths. May 9 04:48:59.236831 systemd[1525]: Reached target timers.target - Timers. May 9 04:48:59.237980 systemd[1525]: Starting dbus.socket - D-Bus User Message Bus Socket... May 9 04:48:59.246555 systemd[1525]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 9 04:48:59.246616 systemd[1525]: Reached target sockets.target - Sockets. May 9 04:48:59.246651 systemd[1525]: Reached target basic.target - Basic System. May 9 04:48:59.246677 systemd[1525]: Reached target default.target - Main User Target. May 9 04:48:59.246700 systemd[1525]: Startup finished in 129ms. May 9 04:48:59.246963 systemd[1]: Started user@500.service - User Manager for UID 500. May 9 04:48:59.255993 systemd[1]: Started session-1.scope - Session 1 of User core. May 9 04:48:59.315864 systemd[1]: Started sshd@1-10.0.0.29:22-10.0.0.1:52516.service - OpenSSH per-connection server daemon (10.0.0.1:52516). May 9 04:48:59.368726 sshd[1536]: Accepted publickey for core from 10.0.0.1 port 52516 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.369917 sshd-session[1536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.373558 systemd-logind[1390]: New session 2 of user core. May 9 04:48:59.387049 systemd[1]: Started session-2.scope - Session 2 of User core. May 9 04:48:59.436637 sshd[1538]: Connection closed by 10.0.0.1 port 52516 May 9 04:48:59.436954 sshd-session[1536]: pam_unix(sshd:session): session closed for user core May 9 04:48:59.448768 systemd[1]: sshd@1-10.0.0.29:22-10.0.0.1:52516.service: Deactivated successfully. May 9 04:48:59.450089 systemd[1]: session-2.scope: Deactivated successfully. May 9 04:48:59.451239 systemd-logind[1390]: Session 2 logged out. Waiting for processes to exit. May 9 04:48:59.452284 systemd[1]: Started sshd@2-10.0.0.29:22-10.0.0.1:52524.service - OpenSSH per-connection server daemon (10.0.0.1:52524). May 9 04:48:59.453003 systemd-logind[1390]: Removed session 2. May 9 04:48:59.508356 sshd[1543]: Accepted publickey for core from 10.0.0.1 port 52524 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.509478 sshd-session[1543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.513867 systemd-logind[1390]: New session 3 of user core. May 9 04:48:59.521977 systemd[1]: Started session-3.scope - Session 3 of User core. May 9 04:48:59.568871 sshd[1546]: Connection closed by 10.0.0.1 port 52524 May 9 04:48:59.569210 sshd-session[1543]: pam_unix(sshd:session): session closed for user core May 9 04:48:59.579735 systemd[1]: sshd@2-10.0.0.29:22-10.0.0.1:52524.service: Deactivated successfully. May 9 04:48:59.581018 systemd[1]: session-3.scope: Deactivated successfully. May 9 04:48:59.582176 systemd-logind[1390]: Session 3 logged out. Waiting for processes to exit. May 9 04:48:59.583172 systemd[1]: Started sshd@3-10.0.0.29:22-10.0.0.1:52536.service - OpenSSH per-connection server daemon (10.0.0.1:52536). May 9 04:48:59.586253 systemd-logind[1390]: Removed session 3. May 9 04:48:59.625271 sshd[1551]: Accepted publickey for core from 10.0.0.1 port 52536 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.626423 sshd-session[1551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.630719 systemd-logind[1390]: New session 4 of user core. May 9 04:48:59.635972 systemd[1]: Started session-4.scope - Session 4 of User core. May 9 04:48:59.686330 sshd[1554]: Connection closed by 10.0.0.1 port 52536 May 9 04:48:59.686756 sshd-session[1551]: pam_unix(sshd:session): session closed for user core May 9 04:48:59.695677 systemd[1]: sshd@3-10.0.0.29:22-10.0.0.1:52536.service: Deactivated successfully. May 9 04:48:59.697014 systemd[1]: session-4.scope: Deactivated successfully. May 9 04:48:59.698171 systemd-logind[1390]: Session 4 logged out. Waiting for processes to exit. May 9 04:48:59.699177 systemd[1]: Started sshd@4-10.0.0.29:22-10.0.0.1:52544.service - OpenSSH per-connection server daemon (10.0.0.1:52544). May 9 04:48:59.699828 systemd-logind[1390]: Removed session 4. May 9 04:48:59.749467 sshd[1559]: Accepted publickey for core from 10.0.0.1 port 52544 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.750620 sshd-session[1559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.754451 systemd-logind[1390]: New session 5 of user core. May 9 04:48:59.766995 systemd[1]: Started session-5.scope - Session 5 of User core. May 9 04:48:59.829067 sudo[1563]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 9 04:48:59.829344 sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 04:48:59.841770 sudo[1563]: pam_unix(sudo:session): session closed for user root May 9 04:48:59.843883 sshd[1562]: Connection closed by 10.0.0.1 port 52544 May 9 04:48:59.843942 sshd-session[1559]: pam_unix(sshd:session): session closed for user core May 9 04:48:59.863026 systemd[1]: sshd@4-10.0.0.29:22-10.0.0.1:52544.service: Deactivated successfully. May 9 04:48:59.864333 systemd[1]: session-5.scope: Deactivated successfully. May 9 04:48:59.865066 systemd-logind[1390]: Session 5 logged out. Waiting for processes to exit. May 9 04:48:59.866670 systemd[1]: Started sshd@5-10.0.0.29:22-10.0.0.1:52556.service - OpenSSH per-connection server daemon (10.0.0.1:52556). May 9 04:48:59.867535 systemd-logind[1390]: Removed session 5. May 9 04:48:59.924160 sshd[1568]: Accepted publickey for core from 10.0.0.1 port 52556 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:48:59.925366 sshd-session[1568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:48:59.929921 systemd-logind[1390]: New session 6 of user core. May 9 04:48:59.936980 systemd[1]: Started session-6.scope - Session 6 of User core. May 9 04:48:59.986809 sudo[1573]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 9 04:48:59.987100 sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 04:48:59.990062 sudo[1573]: pam_unix(sudo:session): session closed for user root May 9 04:48:59.994308 sudo[1572]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 9 04:48:59.994568 sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 04:49:00.002370 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 9 04:49:00.043104 augenrules[1595]: No rules May 9 04:49:00.044299 systemd[1]: audit-rules.service: Deactivated successfully. May 9 04:49:00.044497 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 9 04:49:00.045596 sudo[1572]: pam_unix(sudo:session): session closed for user root May 9 04:49:00.046779 sshd[1571]: Connection closed by 10.0.0.1 port 52556 May 9 04:49:00.047207 sshd-session[1568]: pam_unix(sshd:session): session closed for user core May 9 04:49:00.058136 systemd[1]: sshd@5-10.0.0.29:22-10.0.0.1:52556.service: Deactivated successfully. May 9 04:49:00.059405 systemd[1]: session-6.scope: Deactivated successfully. May 9 04:49:00.060022 systemd-logind[1390]: Session 6 logged out. Waiting for processes to exit. May 9 04:49:00.061612 systemd[1]: Started sshd@6-10.0.0.29:22-10.0.0.1:52558.service - OpenSSH per-connection server daemon (10.0.0.1:52558). May 9 04:49:00.062562 systemd-logind[1390]: Removed session 6. May 9 04:49:00.110581 sshd[1603]: Accepted publickey for core from 10.0.0.1 port 52558 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:00.111709 sshd-session[1603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:00.116261 systemd-logind[1390]: New session 7 of user core. May 9 04:49:00.124004 systemd[1]: Started session-7.scope - Session 7 of User core. May 9 04:49:00.178920 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 04:49:00.193114 (dockerd)[1608]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 04:49:00.451447 dockerd[1608]: time="2025-05-09T04:49:00.451320434Z" level=info msg="Starting up" May 9 04:49:00.452301 dockerd[1608]: time="2025-05-09T04:49:00.452260449Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 9 04:49:00.495743 dockerd[1608]: time="2025-05-09T04:49:00.495691116Z" level=info msg="Loading containers: start." May 9 04:49:00.505868 kernel: Initializing XFRM netlink socket May 9 04:49:00.685554 systemd-networkd[1355]: docker0: Link UP May 9 04:49:00.689290 dockerd[1608]: time="2025-05-09T04:49:00.689252695Z" level=info msg="Loading containers: done." May 9 04:49:00.701304 dockerd[1608]: time="2025-05-09T04:49:00.701254560Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 04:49:00.701439 dockerd[1608]: time="2025-05-09T04:49:00.701333283Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 9 04:49:00.701439 dockerd[1608]: time="2025-05-09T04:49:00.701432354Z" level=info msg="Initializing buildkit" May 9 04:49:00.722178 dockerd[1608]: time="2025-05-09T04:49:00.722080868Z" level=info msg="Completed buildkit initialization" May 9 04:49:00.728676 dockerd[1608]: time="2025-05-09T04:49:00.728638236Z" level=info msg="Daemon has completed initialization" May 9 04:49:00.728891 dockerd[1608]: time="2025-05-09T04:49:00.728802092Z" level=info msg="API listen on /run/docker.sock" May 9 04:49:00.728865 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 04:49:00.736695 sshd[1606]: Connection closed by 10.0.0.1 port 52558 May 9 04:49:00.736570 sshd-session[1603]: pam_unix(sshd:session): session closed for user core May 9 04:49:00.748868 systemd[1]: sshd@6-10.0.0.29:22-10.0.0.1:52558.service: Deactivated successfully. May 9 04:49:00.750227 systemd[1]: session-7.scope: Deactivated successfully. May 9 04:49:00.750952 systemd-logind[1390]: Session 7 logged out. Waiting for processes to exit. May 9 04:49:00.752401 systemd[1]: Started sshd@7-10.0.0.29:22-10.0.0.1:52568.service - OpenSSH per-connection server daemon (10.0.0.1:52568). May 9 04:49:00.753146 systemd-logind[1390]: Removed session 7. May 9 04:49:00.810527 sshd[1814]: Accepted publickey for core from 10.0.0.1 port 52568 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:00.811596 sshd-session[1814]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:00.815267 systemd-logind[1390]: New session 8 of user core. May 9 04:49:00.831016 systemd[1]: Started session-8.scope - Session 8 of User core. May 9 04:49:01.958380 systemd[1]: var-lib-docker-overlay2-25140770b0ca6122332a39d1b95aaaa866a87d62162ce0006891076cdebac76b\x2dinit-merged.mount: Deactivated successfully. May 9 04:49:02.004246 containerd[1452]: time="2025-05-09T04:49:02.004191668Z" level=info msg="connecting to shim 1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431" address="unix:///run/containerd/s/ad541b690ed83485d67afeeb8d5c7f0bed8f0daf8bc9099ba8ea9257d5c57279" namespace=moby protocol=ttrpc version=3 May 9 04:49:02.027982 systemd[1]: Started docker-1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431.scope - libcontainer container 1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431. May 9 04:49:02.039401 systemd-resolved[1286]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 04:49:02.056123 kernel: docker0: port 1(veth3bc24d9) entered blocking state May 9 04:49:02.056199 kernel: docker0: port 1(veth3bc24d9) entered disabled state May 9 04:49:02.056216 kernel: veth3bc24d9: entered allmulticast mode May 9 04:49:02.056236 kernel: veth3bc24d9: entered promiscuous mode May 9 04:49:02.057057 systemd-networkd[1355]: veth3bc24d9: Link UP May 9 04:49:02.065871 kernel: eth0: renamed from veth3d760b6 May 9 04:49:02.079511 systemd-networkd[1355]: veth3bc24d9: Gained carrier May 9 04:49:02.079745 systemd-networkd[1355]: docker0: Gained carrier May 9 04:49:02.079875 kernel: docker0: port 1(veth3bc24d9) entered blocking state May 9 04:49:02.079977 kernel: docker0: port 1(veth3bc24d9) entered forwarding state May 9 04:49:02.105787 sshd[1817]: Connection closed by 10.0.0.1 port 52568 May 9 04:49:02.106228 sshd-session[1814]: pam_unix(sshd:session): session closed for user core May 9 04:49:02.118746 systemd[1]: sshd@7-10.0.0.29:22-10.0.0.1:52568.service: Deactivated successfully. May 9 04:49:02.120062 systemd[1]: session-8.scope: Deactivated successfully. May 9 04:49:02.121478 systemd-logind[1390]: Session 8 logged out. Waiting for processes to exit. May 9 04:49:02.122219 systemd[1]: Started sshd@8-10.0.0.29:22-10.0.0.1:52578.service - OpenSSH per-connection server daemon (10.0.0.1:52578). May 9 04:49:02.123163 systemd-logind[1390]: Removed session 8. May 9 04:49:02.176306 sshd[1889]: Accepted publickey for core from 10.0.0.1 port 52578 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:02.177457 sshd-session[1889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:02.181805 systemd-logind[1390]: New session 9 of user core. May 9 04:49:02.192987 systemd[1]: Started session-9.scope - Session 9 of User core. May 9 04:49:02.247522 sshd[1892]: Connection closed by 10.0.0.1 port 52578 May 9 04:49:02.248055 sshd-session[1889]: pam_unix(sshd:session): session closed for user core May 9 04:49:02.260803 systemd[1]: sshd@8-10.0.0.29:22-10.0.0.1:52578.service: Deactivated successfully. May 9 04:49:02.262202 systemd[1]: session-9.scope: Deactivated successfully. May 9 04:49:02.264027 systemd-logind[1390]: Session 9 logged out. Waiting for processes to exit. May 9 04:49:02.264580 systemd[1]: Started sshd@9-10.0.0.29:22-10.0.0.1:52594.service - OpenSSH per-connection server daemon (10.0.0.1:52594). May 9 04:49:02.265446 systemd-logind[1390]: Removed session 9. May 9 04:49:02.322936 sshd[1897]: Accepted publickey for core from 10.0.0.1 port 52594 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:02.324094 sshd-session[1897]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:02.328543 systemd-logind[1390]: New session 10 of user core. May 9 04:49:02.340065 systemd[1]: Started session-10.scope - Session 10 of User core. May 9 04:49:02.389229 sudo[1901]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1452 May 9 04:49:02.389495 sudo[1901]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 04:49:02.394399 sudo[1901]: pam_unix(sudo:session): session closed for user root May 9 04:49:02.394833 containerd[1452]: time="2025-05-09T04:49:02.394553459Z" level=info msg="Stop CRI service" May 9 04:49:02.394833 containerd[1452]: time="2025-05-09T04:49:02.394654815Z" level=info msg="Stop CRI service" May 9 04:49:02.394833 containerd[1452]: time="2025-05-09T04:49:02.394727166Z" level=info msg="Event monitor stopped" May 9 04:49:02.394833 containerd[1452]: time="2025-05-09T04:49:02.394734218Z" level=info msg="Stream server stopped" May 9 04:49:02.395512 dockerd[1608]: time="2025-05-09T04:49:02.394991752Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 9 04:49:02.395512 dockerd[1608]: time="2025-05-09T04:49:02.395030318Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 9 04:49:02.395512 dockerd[1608]: time="2025-05-09T04:49:02.395001075Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 9 04:49:02.395512 dockerd[1608]: time="2025-05-09T04:49:02.395113028Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 9 04:49:02.396603 sshd[1900]: Connection closed by 10.0.0.1 port 52594 May 9 04:49:02.396427 sshd-session[1897]: pam_unix(sshd:session): session closed for user core May 9 04:49:02.406521 systemd[1]: containerd.service: Deactivated successfully. May 9 04:49:02.406614 systemd[1]: containerd.service: Unit process 1839 (containerd-shim) remains running after unit stopped. May 9 04:49:02.406831 systemd[1]: containerd.service: Consumed 348ms CPU time, 138.6M memory peak. May 9 04:49:02.407225 systemd[1]: sshd@9-10.0.0.29:22-10.0.0.1:52594.service: Deactivated successfully. May 9 04:49:02.408620 systemd[1]: session-10.scope: Deactivated successfully. May 9 04:49:02.409934 systemd-logind[1390]: Session 10 logged out. Waiting for processes to exit. May 9 04:49:02.411676 systemd[1]: Started sshd@10-10.0.0.29:22-10.0.0.1:52598.service - OpenSSH per-connection server daemon (10.0.0.1:52598). May 9 04:49:02.412708 systemd-logind[1390]: Removed session 10. May 9 04:49:02.464348 sshd[1907]: Accepted publickey for core from 10.0.0.1 port 52598 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:02.465688 sshd-session[1907]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:02.470930 systemd-logind[1390]: New session 11 of user core. May 9 04:49:02.481987 systemd[1]: Started session-11.scope - Session 11 of User core. May 9 04:49:02.534528 sshd[1910]: Connection closed by 10.0.0.1 port 52598 May 9 04:49:02.534739 sshd-session[1907]: pam_unix(sshd:session): session closed for user core May 9 04:49:02.539682 systemd-logind[1390]: Session 11 logged out. Waiting for processes to exit. May 9 04:49:02.539841 systemd[1]: sshd@10-10.0.0.29:22-10.0.0.1:52598.service: Deactivated successfully. May 9 04:49:02.541236 systemd[1]: session-11.scope: Deactivated successfully. May 9 04:49:02.543022 systemd-logind[1390]: Removed session 11. May 9 04:49:03.334995 systemd-networkd[1355]: veth3bc24d9: Gained IPv6LL May 9 04:49:03.975026 systemd-networkd[1355]: docker0: Gained IPv6LL May 9 04:49:07.657112 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. May 9 04:49:07.657430 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 9 04:49:07.657650 dockerd[1608]: time="2025-05-09T04:49:07.657566585Z" level=info msg="Processing signal 'terminated'" May 9 04:49:08.529631 systemd[1]: Started sshd@11-10.0.0.29:22-10.0.0.1:57364.service - OpenSSH per-connection server daemon (10.0.0.1:57364). May 9 04:49:08.583586 sshd[1916]: Accepted publickey for core from 10.0.0.1 port 57364 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:08.584041 sshd-session[1916]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:08.588738 systemd-logind[1390]: New session 12 of user core. May 9 04:49:08.605462 systemd[1]: Started session-12.scope - Session 12 of User core. May 9 04:49:08.668233 sshd[1918]: Connection closed by 10.0.0.1 port 57364 May 9 04:49:08.667293 sshd-session[1916]: pam_unix(sshd:session): session closed for user core May 9 04:49:08.671047 systemd[1]: sshd@11-10.0.0.29:22-10.0.0.1:57364.service: Deactivated successfully. May 9 04:49:08.672488 systemd[1]: session-12.scope: Deactivated successfully. May 9 04:49:08.675069 systemd-logind[1390]: Session 12 logged out. Waiting for processes to exit. May 9 04:49:08.676088 systemd-logind[1390]: Removed session 12. May 9 04:49:09.658502 dockerd[1608]: time="2025-05-09T04:49:09.658443117Z" level=error msg="Error sending stop (signal 15) to container" container=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 error="Cannot kill container 1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\"" May 9 04:49:09.658502 dockerd[1608]: time="2025-05-09T04:49:09.658493190Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 May 9 04:49:14.670247 systemd[1]: Started sshd@12-10.0.0.29:22-10.0.0.1:51062.service - OpenSSH per-connection server daemon (10.0.0.1:51062). May 9 04:49:14.717128 sshd[1924]: Accepted publickey for core from 10.0.0.1 port 51062 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:14.718459 sshd-session[1924]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:14.724019 systemd-logind[1390]: New session 13 of user core. May 9 04:49:14.744980 systemd[1]: Started session-13.scope - Session 13 of User core. May 9 04:49:14.806299 sshd[1926]: Connection closed by 10.0.0.1 port 51062 May 9 04:49:14.806930 sshd-session[1924]: pam_unix(sshd:session): session closed for user core May 9 04:49:14.811753 systemd[1]: sshd@12-10.0.0.29:22-10.0.0.1:51062.service: Deactivated successfully. May 9 04:49:14.814263 systemd[1]: session-13.scope: Deactivated successfully. May 9 04:49:14.816132 systemd-logind[1390]: Session 13 logged out. Waiting for processes to exit. May 9 04:49:14.817316 systemd-logind[1390]: Removed session 13. May 9 04:49:19.661315 dockerd[1608]: time="2025-05-09T04:49:19.661227992Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 error="context deadline exceeded" May 9 04:49:19.662141 systemd[1]: docker-1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431.scope: Deactivated successfully. May 9 04:49:20.816728 systemd[1]: Started sshd@13-10.0.0.29:22-10.0.0.1:51076.service - OpenSSH per-connection server daemon (10.0.0.1:51076). May 9 04:49:20.866243 sshd[1932]: Accepted publickey for core from 10.0.0.1 port 51076 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:20.867293 sshd-session[1932]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:20.871276 systemd-logind[1390]: New session 14 of user core. May 9 04:49:20.884036 systemd[1]: Started session-14.scope - Session 14 of User core. May 9 04:49:20.936955 sshd[1934]: Connection closed by 10.0.0.1 port 51076 May 9 04:49:20.936894 sshd-session[1932]: pam_unix(sshd:session): session closed for user core May 9 04:49:20.940519 systemd[1]: sshd@13-10.0.0.29:22-10.0.0.1:51076.service: Deactivated successfully. May 9 04:49:20.941932 systemd[1]: session-14.scope: Deactivated successfully. May 9 04:49:20.944473 systemd-logind[1390]: Session 14 logged out. Waiting for processes to exit. May 9 04:49:20.945334 systemd-logind[1390]: Removed session 14. May 9 04:49:22.658577 dockerd[1608]: time="2025-05-09T04:49:22.658302045Z" level=error msg="Force shutdown daemon" May 9 04:49:22.658577 dockerd[1608]: time="2025-05-09T04:49:22.658414122Z" level=info msg="Daemon shutdown complete" May 9 04:49:22.664377 systemd[1]: docker.service: Deactivated successfully. May 9 04:49:22.664725 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 9 04:49:22.664798 systemd[1]: docker.service: Consumed 762ms CPU time, 129.8M memory peak. May 9 04:49:22.665158 systemd[1]: docker.socket: Deactivated successfully. May 9 04:49:22.665425 systemd[1]: Closed docker.socket - Docker Socket for the API. May 9 04:49:22.665456 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 9 04:49:22.666959 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 04:49:22.667120 systemd[1]: containerd.service: Found left-over process 1839 (containerd-shim) in control group while starting unit. Ignoring. May 9 04:49:22.667123 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 04:49:22.670128 systemd[1]: Starting containerd.service - containerd container runtime... May 9 04:49:22.689985 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 04:49:22.699341 systemd[1]: containerd.service: Found left-over process 1839 (containerd-shim) in control group while starting unit. Ignoring. May 9 04:49:22.700636 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 04:49:22.704981 (ntainerd)[1945]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 04:49:22.723298 containerd[1945]: time="2025-05-09T04:49:22Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 9 04:49:22.725691 containerd[1945]: time="2025-05-09T04:49:22.725654302Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 9 04:49:22.735948 containerd[1945]: time="2025-05-09T04:49:22.735906735Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.229µs" May 9 04:49:22.735948 containerd[1945]: time="2025-05-09T04:49:22.735946052Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 9 04:49:22.736042 containerd[1945]: time="2025-05-09T04:49:22.735970465Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 9 04:49:22.736042 containerd[1945]: time="2025-05-09T04:49:22.736015656Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 9 04:49:22.736042 containerd[1945]: time="2025-05-09T04:49:22.736027802Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 9 04:49:22.736260 containerd[1945]: time="2025-05-09T04:49:22.736235136Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:49:22.738240 containerd[1945]: time="2025-05-09T04:49:22.738204823Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:49:22.738267 containerd[1945]: time="2025-05-09T04:49:22.738241782Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738649217Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738684858Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738699043Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738707913Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738729969Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738830739Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738878526Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738890633Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.738939979Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.739219314Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.739243687Z" level=info msg="metadata content store policy set" policy=shared May 9 04:49:22.745252 containerd[1945]: time="2025-05-09T04:49:22.739508917Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739545877Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739557904Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739569012Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739580319Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739592865Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739604453Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739616759Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739629785Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739640813Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739650323Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739665266Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 9 04:49:22.745629 containerd[1945]: time="2025-05-09T04:49:22.739933373Z" level=info msg="connecting to shim 1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431" address="unix:///run/containerd/s/ad541b690ed83485d67afeeb8d5c7f0bed8f0daf8bc9099ba8ea9257d5c57279" namespace=moby protocol=ttrpc version=3 May 9 04:49:22.746503 containerd[1945]: time="2025-05-09T04:49:22.746476341Z" level=info msg="cleaning leaked shim process" id=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 namespace=moby May 9 04:49:22.755227 containerd[1945]: time="2025-05-09T04:49:22.755097757Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 9 04:49:22.755435 containerd[1945]: time="2025-05-09T04:49:22.755399507Z" level=info msg="shim disconnected" id=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 namespace=moby May 9 04:49:22.755471 containerd[1945]: time="2025-05-09T04:49:22.755421323Z" level=warning msg="cleaning up after shim disconnected" id=1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431 namespace=moby May 9 04:49:22.755471 containerd[1945]: time="2025-05-09T04:49:22.755453368Z" level=info msg="cleaning up dead shim" namespace=moby May 9 04:49:22.764928 containerd[1945]: time="2025-05-09T04:49:22.764841306Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.764976798Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765084920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765101782Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765116406Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765135385Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765146213Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765157800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765180456Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765196278Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765207586Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765217735Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765278149Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765292973Z" level=info msg="Start snapshots syncer" May 9 04:49:22.765569 containerd[1945]: time="2025-05-09T04:49:22.765322700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 9 04:49:22.765865 containerd[1945]: time="2025-05-09T04:49:22.765523441Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 9 04:49:22.765865 containerd[1945]: time="2025-05-09T04:49:22.765588090Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765660531Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765711275Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765734210Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765748914Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765760062Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765771689Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765782517Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765792547Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765818278Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765829586Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765839495Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765898311Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765914573Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:49:22.765975 containerd[1945]: time="2025-05-09T04:49:22.765923643Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765933992Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765942183Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765957286Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765971031Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765990131Z" level=info msg="runtime interface created" May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.765995964Z" level=info msg="created NRI interface" May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.766004914Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.766019219Z" level=info msg="Connect containerd service" May 9 04:49:22.766213 containerd[1945]: time="2025-05-09T04:49:22.766044671Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 04:49:22.766588 containerd[1945]: time="2025-05-09T04:49:22.766552596Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 04:49:22.778246 containerd[1945]: time="2025-05-09T04:49:22.778204859Z" level=info msg="Start subscribing containerd event" May 9 04:49:22.778445 containerd[1945]: time="2025-05-09T04:49:22.778310463Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 04:49:22.778445 containerd[1945]: time="2025-05-09T04:49:22.778393892Z" level=info msg="Start recovering state" May 9 04:49:22.778445 containerd[1945]: time="2025-05-09T04:49:22.778418545Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778789140Z" level=info msg="Start event monitor" May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778821025Z" level=info msg="Start cni network conf syncer for default" May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778831334Z" level=info msg="Start streaming server" May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778841003Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778874327Z" level=info msg="runtime interface starting up..." May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778881679Z" level=info msg="starting plugins..." May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.778891788Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 9 04:49:22.779900 containerd[1945]: time="2025-05-09T04:49:22.779095565Z" level=info msg="containerd successfully booted in 0.056472s" May 9 04:49:22.779531 systemd[1]: Started containerd.service - containerd container runtime. May 9 04:49:22.781512 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 04:49:22.801559 (dockerd)[1992]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 04:49:22.825962 dockerd[1992]: time="2025-05-09T04:49:22.825905677Z" level=info msg="Starting up" May 9 04:49:22.826825 dockerd[1992]: time="2025-05-09T04:49:22.826803375Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 9 04:49:22.863285 dockerd[1992]: time="2025-05-09T04:49:22.862909228Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 9 04:49:22.870256 dockerd[1992]: time="2025-05-09T04:49:22.870207650Z" level=info msg="Loading containers: start." May 9 04:49:22.884734 systemd[1]: var-lib-docker-overlay2-25140770b0ca6122332a39d1b95aaaa866a87d62162ce0006891076cdebac76b-merged.mount: Deactivated successfully. May 9 04:49:23.256535 dockerd[1992]: time="2025-05-09T04:49:23.256500395Z" level=info msg="Removing stale sandbox a942159558f66d1621f2bf9a7230836134cfc7ca9cb269dce34ff558c8a0d2e0 (1e91376d2c82ac1f099ea7f71c5e681fb070f3811c96d354506f31fb80e12431)" May 9 04:49:23.258993 dockerd[1992]: time="2025-05-09T04:49:23.258967227Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 9 04:49:23.259067 dockerd[1992]: time="2025-05-09T04:49:23.259006147Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 320cbe4c6f9ce4b450543d764c04c1a30da092f7972b5d1042c1fe0d7625c9eb 4b24c07abf6de92d5b0302ca06dab8b37af88915b7b3eb13d000d937229d5226], retrying...." May 9 04:49:23.265124 systemd-networkd[1355]: veth3bc24d9: Link DOWN May 9 04:49:23.265131 systemd-networkd[1355]: veth3bc24d9: Lost carrier May 9 04:49:23.265877 kernel: docker0: port 1(veth3bc24d9) entered disabled state May 9 04:49:23.267148 kernel: veth3bc24d9 (unregistering): left allmulticast mode May 9 04:49:23.267208 kernel: veth3bc24d9 (unregistering): left promiscuous mode May 9 04:49:23.267224 kernel: docker0: port 1(veth3bc24d9) entered disabled state May 9 04:49:23.277278 systemd-networkd[1355]: docker0: Lost carrier May 9 04:49:23.425274 dockerd[1992]: time="2025-05-09T04:49:23.425222663Z" level=warning msg="error locating sandbox id a942159558f66d1621f2bf9a7230836134cfc7ca9cb269dce34ff558c8a0d2e0: sandbox a942159558f66d1621f2bf9a7230836134cfc7ca9cb269dce34ff558c8a0d2e0 not found" May 9 04:49:23.425383 dockerd[1992]: time="2025-05-09T04:49:23.425298864Z" level=info msg="Loading containers: done." May 9 04:49:23.446738 dockerd[1992]: time="2025-05-09T04:49:23.446684470Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 04:49:23.446918 dockerd[1992]: time="2025-05-09T04:49:23.446768264Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 9 04:49:23.446918 dockerd[1992]: time="2025-05-09T04:49:23.446809143Z" level=info msg="Initializing buildkit" May 9 04:49:23.460862 dockerd[1992]: time="2025-05-09T04:49:23.460811514Z" level=info msg="Completed buildkit initialization" May 9 04:49:23.465645 dockerd[1992]: time="2025-05-09T04:49:23.465606321Z" level=info msg="Daemon has completed initialization" May 9 04:49:23.465714 dockerd[1992]: time="2025-05-09T04:49:23.465671494Z" level=info msg="API listen on /run/docker.sock" May 9 04:49:23.465815 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 04:49:23.845053 systemd[1]: run-docker-netns-a942159558f6.mount: Deactivated successfully. May 9 04:49:26.944763 systemd[1]: Started sshd@14-10.0.0.29:22-10.0.0.1:40230.service - OpenSSH per-connection server daemon (10.0.0.1:40230). May 9 04:49:26.993762 sshd[2214]: Accepted publickey for core from 10.0.0.1 port 40230 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:26.994772 sshd-session[2214]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:26.998874 systemd-logind[1390]: New session 15 of user core. May 9 04:49:27.004994 systemd[1]: Started session-15.scope - Session 15 of User core. May 9 04:49:27.062150 sshd[2216]: Connection closed by 10.0.0.1 port 40230 May 9 04:49:27.062422 sshd-session[2214]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.075682 systemd[1]: sshd@14-10.0.0.29:22-10.0.0.1:40230.service: Deactivated successfully. May 9 04:49:27.078155 systemd[1]: session-15.scope: Deactivated successfully. May 9 04:49:27.079406 systemd-logind[1390]: Session 15 logged out. Waiting for processes to exit. May 9 04:49:27.080423 systemd[1]: Started sshd@15-10.0.0.29:22-10.0.0.1:40244.service - OpenSSH per-connection server daemon (10.0.0.1:40244). May 9 04:49:27.081319 systemd-logind[1390]: Removed session 15. May 9 04:49:27.130070 sshd[2221]: Accepted publickey for core from 10.0.0.1 port 40244 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.131096 sshd-session[2221]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.134903 systemd-logind[1390]: New session 16 of user core. May 9 04:49:27.142053 systemd[1]: Started session-16.scope - Session 16 of User core. May 9 04:49:27.193799 sshd[2224]: Connection closed by 10.0.0.1 port 40244 May 9 04:49:27.193695 sshd-session[2221]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.208345 systemd[1]: sshd@15-10.0.0.29:22-10.0.0.1:40244.service: Deactivated successfully. May 9 04:49:27.209936 systemd[1]: session-16.scope: Deactivated successfully. May 9 04:49:27.210673 systemd-logind[1390]: Session 16 logged out. Waiting for processes to exit. May 9 04:49:27.213322 systemd[1]: Started sshd@16-10.0.0.29:22-10.0.0.1:40252.service - OpenSSH per-connection server daemon (10.0.0.1:40252). May 9 04:49:27.214536 systemd-logind[1390]: Removed session 16. May 9 04:49:27.265417 sshd[2229]: Accepted publickey for core from 10.0.0.1 port 40252 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.266477 sshd-session[2229]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.271098 systemd-logind[1390]: New session 17 of user core. May 9 04:49:27.284002 systemd[1]: Started session-17.scope - Session 17 of User core. May 9 04:49:27.338018 sshd[2232]: Connection closed by 10.0.0.1 port 40252 May 9 04:49:27.338299 sshd-session[2229]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.355775 systemd[1]: sshd@16-10.0.0.29:22-10.0.0.1:40252.service: Deactivated successfully. May 9 04:49:27.357249 systemd[1]: session-17.scope: Deactivated successfully. May 9 04:49:27.357889 systemd-logind[1390]: Session 17 logged out. Waiting for processes to exit. May 9 04:49:27.359442 systemd[1]: Started sshd@17-10.0.0.29:22-10.0.0.1:40264.service - OpenSSH per-connection server daemon (10.0.0.1:40264). May 9 04:49:27.360139 systemd-logind[1390]: Removed session 17. May 9 04:49:27.411397 sshd[2244]: Accepted publickey for core from 10.0.0.1 port 40264 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.412419 sshd-session[2244]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.415873 systemd-logind[1390]: New session 18 of user core. May 9 04:49:27.428993 systemd[1]: Started session-18.scope - Session 18 of User core. May 9 04:49:27.497185 systemd[1]: var-lib-docker-overlay2-156e02bfe3108e85a00380178bcc2ae662911e58fbea7acc181aec789568e668\x2dinit-merged.mount: Deactivated successfully. May 9 04:49:27.536093 containerd[1945]: time="2025-05-09T04:49:27.536043844Z" level=info msg="connecting to shim 8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa" address="unix:///run/containerd/s/d0aff6a2ad105f0c3310a763ad0753ce29c16c1cf774eebeef60fbbf62c80662" namespace=moby protocol=ttrpc version=3 May 9 04:49:27.571057 systemd[1]: Started docker-8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa.scope - libcontainer container 8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa. May 9 04:49:27.581899 systemd-resolved[1286]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 04:49:27.597240 kernel: docker0: port 1(vethb9d1593) entered blocking state May 9 04:49:27.597317 kernel: docker0: port 1(vethb9d1593) entered disabled state May 9 04:49:27.597334 kernel: vethb9d1593: entered allmulticast mode May 9 04:49:27.597351 kernel: vethb9d1593: entered promiscuous mode May 9 04:49:27.597830 systemd-networkd[1355]: vethb9d1593: Link UP May 9 04:49:27.602885 kernel: eth0: renamed from veth7df0b68 May 9 04:49:27.622866 kernel: docker0: port 1(vethb9d1593) entered blocking state May 9 04:49:27.622952 kernel: docker0: port 1(vethb9d1593) entered forwarding state May 9 04:49:27.622914 systemd-networkd[1355]: vethb9d1593: Gained carrier May 9 04:49:27.623116 systemd-networkd[1355]: docker0: Gained carrier May 9 04:49:27.651917 sshd[2247]: Connection closed by 10.0.0.1 port 40264 May 9 04:49:27.652239 sshd-session[2244]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.661735 systemd[1]: sshd@17-10.0.0.29:22-10.0.0.1:40264.service: Deactivated successfully. May 9 04:49:27.662997 systemd[1]: session-18.scope: Deactivated successfully. May 9 04:49:27.664250 systemd-logind[1390]: Session 18 logged out. Waiting for processes to exit. May 9 04:49:27.665285 systemd[1]: Started sshd@18-10.0.0.29:22-10.0.0.1:40268.service - OpenSSH per-connection server daemon (10.0.0.1:40268). May 9 04:49:27.666007 systemd-logind[1390]: Removed session 18. May 9 04:49:27.707509 sshd[2315]: Accepted publickey for core from 10.0.0.1 port 40268 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.708614 sshd-session[2315]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.712299 systemd-logind[1390]: New session 19 of user core. May 9 04:49:27.724737 systemd[1]: Started session-19.scope - Session 19 of User core. May 9 04:49:27.777336 sshd[2318]: Connection closed by 10.0.0.1 port 40268 May 9 04:49:27.777188 sshd-session[2315]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.790877 systemd[1]: sshd@18-10.0.0.29:22-10.0.0.1:40268.service: Deactivated successfully. May 9 04:49:27.792268 systemd[1]: session-19.scope: Deactivated successfully. May 9 04:49:27.793321 systemd-logind[1390]: Session 19 logged out. Waiting for processes to exit. May 9 04:49:27.794370 systemd[1]: Started sshd@19-10.0.0.29:22-10.0.0.1:40276.service - OpenSSH per-connection server daemon (10.0.0.1:40276). May 9 04:49:27.795187 systemd-logind[1390]: Removed session 19. May 9 04:49:27.846507 sshd[2323]: Accepted publickey for core from 10.0.0.1 port 40276 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.847637 sshd-session[2323]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.851911 systemd-logind[1390]: New session 20 of user core. May 9 04:49:27.860989 systemd[1]: Started session-20.scope - Session 20 of User core. May 9 04:49:27.911523 sudo[2327]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1945 May 9 04:49:27.911782 sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 04:49:27.914692 sudo[2327]: pam_unix(sudo:session): session closed for user root May 9 04:49:27.916795 sshd[2326]: Connection closed by 10.0.0.1 port 40276 May 9 04:49:27.916918 sshd-session[2323]: pam_unix(sshd:session): session closed for user core May 9 04:49:27.921214 dockerd[1992]: time="2025-05-09T04:49:27.917290532Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 9 04:49:27.921214 dockerd[1992]: time="2025-05-09T04:49:27.917323546Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 9 04:49:27.921214 dockerd[1992]: time="2025-05-09T04:49:27.919036990Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 9 04:49:27.921214 dockerd[1992]: time="2025-05-09T04:49:27.919058852Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 9 04:49:27.923674 systemd[1]: containerd.service: Deactivated successfully. May 9 04:49:27.923759 systemd[1]: containerd.service: Unit process 2265 (containerd-shim) remains running after unit stopped. May 9 04:49:27.923974 systemd[1]: containerd.service: Consumed 157ms CPU time, 139.4M memory peak. May 9 04:49:27.924294 systemd[1]: sshd@19-10.0.0.29:22-10.0.0.1:40276.service: Deactivated successfully. May 9 04:49:27.926998 systemd[1]: session-20.scope: Deactivated successfully. May 9 04:49:27.928481 systemd-logind[1390]: Session 20 logged out. Waiting for processes to exit. May 9 04:49:27.929899 systemd[1]: Started sshd@20-10.0.0.29:22-10.0.0.1:40292.service - OpenSSH per-connection server daemon (10.0.0.1:40292). May 9 04:49:27.930929 systemd-logind[1390]: Removed session 20. May 9 04:49:27.977633 sshd[2333]: Accepted publickey for core from 10.0.0.1 port 40292 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:27.978792 sshd-session[2333]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:27.982869 systemd-logind[1390]: New session 21 of user core. May 9 04:49:27.993018 systemd[1]: Started session-21.scope - Session 21 of User core. May 9 04:49:28.045461 sshd[2336]: Connection closed by 10.0.0.1 port 40292 May 9 04:49:28.045687 sshd-session[2333]: pam_unix(sshd:session): session closed for user core May 9 04:49:28.048621 systemd[1]: sshd@20-10.0.0.29:22-10.0.0.1:40292.service: Deactivated successfully. May 9 04:49:28.049963 systemd[1]: session-21.scope: Deactivated successfully. May 9 04:49:28.052236 systemd-logind[1390]: Session 21 logged out. Waiting for processes to exit. May 9 04:49:28.053198 systemd-logind[1390]: Removed session 21. May 9 04:49:28.678986 systemd-networkd[1355]: vethb9d1593: Gained IPv6LL May 9 04:49:33.072196 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. May 9 04:49:33.072588 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 9 04:49:33.073210 dockerd[1992]: time="2025-05-09T04:49:33.072588137Z" level=info msg="Processing signal 'terminated'" May 9 04:49:34.052998 systemd[1]: Started sshd@21-10.0.0.29:22-10.0.0.1:50968.service - OpenSSH per-connection server daemon (10.0.0.1:50968). May 9 04:49:34.106331 sshd[2342]: Accepted publickey for core from 10.0.0.1 port 50968 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:34.107648 sshd-session[2342]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:34.112011 systemd-logind[1390]: New session 22 of user core. May 9 04:49:34.120031 systemd[1]: Started session-22.scope - Session 22 of User core. May 9 04:49:34.174227 sshd[2344]: Connection closed by 10.0.0.1 port 50968 May 9 04:49:34.174547 sshd-session[2342]: pam_unix(sshd:session): session closed for user core May 9 04:49:34.177881 systemd[1]: sshd@21-10.0.0.29:22-10.0.0.1:50968.service: Deactivated successfully. May 9 04:49:34.180277 systemd[1]: session-22.scope: Deactivated successfully. May 9 04:49:34.180960 systemd-logind[1390]: Session 22 logged out. Waiting for processes to exit. May 9 04:49:34.181815 systemd-logind[1390]: Removed session 22. May 9 04:49:35.073553 dockerd[1992]: time="2025-05-09T04:49:35.073465518Z" level=error msg="Error sending stop (signal 15) to container" container=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa error="Cannot kill container 8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" May 9 04:49:35.073553 dockerd[1992]: time="2025-05-09T04:49:35.073543281Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa May 9 04:49:37.490312 update_engine[1395]: I20250509 04:49:37.490200 1395 update_attempter.cc:509] Updating boot flags... May 9 04:49:37.516901 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2356) May 9 04:49:37.540895 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2357) May 9 04:49:37.574971 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2357) May 9 04:49:40.184223 systemd[1]: Started sshd@22-10.0.0.29:22-10.0.0.1:50984.service - OpenSSH per-connection server daemon (10.0.0.1:50984). May 9 04:49:40.237260 sshd[2365]: Accepted publickey for core from 10.0.0.1 port 50984 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:40.238355 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:40.243519 systemd-logind[1390]: New session 23 of user core. May 9 04:49:40.257002 systemd[1]: Started session-23.scope - Session 23 of User core. May 9 04:49:40.310671 sshd[2367]: Connection closed by 10.0.0.1 port 50984 May 9 04:49:40.310995 sshd-session[2365]: pam_unix(sshd:session): session closed for user core May 9 04:49:40.314900 systemd[1]: sshd@22-10.0.0.29:22-10.0.0.1:50984.service: Deactivated successfully. May 9 04:49:40.316465 systemd[1]: session-23.scope: Deactivated successfully. May 9 04:49:40.318491 systemd-logind[1390]: Session 23 logged out. Waiting for processes to exit. May 9 04:49:40.319276 systemd-logind[1390]: Removed session 23. May 9 04:49:45.074227 dockerd[1992]: time="2025-05-09T04:49:45.074148303Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa error="context deadline exceeded" May 9 04:49:45.074753 systemd[1]: docker-8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa.scope: Deactivated successfully. May 9 04:49:46.319940 systemd[1]: Started sshd@23-10.0.0.29:22-10.0.0.1:33660.service - OpenSSH per-connection server daemon (10.0.0.1:33660). May 9 04:49:46.406224 sshd[2373]: Accepted publickey for core from 10.0.0.1 port 33660 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:46.407472 sshd-session[2373]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:46.412517 systemd-logind[1390]: New session 24 of user core. May 9 04:49:46.428045 systemd[1]: Started session-24.scope - Session 24 of User core. May 9 04:49:46.482827 sshd[2375]: Connection closed by 10.0.0.1 port 33660 May 9 04:49:46.483309 sshd-session[2373]: pam_unix(sshd:session): session closed for user core May 9 04:49:46.486424 systemd[1]: sshd@23-10.0.0.29:22-10.0.0.1:33660.service: Deactivated successfully. May 9 04:49:46.487952 systemd[1]: session-24.scope: Deactivated successfully. May 9 04:49:46.488929 systemd-logind[1390]: Session 24 logged out. Waiting for processes to exit. May 9 04:49:46.490524 systemd-logind[1390]: Removed session 24. May 9 04:49:48.073419 dockerd[1992]: time="2025-05-09T04:49:48.073355441Z" level=error msg="Force shutdown daemon" May 9 04:49:48.073763 dockerd[1992]: time="2025-05-09T04:49:48.073500651Z" level=info msg="Daemon shutdown complete" May 9 04:49:48.077603 systemd[1]: docker.service: Deactivated successfully. May 9 04:49:48.077934 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 9 04:49:48.078460 systemd[1]: docker.socket: Deactivated successfully. May 9 04:49:48.078758 systemd[1]: Closed docker.socket - Docker Socket for the API. May 9 04:49:48.078793 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 9 04:49:48.080180 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 04:49:48.080328 systemd[1]: containerd.service: Found left-over process 2265 (containerd-shim) in control group while starting unit. Ignoring. May 9 04:49:48.080338 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 04:49:48.087301 systemd[1]: Starting containerd.service - containerd container runtime... May 9 04:49:48.087624 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 04:49:48.098125 systemd[1]: containerd.service: Found left-over process 2265 (containerd-shim) in control group while starting unit. Ignoring. May 9 04:49:48.098138 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 04:49:48.101832 (ntainerd)[2386]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 04:49:48.113977 containerd[2386]: time="2025-05-09T04:49:48Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 9 04:49:48.114376 containerd[2386]: time="2025-05-09T04:49:48.114329084Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 9 04:49:48.122743 containerd[2386]: time="2025-05-09T04:49:48.122691819Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.518µs" May 9 04:49:48.122743 containerd[2386]: time="2025-05-09T04:49:48.122731731Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 9 04:49:48.122859 containerd[2386]: time="2025-05-09T04:49:48.122753646Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 9 04:49:48.122859 containerd[2386]: time="2025-05-09T04:49:48.122793118Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 9 04:49:48.122859 containerd[2386]: time="2025-05-09T04:49:48.122805396Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 9 04:49:48.123038 containerd[2386]: time="2025-05-09T04:49:48.123005995Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:49:48.123074 containerd[2386]: time="2025-05-09T04:49:48.123038508Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 9 04:49:48.123074 containerd[2386]: time="2025-05-09T04:49:48.123048466Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:49:48.123241 containerd[2386]: time="2025-05-09T04:49:48.123213392Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 9 04:49:48.123241 containerd[2386]: time="2025-05-09T04:49:48.123231029Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:49:48.123292 containerd[2386]: time="2025-05-09T04:49:48.123241867Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 9 04:49:48.123292 containerd[2386]: time="2025-05-09T04:49:48.123249345Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 9 04:49:48.123292 containerd[2386]: time="2025-05-09T04:49:48.123268381Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 9 04:49:48.123349 containerd[2386]: time="2025-05-09T04:49:48.123340447Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:49:48.123369 containerd[2386]: time="2025-05-09T04:49:48.123359763Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 9 04:49:48.123393 containerd[2386]: time="2025-05-09T04:49:48.123369441Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 9 04:49:48.123420 containerd[2386]: time="2025-05-09T04:49:48.123407513Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 9 04:49:48.123623 containerd[2386]: time="2025-05-09T04:49:48.123599194Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 9 04:49:48.123650 containerd[2386]: time="2025-05-09T04:49:48.123625908Z" level=info msg="metadata content store policy set" policy=shared May 9 04:49:48.123752 containerd[2386]: time="2025-05-09T04:49:48.123739045Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 9 04:49:48.123781 containerd[2386]: time="2025-05-09T04:49:48.123771599Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 9 04:49:48.123804 containerd[2386]: time="2025-05-09T04:49:48.123785276Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 9 04:49:48.123804 containerd[2386]: time="2025-05-09T04:49:48.123796154Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 9 04:49:48.123840 containerd[2386]: time="2025-05-09T04:49:48.123806472Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 9 04:49:48.123840 containerd[2386]: time="2025-05-09T04:49:48.123818189Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 9 04:49:48.123840 containerd[2386]: time="2025-05-09T04:49:48.123828627Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 9 04:49:48.123840 containerd[2386]: time="2025-05-09T04:49:48.123838745Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 9 04:49:48.123929 containerd[2386]: time="2025-05-09T04:49:48.123866019Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 9 04:49:48.123929 containerd[2386]: time="2025-05-09T04:49:48.123877657Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 9 04:49:48.123929 containerd[2386]: time="2025-05-09T04:49:48.123886775Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 9 04:49:48.123929 containerd[2386]: time="2025-05-09T04:49:48.123897773Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 9 04:49:48.124148 containerd[2386]: time="2025-05-09T04:49:48.124130685Z" level=info msg="connecting to shim 8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa" address="unix:///run/containerd/s/d0aff6a2ad105f0c3310a763ad0753ce29c16c1cf774eebeef60fbbf62c80662" namespace=moby protocol=ttrpc version=3 May 9 04:49:48.134402 containerd[2386]: time="2025-05-09T04:49:48.134342363Z" level=info msg="cleaning leaked shim process" id=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa namespace=moby May 9 04:49:48.141873 containerd[2386]: time="2025-05-09T04:49:48.141816398Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 9 04:49:48.142112 containerd[2386]: time="2025-05-09T04:49:48.142082184Z" level=info msg="shim disconnected" id=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa namespace=moby May 9 04:49:48.142153 containerd[2386]: time="2025-05-09T04:49:48.142109739Z" level=warning msg="cleaning up after shim disconnected" id=8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa namespace=moby May 9 04:49:48.142153 containerd[2386]: time="2025-05-09T04:49:48.142138053Z" level=info msg="cleaning up dead shim" namespace=moby May 9 04:49:48.151741 containerd[2386]: time="2025-05-09T04:49:48.151560731Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby May 9 04:49:48.151741 containerd[2386]: time="2025-05-09T04:49:48.151708381Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 9 04:49:48.151836 containerd[2386]: time="2025-05-09T04:49:48.151762090Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 9 04:49:48.151836 containerd[2386]: time="2025-05-09T04:49:48.151785925Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 9 04:49:48.151836 containerd[2386]: time="2025-05-09T04:49:48.151808920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 9 04:49:48.151836 containerd[2386]: time="2025-05-09T04:49:48.151819798Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 9 04:49:48.151836 containerd[2386]: time="2025-05-09T04:49:48.151830636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 9 04:49:48.151947 containerd[2386]: time="2025-05-09T04:49:48.151842114Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 9 04:49:48.151947 containerd[2386]: time="2025-05-09T04:49:48.151859510Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 9 04:49:48.151947 containerd[2386]: time="2025-05-09T04:49:48.151871708Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 9 04:49:48.151947 containerd[2386]: time="2025-05-09T04:49:48.151882665Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 9 04:49:48.151947 containerd[2386]: time="2025-05-09T04:49:48.151893103Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 9 04:49:48.152034 containerd[2386]: time="2025-05-09T04:49:48.151955930Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 9 04:49:48.152034 containerd[2386]: time="2025-05-09T04:49:48.151971007Z" level=info msg="Start snapshots syncer" May 9 04:49:48.152034 containerd[2386]: time="2025-05-09T04:49:48.152000521Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 9 04:49:48.152287 containerd[2386]: time="2025-05-09T04:49:48.152242712Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 9 04:49:48.152364 containerd[2386]: time="2025-05-09T04:49:48.152297021Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 9 04:49:48.152394 containerd[2386]: time="2025-05-09T04:49:48.152364287Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 9 04:49:48.152417 containerd[2386]: time="2025-05-09T04:49:48.152405239Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 9 04:49:48.152455 containerd[2386]: time="2025-05-09T04:49:48.152440472Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 9 04:49:48.152478 containerd[2386]: time="2025-05-09T04:49:48.152457228Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 9 04:49:48.152478 containerd[2386]: time="2025-05-09T04:49:48.152468946Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 9 04:49:48.152511 containerd[2386]: time="2025-05-09T04:49:48.152479983Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 9 04:49:48.152511 containerd[2386]: time="2025-05-09T04:49:48.152494221Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 9 04:49:48.152511 containerd[2386]: time="2025-05-09T04:49:48.152505058Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 9 04:49:48.152567 containerd[2386]: time="2025-05-09T04:49:48.152528734Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 9 04:49:48.152567 containerd[2386]: time="2025-05-09T04:49:48.152539691Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 9 04:49:48.152567 containerd[2386]: time="2025-05-09T04:49:48.152550049Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 9 04:49:48.152622 containerd[2386]: time="2025-05-09T04:49:48.152577564Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:49:48.152622 containerd[2386]: time="2025-05-09T04:49:48.152591001Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 9 04:49:48.152622 containerd[2386]: time="2025-05-09T04:49:48.152598959Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:49:48.152622 containerd[2386]: time="2025-05-09T04:49:48.152607877Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 9 04:49:48.152622 containerd[2386]: time="2025-05-09T04:49:48.152617395Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152626314Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152636352Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152651069Z" level=info msg="runtime interface created" May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152657187Z" level=info msg="created NRI interface" May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152665746Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152676223Z" level=info msg="Connect containerd service" May 9 04:49:48.152711 containerd[2386]: time="2025-05-09T04:49:48.152700499Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 04:49:48.153158 containerd[2386]: time="2025-05-09T04:49:48.153133450Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 04:49:48.161033 containerd[2386]: time="2025-05-09T04:49:48.160993807Z" level=info msg="Start subscribing containerd event" May 9 04:49:48.161092 containerd[2386]: time="2025-05-09T04:49:48.161045557Z" level=info msg="Start recovering state" May 9 04:49:48.161130 containerd[2386]: time="2025-05-09T04:49:48.161106984Z" level=info msg="Start event monitor" May 9 04:49:48.161130 containerd[2386]: time="2025-05-09T04:49:48.161119062Z" level=info msg="Start cni network conf syncer for default" May 9 04:49:48.161130 containerd[2386]: time="2025-05-09T04:49:48.161126420Z" level=info msg="Start streaming server" May 9 04:49:48.161187 containerd[2386]: time="2025-05-09T04:49:48.161109463Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 04:49:48.161207 containerd[2386]: time="2025-05-09T04:49:48.161197406Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 04:49:48.161225 containerd[2386]: time="2025-05-09T04:49:48.161134538Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 9 04:49:48.161225 containerd[2386]: time="2025-05-09T04:49:48.161214242Z" level=info msg="runtime interface starting up..." May 9 04:49:48.161225 containerd[2386]: time="2025-05-09T04:49:48.161219761Z" level=info msg="starting plugins..." May 9 04:49:48.161282 containerd[2386]: time="2025-05-09T04:49:48.161227759Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 9 04:49:48.161694 containerd[2386]: time="2025-05-09T04:49:48.161660591Z" level=info msg="containerd successfully booted in 0.047864s" May 9 04:49:48.161740 systemd[1]: Started containerd.service - containerd container runtime. May 9 04:49:48.163343 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 04:49:48.177120 (dockerd)[2433]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 04:49:48.199562 dockerd[2433]: time="2025-05-09T04:49:48.199504233Z" level=info msg="Starting up" May 9 04:49:48.199988 dockerd[2433]: time="2025-05-09T04:49:48.199970498Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 9 04:49:48.228635 dockerd[2433]: time="2025-05-09T04:49:48.228602338Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 9 04:49:48.235818 dockerd[2433]: time="2025-05-09T04:49:48.235777595Z" level=info msg="Loading containers: start." May 9 04:49:48.251497 systemd[1]: var-lib-docker-overlay2-156e02bfe3108e85a00380178bcc2ae662911e58fbea7acc181aec789568e668-merged.mount: Deactivated successfully. May 9 04:49:48.547669 dockerd[2433]: time="2025-05-09T04:49:48.547537691Z" level=info msg="Removing stale sandbox fd3ee599f14bb1285f6fb75877ea83fb26097c987a662db731513690aba36fe6 (8d77264c0557b54b23b6a941687059ddfef584a8e9ae1ec4c67a71d82ab88daa)" May 9 04:49:48.551097 dockerd[2433]: time="2025-05-09T04:49:48.551058413Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" May 9 04:49:48.551169 dockerd[2433]: time="2025-05-09T04:49:48.551103523Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint f333a2c4f7ed78f182fbbbd0b05ebdf25b0e716d5646a8a8b45e10245d75cc81 41d022b8604ca6ff5fd0b794f32e8c35f34f7cee9592f82e8bf8d2a57a32c83b], retrying...." May 9 04:49:48.556068 systemd-networkd[1355]: vethb9d1593: Link DOWN May 9 04:49:48.556986 kernel: docker0: port 1(vethb9d1593) entered disabled state May 9 04:49:48.557015 kernel: vethb9d1593 (unregistering): left allmulticast mode May 9 04:49:48.556073 systemd-networkd[1355]: vethb9d1593: Lost carrier May 9 04:49:48.558170 kernel: vethb9d1593 (unregistering): left promiscuous mode May 9 04:49:48.558214 kernel: docker0: port 1(vethb9d1593) entered disabled state May 9 04:49:48.566093 systemd-networkd[1355]: docker0: Lost carrier May 9 04:49:48.712402 dockerd[2433]: time="2025-05-09T04:49:48.712329761Z" level=warning msg="error locating sandbox id a942159558f66d1621f2bf9a7230836134cfc7ca9cb269dce34ff558c8a0d2e0: sandbox a942159558f66d1621f2bf9a7230836134cfc7ca9cb269dce34ff558c8a0d2e0 not found" May 9 04:49:48.712402 dockerd[2433]: time="2025-05-09T04:49:48.712371232Z" level=warning msg="error locating sandbox id fd3ee599f14bb1285f6fb75877ea83fb26097c987a662db731513690aba36fe6: sandbox fd3ee599f14bb1285f6fb75877ea83fb26097c987a662db731513690aba36fe6 not found" May 9 04:49:48.713091 dockerd[2433]: time="2025-05-09T04:49:48.712582389Z" level=info msg="Loading containers: done." May 9 04:49:48.726834 dockerd[2433]: time="2025-05-09T04:49:48.726780494Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 04:49:48.726971 dockerd[2433]: time="2025-05-09T04:49:48.726885632Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 9 04:49:48.726971 dockerd[2433]: time="2025-05-09T04:49:48.726925424Z" level=info msg="Initializing buildkit" May 9 04:49:48.738130 dockerd[2433]: time="2025-05-09T04:49:48.738097825Z" level=info msg="Completed buildkit initialization" May 9 04:49:48.744634 dockerd[2433]: time="2025-05-09T04:49:48.744564147Z" level=info msg="Daemon has completed initialization" May 9 04:49:48.744719 dockerd[2433]: time="2025-05-09T04:49:48.744624454Z" level=info msg="API listen on /run/docker.sock" May 9 04:49:48.744776 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 04:49:49.214983 systemd[1]: run-docker-netns-fd3ee599f14b.mount: Deactivated successfully. May 9 04:49:52.499939 systemd[1]: Started sshd@24-10.0.0.29:22-10.0.0.1:57286.service - OpenSSH per-connection server daemon (10.0.0.1:57286). May 9 04:49:52.549984 sshd[2658]: Accepted publickey for core from 10.0.0.1 port 57286 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:52.551059 sshd-session[2658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:52.555830 systemd-logind[1390]: New session 25 of user core. May 9 04:49:52.564038 systemd[1]: Started session-25.scope - Session 25 of User core. May 9 04:49:52.616647 sshd[2660]: Connection closed by 10.0.0.1 port 57286 May 9 04:49:52.616991 sshd-session[2658]: pam_unix(sshd:session): session closed for user core May 9 04:49:52.635693 systemd[1]: sshd@24-10.0.0.29:22-10.0.0.1:57286.service: Deactivated successfully. May 9 04:49:52.636918 systemd[1]: session-25.scope: Deactivated successfully. May 9 04:49:52.638188 systemd-logind[1390]: Session 25 logged out. Waiting for processes to exit. May 9 04:49:52.640272 systemd[1]: Started sshd@25-10.0.0.29:22-10.0.0.1:57288.service - OpenSSH per-connection server daemon (10.0.0.1:57288). May 9 04:49:52.641146 systemd-logind[1390]: Removed session 25. May 9 04:49:52.693146 sshd[2665]: Accepted publickey for core from 10.0.0.1 port 57288 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:52.694239 sshd-session[2665]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:52.698908 systemd-logind[1390]: New session 26 of user core. May 9 04:49:52.717025 systemd[1]: Started session-26.scope - Session 26 of User core. May 9 04:49:52.769562 sshd[2668]: Connection closed by 10.0.0.1 port 57288 May 9 04:49:52.769992 sshd-session[2665]: pam_unix(sshd:session): session closed for user core May 9 04:49:52.787967 systemd[1]: sshd@25-10.0.0.29:22-10.0.0.1:57288.service: Deactivated successfully. May 9 04:49:52.789814 systemd[1]: session-26.scope: Deactivated successfully. May 9 04:49:52.791919 systemd-logind[1390]: Session 26 logged out. Waiting for processes to exit. May 9 04:49:52.792238 systemd[1]: Started sshd@26-10.0.0.29:22-10.0.0.1:57296.service - OpenSSH per-connection server daemon (10.0.0.1:57296). May 9 04:49:52.793681 systemd-logind[1390]: Removed session 26. May 9 04:49:52.843520 sshd[2673]: Accepted publickey for core from 10.0.0.1 port 57296 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:52.844748 sshd-session[2673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:52.848913 systemd-logind[1390]: New session 27 of user core. May 9 04:49:52.864096 systemd[1]: Started session-27.scope - Session 27 of User core. May 9 04:49:52.922789 sshd[2676]: Connection closed by 10.0.0.1 port 57296 May 9 04:49:52.923250 sshd-session[2673]: pam_unix(sshd:session): session closed for user core May 9 04:49:52.936415 systemd[1]: sshd@26-10.0.0.29:22-10.0.0.1:57296.service: Deactivated successfully. May 9 04:49:52.938249 systemd[1]: session-27.scope: Deactivated successfully. May 9 04:49:52.939922 systemd-logind[1390]: Session 27 logged out. Waiting for processes to exit. May 9 04:49:52.941650 systemd[1]: Started sshd@27-10.0.0.29:22-10.0.0.1:57310.service - OpenSSH per-connection server daemon (10.0.0.1:57310). May 9 04:49:52.943218 systemd-logind[1390]: Removed session 27. May 9 04:49:52.991452 sshd[2688]: Accepted publickey for core from 10.0.0.1 port 57310 ssh2: RSA SHA256:cGfwtCSR41ihX2TEzmGxMnuwv2fv9xnRwxIIs+hE9lQ May 9 04:49:52.992879 sshd-session[2688]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 04:49:52.997900 systemd-logind[1390]: New session 28 of user core. May 9 04:49:53.008037 systemd[1]: Started session-28.scope - Session 28 of User core. May 9 04:49:53.139736 systemd[1]: var-lib-docker-overlay2-6080f9aaec084921b46e29cb07c57902ffb45cf18acdc2f459516b93980138fa\x2dinit-merged.mount: Deactivated successfully. May 9 04:49:53.337734 containerd[2386]: time="2025-05-09T04:49:53.337662650Z" level=info msg="connecting to shim ffdd3ab77614a166fcd2b19d19707bd30098fb41fca33f77c0943a9e5fac1741" address="unix:///run/containerd/s/e2840d94b64991a74724b5dd54c9649034df514508c9b20a11b05f9c2a56a49c" namespace=moby protocol=ttrpc version=3 May 9 04:49:53.364011 systemd[1]: Started docker-ffdd3ab77614a166fcd2b19d19707bd30098fb41fca33f77c0943a9e5fac1741.scope - libcontainer container ffdd3ab77614a166fcd2b19d19707bd30098fb41fca33f77c0943a9e5fac1741. May 9 04:49:53.376424 systemd-resolved[1286]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 04:49:53.397307 kernel: docker0: port 1(veth47b900c) entered blocking state May 9 04:49:53.397396 kernel: docker0: port 1(veth47b900c) entered disabled state May 9 04:49:53.397414 kernel: veth47b900c: entered allmulticast mode May 9 04:49:53.397429 kernel: veth47b900c: entered promiscuous mode May 9 04:49:53.397331 systemd-networkd[1355]: veth47b900c: Link UP May 9 04:49:53.408878 kernel: eth0: renamed from veth7bc4444 May 9 04:49:53.420518 systemd-networkd[1355]: veth47b900c: Gained carrier May 9 04:49:53.421247 kernel: docker0: port 1(veth47b900c) entered blocking state May 9 04:49:53.421301 kernel: docker0: port 1(veth47b900c) entered forwarding state May 9 04:49:53.420810 systemd-networkd[1355]: docker0: Gained carrier May 9 04:49:53.439191 sshd[2691]: Connection closed by 10.0.0.1 port 57310 May 9 04:49:53.439515 sshd-session[2688]: pam_unix(sshd:session): session closed for user core May 9 04:49:53.443533 systemd[1]: sshd@27-10.0.0.29:22-10.0.0.1:57310.service: Deactivated successfully. May 9 04:49:53.445166 systemd[1]: session-28.scope: Deactivated successfully. May 9 04:49:53.446363 systemd-logind[1390]: Session 28 logged out. Waiting for processes to exit. May 9 04:49:53.447252 systemd-logind[1390]: Removed session 28.