Apr 22 15:14:15.879806 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Apr 22 15:14:15.879835 kernel: Linux version 6.6.83-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 Mon Mar 24 23:39:14 -00 2025 Apr 22 15:14:15.879845 kernel: KASLR enabled Apr 22 15:14:15.879851 kernel: efi: EFI v2.7 by EDK II Apr 22 15:14:15.879856 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb4ff018 ACPI 2.0=0xd93ef018 RNG=0xd93efa18 MEMRESERVE=0xd91d9d18 Apr 22 15:14:15.879862 kernel: random: crng init done Apr 22 15:14:15.879869 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Apr 22 15:14:15.879875 kernel: secureboot: Secure boot enabled Apr 22 15:14:15.879880 kernel: ACPI: Early table checksum verification disabled Apr 22 15:14:15.879886 kernel: ACPI: RSDP 0x00000000D93EF018 000024 (v02 BOCHS ) Apr 22 15:14:15.879893 kernel: ACPI: XSDT 0x00000000D93EFF18 000064 (v01 BOCHS BXPC 00000001 01000013) Apr 22 15:14:15.879898 kernel: ACPI: FACP 0x00000000D93EFB18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879904 kernel: ACPI: DSDT 0x00000000D93ED018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879910 kernel: ACPI: APIC 0x00000000D93EFC98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879917 kernel: ACPI: PPTT 0x00000000D93EF098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879930 kernel: ACPI: GTDT 0x00000000D93EF818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879936 kernel: ACPI: MCFG 0x00000000D93EFA98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879942 kernel: ACPI: SPCR 0x00000000D93EF918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879948 kernel: ACPI: DBG2 0x00000000D93EF998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879954 kernel: ACPI: IORT 0x00000000D93EF198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 22 15:14:15.879960 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Apr 22 15:14:15.879966 kernel: NUMA: Failed to initialise from firmware Apr 22 15:14:15.879972 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Apr 22 15:14:15.879979 kernel: NUMA: NODE_DATA [mem 0xdc72a800-0xdc72ffff] Apr 22 15:14:15.879984 kernel: Zone ranges: Apr 22 15:14:15.880005 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Apr 22 15:14:15.880011 kernel: DMA32 empty Apr 22 15:14:15.880017 kernel: Normal empty Apr 22 15:14:15.880023 kernel: Movable zone start for each node Apr 22 15:14:15.880029 kernel: Early memory node ranges Apr 22 15:14:15.880035 kernel: node 0: [mem 0x0000000040000000-0x00000000d93effff] Apr 22 15:14:15.880040 kernel: node 0: [mem 0x00000000d93f0000-0x00000000d972ffff] Apr 22 15:14:15.880046 kernel: node 0: [mem 0x00000000d9730000-0x00000000dcbfffff] Apr 22 15:14:15.880052 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Apr 22 15:14:15.880058 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Apr 22 15:14:15.880064 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Apr 22 15:14:15.880070 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Apr 22 15:14:15.880078 kernel: psci: probing for conduit method from ACPI. Apr 22 15:14:15.880084 kernel: psci: PSCIv1.1 detected in firmware. Apr 22 15:14:15.880090 kernel: psci: Using standard PSCI v0.2 function IDs Apr 22 15:14:15.880099 kernel: psci: Trusted OS migration not required Apr 22 15:14:15.880105 kernel: psci: SMC Calling Convention v1.1 Apr 22 15:14:15.880112 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Apr 22 15:14:15.880118 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Apr 22 15:14:15.880126 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Apr 22 15:14:15.880133 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 22 15:14:15.880139 kernel: Detected PIPT I-cache on CPU0 Apr 22 15:14:15.880145 kernel: CPU features: detected: GIC system register CPU interface Apr 22 15:14:15.880152 kernel: CPU features: detected: Hardware dirty bit management Apr 22 15:14:15.880158 kernel: CPU features: detected: Spectre-v4 Apr 22 15:14:15.880165 kernel: CPU features: detected: Spectre-BHB Apr 22 15:14:15.880171 kernel: CPU features: kernel page table isolation forced ON by KASLR Apr 22 15:14:15.880177 kernel: CPU features: detected: Kernel page table isolation (KPTI) Apr 22 15:14:15.880184 kernel: CPU features: detected: ARM erratum 1418040 Apr 22 15:14:15.880192 kernel: CPU features: detected: SSBS not fully self-synchronizing Apr 22 15:14:15.880198 kernel: alternatives: applying boot alternatives Apr 22 15:14:15.880205 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=b84e5f613acd6cd0a8a878f32f5653a14f2e6fb2820997fecd5b2bd33a4ba3ab Apr 22 15:14:15.880212 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 22 15:14:15.880219 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 22 15:14:15.880225 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 22 15:14:15.880231 kernel: Fallback order for Node 0: 0 Apr 22 15:14:15.880238 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Apr 22 15:14:15.880244 kernel: Policy zone: DMA Apr 22 15:14:15.880250 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 22 15:14:15.880260 kernel: software IO TLB: area num 4. Apr 22 15:14:15.880267 kernel: software IO TLB: mapped [mem 0x00000000d2800000-0x00000000d6800000] (64MB) Apr 22 15:14:15.880276 kernel: Memory: 2385820K/2572288K available (10304K kernel code, 2186K rwdata, 8096K rodata, 38464K init, 897K bss, 186468K reserved, 0K cma-reserved) Apr 22 15:14:15.880283 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 22 15:14:15.880289 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 22 15:14:15.880298 kernel: rcu: RCU event tracing is enabled. Apr 22 15:14:15.880307 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Apr 22 15:14:15.880315 kernel: Trampoline variant of Tasks RCU enabled. Apr 22 15:14:15.880322 kernel: Tracing variant of Tasks RCU enabled. Apr 22 15:14:15.880329 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 22 15:14:15.880336 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Apr 22 15:14:15.880342 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 22 15:14:15.880350 kernel: GICv3: 256 SPIs implemented Apr 22 15:14:15.880357 kernel: GICv3: 0 Extended SPIs implemented Apr 22 15:14:15.880363 kernel: Root IRQ handler: gic_handle_irq Apr 22 15:14:15.880369 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Apr 22 15:14:15.880376 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Apr 22 15:14:15.880382 kernel: ITS [mem 0x08080000-0x0809ffff] Apr 22 15:14:15.880389 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Apr 22 15:14:15.880396 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Apr 22 15:14:15.880402 kernel: GICv3: using LPI property table @0x00000000400f0000 Apr 22 15:14:15.880409 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Apr 22 15:14:15.880416 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 22 15:14:15.880424 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 22 15:14:15.880431 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Apr 22 15:14:15.880437 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Apr 22 15:14:15.880444 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Apr 22 15:14:15.880451 kernel: arm-pv: using stolen time PV Apr 22 15:14:15.880458 kernel: Console: colour dummy device 80x25 Apr 22 15:14:15.880464 kernel: ACPI: Core revision 20230628 Apr 22 15:14:15.880471 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Apr 22 15:14:15.880478 kernel: pid_max: default: 32768 minimum: 301 Apr 22 15:14:15.880485 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 22 15:14:15.880493 kernel: landlock: Up and running. Apr 22 15:14:15.880499 kernel: SELinux: Initializing. Apr 22 15:14:15.880506 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 22 15:14:15.880513 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 22 15:14:15.880520 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 22 15:14:15.880527 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 22 15:14:15.880534 kernel: rcu: Hierarchical SRCU implementation. Apr 22 15:14:15.880541 kernel: rcu: Max phase no-delay instances is 400. Apr 22 15:14:15.880555 kernel: Platform MSI: ITS@0x8080000 domain created Apr 22 15:14:15.880564 kernel: PCI/MSI: ITS@0x8080000 domain created Apr 22 15:14:15.880571 kernel: Remapping and enabling EFI services. Apr 22 15:14:15.880578 kernel: smp: Bringing up secondary CPUs ... Apr 22 15:14:15.880585 kernel: Detected PIPT I-cache on CPU1 Apr 22 15:14:15.880592 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Apr 22 15:14:15.880598 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Apr 22 15:14:15.880605 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 22 15:14:15.880612 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Apr 22 15:14:15.880619 kernel: Detected PIPT I-cache on CPU2 Apr 22 15:14:15.880626 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Apr 22 15:14:15.880634 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Apr 22 15:14:15.880641 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 22 15:14:15.880652 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Apr 22 15:14:15.880660 kernel: Detected PIPT I-cache on CPU3 Apr 22 15:14:15.880667 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Apr 22 15:14:15.880675 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Apr 22 15:14:15.880682 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 22 15:14:15.880689 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Apr 22 15:14:15.880696 kernel: smp: Brought up 1 node, 4 CPUs Apr 22 15:14:15.880703 kernel: SMP: Total of 4 processors activated. Apr 22 15:14:15.880711 kernel: CPU features: detected: 32-bit EL0 Support Apr 22 15:14:15.880718 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Apr 22 15:14:15.880726 kernel: CPU features: detected: Common not Private translations Apr 22 15:14:15.880733 kernel: CPU features: detected: CRC32 instructions Apr 22 15:14:15.880740 kernel: CPU features: detected: Enhanced Virtualization Traps Apr 22 15:14:15.880747 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Apr 22 15:14:15.880754 kernel: CPU features: detected: LSE atomic instructions Apr 22 15:14:15.880762 kernel: CPU features: detected: Privileged Access Never Apr 22 15:14:15.880769 kernel: CPU features: detected: RAS Extension Support Apr 22 15:14:15.880776 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Apr 22 15:14:15.880783 kernel: CPU: All CPU(s) started at EL1 Apr 22 15:14:15.880791 kernel: alternatives: applying system-wide alternatives Apr 22 15:14:15.880798 kernel: devtmpfs: initialized Apr 22 15:14:15.880805 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 22 15:14:15.880812 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 22 15:14:15.880819 kernel: pinctrl core: initialized pinctrl subsystem Apr 22 15:14:15.880832 kernel: SMBIOS 3.0.0 present. Apr 22 15:14:15.880839 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Apr 22 15:14:15.880846 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 22 15:14:15.880857 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 22 15:14:15.880865 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 22 15:14:15.880872 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 22 15:14:15.880879 kernel: audit: initializing netlink subsys (disabled) Apr 22 15:14:15.880886 kernel: audit: type=2000 audit(0.022:1): state=initialized audit_enabled=0 res=1 Apr 22 15:14:15.880893 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 22 15:14:15.880903 kernel: cpuidle: using governor menu Apr 22 15:14:15.880910 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 22 15:14:15.880918 kernel: ASID allocator initialised with 32768 entries Apr 22 15:14:15.880925 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 22 15:14:15.880932 kernel: Serial: AMBA PL011 UART driver Apr 22 15:14:15.880939 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Apr 22 15:14:15.880946 kernel: Modules: 0 pages in range for non-PLT usage Apr 22 15:14:15.880953 kernel: Modules: 509248 pages in range for PLT usage Apr 22 15:14:15.880960 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 22 15:14:15.880968 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 22 15:14:15.880976 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 22 15:14:15.880983 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 22 15:14:15.880990 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 22 15:14:15.880997 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 22 15:14:15.881004 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 22 15:14:15.881011 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 22 15:14:15.881018 kernel: ACPI: Added _OSI(Module Device) Apr 22 15:14:15.881025 kernel: ACPI: Added _OSI(Processor Device) Apr 22 15:14:15.881033 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 22 15:14:15.881040 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 22 15:14:15.881047 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 22 15:14:15.881054 kernel: ACPI: Interpreter enabled Apr 22 15:14:15.881061 kernel: ACPI: Using GIC for interrupt routing Apr 22 15:14:15.881068 kernel: ACPI: MCFG table detected, 1 entries Apr 22 15:14:15.881075 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Apr 22 15:14:15.881082 kernel: printk: console [ttyAMA0] enabled Apr 22 15:14:15.881090 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 22 15:14:15.881225 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 22 15:14:15.881301 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 22 15:14:15.881370 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 22 15:14:15.881437 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Apr 22 15:14:15.881503 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Apr 22 15:14:15.881512 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Apr 22 15:14:15.881519 kernel: PCI host bridge to bus 0000:00 Apr 22 15:14:15.881609 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Apr 22 15:14:15.881674 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 22 15:14:15.881736 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Apr 22 15:14:15.881796 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 22 15:14:15.881896 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Apr 22 15:14:15.881975 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Apr 22 15:14:15.882050 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Apr 22 15:14:15.882120 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Apr 22 15:14:15.882189 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Apr 22 15:14:15.882254 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Apr 22 15:14:15.882320 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Apr 22 15:14:15.882385 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Apr 22 15:14:15.882445 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Apr 22 15:14:15.882513 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 22 15:14:15.882622 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Apr 22 15:14:15.882632 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 22 15:14:15.882640 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 22 15:14:15.882647 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 22 15:14:15.882654 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 22 15:14:15.882661 kernel: iommu: Default domain type: Translated Apr 22 15:14:15.882668 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 22 15:14:15.882675 kernel: efivars: Registered efivars operations Apr 22 15:14:15.882685 kernel: vgaarb: loaded Apr 22 15:14:15.882692 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 22 15:14:15.882699 kernel: VFS: Disk quotas dquot_6.6.0 Apr 22 15:14:15.882706 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 22 15:14:15.882713 kernel: pnp: PnP ACPI init Apr 22 15:14:15.882791 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Apr 22 15:14:15.882802 kernel: pnp: PnP ACPI: found 1 devices Apr 22 15:14:15.882809 kernel: NET: Registered PF_INET protocol family Apr 22 15:14:15.882818 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 22 15:14:15.882833 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 22 15:14:15.882841 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 22 15:14:15.882848 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 22 15:14:15.882855 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 22 15:14:15.882862 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 22 15:14:15.882872 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 22 15:14:15.882880 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 22 15:14:15.882887 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 22 15:14:15.882896 kernel: PCI: CLS 0 bytes, default 64 Apr 22 15:14:15.882903 kernel: kvm [1]: HYP mode not available Apr 22 15:14:15.882909 kernel: Initialise system trusted keyrings Apr 22 15:14:15.882916 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 22 15:14:15.882923 kernel: Key type asymmetric registered Apr 22 15:14:15.882930 kernel: Asymmetric key parser 'x509' registered Apr 22 15:14:15.882937 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 22 15:14:15.882944 kernel: io scheduler mq-deadline registered Apr 22 15:14:15.882951 kernel: io scheduler kyber registered Apr 22 15:14:15.882959 kernel: io scheduler bfq registered Apr 22 15:14:15.882967 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 22 15:14:15.882974 kernel: ACPI: button: Power Button [PWRB] Apr 22 15:14:15.882981 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 22 15:14:15.883052 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Apr 22 15:14:15.883062 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 22 15:14:15.883068 kernel: thunder_xcv, ver 1.0 Apr 22 15:14:15.883075 kernel: thunder_bgx, ver 1.0 Apr 22 15:14:15.883082 kernel: nicpf, ver 1.0 Apr 22 15:14:15.883092 kernel: nicvf, ver 1.0 Apr 22 15:14:15.883165 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 22 15:14:15.883228 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-04-22T15:14:15 UTC (1745334855) Apr 22 15:14:15.883238 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 22 15:14:15.883245 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Apr 22 15:14:15.883252 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 22 15:14:15.883259 kernel: watchdog: Hard watchdog permanently disabled Apr 22 15:14:15.883266 kernel: NET: Registered PF_INET6 protocol family Apr 22 15:14:15.883275 kernel: Segment Routing with IPv6 Apr 22 15:14:15.883282 kernel: In-situ OAM (IOAM) with IPv6 Apr 22 15:14:15.883289 kernel: NET: Registered PF_PACKET protocol family Apr 22 15:14:15.883296 kernel: Key type dns_resolver registered Apr 22 15:14:15.883303 kernel: registered taskstats version 1 Apr 22 15:14:15.883309 kernel: Loading compiled-in X.509 certificates Apr 22 15:14:15.883317 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: ed4ababe871f0afac8b4236504477de11a6baf07' Apr 22 15:14:15.883323 kernel: Key type .fscrypt registered Apr 22 15:14:15.883330 kernel: Key type fscrypt-provisioning registered Apr 22 15:14:15.883339 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 22 15:14:15.883346 kernel: ima: Allocated hash algorithm: sha1 Apr 22 15:14:15.883353 kernel: ima: No architecture policies found Apr 22 15:14:15.883360 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 22 15:14:15.883367 kernel: clk: Disabling unused clocks Apr 22 15:14:15.883374 kernel: Freeing unused kernel memory: 38464K Apr 22 15:14:15.883380 kernel: Run /init as init process Apr 22 15:14:15.883387 kernel: with arguments: Apr 22 15:14:15.883394 kernel: /init Apr 22 15:14:15.883402 kernel: with environment: Apr 22 15:14:15.883409 kernel: HOME=/ Apr 22 15:14:15.883416 kernel: TERM=linux Apr 22 15:14:15.883423 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 22 15:14:15.883431 systemd[1]: Successfully made /usr/ read-only. Apr 22 15:14:15.883441 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) Apr 22 15:14:15.883449 systemd[1]: Detected virtualization kvm. Apr 22 15:14:15.883456 systemd[1]: Detected architecture arm64. Apr 22 15:14:15.883465 systemd[1]: Running in initrd. Apr 22 15:14:15.883472 systemd[1]: No hostname configured, using default hostname. Apr 22 15:14:15.883484 systemd[1]: Hostname set to . Apr 22 15:14:15.883492 systemd[1]: Initializing machine ID from VM UUID. Apr 22 15:14:15.883499 systemd[1]: Queued start job for default target initrd.target. Apr 22 15:14:15.883507 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 22 15:14:15.883515 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 22 15:14:15.883523 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 22 15:14:15.883532 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 22 15:14:15.883540 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 22 15:14:15.883560 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 22 15:14:15.883569 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 22 15:14:15.883577 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 22 15:14:15.883585 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 22 15:14:15.883592 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 22 15:14:15.883602 systemd[1]: Reached target paths.target - Path Units. Apr 22 15:14:15.883610 systemd[1]: Reached target slices.target - Slice Units. Apr 22 15:14:15.883617 systemd[1]: Reached target swap.target - Swaps. Apr 22 15:14:15.883625 systemd[1]: Reached target timers.target - Timer Units. Apr 22 15:14:15.883633 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 22 15:14:15.883640 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 22 15:14:15.883648 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 22 15:14:15.883655 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Apr 22 15:14:15.883664 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 22 15:14:15.883672 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 22 15:14:15.883680 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 22 15:14:15.883687 systemd[1]: Reached target sockets.target - Socket Units. Apr 22 15:14:15.883695 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 22 15:14:15.883702 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 22 15:14:15.883710 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 22 15:14:15.883717 systemd[1]: Starting systemd-fsck-usr.service... Apr 22 15:14:15.883725 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 22 15:14:15.883734 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 22 15:14:15.883742 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 22 15:14:15.883749 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 22 15:14:15.883757 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 22 15:14:15.883765 systemd[1]: Finished systemd-fsck-usr.service. Apr 22 15:14:15.883774 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 22 15:14:15.883798 systemd-journald[236]: Collecting audit messages is disabled. Apr 22 15:14:15.883817 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 22 15:14:15.883833 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 22 15:14:15.883841 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 22 15:14:15.883849 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 22 15:14:15.883857 kernel: Bridge firewalling registered Apr 22 15:14:15.883864 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 22 15:14:15.883872 systemd-journald[236]: Journal started Apr 22 15:14:15.883892 systemd-journald[236]: Runtime Journal (/run/log/journal/37050bc3ced449f29dfab79478f5a2cc) is 5.9M, max 47.3M, 41.4M free. Apr 22 15:14:15.864782 systemd-modules-load[237]: Inserted module 'overlay' Apr 22 15:14:15.882536 systemd-modules-load[237]: Inserted module 'br_netfilter' Apr 22 15:14:15.887598 systemd[1]: Started systemd-journald.service - Journal Service. Apr 22 15:14:15.890058 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 22 15:14:15.891692 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 22 15:14:15.894687 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 22 15:14:15.898954 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 22 15:14:15.900588 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 22 15:14:15.906436 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 22 15:14:15.907662 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 22 15:14:15.909373 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 22 15:14:15.912339 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 22 15:14:15.915437 dracut-cmdline[272]: dracut-dracut-053 Apr 22 15:14:15.917777 dracut-cmdline[272]: Using kernel command line parameters: rd.driver.pre=btrfs 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=b84e5f613acd6cd0a8a878f32f5653a14f2e6fb2820997fecd5b2bd33a4ba3ab Apr 22 15:14:15.948669 systemd-resolved[282]: Positive Trust Anchors: Apr 22 15:14:15.948686 systemd-resolved[282]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 22 15:14:15.948721 systemd-resolved[282]: 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 Apr 22 15:14:15.953446 systemd-resolved[282]: Defaulting to hostname 'linux'. Apr 22 15:14:15.954640 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 22 15:14:15.955999 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 22 15:14:15.981577 kernel: SCSI subsystem initialized Apr 22 15:14:15.988564 kernel: Loading iSCSI transport class v2.0-870. Apr 22 15:14:15.995571 kernel: iscsi: registered transport (tcp) Apr 22 15:14:16.007654 kernel: iscsi: registered transport (qla4xxx) Apr 22 15:14:16.007667 kernel: QLogic iSCSI HBA Driver Apr 22 15:14:16.047305 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 22 15:14:16.049196 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 22 15:14:16.076654 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 22 15:14:16.076694 kernel: device-mapper: uevent: version 1.0.3 Apr 22 15:14:16.077565 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 22 15:14:16.123571 kernel: raid6: neonx8 gen() 15776 MB/s Apr 22 15:14:16.140555 kernel: raid6: neonx4 gen() 15814 MB/s Apr 22 15:14:16.157559 kernel: raid6: neonx2 gen() 13201 MB/s Apr 22 15:14:16.174555 kernel: raid6: neonx1 gen() 10486 MB/s Apr 22 15:14:16.191555 kernel: raid6: int64x8 gen() 6792 MB/s Apr 22 15:14:16.208561 kernel: raid6: int64x4 gen() 7331 MB/s Apr 22 15:14:16.225569 kernel: raid6: int64x2 gen() 6105 MB/s Apr 22 15:14:16.242572 kernel: raid6: int64x1 gen() 5055 MB/s Apr 22 15:14:16.242595 kernel: raid6: using algorithm neonx4 gen() 15814 MB/s Apr 22 15:14:16.259576 kernel: raid6: .... xor() 12454 MB/s, rmw enabled Apr 22 15:14:16.259599 kernel: raid6: using neon recovery algorithm Apr 22 15:14:16.264852 kernel: xor: measuring software checksum speed Apr 22 15:14:16.264879 kernel: 8regs : 21004 MB/sec Apr 22 15:14:16.264892 kernel: 32regs : 21710 MB/sec Apr 22 15:14:16.265780 kernel: arm64_neon : 27879 MB/sec Apr 22 15:14:16.265793 kernel: xor: using function: arm64_neon (27879 MB/sec) Apr 22 15:14:16.316573 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 22 15:14:16.327015 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 22 15:14:16.329486 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 22 15:14:16.354873 systemd-udevd[462]: Using default interface naming scheme 'v255'. Apr 22 15:14:16.358487 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 22 15:14:16.361421 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 22 15:14:16.382507 dracut-pre-trigger[471]: rd.md=0: removing MD RAID activation Apr 22 15:14:16.406609 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 22 15:14:16.408723 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 22 15:14:16.460182 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 22 15:14:16.464011 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 22 15:14:16.486582 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 22 15:14:16.487750 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 22 15:14:16.489189 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 22 15:14:16.491158 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 22 15:14:16.493461 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 22 15:14:16.507559 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Apr 22 15:14:16.519885 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Apr 22 15:14:16.519989 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 22 15:14:16.518310 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 22 15:14:16.522017 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 22 15:14:16.522121 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 22 15:14:16.524880 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 22 15:14:16.525706 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 22 15:14:16.525833 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 22 15:14:16.529326 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 22 15:14:16.535116 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 22 15:14:16.542565 kernel: BTRFS: device fsid bf348154-9cb1-474d-801c-0e035a5758cf devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (512) Apr 22 15:14:16.543934 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (518) Apr 22 15:14:16.547018 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 22 15:14:16.554586 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 22 15:14:16.570904 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 22 15:14:16.578057 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 22 15:14:16.583893 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 22 15:14:16.584791 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 22 15:14:16.587670 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 22 15:14:16.589131 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 22 15:14:16.614578 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 22 15:14:16.616461 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 22 15:14:17.628567 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 22 15:14:17.629255 disk-uuid[556]: The operation has completed successfully. Apr 22 15:14:17.648160 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 22 15:14:17.648251 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 22 15:14:17.678489 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 22 15:14:17.693321 sh[571]: Success Apr 22 15:14:17.709578 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 22 15:14:17.737171 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 22 15:14:17.738627 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 22 15:14:17.747367 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 22 15:14:17.753090 kernel: BTRFS info (device dm-0): first mount of filesystem bf348154-9cb1-474d-801c-0e035a5758cf Apr 22 15:14:17.753124 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 22 15:14:17.755899 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 22 15:14:17.755927 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 22 15:14:17.755946 kernel: BTRFS info (device dm-0): using free space tree Apr 22 15:14:17.760646 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 22 15:14:17.761645 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 22 15:14:17.762259 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 22 15:14:17.764756 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 22 15:14:17.781057 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Apr 22 15:14:17.781102 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 22 15:14:17.781113 kernel: BTRFS info (device vda6): using free space tree Apr 22 15:14:17.783587 kernel: BTRFS info (device vda6): auto enabling async discard Apr 22 15:14:17.788565 kernel: BTRFS info (device vda6): last unmount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Apr 22 15:14:17.792023 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 22 15:14:17.794692 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 22 15:14:17.854584 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 22 15:14:17.857254 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 22 15:14:17.895357 ignition[664]: Ignition 2.20.0 Apr 22 15:14:17.895366 ignition[664]: Stage: fetch-offline Apr 22 15:14:17.895395 ignition[664]: no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:17.895403 ignition[664]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:17.895563 ignition[664]: parsed url from cmdline: "" Apr 22 15:14:17.895566 ignition[664]: no config URL provided Apr 22 15:14:17.895571 ignition[664]: reading system config file "/usr/lib/ignition/user.ign" Apr 22 15:14:17.895577 ignition[664]: no config at "/usr/lib/ignition/user.ign" Apr 22 15:14:17.895599 ignition[664]: op(1): [started] loading QEMU firmware config module Apr 22 15:14:17.895603 ignition[664]: op(1): executing: "modprobe" "qemu_fw_cfg" Apr 22 15:14:17.905347 systemd-networkd[759]: lo: Link UP Apr 22 15:14:17.905358 systemd-networkd[759]: lo: Gained carrier Apr 22 15:14:17.906199 systemd-networkd[759]: Enumeration completed Apr 22 15:14:17.906665 systemd-networkd[759]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 22 15:14:17.906889 ignition[664]: op(1): [finished] loading QEMU firmware config module Apr 22 15:14:17.906669 systemd-networkd[759]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 22 15:14:17.906950 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 22 15:14:17.907376 systemd-networkd[759]: eth0: Link UP Apr 22 15:14:17.907379 systemd-networkd[759]: eth0: Gained carrier Apr 22 15:14:17.907385 systemd-networkd[759]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 22 15:14:17.914923 ignition[664]: parsing config with SHA512: 0ffe4f9fc408587ce72bec0278f65991b7cb4c700096aee1195eff6e2ee33d3b27442b9fb689eed9ebaa8df2e3ddd8269a4064e0b982c7249ac6da93738a568a Apr 22 15:14:17.908884 systemd[1]: Reached target network.target - Network. Apr 22 15:14:17.920769 unknown[664]: fetched base config from "system" Apr 22 15:14:17.921072 unknown[664]: fetched user config from "qemu" Apr 22 15:14:17.921261 ignition[664]: fetch-offline: fetch-offline passed Apr 22 15:14:17.921361 ignition[664]: Ignition finished successfully Apr 22 15:14:17.922879 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 22 15:14:17.922905 systemd-networkd[759]: eth0: DHCPv4 address 10.0.0.74/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 22 15:14:17.924489 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Apr 22 15:14:17.925218 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 22 15:14:17.953404 ignition[769]: Ignition 2.20.0 Apr 22 15:14:17.953414 ignition[769]: Stage: kargs Apr 22 15:14:17.953572 ignition[769]: no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:17.953581 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:17.954127 ignition[769]: kargs: kargs passed Apr 22 15:14:17.954165 ignition[769]: Ignition finished successfully Apr 22 15:14:17.956859 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 22 15:14:17.958670 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 22 15:14:17.983266 ignition[778]: Ignition 2.20.0 Apr 22 15:14:17.983276 ignition[778]: Stage: disks Apr 22 15:14:17.983415 ignition[778]: no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:17.983424 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:17.985106 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 22 15:14:17.983994 ignition[778]: disks: disks passed Apr 22 15:14:17.986936 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 22 15:14:17.984033 ignition[778]: Ignition finished successfully Apr 22 15:14:17.988347 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 22 15:14:17.989797 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 22 15:14:17.991401 systemd[1]: Reached target sysinit.target - System Initialization. Apr 22 15:14:17.992854 systemd[1]: Reached target basic.target - Basic System. Apr 22 15:14:17.995174 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 22 15:14:18.020443 systemd-fsck[789]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 22 15:14:18.023847 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 22 15:14:18.025814 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 22 15:14:18.075454 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 22 15:14:18.076792 kernel: EXT4-fs (vda9): mounted filesystem a7a89271-ee7d-4bda-a834-705261d6cda9 r/w with ordered data mode. Quota mode: none. Apr 22 15:14:18.076709 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 22 15:14:18.079367 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 22 15:14:18.081349 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 22 15:14:18.082352 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 22 15:14:18.082390 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 22 15:14:18.082412 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 22 15:14:18.094938 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 22 15:14:18.096899 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 22 15:14:18.102150 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (797) Apr 22 15:14:18.102186 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Apr 22 15:14:18.102197 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 22 15:14:18.102206 kernel: BTRFS info (device vda6): using free space tree Apr 22 15:14:18.104578 kernel: BTRFS info (device vda6): auto enabling async discard Apr 22 15:14:18.105310 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 22 15:14:18.139913 initrd-setup-root[821]: cut: /sysroot/etc/passwd: No such file or directory Apr 22 15:14:18.144059 initrd-setup-root[828]: cut: /sysroot/etc/group: No such file or directory Apr 22 15:14:18.147721 initrd-setup-root[835]: cut: /sysroot/etc/shadow: No such file or directory Apr 22 15:14:18.151152 initrd-setup-root[842]: cut: /sysroot/etc/gshadow: No such file or directory Apr 22 15:14:18.217197 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 22 15:14:18.219146 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 22 15:14:18.221971 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 22 15:14:18.242570 kernel: BTRFS info (device vda6): last unmount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Apr 22 15:14:18.254706 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 22 15:14:18.263657 ignition[910]: INFO : Ignition 2.20.0 Apr 22 15:14:18.263657 ignition[910]: INFO : Stage: mount Apr 22 15:14:18.265175 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:18.265175 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:18.265175 ignition[910]: INFO : mount: mount passed Apr 22 15:14:18.265175 ignition[910]: INFO : Ignition finished successfully Apr 22 15:14:18.265735 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 22 15:14:18.269314 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 22 15:14:18.879636 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 22 15:14:18.881120 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 22 15:14:18.897568 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (924) Apr 22 15:14:18.899200 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Apr 22 15:14:18.899215 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 22 15:14:18.899226 kernel: BTRFS info (device vda6): using free space tree Apr 22 15:14:18.901557 kernel: BTRFS info (device vda6): auto enabling async discard Apr 22 15:14:18.902567 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 22 15:14:18.927570 ignition[941]: INFO : Ignition 2.20.0 Apr 22 15:14:18.927570 ignition[941]: INFO : Stage: files Apr 22 15:14:18.929195 ignition[941]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:18.929195 ignition[941]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:18.929195 ignition[941]: DEBUG : files: compiled without relabeling support, skipping Apr 22 15:14:18.929195 ignition[941]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 22 15:14:18.929195 ignition[941]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 22 15:14:18.935383 ignition[941]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 22 15:14:18.935383 ignition[941]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 22 15:14:18.935383 ignition[941]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 22 15:14:18.935383 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 22 15:14:18.935383 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(4): [started] processing unit "docker.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(4): [finished] processing unit "docker.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Apr 22 15:14:18.935383 ignition[941]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Apr 22 15:14:18.931423 unknown[941]: wrote ssh authorized keys file for user: core Apr 22 15:14:18.955901 ignition[941]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Apr 22 15:14:18.955901 ignition[941]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Apr 22 15:14:18.955901 ignition[941]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Apr 22 15:14:18.955901 ignition[941]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Apr 22 15:14:18.955901 ignition[941]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Apr 22 15:14:18.955901 ignition[941]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 22 15:14:18.955901 ignition[941]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 22 15:14:18.955901 ignition[941]: INFO : files: files passed Apr 22 15:14:18.955901 ignition[941]: INFO : Ignition finished successfully Apr 22 15:14:18.953372 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 22 15:14:18.955928 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 22 15:14:18.958679 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 22 15:14:18.966241 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 22 15:14:18.974993 initrd-setup-root-after-ignition[970]: grep: /sysroot/oem/oem-release: No such file or directory Apr 22 15:14:18.966317 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 22 15:14:18.977881 initrd-setup-root-after-ignition[972]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 22 15:14:18.977881 initrd-setup-root-after-ignition[972]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 22 15:14:18.971392 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 22 15:14:18.981893 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 22 15:14:18.972674 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 22 15:14:18.974787 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 22 15:14:19.015469 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 22 15:14:19.015580 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 22 15:14:19.017409 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 22 15:14:19.019007 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 22 15:14:19.020498 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 22 15:14:19.021189 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 22 15:14:19.035684 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 22 15:14:19.037652 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 22 15:14:19.055731 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 22 15:14:19.056666 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 22 15:14:19.058424 systemd[1]: Stopped target timers.target - Timer Units. Apr 22 15:14:19.059982 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 22 15:14:19.060090 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 22 15:14:19.062265 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 22 15:14:19.064001 systemd[1]: Stopped target basic.target - Basic System. Apr 22 15:14:19.065379 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 22 15:14:19.066835 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 22 15:14:19.068441 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 22 15:14:19.070167 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 22 15:14:19.071747 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 22 15:14:19.073490 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 22 15:14:19.075267 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 22 15:14:19.076774 systemd[1]: Stopped target swap.target - Swaps. Apr 22 15:14:19.078100 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 22 15:14:19.078218 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 22 15:14:19.080323 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 22 15:14:19.082138 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 22 15:14:19.083895 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 22 15:14:19.084661 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 22 15:14:19.085705 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 22 15:14:19.085823 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 22 15:14:19.088463 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 22 15:14:19.088597 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 22 15:14:19.090282 systemd[1]: Stopped target paths.target - Path Units. Apr 22 15:14:19.091649 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 22 15:14:19.095601 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 22 15:14:19.096566 systemd[1]: Stopped target slices.target - Slice Units. Apr 22 15:14:19.098388 systemd[1]: Stopped target sockets.target - Socket Units. Apr 22 15:14:19.099716 systemd[1]: iscsid.socket: Deactivated successfully. Apr 22 15:14:19.099793 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 22 15:14:19.101107 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 22 15:14:19.101184 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 22 15:14:19.102441 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 22 15:14:19.102559 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 22 15:14:19.104024 systemd[1]: ignition-files.service: Deactivated successfully. Apr 22 15:14:19.104117 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 22 15:14:19.106233 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 22 15:14:19.108511 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 22 15:14:19.109507 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 22 15:14:19.109628 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 22 15:14:19.111195 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 22 15:14:19.111290 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 22 15:14:19.121838 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 22 15:14:19.121949 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 22 15:14:19.129498 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 22 15:14:19.131948 ignition[998]: INFO : Ignition 2.20.0 Apr 22 15:14:19.131948 ignition[998]: INFO : Stage: umount Apr 22 15:14:19.133433 ignition[998]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 22 15:14:19.133433 ignition[998]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 22 15:14:19.133433 ignition[998]: INFO : umount: umount passed Apr 22 15:14:19.133433 ignition[998]: INFO : Ignition finished successfully Apr 22 15:14:19.132467 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 22 15:14:19.132571 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 22 15:14:19.135245 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 22 15:14:19.135334 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 22 15:14:19.137377 systemd[1]: Stopped target network.target - Network. Apr 22 15:14:19.138753 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 22 15:14:19.138809 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 22 15:14:19.140186 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 22 15:14:19.140225 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 22 15:14:19.141611 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 22 15:14:19.141649 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 22 15:14:19.143081 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 22 15:14:19.143117 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 22 15:14:19.144593 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 22 15:14:19.144632 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 22 15:14:19.146116 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 22 15:14:19.147525 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 22 15:14:19.153031 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 22 15:14:19.153138 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 22 15:14:19.156065 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Apr 22 15:14:19.156277 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 22 15:14:19.156359 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 22 15:14:19.159361 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Apr 22 15:14:19.159909 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 22 15:14:19.159958 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 22 15:14:19.162150 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 22 15:14:19.163033 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 22 15:14:19.163081 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 22 15:14:19.164982 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 22 15:14:19.165019 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 22 15:14:19.167369 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 22 15:14:19.167410 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 22 15:14:19.169267 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 22 15:14:19.169302 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 22 15:14:19.171847 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 22 15:14:19.188717 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 22 15:14:19.188859 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 22 15:14:19.190660 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 22 15:14:19.190694 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 22 15:14:19.192055 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 22 15:14:19.192085 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 22 15:14:19.193325 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 22 15:14:19.193362 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 22 15:14:19.195355 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 22 15:14:19.195395 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 22 15:14:19.197289 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 22 15:14:19.197327 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 22 15:14:19.200019 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 22 15:14:19.200798 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 22 15:14:19.200854 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 22 15:14:19.203137 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Apr 22 15:14:19.203174 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 22 15:14:19.204771 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 22 15:14:19.204811 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 22 15:14:19.206276 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 22 15:14:19.206311 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 22 15:14:19.210799 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 22 15:14:19.210921 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 22 15:14:19.215190 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 22 15:14:19.215293 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 22 15:14:19.217009 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 22 15:14:19.219003 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 22 15:14:19.239497 systemd[1]: Switching root. Apr 22 15:14:19.266384 systemd-journald[236]: Journal stopped Apr 22 15:14:19.935470 systemd-journald[236]: Received SIGTERM from PID 1 (systemd). Apr 22 15:14:19.935523 kernel: SELinux: policy capability network_peer_controls=1 Apr 22 15:14:19.935537 kernel: SELinux: policy capability open_perms=1 Apr 22 15:14:19.935563 kernel: SELinux: policy capability extended_socket_class=1 Apr 22 15:14:19.935573 kernel: SELinux: policy capability always_check_network=0 Apr 22 15:14:19.935583 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 22 15:14:19.935592 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 22 15:14:19.935601 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 22 15:14:19.935610 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 22 15:14:19.935624 kernel: audit: type=1403 audit(1745334859.363:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 22 15:14:19.935636 systemd[1]: Successfully loaded SELinux policy in 29.241ms. Apr 22 15:14:19.935657 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.550ms. Apr 22 15:14:19.935668 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) Apr 22 15:14:19.935681 systemd[1]: Detected virtualization kvm. Apr 22 15:14:19.935691 systemd[1]: Detected architecture arm64. Apr 22 15:14:19.935700 systemd[1]: Detected first boot. Apr 22 15:14:19.935730 systemd[1]: Initializing machine ID from VM UUID. Apr 22 15:14:19.935740 zram_generator::config[1046]: No configuration found. Apr 22 15:14:19.935754 kernel: NET: Registered PF_VSOCK protocol family Apr 22 15:14:19.935765 systemd[1]: Populated /etc with preset unit settings. Apr 22 15:14:19.935777 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Apr 22 15:14:19.935787 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 22 15:14:19.935797 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 22 15:14:19.935807 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 22 15:14:19.935817 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 22 15:14:19.935834 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 22 15:14:19.935846 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 22 15:14:19.935858 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 22 15:14:19.935868 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 22 15:14:19.935878 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 22 15:14:19.935889 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 22 15:14:19.935899 systemd[1]: Created slice user.slice - User and Session Slice. Apr 22 15:14:19.935909 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 22 15:14:19.935921 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 22 15:14:19.935933 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 22 15:14:19.935943 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 22 15:14:19.935954 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 22 15:14:19.935964 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 22 15:14:19.935974 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Apr 22 15:14:19.935984 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 22 15:14:19.935994 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 22 15:14:19.936006 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 22 15:14:19.936017 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 22 15:14:19.936027 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 22 15:14:19.936038 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 22 15:14:19.936048 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 22 15:14:19.936058 systemd[1]: Reached target slices.target - Slice Units. Apr 22 15:14:19.936068 systemd[1]: Reached target swap.target - Swaps. Apr 22 15:14:19.936078 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 22 15:14:19.936089 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 22 15:14:19.936100 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Apr 22 15:14:19.936111 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 22 15:14:19.936122 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 22 15:14:19.936132 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 22 15:14:19.936143 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 22 15:14:19.936154 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 22 15:14:19.936165 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 22 15:14:19.936175 systemd[1]: Mounting media.mount - External Media Directory... Apr 22 15:14:19.936186 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 22 15:14:19.936198 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 22 15:14:19.936211 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 22 15:14:19.936222 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). Apr 22 15:14:19.936233 systemd[1]: Reached target machines.target - Containers. Apr 22 15:14:19.936243 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 22 15:14:19.936254 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 22 15:14:19.936264 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 22 15:14:19.936274 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 22 15:14:19.936286 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 22 15:14:19.936297 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 22 15:14:19.936308 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 22 15:14:19.936318 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 22 15:14:19.936328 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 22 15:14:19.936338 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 22 15:14:19.936349 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 22 15:14:19.936359 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 22 15:14:19.936369 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 22 15:14:19.936381 systemd[1]: Stopped systemd-fsck-usr.service. Apr 22 15:14:19.936393 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). Apr 22 15:14:19.936403 kernel: loop: module loaded Apr 22 15:14:19.936413 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 22 15:14:19.936423 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 22 15:14:19.936446 kernel: ACPI: bus type drm_connector registered Apr 22 15:14:19.936455 kernel: fuse: init (API version 7.39) Apr 22 15:14:19.936466 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 22 15:14:19.936478 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 22 15:14:19.936489 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Apr 22 15:14:19.936500 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 22 15:14:19.936510 systemd[1]: verity-setup.service: Deactivated successfully. Apr 22 15:14:19.936520 systemd[1]: Stopped verity-setup.service. Apr 22 15:14:19.936572 systemd-journald[1121]: Collecting audit messages is disabled. Apr 22 15:14:19.936595 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 22 15:14:19.936607 systemd-journald[1121]: Journal started Apr 22 15:14:19.936636 systemd-journald[1121]: Runtime Journal (/run/log/journal/37050bc3ced449f29dfab79478f5a2cc) is 5.9M, max 47.3M, 41.4M free. Apr 22 15:14:19.743884 systemd[1]: Queued start job for default target multi-user.target. Apr 22 15:14:19.754343 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 22 15:14:19.754716 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 22 15:14:19.938383 systemd[1]: Started systemd-journald.service - Journal Service. Apr 22 15:14:19.939743 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 22 15:14:19.941004 systemd[1]: Mounted media.mount - External Media Directory. Apr 22 15:14:19.942092 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 22 15:14:19.943311 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 22 15:14:19.944582 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 22 15:14:19.946623 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 22 15:14:19.947959 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 22 15:14:19.949092 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 22 15:14:19.949255 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 22 15:14:19.950389 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 22 15:14:19.950573 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 22 15:14:19.951641 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 22 15:14:19.951804 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 22 15:14:19.952813 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 22 15:14:19.952986 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 22 15:14:19.954101 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 22 15:14:19.954249 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 22 15:14:19.955311 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 22 15:14:19.955477 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 22 15:14:19.956756 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 22 15:14:19.958020 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 22 15:14:19.959295 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 22 15:14:19.960473 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Apr 22 15:14:19.971947 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 22 15:14:19.974175 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 22 15:14:19.975990 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 22 15:14:19.976871 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 22 15:14:19.976919 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 22 15:14:19.978564 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Apr 22 15:14:19.987086 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 22 15:14:19.989232 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 22 15:14:19.990325 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 22 15:14:19.991290 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 22 15:14:19.993287 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 22 15:14:19.994582 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 22 15:14:19.995479 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 22 15:14:19.996682 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 22 15:14:20.000642 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 22 15:14:20.002381 systemd-journald[1121]: Time spent on flushing to /var/log/journal/37050bc3ced449f29dfab79478f5a2cc is 13.873ms for 832 entries. Apr 22 15:14:20.002381 systemd-journald[1121]: System Journal (/var/log/journal/37050bc3ced449f29dfab79478f5a2cc) is 8M, max 195.6M, 187.6M free. Apr 22 15:14:20.030645 systemd-journald[1121]: Received client request to flush runtime journal. Apr 22 15:14:20.030709 kernel: loop0: detected capacity change from 0 to 126448 Apr 22 15:14:20.002750 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 22 15:14:20.005741 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 22 15:14:20.011466 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 22 15:14:20.014707 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 22 15:14:20.016132 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 22 15:14:20.017680 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 22 15:14:20.019348 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 22 15:14:20.024177 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 22 15:14:20.028183 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Apr 22 15:14:20.038802 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 22 15:14:20.042638 systemd-tmpfiles[1164]: ACLs are not supported, ignoring. Apr 22 15:14:20.043371 systemd-tmpfiles[1164]: ACLs are not supported, ignoring. Apr 22 15:14:20.046300 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 22 15:14:20.048044 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 22 15:14:20.049586 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 22 15:14:20.050947 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 22 15:14:20.055452 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 22 15:14:20.060715 udevadm[1178]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 22 15:14:20.069626 kernel: loop1: detected capacity change from 0 to 103832 Apr 22 15:14:20.073977 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Apr 22 15:14:20.089160 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 22 15:14:20.091904 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 22 15:14:20.097589 kernel: loop2: detected capacity change from 0 to 126448 Apr 22 15:14:20.106567 kernel: loop3: detected capacity change from 0 to 103832 Apr 22 15:14:20.110400 (sd-merge)[1188]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Apr 22 15:14:20.111061 (sd-merge)[1188]: Merged extensions into '/usr'. Apr 22 15:14:20.113438 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 22 15:14:20.114632 systemd-tmpfiles[1187]: ACLs are not supported, ignoring. Apr 22 15:14:20.114642 systemd-tmpfiles[1187]: ACLs are not supported, ignoring. Apr 22 15:14:20.116238 systemd[1]: Starting ensure-sysext.service... Apr 22 15:14:20.119777 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 22 15:14:20.133034 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 22 15:14:20.145362 systemd[1]: Reload requested from client PID 1191 ('systemctl') (unit ensure-sysext.service)... Apr 22 15:14:20.145377 systemd[1]: Reloading... Apr 22 15:14:20.150883 systemd-tmpfiles[1192]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 22 15:14:20.151324 systemd-tmpfiles[1192]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 22 15:14:20.152011 systemd-tmpfiles[1192]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 22 15:14:20.152280 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Apr 22 15:14:20.152384 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Apr 22 15:14:20.155209 systemd-tmpfiles[1192]: Detected autofs mount point /boot during canonicalization of boot. Apr 22 15:14:20.155307 systemd-tmpfiles[1192]: Skipping /boot Apr 22 15:14:20.164102 systemd-tmpfiles[1192]: Detected autofs mount point /boot during canonicalization of boot. Apr 22 15:14:20.164187 systemd-tmpfiles[1192]: Skipping /boot Apr 22 15:14:20.206579 zram_generator::config[1229]: No configuration found. Apr 22 15:14:20.285373 ldconfig[1158]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 22 15:14:20.297681 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. Apr 22 15:14:20.347625 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 22 15:14:20.347771 systemd[1]: Reloading finished in 202 ms. Apr 22 15:14:20.366084 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 22 15:14:20.386617 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 22 15:14:20.394331 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 22 15:14:20.396640 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 22 15:14:20.402370 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 22 15:14:20.405353 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 22 15:14:20.407808 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 22 15:14:20.413661 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 22 15:14:20.414706 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 22 15:14:20.416741 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 22 15:14:20.418926 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 22 15:14:20.419981 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 22 15:14:20.420095 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). Apr 22 15:14:20.420982 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 22 15:14:20.421150 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 22 15:14:20.422741 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 22 15:14:20.422892 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 22 15:14:20.424659 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 22 15:14:20.424799 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 22 15:14:20.430307 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 22 15:14:20.431507 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 22 15:14:20.433467 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 22 15:14:20.436717 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 22 15:14:20.437935 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 22 15:14:20.438042 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). Apr 22 15:14:20.439919 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 22 15:14:20.441948 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 22 15:14:20.442179 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 22 15:14:20.446918 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 22 15:14:20.447107 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 22 15:14:20.455853 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 22 15:14:20.458054 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 22 15:14:20.458224 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 22 15:14:20.463116 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 22 15:14:20.464911 systemd[1]: Finished ensure-sysext.service. Apr 22 15:14:20.469948 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 22 15:14:20.471723 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 22 15:14:20.476478 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 22 15:14:20.477646 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 22 15:14:20.477694 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). Apr 22 15:14:20.477735 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 22 15:14:20.486878 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 22 15:14:20.488535 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 22 15:14:20.490018 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 22 15:14:20.491526 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 22 15:14:20.493121 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 22 15:14:20.496843 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 22 15:14:20.496988 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 22 15:14:20.503090 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 22 15:14:20.504489 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 22 15:14:20.509394 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 22 15:14:20.510418 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). Apr 22 15:14:20.511497 augenrules[1312]: No rules Apr 22 15:14:20.518809 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 22 15:14:20.520119 systemd[1]: audit-rules.service: Deactivated successfully. Apr 22 15:14:20.520343 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 22 15:14:20.529535 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 22 15:14:20.544890 systemd-udevd[1311]: Using default interface naming scheme 'v255'. Apr 22 15:14:20.561597 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 22 15:14:20.565800 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 22 15:14:20.567370 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 22 15:14:20.571009 systemd[1]: Reached target time-set.target - System Time Set. Apr 22 15:14:20.580818 systemd-resolved[1264]: Positive Trust Anchors: Apr 22 15:14:20.580971 systemd-resolved[1264]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 22 15:14:20.581003 systemd-resolved[1264]: 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 Apr 22 15:14:20.589174 systemd-resolved[1264]: Defaulting to hostname 'linux'. Apr 22 15:14:20.590628 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 22 15:14:20.591860 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Apr 22 15:14:20.592051 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 22 15:14:20.634581 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1338) Apr 22 15:14:20.655712 systemd-networkd[1331]: lo: Link UP Apr 22 15:14:20.655724 systemd-networkd[1331]: lo: Gained carrier Apr 22 15:14:20.657423 systemd-networkd[1331]: Enumeration completed Apr 22 15:14:20.657865 systemd-networkd[1331]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 22 15:14:20.657872 systemd-networkd[1331]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 22 15:14:20.658320 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 22 15:14:20.658326 systemd-networkd[1331]: eth0: Link UP Apr 22 15:14:20.658331 systemd-networkd[1331]: eth0: Gained carrier Apr 22 15:14:20.658344 systemd-networkd[1331]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 22 15:14:20.660709 systemd[1]: Reached target network.target - Network. Apr 22 15:14:20.662758 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Apr 22 15:14:20.665753 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 22 15:14:20.669789 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 22 15:14:20.672646 systemd-networkd[1331]: eth0: DHCPv4 address 10.0.0.74/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 22 15:14:20.673363 systemd-timesyncd[1299]: Network configuration changed, trying to establish connection. Apr 22 15:14:20.243618 systemd-resolved[1264]: Clock change detected. Flushing caches. Apr 22 15:14:20.252305 systemd-journald[1121]: Time jumped backwards, rotating. Apr 22 15:14:20.243730 systemd-timesyncd[1299]: Contacted time server 10.0.0.1:123 (10.0.0.1). Apr 22 15:14:20.243779 systemd-timesyncd[1299]: Initial clock synchronization to Tue 2025-04-22 15:14:20.243580 UTC. Apr 22 15:14:20.246670 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 22 15:14:20.259044 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Apr 22 15:14:20.267705 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 22 15:14:20.280467 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 22 15:14:20.293561 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 22 15:14:20.295940 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 22 15:14:20.311788 lvm[1363]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 22 15:14:20.332969 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 22 15:14:20.356609 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 22 15:14:20.357729 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 22 15:14:20.358572 systemd[1]: Reached target sysinit.target - System Initialization. Apr 22 15:14:20.359384 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 22 15:14:20.360270 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 22 15:14:20.361327 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 22 15:14:20.362192 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 22 15:14:20.363075 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 22 15:14:20.363977 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 22 15:14:20.364007 systemd[1]: Reached target paths.target - Path Units. Apr 22 15:14:20.364665 systemd[1]: Reached target timers.target - Timer Units. Apr 22 15:14:20.365939 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 22 15:14:20.367960 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 22 15:14:20.370790 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Apr 22 15:14:20.371918 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Apr 22 15:14:20.372893 systemd[1]: Reached target ssh-access.target - SSH Access Available. Apr 22 15:14:20.375691 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 22 15:14:20.377008 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Apr 22 15:14:20.378879 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 22 15:14:20.380162 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 22 15:14:20.381071 systemd[1]: Reached target sockets.target - Socket Units. Apr 22 15:14:20.381825 systemd[1]: Reached target basic.target - Basic System. Apr 22 15:14:20.382522 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 22 15:14:20.382548 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 22 15:14:20.383347 systemd[1]: Starting containerd.service - containerd container runtime... Apr 22 15:14:20.385001 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 22 15:14:20.386016 lvm[1374]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 22 15:14:20.387623 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 22 15:14:20.389619 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 22 15:14:20.390524 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). Apr 22 15:14:20.394591 jq[1377]: false Apr 22 15:14:20.394970 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 22 15:14:20.396899 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 22 15:14:20.398926 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 22 15:14:20.403620 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 22 15:14:20.405384 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 22 15:14:20.405815 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 22 15:14:20.406610 systemd[1]: Starting update-engine.service - Update Engine... Apr 22 15:14:20.408378 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 22 15:14:20.410015 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 22 15:14:20.418622 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 22 15:14:20.418804 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 22 15:14:20.419051 systemd[1]: motdgen.service: Deactivated successfully. Apr 22 15:14:20.419208 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 22 15:14:20.420254 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 22 15:14:20.420417 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 22 15:14:20.427919 dbus-daemon[1376]: [system] SELinux support is enabled Apr 22 15:14:20.428152 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 22 15:14:20.431022 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). Apr 22 15:14:20.431060 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 22 15:14:20.431878 jq[1392]: true Apr 22 15:14:20.432119 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). Apr 22 15:14:20.432150 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 22 15:14:20.443651 extend-filesystems[1378]: Found loop2 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found loop3 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda1 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda2 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda3 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found usr Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda4 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda6 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda7 Apr 22 15:14:20.449274 extend-filesystems[1378]: Found vda9 Apr 22 15:14:20.449274 extend-filesystems[1378]: Checking size of /dev/vda9 Apr 22 15:14:20.458947 update_engine[1387]: I20250422 15:14:20.454900 1387 main.cc:92] Flatcar Update Engine starting Apr 22 15:14:20.450716 (ntainerd)[1401]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 22 15:14:20.463761 jq[1402]: true Apr 22 15:14:20.464274 extend-filesystems[1378]: Old size kept for /dev/vda9 Apr 22 15:14:20.470635 update_engine[1387]: I20250422 15:14:20.470538 1387 update_check_scheduler.cc:74] Next update check in 3m20s Apr 22 15:14:20.466743 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 22 15:14:20.466933 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 22 15:14:20.469708 systemd[1]: Started update-engine.service - Update Engine. Apr 22 15:14:20.475834 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 22 15:14:20.477394 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1335) Apr 22 15:14:20.491173 systemd-logind[1384]: Watching system buttons on /dev/input/event0 (Power Button) Apr 22 15:14:20.493845 systemd-logind[1384]: New seat seat0. Apr 22 15:14:20.494894 systemd[1]: Started systemd-logind.service - User Login Management. Apr 22 15:14:20.540254 locksmithd[1411]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 22 15:14:20.541041 bash[1426]: Updated "/home/core/.ssh/authorized_keys" Apr 22 15:14:20.543302 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 22 15:14:20.544976 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Apr 22 15:14:20.669008 containerd[1401]: time="2025-04-22T15:14:20Z" 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 Apr 22 15:14:20.670246 containerd[1401]: time="2025-04-22T15:14:20.670101676Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Apr 22 15:14:20.680146 containerd[1401]: time="2025-04-22T15:14:20.680104476Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.56µs" Apr 22 15:14:20.680241 containerd[1401]: time="2025-04-22T15:14:20.680212316Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Apr 22 15:14:20.680293 containerd[1401]: time="2025-04-22T15:14:20.680280596Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Apr 22 15:14:20.680496 containerd[1401]: time="2025-04-22T15:14:20.680474596Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Apr 22 15:14:20.680584 containerd[1401]: time="2025-04-22T15:14:20.680568276Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Apr 22 15:14:20.680645 containerd[1401]: time="2025-04-22T15:14:20.680632916Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.680731356Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.680749916Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.681030196Z" 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 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.681045636Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.681056676Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.681065076Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681257 containerd[1401]: time="2025-04-22T15:14:20.681128316Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681563 containerd[1401]: time="2025-04-22T15:14:20.681532076Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Apr 22 15:14:20.681645 containerd[1401]: time="2025-04-22T15:14:20.681629516Z" 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 Apr 22 15:14:20.681688 containerd[1401]: time="2025-04-22T15:14:20.681676396Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Apr 22 15:14:20.681777 containerd[1401]: time="2025-04-22T15:14:20.681761756Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Apr 22 15:14:20.682065 containerd[1401]: time="2025-04-22T15:14:20.682046036Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Apr 22 15:14:20.682179 containerd[1401]: time="2025-04-22T15:14:20.682162276Z" level=info msg="metadata content store policy set" policy=shared Apr 22 15:14:20.685754 containerd[1401]: time="2025-04-22T15:14:20.685725476Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Apr 22 15:14:20.685861 containerd[1401]: time="2025-04-22T15:14:20.685845316Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Apr 22 15:14:20.685935 containerd[1401]: time="2025-04-22T15:14:20.685905876Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Apr 22 15:14:20.686014 containerd[1401]: time="2025-04-22T15:14:20.685999436Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Apr 22 15:14:20.686072 containerd[1401]: time="2025-04-22T15:14:20.686059516Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Apr 22 15:14:20.686125 containerd[1401]: time="2025-04-22T15:14:20.686111276Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Apr 22 15:14:20.686173 containerd[1401]: time="2025-04-22T15:14:20.686161436Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Apr 22 15:14:20.686237 containerd[1401]: time="2025-04-22T15:14:20.686208396Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Apr 22 15:14:20.686300 containerd[1401]: time="2025-04-22T15:14:20.686285716Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Apr 22 15:14:20.686360 containerd[1401]: time="2025-04-22T15:14:20.686346236Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Apr 22 15:14:20.686409 containerd[1401]: time="2025-04-22T15:14:20.686397556Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Apr 22 15:14:20.686459 containerd[1401]: time="2025-04-22T15:14:20.686446796Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Apr 22 15:14:20.686623 containerd[1401]: time="2025-04-22T15:14:20.686602516Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Apr 22 15:14:20.686689 containerd[1401]: time="2025-04-22T15:14:20.686675956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Apr 22 15:14:20.686738 containerd[1401]: time="2025-04-22T15:14:20.686726676Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Apr 22 15:14:20.686800 containerd[1401]: time="2025-04-22T15:14:20.686785796Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Apr 22 15:14:20.686849 containerd[1401]: time="2025-04-22T15:14:20.686836756Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Apr 22 15:14:20.686898 containerd[1401]: time="2025-04-22T15:14:20.686885756Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Apr 22 15:14:20.686958 containerd[1401]: time="2025-04-22T15:14:20.686944676Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Apr 22 15:14:20.687011 containerd[1401]: time="2025-04-22T15:14:20.686997596Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Apr 22 15:14:20.687071 containerd[1401]: time="2025-04-22T15:14:20.687056956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Apr 22 15:14:20.687123 containerd[1401]: time="2025-04-22T15:14:20.687109316Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Apr 22 15:14:20.687172 containerd[1401]: time="2025-04-22T15:14:20.687159396Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Apr 22 15:14:20.687482 containerd[1401]: time="2025-04-22T15:14:20.687464796Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Apr 22 15:14:20.687550 containerd[1401]: time="2025-04-22T15:14:20.687536436Z" level=info msg="Start snapshots syncer" Apr 22 15:14:20.687624 containerd[1401]: time="2025-04-22T15:14:20.687609996Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Apr 22 15:14:20.687972 containerd[1401]: time="2025-04-22T15:14:20.687933156Z" 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\"}" Apr 22 15:14:20.688122 containerd[1401]: time="2025-04-22T15:14:20.688105956Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Apr 22 15:14:20.688281 containerd[1401]: time="2025-04-22T15:14:20.688263236Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Apr 22 15:14:20.688464 containerd[1401]: time="2025-04-22T15:14:20.688444236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Apr 22 15:14:20.688551 containerd[1401]: time="2025-04-22T15:14:20.688535316Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Apr 22 15:14:20.688619 containerd[1401]: time="2025-04-22T15:14:20.688604876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688655076Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688676836Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688689956Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688700476Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688726876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688739476Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Apr 22 15:14:20.688785 containerd[1401]: time="2025-04-22T15:14:20.688752956Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Apr 22 15:14:20.688944 containerd[1401]: time="2025-04-22T15:14:20.688926916Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Apr 22 15:14:20.689061 containerd[1401]: time="2025-04-22T15:14:20.689043516Z" 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 Apr 22 15:14:20.689111 containerd[1401]: time="2025-04-22T15:14:20.689099476Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:14:20.689158 containerd[1401]: time="2025-04-22T15:14:20.689145436Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:14:20.689201 containerd[1401]: time="2025-04-22T15:14:20.689189196Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Apr 22 15:14:20.689286 containerd[1401]: time="2025-04-22T15:14:20.689271636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689325716Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689407196Z" level=info msg="runtime interface created" Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689412796Z" level=info msg="created NRI interface" Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689420996Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689431916Z" level=info msg="Connect containerd service" Apr 22 15:14:20.689492 containerd[1401]: time="2025-04-22T15:14:20.689462556Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 22 15:14:20.690540 containerd[1401]: time="2025-04-22T15:14:20.690507436Z" 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" Apr 22 15:14:20.788158 containerd[1401]: time="2025-04-22T15:14:20.788006476Z" level=info msg="Start subscribing containerd event" Apr 22 15:14:20.788158 containerd[1401]: time="2025-04-22T15:14:20.788062796Z" level=info msg="Start recovering state" Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788186076Z" level=info msg="Start event monitor" Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788206916Z" level=info msg="Start cni network conf syncer for default" Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788241996Z" level=info msg="Start streaming server" Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788252716Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788261716Z" level=info msg="runtime interface starting up..." Apr 22 15:14:20.788268 containerd[1401]: time="2025-04-22T15:14:20.788267676Z" level=info msg="starting plugins..." Apr 22 15:14:20.788377 containerd[1401]: time="2025-04-22T15:14:20.788283196Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Apr 22 15:14:20.788705 containerd[1401]: time="2025-04-22T15:14:20.788578236Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 22 15:14:20.788705 containerd[1401]: time="2025-04-22T15:14:20.788630796Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 22 15:14:20.788705 containerd[1401]: time="2025-04-22T15:14:20.788683716Z" level=info msg="containerd successfully booted in 0.120006s" Apr 22 15:14:20.788783 systemd[1]: Started containerd.service - containerd container runtime. Apr 22 15:14:21.037470 sshd_keygen[1393]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 22 15:14:21.055102 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 22 15:14:21.058453 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 22 15:14:21.072334 systemd[1]: issuegen.service: Deactivated successfully. Apr 22 15:14:21.072558 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 22 15:14:21.075316 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 22 15:14:21.094188 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 22 15:14:21.096458 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 22 15:14:21.098168 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Apr 22 15:14:21.099248 systemd[1]: Reached target getty.target - Login Prompts. Apr 22 15:14:21.333346 systemd-networkd[1331]: eth0: Gained IPv6LL Apr 22 15:14:21.335745 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 22 15:14:21.337303 systemd[1]: Reached target network-online.target - Network is Online. Apr 22 15:14:21.339510 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Apr 22 15:14:21.341418 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 22 15:14:21.367662 systemd[1]: coreos-metadata.service: Deactivated successfully. Apr 22 15:14:21.367925 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Apr 22 15:14:21.369773 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 22 15:14:21.370958 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 22 15:14:21.372301 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 22 15:14:21.377328 systemd[1]: Startup finished in 519ms (kernel) + 3.673s (initrd) + 2.474s (userspace) = 6.667s. Apr 22 15:14:28.122745 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 22 15:14:28.123918 systemd[1]: Started sshd@0-10.0.0.74:22-10.0.0.1:32834.service - OpenSSH per-connection server daemon (10.0.0.1:32834). Apr 22 15:14:28.197349 sshd[1491]: Accepted publickey for core from 10.0.0.1 port 32834 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:28.199078 sshd-session[1491]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:28.204535 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 22 15:14:28.205387 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 22 15:14:28.210270 systemd-logind[1384]: New session 1 of user core. Apr 22 15:14:28.229243 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 22 15:14:28.231595 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 22 15:14:28.249067 (systemd)[1495]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 22 15:14:28.251340 systemd-logind[1384]: New session c1 of user core. Apr 22 15:14:28.343606 systemd[1495]: Queued start job for default target default.target. Apr 22 15:14:28.361150 systemd[1495]: Created slice app.slice - User Application Slice. Apr 22 15:14:28.361179 systemd[1495]: Reached target paths.target - Paths. Apr 22 15:14:28.361230 systemd[1495]: Reached target timers.target - Timers. Apr 22 15:14:28.362360 systemd[1495]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 22 15:14:28.370436 systemd[1495]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 22 15:14:28.370496 systemd[1495]: Reached target sockets.target - Sockets. Apr 22 15:14:28.370542 systemd[1495]: Reached target basic.target - Basic System. Apr 22 15:14:28.370572 systemd[1495]: Reached target default.target - Main User Target. Apr 22 15:14:28.370596 systemd[1495]: Startup finished in 112ms. Apr 22 15:14:28.370716 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 22 15:14:28.372012 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 22 15:14:28.434638 systemd[1]: Started sshd@1-10.0.0.74:22-10.0.0.1:32844.service - OpenSSH per-connection server daemon (10.0.0.1:32844). Apr 22 15:14:28.488235 sshd[1506]: Accepted publickey for core from 10.0.0.1 port 32844 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:28.489349 sshd-session[1506]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:28.493512 systemd-logind[1384]: New session 2 of user core. Apr 22 15:14:28.508353 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 22 15:14:28.558523 sshd[1508]: Connection closed by 10.0.0.1 port 32844 Apr 22 15:14:28.558935 sshd-session[1506]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:28.568046 systemd[1]: sshd@1-10.0.0.74:22-10.0.0.1:32844.service: Deactivated successfully. Apr 22 15:14:28.569439 systemd[1]: session-2.scope: Deactivated successfully. Apr 22 15:14:28.572208 systemd-logind[1384]: Session 2 logged out. Waiting for processes to exit. Apr 22 15:14:28.573250 systemd[1]: Started sshd@2-10.0.0.74:22-10.0.0.1:32856.service - OpenSSH per-connection server daemon (10.0.0.1:32856). Apr 22 15:14:28.573936 systemd-logind[1384]: Removed session 2. Apr 22 15:14:28.627125 sshd[1513]: Accepted publickey for core from 10.0.0.1 port 32856 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:28.628196 sshd-session[1513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:28.631748 systemd-logind[1384]: New session 3 of user core. Apr 22 15:14:28.646348 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 22 15:14:28.692828 sshd[1516]: Connection closed by 10.0.0.1 port 32856 Apr 22 15:14:28.693155 sshd-session[1513]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:28.710099 systemd[1]: sshd@2-10.0.0.74:22-10.0.0.1:32856.service: Deactivated successfully. Apr 22 15:14:28.711383 systemd[1]: session-3.scope: Deactivated successfully. Apr 22 15:14:28.711987 systemd-logind[1384]: Session 3 logged out. Waiting for processes to exit. Apr 22 15:14:28.713555 systemd[1]: Started sshd@3-10.0.0.74:22-10.0.0.1:32864.service - OpenSSH per-connection server daemon (10.0.0.1:32864). Apr 22 15:14:28.714161 systemd-logind[1384]: Removed session 3. Apr 22 15:14:28.764362 sshd[1521]: Accepted publickey for core from 10.0.0.1 port 32864 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:28.765415 sshd-session[1521]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:28.768855 systemd-logind[1384]: New session 4 of user core. Apr 22 15:14:28.780365 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 22 15:14:28.830255 sshd[1524]: Connection closed by 10.0.0.1 port 32864 Apr 22 15:14:28.830518 sshd-session[1521]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:28.841173 systemd[1]: sshd@3-10.0.0.74:22-10.0.0.1:32864.service: Deactivated successfully. Apr 22 15:14:28.842491 systemd[1]: session-4.scope: Deactivated successfully. Apr 22 15:14:28.843100 systemd-logind[1384]: Session 4 logged out. Waiting for processes to exit. Apr 22 15:14:28.844660 systemd[1]: Started sshd@4-10.0.0.74:22-10.0.0.1:32876.service - OpenSSH per-connection server daemon (10.0.0.1:32876). Apr 22 15:14:28.845387 systemd-logind[1384]: Removed session 4. Apr 22 15:14:28.897321 sshd[1529]: Accepted publickey for core from 10.0.0.1 port 32876 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:28.898403 sshd-session[1529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:28.902284 systemd-logind[1384]: New session 5 of user core. Apr 22 15:14:28.911361 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 22 15:14:28.969236 sudo[1533]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Apr 22 15:14:28.969512 sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 22 15:14:28.992002 sudo[1533]: pam_unix(sudo:session): session closed for user root Apr 22 15:14:28.993228 sshd[1532]: Connection closed by 10.0.0.1 port 32876 Apr 22 15:14:28.993691 sshd-session[1529]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:29.003291 systemd[1]: sshd@4-10.0.0.74:22-10.0.0.1:32876.service: Deactivated successfully. Apr 22 15:14:29.004611 systemd[1]: session-5.scope: Deactivated successfully. Apr 22 15:14:29.006060 systemd-logind[1384]: Session 5 logged out. Waiting for processes to exit. Apr 22 15:14:29.006879 systemd[1]: Started sshd@5-10.0.0.74:22-10.0.0.1:32890.service - OpenSSH per-connection server daemon (10.0.0.1:32890). Apr 22 15:14:29.007467 systemd-logind[1384]: Removed session 5. Apr 22 15:14:29.058677 sshd[1538]: Accepted publickey for core from 10.0.0.1 port 32890 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:29.059758 sshd-session[1538]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:29.063107 systemd-logind[1384]: New session 6 of user core. Apr 22 15:14:29.071338 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 22 15:14:29.120647 sudo[1543]: 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 Apr 22 15:14:29.120906 sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 22 15:14:29.123625 sudo[1543]: pam_unix(sudo:session): session closed for user root Apr 22 15:14:29.127850 sudo[1542]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Apr 22 15:14:29.128352 sudo[1542]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 22 15:14:29.136483 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 22 15:14:29.169606 augenrules[1565]: No rules Apr 22 15:14:29.170733 systemd[1]: audit-rules.service: Deactivated successfully. Apr 22 15:14:29.170967 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 22 15:14:29.171806 sudo[1542]: pam_unix(sudo:session): session closed for user root Apr 22 15:14:29.173289 sshd[1541]: Connection closed by 10.0.0.1 port 32890 Apr 22 15:14:29.173268 sshd-session[1538]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:29.184062 systemd[1]: sshd@5-10.0.0.74:22-10.0.0.1:32890.service: Deactivated successfully. Apr 22 15:14:29.185360 systemd[1]: session-6.scope: Deactivated successfully. Apr 22 15:14:29.186490 systemd-logind[1384]: Session 6 logged out. Waiting for processes to exit. Apr 22 15:14:29.187554 systemd[1]: Started sshd@6-10.0.0.74:22-10.0.0.1:32898.service - OpenSSH per-connection server daemon (10.0.0.1:32898). Apr 22 15:14:29.189565 systemd-logind[1384]: Removed session 6. Apr 22 15:14:29.241415 sshd[1573]: Accepted publickey for core from 10.0.0.1 port 32898 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:29.242380 sshd-session[1573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:29.246271 systemd-logind[1384]: New session 7 of user core. Apr 22 15:14:29.256342 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 22 15:14:29.311716 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 22 15:14:29.322566 (dockerd)[1578]: 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 Apr 22 15:14:29.558776 dockerd[1578]: time="2025-04-22T15:14:29.558654356Z" level=info msg="Starting up" Apr 22 15:14:29.560534 dockerd[1578]: time="2025-04-22T15:14:29.560485196Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Apr 22 15:14:29.605429 dockerd[1578]: time="2025-04-22T15:14:29.605385716Z" level=info msg="Loading containers: start." Apr 22 15:14:29.737272 kernel: Initializing XFRM netlink socket Apr 22 15:14:29.792145 systemd-networkd[1331]: docker0: Link UP Apr 22 15:14:29.851448 dockerd[1578]: time="2025-04-22T15:14:29.851351636Z" level=info msg="Loading containers: done." Apr 22 15:14:29.863533 dockerd[1578]: time="2025-04-22T15:14:29.863480196Z" 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 Apr 22 15:14:29.863647 dockerd[1578]: time="2025-04-22T15:14:29.863562596Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Apr 22 15:14:29.863749 dockerd[1578]: time="2025-04-22T15:14:29.863722716Z" level=info msg="Daemon has completed initialization" Apr 22 15:14:29.890133 dockerd[1578]: time="2025-04-22T15:14:29.890067676Z" level=info msg="API listen on /run/docker.sock" Apr 22 15:14:29.890252 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 22 15:14:29.898830 sshd[1576]: Connection closed by 10.0.0.1 port 32898 Apr 22 15:14:29.899304 sshd-session[1573]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:29.910092 systemd[1]: sshd@6-10.0.0.74:22-10.0.0.1:32898.service: Deactivated successfully. Apr 22 15:14:29.911394 systemd[1]: session-7.scope: Deactivated successfully. Apr 22 15:14:29.913381 systemd-logind[1384]: Session 7 logged out. Waiting for processes to exit. Apr 22 15:14:29.914111 systemd[1]: Started sshd@7-10.0.0.74:22-10.0.0.1:32900.service - OpenSSH per-connection server daemon (10.0.0.1:32900). Apr 22 15:14:29.915189 systemd-logind[1384]: Removed session 7. Apr 22 15:14:29.967812 sshd[1779]: Accepted publickey for core from 10.0.0.1 port 32900 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:29.968897 sshd-session[1779]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:29.973875 systemd-logind[1384]: New session 8 of user core. Apr 22 15:14:29.982362 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 22 15:14:31.194083 systemd[1]: var-lib-docker-overlay2-4a458a7be3da526b1d8448aabac5b08da6e86115cdc9ba09a146d8399c9b32bc\x2dinit-merged.mount: Deactivated successfully. Apr 22 15:14:31.223147 kernel: docker0: port 1(veth93731d1) entered blocking state Apr 22 15:14:31.223308 kernel: docker0: port 1(veth93731d1) entered disabled state Apr 22 15:14:31.223330 kernel: veth93731d1: entered allmulticast mode Apr 22 15:14:31.223346 kernel: veth93731d1: entered promiscuous mode Apr 22 15:14:31.224179 systemd-networkd[1331]: veth93731d1: Link UP Apr 22 15:14:31.255225 containerd[1401]: time="2025-04-22T15:14:31.255121036Z" level=info msg="connecting to shim 00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5" address="unix:///run/containerd/s/cbb0bee56aa9a1772c581663996e634da84a2eddf89fbba2b5313b0966cd384b" namespace=moby protocol=ttrpc version=3 Apr 22 15:14:31.277404 systemd[1]: Started docker-00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5.scope - libcontainer container 00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5. Apr 22 15:14:31.323252 kernel: eth0: renamed from vethc453569 Apr 22 15:14:31.332702 kernel: docker0: port 1(veth93731d1) entered blocking state Apr 22 15:14:31.332737 kernel: docker0: port 1(veth93731d1) entered forwarding state Apr 22 15:14:31.332776 systemd-networkd[1331]: veth93731d1: Gained carrier Apr 22 15:14:31.332989 systemd-networkd[1331]: docker0: Gained carrier Apr 22 15:14:31.335716 systemd-resolved[1264]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 22 15:14:31.360398 sshd[1782]: Connection closed by 10.0.0.1 port 32900 Apr 22 15:14:31.360820 sshd-session[1779]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:31.377165 systemd[1]: sshd@7-10.0.0.74:22-10.0.0.1:32900.service: Deactivated successfully. Apr 22 15:14:31.378517 systemd[1]: session-8.scope: Deactivated successfully. Apr 22 15:14:31.379119 systemd-logind[1384]: Session 8 logged out. Waiting for processes to exit. Apr 22 15:14:31.380666 systemd[1]: Started sshd@8-10.0.0.74:22-10.0.0.1:32902.service - OpenSSH per-connection server daemon (10.0.0.1:32902). Apr 22 15:14:31.381406 systemd-logind[1384]: Removed session 8. Apr 22 15:14:31.429666 sshd[1869]: Accepted publickey for core from 10.0.0.1 port 32902 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:31.430720 sshd-session[1869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:31.434825 systemd-logind[1384]: New session 9 of user core. Apr 22 15:14:31.448345 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 22 15:14:31.503655 sshd[1872]: Connection closed by 10.0.0.1 port 32902 Apr 22 15:14:31.504048 sshd-session[1869]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:31.514126 systemd[1]: sshd@8-10.0.0.74:22-10.0.0.1:32902.service: Deactivated successfully. Apr 22 15:14:31.515408 systemd[1]: session-9.scope: Deactivated successfully. Apr 22 15:14:31.517395 systemd-logind[1384]: Session 9 logged out. Waiting for processes to exit. Apr 22 15:14:31.518832 systemd[1]: Started sshd@9-10.0.0.74:22-10.0.0.1:32912.service - OpenSSH per-connection server daemon (10.0.0.1:32912). Apr 22 15:14:31.519660 systemd-logind[1384]: Removed session 9. Apr 22 15:14:31.565317 sshd[1877]: Accepted publickey for core from 10.0.0.1 port 32912 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:31.566435 sshd-session[1877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:31.570386 systemd-logind[1384]: New session 10 of user core. Apr 22 15:14:31.581368 systemd[1]: Started session-10.scope - Session 10 of User core. Apr 22 15:14:31.630826 sudo[1881]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1401 Apr 22 15:14:31.631097 sudo[1881]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 22 15:14:31.633913 sudo[1881]: pam_unix(sudo:session): session closed for user root Apr 22 15:14:31.634802 containerd[1401]: time="2025-04-22T15:14:31.634075196Z" level=info msg="Stop CRI service" Apr 22 15:14:31.634802 containerd[1401]: time="2025-04-22T15:14:31.634678436Z" level=info msg="Stop CRI service" Apr 22 15:14:31.634802 containerd[1401]: time="2025-04-22T15:14:31.634728156Z" level=info msg="Event monitor stopped" Apr 22 15:14:31.634802 containerd[1401]: time="2025-04-22T15:14:31.634745316Z" level=info msg="Stream server stopped" Apr 22 15:14:31.634905 dockerd[1578]: time="2025-04-22T15:14:31.634165756Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Apr 22 15:14:31.634905 dockerd[1578]: time="2025-04-22T15:14:31.634200836Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Apr 22 15:14:31.634905 dockerd[1578]: time="2025-04-22T15:14:31.634531916Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Apr 22 15:14:31.634905 dockerd[1578]: time="2025-04-22T15:14:31.634557276Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Apr 22 15:14:31.635877 sshd[1880]: Connection closed by 10.0.0.1 port 32912 Apr 22 15:14:31.636329 sshd-session[1877]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:31.644971 systemd[1]: containerd.service: Deactivated successfully. Apr 22 15:14:31.645051 systemd[1]: containerd.service: Unit process 1817 (containerd-shim) remains running after unit stopped. Apr 22 15:14:31.645281 systemd[1]: containerd.service: Consumed 366ms CPU time, 160.3M memory peak. Apr 22 15:14:31.645644 systemd[1]: sshd@9-10.0.0.74:22-10.0.0.1:32912.service: Deactivated successfully. Apr 22 15:14:31.646890 systemd[1]: session-10.scope: Deactivated successfully. Apr 22 15:14:31.649361 systemd-logind[1384]: Session 10 logged out. Waiting for processes to exit. Apr 22 15:14:31.649832 systemd[1]: Started sshd@10-10.0.0.74:22-10.0.0.1:32914.service - OpenSSH per-connection server daemon (10.0.0.1:32914). Apr 22 15:14:31.650528 systemd-logind[1384]: Removed session 10. Apr 22 15:14:31.698264 sshd[1887]: Accepted publickey for core from 10.0.0.1 port 32914 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:31.699278 sshd-session[1887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:31.702815 systemd-logind[1384]: New session 11 of user core. Apr 22 15:14:31.715355 systemd[1]: Started session-11.scope - Session 11 of User core. Apr 22 15:14:31.766484 sshd[1890]: Connection closed by 10.0.0.1 port 32914 Apr 22 15:14:31.766778 sshd-session[1887]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:31.769571 systemd[1]: sshd@10-10.0.0.74:22-10.0.0.1:32914.service: Deactivated successfully. Apr 22 15:14:31.771542 systemd[1]: session-11.scope: Deactivated successfully. Apr 22 15:14:31.772104 systemd-logind[1384]: Session 11 logged out. Waiting for processes to exit. Apr 22 15:14:31.773190 systemd-logind[1384]: Removed session 11. Apr 22 15:14:32.405430 systemd-networkd[1331]: docker0: Gained IPv6LL Apr 22 15:14:33.237368 systemd-networkd[1331]: veth93731d1: Gained IPv6LL Apr 22 15:14:36.895654 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Apr 22 15:14:36.896354 systemd[1]: Stopping docker.service - Docker Application Container Engine... Apr 22 15:14:36.896419 dockerd[1578]: time="2025-04-22T15:14:36.896357716Z" level=info msg="Processing signal 'terminated'" Apr 22 15:14:37.782183 systemd[1]: Started sshd@11-10.0.0.74:22-10.0.0.1:40372.service - OpenSSH per-connection server daemon (10.0.0.1:40372). Apr 22 15:14:37.823822 sshd[1896]: Accepted publickey for core from 10.0.0.1 port 40372 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:37.824866 sshd-session[1896]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:37.828944 systemd-logind[1384]: New session 12 of user core. Apr 22 15:14:37.839426 systemd[1]: Started session-12.scope - Session 12 of User core. Apr 22 15:14:37.892662 sshd[1898]: Connection closed by 10.0.0.1 port 40372 Apr 22 15:14:37.892942 sshd-session[1896]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:37.895710 systemd[1]: sshd@11-10.0.0.74:22-10.0.0.1:40372.service: Deactivated successfully. Apr 22 15:14:37.897395 systemd[1]: session-12.scope: Deactivated successfully. Apr 22 15:14:37.899212 systemd-logind[1384]: Session 12 logged out. Waiting for processes to exit. Apr 22 15:14:37.900167 systemd-logind[1384]: Removed session 12. Apr 22 15:14:38.897683 dockerd[1578]: time="2025-04-22T15:14:38.897467276Z" level=error msg="Error sending stop (signal 15) to container" container=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 error="Cannot kill container 00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\": unavailable" Apr 22 15:14:38.897683 dockerd[1578]: time="2025-04-22T15:14:38.897662196Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 Apr 22 15:14:43.908197 systemd[1]: Started sshd@12-10.0.0.74:22-10.0.0.1:42840.service - OpenSSH per-connection server daemon (10.0.0.1:42840). Apr 22 15:14:43.957602 sshd[1904]: Accepted publickey for core from 10.0.0.1 port 42840 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:43.958725 sshd-session[1904]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:43.963282 systemd-logind[1384]: New session 13 of user core. Apr 22 15:14:43.971426 systemd[1]: Started session-13.scope - Session 13 of User core. Apr 22 15:14:44.022660 sshd[1906]: Connection closed by 10.0.0.1 port 42840 Apr 22 15:14:44.022945 sshd-session[1904]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:44.025189 systemd[1]: sshd@12-10.0.0.74:22-10.0.0.1:42840.service: Deactivated successfully. Apr 22 15:14:44.026628 systemd[1]: session-13.scope: Deactivated successfully. Apr 22 15:14:44.027773 systemd-logind[1384]: Session 13 logged out. Waiting for processes to exit. Apr 22 15:14:44.028669 systemd-logind[1384]: Removed session 13. Apr 22 15:14:48.898337 dockerd[1578]: time="2025-04-22T15:14:48.898267036Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 error="context deadline exceeded" Apr 22 15:14:48.900184 systemd[1]: docker-00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5.scope: Deactivated successfully. Apr 22 15:14:50.037290 systemd[1]: Started sshd@13-10.0.0.74:22-10.0.0.1:42846.service - OpenSSH per-connection server daemon (10.0.0.1:42846). Apr 22 15:14:50.088837 sshd[1912]: Accepted publickey for core from 10.0.0.1 port 42846 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:50.089895 sshd-session[1912]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:50.094281 systemd-logind[1384]: New session 14 of user core. Apr 22 15:14:50.102352 systemd[1]: Started session-14.scope - Session 14 of User core. Apr 22 15:14:50.153771 sshd[1914]: Connection closed by 10.0.0.1 port 42846 Apr 22 15:14:50.154047 sshd-session[1912]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:50.157085 systemd[1]: sshd@13-10.0.0.74:22-10.0.0.1:42846.service: Deactivated successfully. Apr 22 15:14:50.158617 systemd[1]: session-14.scope: Deactivated successfully. Apr 22 15:14:50.160697 systemd-logind[1384]: Session 14 logged out. Waiting for processes to exit. Apr 22 15:14:50.161387 systemd-logind[1384]: Removed session 14. Apr 22 15:14:51.896788 dockerd[1578]: time="2025-04-22T15:14:51.896706476Z" level=error msg="Force shutdown daemon" Apr 22 15:14:51.897250 dockerd[1578]: time="2025-04-22T15:14:51.896864556Z" level=info msg="Daemon shutdown complete" Apr 22 15:14:51.900832 systemd[1]: docker.service: Deactivated successfully. Apr 22 15:14:51.901139 systemd[1]: Stopped docker.service - Docker Application Container Engine. Apr 22 15:14:51.901195 systemd[1]: docker.service: Consumed 723ms CPU time, 123.4M memory peak. Apr 22 15:14:51.901621 systemd[1]: docker.socket: Deactivated successfully. Apr 22 15:14:51.901876 systemd[1]: Closed docker.socket - Docker Socket for the API. Apr 22 15:14:51.901914 systemd[1]: Stopping docker.socket - Docker Socket for the API... Apr 22 15:14:51.903333 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 22 15:14:51.903493 systemd[1]: containerd.service: Found left-over process 1817 (containerd-shim) in control group while starting unit. Ignoring. Apr 22 15:14:51.903496 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Apr 22 15:14:51.910202 systemd[1]: Starting containerd.service - containerd container runtime... Apr 22 15:14:51.910449 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 22 15:14:51.920124 systemd[1]: containerd.service: Found left-over process 1817 (containerd-shim) in control group while starting unit. Ignoring. Apr 22 15:14:51.920135 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Apr 22 15:14:51.923899 (ntainerd)[1925]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 22 15:14:51.936280 containerd[1925]: time="2025-04-22T15:14:51Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Apr 22 15:14:51.936736 containerd[1925]: time="2025-04-22T15:14:51.936699636Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Apr 22 15:14:51.949078 containerd[1925]: time="2025-04-22T15:14:51.945129676Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.32µs" Apr 22 15:14:51.949078 containerd[1925]: time="2025-04-22T15:14:51.949045996Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949238436Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949278316Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949291156Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949475996Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949499956Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949509476Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949720956Z" 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 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949737476Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949747516Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949755276Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949776356Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950046 containerd[1925]: time="2025-04-22T15:14:51.949858196Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Apr 22 15:14:51.950308 containerd[1925]: time="2025-04-22T15:14:51.949877996Z" 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 Apr 22 15:14:51.950308 containerd[1925]: time="2025-04-22T15:14:51.949888716Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Apr 22 15:14:51.950308 containerd[1925]: time="2025-04-22T15:14:51.949924636Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Apr 22 15:14:51.951526 containerd[1925]: time="2025-04-22T15:14:51.951416836Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Apr 22 15:14:51.951526 containerd[1925]: time="2025-04-22T15:14:51.951465036Z" level=info msg="metadata content store policy set" policy=shared Apr 22 15:14:51.951693 containerd[1925]: time="2025-04-22T15:14:51.951611076Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Apr 22 15:14:51.951693 containerd[1925]: time="2025-04-22T15:14:51.951651956Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Apr 22 15:14:51.951693 containerd[1925]: time="2025-04-22T15:14:51.951666356Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Apr 22 15:14:51.951693 containerd[1925]: time="2025-04-22T15:14:51.951682756Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951700276Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951715196Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951731636Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951744636Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951758516Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951772156Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Apr 22 15:14:51.951788 containerd[1925]: time="2025-04-22T15:14:51.951784796Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Apr 22 15:14:51.951924 containerd[1925]: time="2025-04-22T15:14:51.951799236Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Apr 22 15:14:51.952059 containerd[1925]: time="2025-04-22T15:14:51.952001716Z" level=info msg="connecting to shim 00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5" address="unix:///run/containerd/s/cbb0bee56aa9a1772c581663996e634da84a2eddf89fbba2b5313b0966cd384b" namespace=moby protocol=ttrpc version=3 Apr 22 15:14:51.960226 containerd[1925]: time="2025-04-22T15:14:51.960196756Z" level=info msg="cleaning leaked shim process" id=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 namespace=moby Apr 22 15:14:51.968138 containerd[1925]: time="2025-04-22T15:14:51.968064316Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Apr 22 15:14:51.968684 containerd[1925]: time="2025-04-22T15:14:51.968657636Z" level=info msg="shim disconnected" id=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 namespace=moby Apr 22 15:14:51.968724 containerd[1925]: time="2025-04-22T15:14:51.968683836Z" level=warning msg="cleaning up after shim disconnected" id=00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5 namespace=moby Apr 22 15:14:51.968724 containerd[1925]: time="2025-04-22T15:14:51.968720396Z" level=info msg="cleaning up dead shim" namespace=moby Apr 22 15:14:51.980429 containerd[1925]: time="2025-04-22T15:14:51.980375916Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Apr 22 15:14:51.980509 containerd[1925]: time="2025-04-22T15:14:51.980492476Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Apr 22 15:14:51.980551 containerd[1925]: time="2025-04-22T15:14:51.980528236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Apr 22 15:14:51.980551 containerd[1925]: time="2025-04-22T15:14:51.980546236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Apr 22 15:14:51.980598 containerd[1925]: time="2025-04-22T15:14:51.980558876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Apr 22 15:14:51.980598 containerd[1925]: time="2025-04-22T15:14:51.980570316Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Apr 22 15:14:51.980598 containerd[1925]: time="2025-04-22T15:14:51.980580236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Apr 22 15:14:51.980598 containerd[1925]: time="2025-04-22T15:14:51.980592076Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Apr 22 15:14:51.980665 containerd[1925]: time="2025-04-22T15:14:51.980602916Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Apr 22 15:14:51.980665 containerd[1925]: time="2025-04-22T15:14:51.980615276Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Apr 22 15:14:51.980665 containerd[1925]: time="2025-04-22T15:14:51.980626156Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Apr 22 15:14:51.980665 containerd[1925]: time="2025-04-22T15:14:51.980635756Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Apr 22 15:14:51.980733 containerd[1925]: time="2025-04-22T15:14:51.980702076Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Apr 22 15:14:51.980733 containerd[1925]: time="2025-04-22T15:14:51.980717236Z" level=info msg="Start snapshots syncer" Apr 22 15:14:51.980767 containerd[1925]: time="2025-04-22T15:14:51.980746836Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Apr 22 15:14:51.981002 containerd[1925]: time="2025-04-22T15:14:51.980967796Z" 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\"}" Apr 22 15:14:51.981083 containerd[1925]: time="2025-04-22T15:14:51.981034596Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Apr 22 15:14:51.981117 containerd[1925]: time="2025-04-22T15:14:51.981102996Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Apr 22 15:14:51.981164 containerd[1925]: time="2025-04-22T15:14:51.981152116Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Apr 22 15:14:51.981186 containerd[1925]: time="2025-04-22T15:14:51.981174716Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Apr 22 15:14:51.981205 containerd[1925]: time="2025-04-22T15:14:51.981185796Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Apr 22 15:14:51.981205 containerd[1925]: time="2025-04-22T15:14:51.981196596Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Apr 22 15:14:51.981254 containerd[1925]: time="2025-04-22T15:14:51.981209196Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Apr 22 15:14:51.981254 containerd[1925]: time="2025-04-22T15:14:51.981235196Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Apr 22 15:14:51.981254 containerd[1925]: time="2025-04-22T15:14:51.981249556Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Apr 22 15:14:51.981307 containerd[1925]: time="2025-04-22T15:14:51.981275396Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Apr 22 15:14:51.981307 containerd[1925]: time="2025-04-22T15:14:51.981287836Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Apr 22 15:14:51.981307 containerd[1925]: time="2025-04-22T15:14:51.981301716Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Apr 22 15:14:51.981355 containerd[1925]: time="2025-04-22T15:14:51.981340436Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Apr 22 15:14:51.981377 containerd[1925]: time="2025-04-22T15:14:51.981355036Z" 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 Apr 22 15:14:51.981377 containerd[1925]: time="2025-04-22T15:14:51.981365156Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:14:51.981415 containerd[1925]: time="2025-04-22T15:14:51.981375236Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:14:51.981415 containerd[1925]: time="2025-04-22T15:14:51.981383756Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Apr 22 15:14:51.981415 containerd[1925]: time="2025-04-22T15:14:51.981392916Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Apr 22 15:14:51.981415 containerd[1925]: time="2025-04-22T15:14:51.981405236Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Apr 22 15:14:51.981479 containerd[1925]: time="2025-04-22T15:14:51.981421556Z" level=info msg="runtime interface created" Apr 22 15:14:51.981479 containerd[1925]: time="2025-04-22T15:14:51.981427316Z" level=info msg="created NRI interface" Apr 22 15:14:51.981479 containerd[1925]: time="2025-04-22T15:14:51.981435636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Apr 22 15:14:51.981479 containerd[1925]: time="2025-04-22T15:14:51.981445836Z" level=info msg="Connect containerd service" Apr 22 15:14:51.981479 containerd[1925]: time="2025-04-22T15:14:51.981472236Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 22 15:14:51.981995 containerd[1925]: time="2025-04-22T15:14:51.981961316Z" 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" Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992381476Z" level=info msg="Start subscribing containerd event" Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992461716Z" level=info msg="Start recovering state" Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992493116Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992552436Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992584556Z" level=info msg="Start event monitor" Apr 22 15:14:51.992557 containerd[1925]: time="2025-04-22T15:14:51.992607036Z" level=info msg="Start cni network conf syncer for default" Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992809316Z" level=info msg="Start streaming server" Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992825916Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992833156Z" level=info msg="runtime interface starting up..." Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992839436Z" level=info msg="starting plugins..." Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992852476Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Apr 22 15:14:51.993088 containerd[1925]: time="2025-04-22T15:14:51.992977196Z" level=info msg="containerd successfully booted in 0.056884s" Apr 22 15:14:51.993059 systemd[1]: Started containerd.service - containerd container runtime. Apr 22 15:14:51.996154 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 22 15:14:52.008484 (dockerd)[1972]: 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 Apr 22 15:14:52.031477 dockerd[1972]: time="2025-04-22T15:14:52.031422076Z" level=info msg="Starting up" Apr 22 15:14:52.032042 dockerd[1972]: time="2025-04-22T15:14:52.032012476Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Apr 22 15:14:52.054190 dockerd[1972]: time="2025-04-22T15:14:52.054140156Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Apr 22 15:14:52.060900 dockerd[1972]: time="2025-04-22T15:14:52.060855956Z" level=info msg="Loading containers: start." Apr 22 15:14:52.075163 systemd[1]: var-lib-docker-overlay2-4a458a7be3da526b1d8448aabac5b08da6e86115cdc9ba09a146d8399c9b32bc-merged.mount: Deactivated successfully. Apr 22 15:14:52.332198 dockerd[1972]: time="2025-04-22T15:14:52.332083716Z" level=info msg="Removing stale sandbox 99c68d80ebc60550a25e520be8a82033fcf29d16d67a03fc86209157ecc8300d (00fe0d3391dbe54d099c76a72ced24289f92a7af89eaf002c17a75f1f364e4e5)" Apr 22 15:14:52.336116 dockerd[1972]: time="2025-04-22T15:14:52.336068716Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 66b7092a187daa0461f27032faab8bf7e5879e3cb201b1ce81b08cbf8c030782 4d515378a6ec2cc4289e4150f6fc3c91eff538b217edd000bd55c0a354d569ed], retrying...." Apr 22 15:14:52.341312 kernel: docker0: port 1(veth93731d1) entered disabled state Apr 22 15:14:52.340537 systemd-networkd[1331]: veth93731d1: Link DOWN Apr 22 15:14:52.340543 systemd-networkd[1331]: veth93731d1: Lost carrier Apr 22 15:14:52.343463 kernel: veth93731d1 (unregistering): left allmulticast mode Apr 22 15:14:52.343506 kernel: veth93731d1 (unregistering): left promiscuous mode Apr 22 15:14:52.343531 kernel: docker0: port 1(veth93731d1) entered disabled state Apr 22 15:14:52.357275 systemd-networkd[1331]: docker0: Lost carrier Apr 22 15:14:52.467412 dockerd[1972]: time="2025-04-22T15:14:52.467360596Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Apr 22 15:14:52.573502 dockerd[1972]: time="2025-04-22T15:14:52.573443836Z" level=warning msg="error locating sandbox id 99c68d80ebc60550a25e520be8a82033fcf29d16d67a03fc86209157ecc8300d: sandbox 99c68d80ebc60550a25e520be8a82033fcf29d16d67a03fc86209157ecc8300d not found" Apr 22 15:14:52.573684 dockerd[1972]: time="2025-04-22T15:14:52.573543396Z" level=info msg="Loading containers: done." Apr 22 15:14:52.583288 dockerd[1972]: time="2025-04-22T15:14:52.582593036Z" 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 Apr 22 15:14:52.583288 dockerd[1972]: time="2025-04-22T15:14:52.582669876Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Apr 22 15:14:52.583288 dockerd[1972]: time="2025-04-22T15:14:52.582715636Z" level=info msg="Daemon has completed initialization" Apr 22 15:14:52.601651 dockerd[1972]: time="2025-04-22T15:14:52.601590516Z" level=info msg="API listen on /run/docker.sock" Apr 22 15:14:52.601741 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 22 15:14:53.046582 systemd[1]: run-docker-netns-99c68d80ebc6.mount: Deactivated successfully. Apr 22 15:14:56.165266 systemd[1]: Started sshd@14-10.0.0.74:22-10.0.0.1:55902.service - OpenSSH per-connection server daemon (10.0.0.1:55902). Apr 22 15:14:56.213374 sshd[2181]: Accepted publickey for core from 10.0.0.1 port 55902 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:56.214444 sshd-session[2181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:56.218567 systemd-logind[1384]: New session 15 of user core. Apr 22 15:14:56.229355 systemd[1]: Started session-15.scope - Session 15 of User core. Apr 22 15:14:56.282048 sshd[2183]: Connection closed by 10.0.0.1 port 55902 Apr 22 15:14:56.282344 sshd-session[2181]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:56.307020 systemd[1]: sshd@14-10.0.0.74:22-10.0.0.1:55902.service: Deactivated successfully. Apr 22 15:14:56.308482 systemd[1]: session-15.scope: Deactivated successfully. Apr 22 15:14:56.310482 systemd-logind[1384]: Session 15 logged out. Waiting for processes to exit. Apr 22 15:14:56.312655 systemd[1]: Started sshd@15-10.0.0.74:22-10.0.0.1:55904.service - OpenSSH per-connection server daemon (10.0.0.1:55904). Apr 22 15:14:56.313463 systemd-logind[1384]: Removed session 15. Apr 22 15:14:56.367908 sshd[2188]: Accepted publickey for core from 10.0.0.1 port 55904 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:56.369032 sshd-session[2188]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:56.373274 systemd-logind[1384]: New session 16 of user core. Apr 22 15:14:56.387426 systemd[1]: Started session-16.scope - Session 16 of User core. Apr 22 15:14:56.440671 sshd[2191]: Connection closed by 10.0.0.1 port 55904 Apr 22 15:14:56.440781 sshd-session[2188]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:56.463275 systemd[1]: sshd@15-10.0.0.74:22-10.0.0.1:55904.service: Deactivated successfully. Apr 22 15:14:56.464671 systemd[1]: session-16.scope: Deactivated successfully. Apr 22 15:14:56.465313 systemd-logind[1384]: Session 16 logged out. Waiting for processes to exit. Apr 22 15:14:56.467071 systemd[1]: Started sshd@16-10.0.0.74:22-10.0.0.1:55914.service - OpenSSH per-connection server daemon (10.0.0.1:55914). Apr 22 15:14:56.467760 systemd-logind[1384]: Removed session 16. Apr 22 15:14:56.522568 sshd[2196]: Accepted publickey for core from 10.0.0.1 port 55914 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:56.523711 sshd-session[2196]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:56.527938 systemd-logind[1384]: New session 17 of user core. Apr 22 15:14:56.543364 systemd[1]: Started session-17.scope - Session 17 of User core. Apr 22 15:14:56.600900 sshd[2199]: Connection closed by 10.0.0.1 port 55914 Apr 22 15:14:56.601213 sshd-session[2196]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:56.618249 systemd[1]: sshd@16-10.0.0.74:22-10.0.0.1:55914.service: Deactivated successfully. Apr 22 15:14:56.619815 systemd[1]: session-17.scope: Deactivated successfully. Apr 22 15:14:56.621330 systemd-logind[1384]: Session 17 logged out. Waiting for processes to exit. Apr 22 15:14:56.622442 systemd[1]: Started sshd@17-10.0.0.74:22-10.0.0.1:55922.service - OpenSSH per-connection server daemon (10.0.0.1:55922). Apr 22 15:14:56.623250 systemd-logind[1384]: Removed session 17. Apr 22 15:14:56.677645 sshd[2211]: Accepted publickey for core from 10.0.0.1 port 55922 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:56.678708 sshd-session[2211]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:56.682874 systemd-logind[1384]: New session 18 of user core. Apr 22 15:14:56.695434 systemd[1]: Started session-18.scope - Session 18 of User core. Apr 22 15:14:56.762791 systemd[1]: var-lib-docker-overlay2-de7700f530d81abbbb1aa692a5b8cca9f70bdb16b664293fd6ae84e8d5ffb84a\x2dinit-merged.mount: Deactivated successfully. Apr 22 15:14:56.779422 kernel: docker0: port 1(vethaaf79ab) entered blocking state Apr 22 15:14:56.779539 kernel: docker0: port 1(vethaaf79ab) entered disabled state Apr 22 15:14:56.779559 kernel: vethaaf79ab: entered allmulticast mode Apr 22 15:14:56.780243 kernel: vethaaf79ab: entered promiscuous mode Apr 22 15:14:56.780939 systemd-networkd[1331]: vethaaf79ab: Link UP Apr 22 15:14:56.804300 containerd[1925]: time="2025-04-22T15:14:56.804243587Z" level=info msg="connecting to shim 2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b" address="unix:///run/containerd/s/ef212331a4d75ccb0bcdbd1d42848df27d5a00f0a7af46d553c2bf6aca66acd2" namespace=moby protocol=ttrpc version=3 Apr 22 15:14:56.826458 systemd[1]: Started docker-2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b.scope - libcontainer container 2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b. Apr 22 15:14:56.869256 kernel: eth0: renamed from veth6c459dd Apr 22 15:14:56.877045 systemd-networkd[1331]: vethaaf79ab: Gained carrier Apr 22 15:14:56.877249 kernel: docker0: port 1(vethaaf79ab) entered blocking state Apr 22 15:14:56.877277 kernel: docker0: port 1(vethaaf79ab) entered forwarding state Apr 22 15:14:56.877544 systemd-networkd[1331]: docker0: Gained carrier Apr 22 15:14:56.880020 systemd-resolved[1264]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 22 15:14:56.903904 sshd[2214]: Connection closed by 10.0.0.1 port 55922 Apr 22 15:14:56.904260 sshd-session[2211]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:56.915270 systemd[1]: sshd@17-10.0.0.74:22-10.0.0.1:55922.service: Deactivated successfully. Apr 22 15:14:56.916669 systemd[1]: session-18.scope: Deactivated successfully. Apr 22 15:14:56.917225 systemd-logind[1384]: Session 18 logged out. Waiting for processes to exit. Apr 22 15:14:56.920427 systemd[1]: Started sshd@18-10.0.0.74:22-10.0.0.1:55934.service - OpenSSH per-connection server daemon (10.0.0.1:55934). Apr 22 15:14:56.921451 systemd-logind[1384]: Removed session 18. Apr 22 15:14:56.975175 sshd[2293]: Accepted publickey for core from 10.0.0.1 port 55934 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:56.976383 sshd-session[2293]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:56.981336 systemd-logind[1384]: New session 19 of user core. Apr 22 15:14:56.994376 systemd[1]: Started session-19.scope - Session 19 of User core. Apr 22 15:14:57.045813 sshd[2296]: Connection closed by 10.0.0.1 port 55934 Apr 22 15:14:57.046428 sshd-session[2293]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:57.063422 systemd[1]: sshd@18-10.0.0.74:22-10.0.0.1:55934.service: Deactivated successfully. Apr 22 15:14:57.064836 systemd[1]: session-19.scope: Deactivated successfully. Apr 22 15:14:57.066106 systemd-logind[1384]: Session 19 logged out. Waiting for processes to exit. Apr 22 15:14:57.067851 systemd[1]: Started sshd@19-10.0.0.74:22-10.0.0.1:55948.service - OpenSSH per-connection server daemon (10.0.0.1:55948). Apr 22 15:14:57.068692 systemd-logind[1384]: Removed session 19. Apr 22 15:14:57.123673 sshd[2301]: Accepted publickey for core from 10.0.0.1 port 55948 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:57.124816 sshd-session[2301]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:57.129282 systemd-logind[1384]: New session 20 of user core. Apr 22 15:14:57.144370 systemd[1]: Started session-20.scope - Session 20 of User core. Apr 22 15:14:57.193625 sudo[2305]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1925 Apr 22 15:14:57.193886 sudo[2305]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 22 15:14:57.196426 sudo[2305]: pam_unix(sudo:session): session closed for user root Apr 22 15:14:57.197340 sshd[2304]: Connection closed by 10.0.0.1 port 55948 Apr 22 15:14:57.197681 sshd-session[2301]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:57.199108 dockerd[1972]: time="2025-04-22T15:14:57.198686394Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Apr 22 15:14:57.199108 dockerd[1972]: time="2025-04-22T15:14:57.198735954Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Apr 22 15:14:57.199108 dockerd[1972]: time="2025-04-22T15:14:57.198866274Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Apr 22 15:14:57.199108 dockerd[1972]: time="2025-04-22T15:14:57.198891914Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Apr 22 15:14:57.205801 systemd[1]: containerd.service: Deactivated successfully. Apr 22 15:14:57.205896 systemd[1]: containerd.service: Unit process 2243 (containerd-shim) remains running after unit stopped. Apr 22 15:14:57.206100 systemd[1]: containerd.service: Consumed 180ms CPU time, 160.3M memory peak. Apr 22 15:14:57.206915 systemd[1]: sshd@19-10.0.0.74:22-10.0.0.1:55948.service: Deactivated successfully. Apr 22 15:14:57.208265 systemd[1]: session-20.scope: Deactivated successfully. Apr 22 15:14:57.208812 systemd-logind[1384]: Session 20 logged out. Waiting for processes to exit. Apr 22 15:14:57.211058 systemd[1]: Started sshd@20-10.0.0.74:22-10.0.0.1:55956.service - OpenSSH per-connection server daemon (10.0.0.1:55956). Apr 22 15:14:57.211485 systemd-logind[1384]: Removed session 20. Apr 22 15:14:57.258786 sshd[2311]: Accepted publickey for core from 10.0.0.1 port 55956 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:14:57.259869 sshd-session[2311]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:14:57.263620 systemd-logind[1384]: New session 21 of user core. Apr 22 15:14:57.278361 systemd[1]: Started session-21.scope - Session 21 of User core. Apr 22 15:14:57.329969 sshd[2314]: Connection closed by 10.0.0.1 port 55956 Apr 22 15:14:57.330275 sshd-session[2311]: pam_unix(sshd:session): session closed for user core Apr 22 15:14:57.333872 systemd[1]: sshd@20-10.0.0.74:22-10.0.0.1:55956.service: Deactivated successfully. Apr 22 15:14:57.335384 systemd[1]: session-21.scope: Deactivated successfully. Apr 22 15:14:57.337728 systemd-logind[1384]: Session 21 logged out. Waiting for processes to exit. Apr 22 15:14:57.338550 systemd-logind[1384]: Removed session 21. Apr 22 15:14:58.261437 systemd-networkd[1331]: vethaaf79ab: Gained IPv6LL Apr 22 15:15:02.448741 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Apr 22 15:15:02.449083 systemd[1]: Stopping docker.service - Docker Application Container Engine... Apr 22 15:15:02.449295 dockerd[1972]: time="2025-04-22T15:15:02.449237342Z" level=info msg="Processing signal 'terminated'" Apr 22 15:15:03.341372 systemd[1]: Started sshd@21-10.0.0.74:22-10.0.0.1:43382.service - OpenSSH per-connection server daemon (10.0.0.1:43382). Apr 22 15:15:03.395117 sshd[2320]: Accepted publickey for core from 10.0.0.1 port 43382 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:03.396552 sshd-session[2320]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:03.400935 systemd-logind[1384]: New session 22 of user core. Apr 22 15:15:03.414420 systemd[1]: Started session-22.scope - Session 22 of User core. Apr 22 15:15:03.469659 sshd[2322]: Connection closed by 10.0.0.1 port 43382 Apr 22 15:15:03.470161 sshd-session[2320]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:03.473702 systemd[1]: sshd@21-10.0.0.74:22-10.0.0.1:43382.service: Deactivated successfully. Apr 22 15:15:03.475623 systemd[1]: session-22.scope: Deactivated successfully. Apr 22 15:15:03.476296 systemd-logind[1384]: Session 22 logged out. Waiting for processes to exit. Apr 22 15:15:03.477213 systemd-logind[1384]: Removed session 22. Apr 22 15:15:04.451150 dockerd[1972]: time="2025-04-22T15:15:04.450898120Z" level=error msg="Error sending stop (signal 15) to container" container=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b error="Cannot kill container 2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\": unavailable" Apr 22 15:15:04.451150 dockerd[1972]: time="2025-04-22T15:15:04.450982240Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b Apr 22 15:15:05.712623 update_engine[1387]: I20250422 15:15:05.712533 1387 update_attempter.cc:509] Updating boot flags... Apr 22 15:15:05.733273 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2334) Apr 22 15:15:05.761159 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2336) Apr 22 15:15:05.795248 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2336) Apr 22 15:15:09.481395 systemd[1]: Started sshd@22-10.0.0.74:22-10.0.0.1:43398.service - OpenSSH per-connection server daemon (10.0.0.1:43398). Apr 22 15:15:09.524155 sshd[2343]: Accepted publickey for core from 10.0.0.1 port 43398 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:09.525392 sshd-session[2343]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:09.530362 systemd-logind[1384]: New session 23 of user core. Apr 22 15:15:09.539456 systemd[1]: Started session-23.scope - Session 23 of User core. Apr 22 15:15:09.593357 sshd[2345]: Connection closed by 10.0.0.1 port 43398 Apr 22 15:15:09.593671 sshd-session[2343]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:09.596046 systemd[1]: sshd@22-10.0.0.74:22-10.0.0.1:43398.service: Deactivated successfully. Apr 22 15:15:09.598578 systemd[1]: session-23.scope: Deactivated successfully. Apr 22 15:15:09.599801 systemd-logind[1384]: Session 23 logged out. Waiting for processes to exit. Apr 22 15:15:09.600753 systemd-logind[1384]: Removed session 23. Apr 22 15:15:14.452248 dockerd[1972]: time="2025-04-22T15:15:14.452152276Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b error="context deadline exceeded" Apr 22 15:15:14.452754 systemd[1]: docker-2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b.scope: Deactivated successfully. Apr 22 15:15:15.605263 systemd[1]: Started sshd@23-10.0.0.74:22-10.0.0.1:43750.service - OpenSSH per-connection server daemon (10.0.0.1:43750). Apr 22 15:15:15.657532 sshd[2351]: Accepted publickey for core from 10.0.0.1 port 43750 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:15.658674 sshd-session[2351]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:15.663017 systemd-logind[1384]: New session 24 of user core. Apr 22 15:15:15.679414 systemd[1]: Started session-24.scope - Session 24 of User core. Apr 22 15:15:15.730803 sshd[2353]: Connection closed by 10.0.0.1 port 43750 Apr 22 15:15:15.731252 sshd-session[2351]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:15.734204 systemd[1]: sshd@23-10.0.0.74:22-10.0.0.1:43750.service: Deactivated successfully. Apr 22 15:15:15.736718 systemd[1]: session-24.scope: Deactivated successfully. Apr 22 15:15:15.737300 systemd-logind[1384]: Session 24 logged out. Waiting for processes to exit. Apr 22 15:15:15.738251 systemd-logind[1384]: Removed session 24. Apr 22 15:15:17.449841 dockerd[1972]: time="2025-04-22T15:15:17.449737479Z" level=error msg="Force shutdown daemon" Apr 22 15:15:17.450370 dockerd[1972]: time="2025-04-22T15:15:17.450211639Z" level=info msg="Daemon shutdown complete" Apr 22 15:15:17.453355 systemd[1]: docker.service: Deactivated successfully. Apr 22 15:15:17.453751 systemd[1]: Stopped docker.service - Docker Application Container Engine. Apr 22 15:15:17.454238 systemd[1]: docker.socket: Deactivated successfully. Apr 22 15:15:17.454515 systemd[1]: Closed docker.socket - Docker Socket for the API. Apr 22 15:15:17.454557 systemd[1]: Stopping docker.socket - Docker Socket for the API... Apr 22 15:15:17.456160 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 22 15:15:17.457451 systemd[1]: containerd.service: Found left-over process 2243 (containerd-shim) in control group while starting unit. Ignoring. Apr 22 15:15:17.457469 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Apr 22 15:15:17.462568 systemd[1]: Starting containerd.service - containerd container runtime... Apr 22 15:15:17.463304 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 22 15:15:17.472837 systemd[1]: containerd.service: Found left-over process 2243 (containerd-shim) in control group while starting unit. Ignoring. Apr 22 15:15:17.472850 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Apr 22 15:15:17.476548 (ntainerd)[2364]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 22 15:15:17.489415 containerd[2364]: time="2025-04-22T15:15:17Z" 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 Apr 22 15:15:17.489874 containerd[2364]: time="2025-04-22T15:15:17.489826731Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Apr 22 15:15:17.499867 containerd[2364]: time="2025-04-22T15:15:17.499820725Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="5.92µs" Apr 22 15:15:17.499867 containerd[2364]: time="2025-04-22T15:15:17.499853924Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Apr 22 15:15:17.499867 containerd[2364]: time="2025-04-22T15:15:17.499872924Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Apr 22 15:15:17.499992 containerd[2364]: time="2025-04-22T15:15:17.499909484Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Apr 22 15:15:17.499992 containerd[2364]: time="2025-04-22T15:15:17.499922724Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Apr 22 15:15:17.500126 containerd[2364]: time="2025-04-22T15:15:17.500092764Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500158 containerd[2364]: time="2025-04-22T15:15:17.500132044Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500158 containerd[2364]: time="2025-04-22T15:15:17.500142964Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500393 containerd[2364]: time="2025-04-22T15:15:17.500362004Z" 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 Apr 22 15:15:17.500393 containerd[2364]: time="2025-04-22T15:15:17.500387564Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500447 containerd[2364]: time="2025-04-22T15:15:17.500398684Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500447 containerd[2364]: time="2025-04-22T15:15:17.500406924Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500447 containerd[2364]: time="2025-04-22T15:15:17.500430004Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500535 containerd[2364]: time="2025-04-22T15:15:17.500518284Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Apr 22 15:15:17.500564 containerd[2364]: time="2025-04-22T15:15:17.500544524Z" 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 Apr 22 15:15:17.500564 containerd[2364]: time="2025-04-22T15:15:17.500553964Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Apr 22 15:15:17.500601 containerd[2364]: time="2025-04-22T15:15:17.500582444Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Apr 22 15:15:17.500866 containerd[2364]: time="2025-04-22T15:15:17.500829764Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Apr 22 15:15:17.500894 containerd[2364]: time="2025-04-22T15:15:17.500871484Z" level=info msg="metadata content store policy set" policy=shared Apr 22 15:15:17.501061 containerd[2364]: time="2025-04-22T15:15:17.501027244Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Apr 22 15:15:17.501088 containerd[2364]: time="2025-04-22T15:15:17.501074524Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Apr 22 15:15:17.501112 containerd[2364]: time="2025-04-22T15:15:17.501087924Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Apr 22 15:15:17.501130 containerd[2364]: time="2025-04-22T15:15:17.501099564Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Apr 22 15:15:17.501130 containerd[2364]: time="2025-04-22T15:15:17.501125324Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Apr 22 15:15:17.501168 containerd[2364]: time="2025-04-22T15:15:17.501138644Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Apr 22 15:15:17.501168 containerd[2364]: time="2025-04-22T15:15:17.501150004Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Apr 22 15:15:17.501201 containerd[2364]: time="2025-04-22T15:15:17.501161004Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Apr 22 15:15:17.501236 containerd[2364]: time="2025-04-22T15:15:17.501201844Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Apr 22 15:15:17.501236 containerd[2364]: time="2025-04-22T15:15:17.501232364Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Apr 22 15:15:17.501278 containerd[2364]: time="2025-04-22T15:15:17.501243404Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Apr 22 15:15:17.501278 containerd[2364]: time="2025-04-22T15:15:17.501256404Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Apr 22 15:15:17.501506 containerd[2364]: time="2025-04-22T15:15:17.501468683Z" level=info msg="connecting to shim 2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b" address="unix:///run/containerd/s/ef212331a4d75ccb0bcdbd1d42848df27d5a00f0a7af46d553c2bf6aca66acd2" namespace=moby protocol=ttrpc version=3 Apr 22 15:15:17.508487 containerd[2364]: time="2025-04-22T15:15:17.508450278Z" level=info msg="cleaning leaked shim process" id=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b namespace=moby Apr 22 15:15:17.516359 containerd[2364]: time="2025-04-22T15:15:17.516308153Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Apr 22 15:15:17.517226 containerd[2364]: time="2025-04-22T15:15:17.517148792Z" level=info msg="shim disconnected" id=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b namespace=moby Apr 22 15:15:17.517226 containerd[2364]: time="2025-04-22T15:15:17.517211952Z" level=warning msg="cleaning up after shim disconnected" id=2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b namespace=moby Apr 22 15:15:17.517280 containerd[2364]: time="2025-04-22T15:15:17.517228552Z" level=info msg="cleaning up dead shim" namespace=moby Apr 22 15:15:17.526122 containerd[2364]: time="2025-04-22T15:15:17.526056946Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Apr 22 15:15:17.526204 containerd[2364]: time="2025-04-22T15:15:17.526188506Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Apr 22 15:15:17.526261 containerd[2364]: time="2025-04-22T15:15:17.526243186Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Apr 22 15:15:17.526289 containerd[2364]: time="2025-04-22T15:15:17.526268586Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Apr 22 15:15:17.526308 containerd[2364]: time="2025-04-22T15:15:17.526284266Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Apr 22 15:15:17.526308 containerd[2364]: time="2025-04-22T15:15:17.526300946Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Apr 22 15:15:17.526349 containerd[2364]: time="2025-04-22T15:15:17.526315186Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Apr 22 15:15:17.526349 containerd[2364]: time="2025-04-22T15:15:17.526331826Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Apr 22 15:15:17.526428 containerd[2364]: time="2025-04-22T15:15:17.526346306Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Apr 22 15:15:17.526657 containerd[2364]: time="2025-04-22T15:15:17.526625506Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Apr 22 15:15:17.526680 containerd[2364]: time="2025-04-22T15:15:17.526662906Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Apr 22 15:15:17.526703 containerd[2364]: time="2025-04-22T15:15:17.526677866Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Apr 22 15:15:17.526751 containerd[2364]: time="2025-04-22T15:15:17.526737226Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Apr 22 15:15:17.526778 containerd[2364]: time="2025-04-22T15:15:17.526756986Z" level=info msg="Start snapshots syncer" Apr 22 15:15:17.526799 containerd[2364]: time="2025-04-22T15:15:17.526780186Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Apr 22 15:15:17.527027 containerd[2364]: time="2025-04-22T15:15:17.526987386Z" 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\"}" Apr 22 15:15:17.527097 containerd[2364]: time="2025-04-22T15:15:17.527038066Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Apr 22 15:15:17.527117 containerd[2364]: time="2025-04-22T15:15:17.527106905Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Apr 22 15:15:17.527171 containerd[2364]: time="2025-04-22T15:15:17.527156065Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Apr 22 15:15:17.527199 containerd[2364]: time="2025-04-22T15:15:17.527179385Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Apr 22 15:15:17.527199 containerd[2364]: time="2025-04-22T15:15:17.527191065Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Apr 22 15:15:17.527246 containerd[2364]: time="2025-04-22T15:15:17.527200865Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Apr 22 15:15:17.527273 containerd[2364]: time="2025-04-22T15:15:17.527213265Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Apr 22 15:15:17.527273 containerd[2364]: time="2025-04-22T15:15:17.527259985Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Apr 22 15:15:17.527309 containerd[2364]: time="2025-04-22T15:15:17.527277465Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Apr 22 15:15:17.527309 containerd[2364]: time="2025-04-22T15:15:17.527305705Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Apr 22 15:15:17.527347 containerd[2364]: time="2025-04-22T15:15:17.527318425Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Apr 22 15:15:17.527347 containerd[2364]: time="2025-04-22T15:15:17.527329105Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Apr 22 15:15:17.527387 containerd[2364]: time="2025-04-22T15:15:17.527367185Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Apr 22 15:15:17.527408 containerd[2364]: time="2025-04-22T15:15:17.527382745Z" 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 Apr 22 15:15:17.527408 containerd[2364]: time="2025-04-22T15:15:17.527391745Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:15:17.527408 containerd[2364]: time="2025-04-22T15:15:17.527401825Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Apr 22 15:15:17.527460 containerd[2364]: time="2025-04-22T15:15:17.527409345Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Apr 22 15:15:17.527460 containerd[2364]: time="2025-04-22T15:15:17.527422385Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Apr 22 15:15:17.527460 containerd[2364]: time="2025-04-22T15:15:17.527433985Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Apr 22 15:15:17.527460 containerd[2364]: time="2025-04-22T15:15:17.527452225Z" level=info msg="runtime interface created" Apr 22 15:15:17.527460 containerd[2364]: time="2025-04-22T15:15:17.527457825Z" level=info msg="created NRI interface" Apr 22 15:15:17.527555 containerd[2364]: time="2025-04-22T15:15:17.527471345Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Apr 22 15:15:17.527555 containerd[2364]: time="2025-04-22T15:15:17.527492825Z" level=info msg="Connect containerd service" Apr 22 15:15:17.527555 containerd[2364]: time="2025-04-22T15:15:17.527529905Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 22 15:15:17.528000 containerd[2364]: time="2025-04-22T15:15:17.527963305Z" 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" Apr 22 15:15:17.535876 containerd[2364]: time="2025-04-22T15:15:17.535838699Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 22 15:15:17.535952 containerd[2364]: time="2025-04-22T15:15:17.535898819Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 22 15:15:17.535952 containerd[2364]: time="2025-04-22T15:15:17.535930539Z" level=info msg="Start subscribing containerd event" Apr 22 15:15:17.535993 containerd[2364]: time="2025-04-22T15:15:17.535959339Z" level=info msg="Start recovering state" Apr 22 15:15:17.536047 containerd[2364]: time="2025-04-22T15:15:17.536021539Z" level=info msg="Start event monitor" Apr 22 15:15:17.536047 containerd[2364]: time="2025-04-22T15:15:17.536041739Z" level=info msg="Start cni network conf syncer for default" Apr 22 15:15:17.536088 containerd[2364]: time="2025-04-22T15:15:17.536049379Z" level=info msg="Start streaming server" Apr 22 15:15:17.536088 containerd[2364]: time="2025-04-22T15:15:17.536056699Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Apr 22 15:15:17.536088 containerd[2364]: time="2025-04-22T15:15:17.536062819Z" level=info msg="runtime interface starting up..." Apr 22 15:15:17.536088 containerd[2364]: time="2025-04-22T15:15:17.536068099Z" level=info msg="starting plugins..." Apr 22 15:15:17.536088 containerd[2364]: time="2025-04-22T15:15:17.536076619Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Apr 22 15:15:17.536179 containerd[2364]: time="2025-04-22T15:15:17.536167139Z" level=info msg="containerd successfully booted in 0.046957s" Apr 22 15:15:17.537322 systemd[1]: Started containerd.service - containerd container runtime. Apr 22 15:15:17.538853 systemd[1]: Starting docker.service - Docker Application Container Engine... Apr 22 15:15:17.555575 (dockerd)[2412]: 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 Apr 22 15:15:17.578124 dockerd[2412]: time="2025-04-22T15:15:17.578069710Z" level=info msg="Starting up" Apr 22 15:15:17.578592 dockerd[2412]: time="2025-04-22T15:15:17.578573270Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Apr 22 15:15:17.601895 dockerd[2412]: time="2025-04-22T15:15:17.601861893Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Apr 22 15:15:17.608579 dockerd[2412]: time="2025-04-22T15:15:17.608548849Z" level=info msg="Loading containers: start." Apr 22 15:15:17.620899 systemd[1]: var-lib-docker-overlay2-de7700f530d81abbbb1aa692a5b8cca9f70bdb16b664293fd6ae84e8d5ffb84a-merged.mount: Deactivated successfully. Apr 22 15:15:17.890926 dockerd[2412]: time="2025-04-22T15:15:17.890815812Z" level=info msg="Removing stale sandbox a4ead40f1a42dfba74e0f9866fbd8387d52da251221f7491490c72c1d7d18bee (2a6014cce636f1e39e8ccc49bf76bb5fd4a5b6c55f6c0f287df0e68dbf6fc04b)" Apr 22 15:15:17.893934 dockerd[2412]: time="2025-04-22T15:15:17.893892970Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint e69830fb4ff0dbfb933330fa2da53a7fbcbf7ab09363baa4553932f438c8fc3d 64406ce33fd3c9f6f7f8911d479dad344bf7a376a663ae02c0e94e10da370916], retrying...." Apr 22 15:15:17.894504 systemd-networkd[1331]: vethaaf79ab: Link DOWN Apr 22 15:15:17.895382 kernel: docker0: port 1(vethaaf79ab) entered disabled state Apr 22 15:15:17.894512 systemd-networkd[1331]: vethaaf79ab: Lost carrier Apr 22 15:15:17.896519 kernel: vethaaf79ab (unregistering): left allmulticast mode Apr 22 15:15:17.896562 kernel: vethaaf79ab (unregistering): left promiscuous mode Apr 22 15:15:17.896579 kernel: docker0: port 1(vethaaf79ab) entered disabled state Apr 22 15:15:17.903688 systemd-networkd[1331]: docker0: Lost carrier Apr 22 15:15:18.064278 dockerd[2412]: time="2025-04-22T15:15:18.064234494Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Apr 22 15:15:18.174646 dockerd[2412]: time="2025-04-22T15:15:18.174340102Z" level=warning msg="error locating sandbox id 99c68d80ebc60550a25e520be8a82033fcf29d16d67a03fc86209157ecc8300d: sandbox 99c68d80ebc60550a25e520be8a82033fcf29d16d67a03fc86209157ecc8300d not found" Apr 22 15:15:18.174646 dockerd[2412]: time="2025-04-22T15:15:18.174381782Z" level=warning msg="error locating sandbox id a4ead40f1a42dfba74e0f9866fbd8387d52da251221f7491490c72c1d7d18bee: sandbox a4ead40f1a42dfba74e0f9866fbd8387d52da251221f7491490c72c1d7d18bee not found" Apr 22 15:15:18.174646 dockerd[2412]: time="2025-04-22T15:15:18.174439262Z" level=info msg="Loading containers: done." Apr 22 15:15:18.184241 dockerd[2412]: time="2025-04-22T15:15:18.184183696Z" 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 Apr 22 15:15:18.184365 dockerd[2412]: time="2025-04-22T15:15:18.184274656Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Apr 22 15:15:18.184365 dockerd[2412]: time="2025-04-22T15:15:18.184325616Z" level=info msg="Daemon has completed initialization" Apr 22 15:15:18.204395 dockerd[2412]: time="2025-04-22T15:15:18.204341883Z" level=info msg="API listen on /run/docker.sock" Apr 22 15:15:18.204669 systemd[1]: Started docker.service - Docker Application Container Engine. Apr 22 15:15:18.593526 systemd[1]: run-docker-netns-a4ead40f1a42.mount: Deactivated successfully. Apr 22 15:15:21.744318 systemd[1]: Started sshd@24-10.0.0.74:22-10.0.0.1:43754.service - OpenSSH per-connection server daemon (10.0.0.1:43754). Apr 22 15:15:21.792656 sshd[2620]: Accepted publickey for core from 10.0.0.1 port 43754 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:21.793728 sshd-session[2620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:21.798280 systemd-logind[1384]: New session 25 of user core. Apr 22 15:15:21.808378 systemd[1]: Started session-25.scope - Session 25 of User core. Apr 22 15:15:21.859561 sshd[2622]: Connection closed by 10.0.0.1 port 43754 Apr 22 15:15:21.859899 sshd-session[2620]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:21.869147 systemd[1]: sshd@24-10.0.0.74:22-10.0.0.1:43754.service: Deactivated successfully. Apr 22 15:15:21.870416 systemd[1]: session-25.scope: Deactivated successfully. Apr 22 15:15:21.873318 systemd-logind[1384]: Session 25 logged out. Waiting for processes to exit. Apr 22 15:15:21.873896 systemd[1]: Started sshd@25-10.0.0.74:22-10.0.0.1:43756.service - OpenSSH per-connection server daemon (10.0.0.1:43756). Apr 22 15:15:21.874585 systemd-logind[1384]: Removed session 25. Apr 22 15:15:21.930354 sshd[2627]: Accepted publickey for core from 10.0.0.1 port 43756 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:21.931393 sshd-session[2627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:21.934531 systemd-logind[1384]: New session 26 of user core. Apr 22 15:15:21.944339 systemd[1]: Started session-26.scope - Session 26 of User core. Apr 22 15:15:21.995122 sshd[2630]: Connection closed by 10.0.0.1 port 43756 Apr 22 15:15:21.995432 sshd-session[2627]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:22.014261 systemd[1]: Started sshd@26-10.0.0.74:22-10.0.0.1:43764.service - OpenSSH per-connection server daemon (10.0.0.1:43764). Apr 22 15:15:22.014659 systemd[1]: sshd@25-10.0.0.74:22-10.0.0.1:43756.service: Deactivated successfully. Apr 22 15:15:22.015931 systemd[1]: session-26.scope: Deactivated successfully. Apr 22 15:15:22.017067 systemd-logind[1384]: Session 26 logged out. Waiting for processes to exit. Apr 22 15:15:22.018823 systemd-logind[1384]: Removed session 26. Apr 22 15:15:22.062324 sshd[2633]: Accepted publickey for core from 10.0.0.1 port 43764 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:22.063361 sshd-session[2633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:22.067356 systemd-logind[1384]: New session 27 of user core. Apr 22 15:15:22.080348 systemd[1]: Started session-27.scope - Session 27 of User core. Apr 22 15:15:22.137259 sshd[2638]: Connection closed by 10.0.0.1 port 43764 Apr 22 15:15:22.137111 sshd-session[2633]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:22.150970 systemd[1]: sshd@26-10.0.0.74:22-10.0.0.1:43764.service: Deactivated successfully. Apr 22 15:15:22.152406 systemd[1]: session-27.scope: Deactivated successfully. Apr 22 15:15:22.152985 systemd-logind[1384]: Session 27 logged out. Waiting for processes to exit. Apr 22 15:15:22.154656 systemd[1]: Started sshd@27-10.0.0.74:22-10.0.0.1:43768.service - OpenSSH per-connection server daemon (10.0.0.1:43768). Apr 22 15:15:22.155342 systemd-logind[1384]: Removed session 27. Apr 22 15:15:22.206228 sshd[2650]: Accepted publickey for core from 10.0.0.1 port 43768 ssh2: RSA SHA256:vSMEaMy/bsMRI0wkzsr2vqgekxsKtnIZxYOZanmPdeI Apr 22 15:15:22.207440 sshd-session[2650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 22 15:15:22.211231 systemd-logind[1384]: New session 28 of user core. Apr 22 15:15:22.219418 systemd[1]: Started session-28.scope - Session 28 of User core. Apr 22 15:15:22.303369 kernel: docker0: port 1(veth2db7b40) entered blocking state Apr 22 15:15:22.303468 kernel: docker0: port 1(veth2db7b40) entered disabled state Apr 22 15:15:22.303498 kernel: veth2db7b40: entered allmulticast mode Apr 22 15:15:22.303516 kernel: veth2db7b40: entered promiscuous mode Apr 22 15:15:22.305649 systemd-networkd[1331]: veth2db7b40: Link UP Apr 22 15:15:22.329615 containerd[2364]: time="2025-04-22T15:15:22.329567909Z" level=info msg="connecting to shim 3897f302ffd0f064861d48112659121786f4a8c28597556bc70d3f580ecaae09" address="unix:///run/containerd/s/9f7d0eaca58cf2b57036f41ca785317497613c38b26c89f46150f85476d62cc0" namespace=moby protocol=ttrpc version=3 Apr 22 15:15:22.354350 systemd[1]: Started docker-3897f302ffd0f064861d48112659121786f4a8c28597556bc70d3f580ecaae09.scope - libcontainer container 3897f302ffd0f064861d48112659121786f4a8c28597556bc70d3f580ecaae09. Apr 22 15:15:22.396248 kernel: eth0: renamed from vethb90d9b0 Apr 22 15:15:22.405721 kernel: docker0: port 1(veth2db7b40) entered blocking state Apr 22 15:15:22.405758 kernel: docker0: port 1(veth2db7b40) entered forwarding state Apr 22 15:15:22.405795 systemd-networkd[1331]: veth2db7b40: Gained carrier Apr 22 15:15:22.405999 systemd-networkd[1331]: docker0: Gained carrier Apr 22 15:15:22.408442 systemd-resolved[1264]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 22 15:15:22.431331 sshd[2653]: Connection closed by 10.0.0.1 port 43768 Apr 22 15:15:22.431686 sshd-session[2650]: pam_unix(sshd:session): session closed for user core Apr 22 15:15:22.434806 systemd[1]: sshd@27-10.0.0.74:22-10.0.0.1:43768.service: Deactivated successfully. Apr 22 15:15:22.436381 systemd[1]: session-28.scope: Deactivated successfully. Apr 22 15:15:22.437674 systemd-logind[1384]: Session 28 logged out. Waiting for processes to exit. Apr 22 15:15:22.438414 systemd-logind[1384]: Removed session 28. Apr 22 15:15:23.925404 systemd-networkd[1331]: veth2db7b40: Gained IPv6LL