Mar 21 12:27:52.893673 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 21 12:27:52.893694 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 Fri Mar 21 10:53:54 -00 2025 Mar 21 12:27:52.893704 kernel: KASLR enabled Mar 21 12:27:52.893709 kernel: efi: EFI v2.7 by EDK II Mar 21 12:27:52.893715 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 Mar 21 12:27:52.893720 kernel: random: crng init done Mar 21 12:27:52.893727 kernel: secureboot: Secure boot disabled Mar 21 12:27:52.893732 kernel: ACPI: Early table checksum verification disabled Mar 21 12:27:52.893738 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Mar 21 12:27:52.893745 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Mar 21 12:27:52.893751 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893757 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893762 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893768 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893775 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893782 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893788 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893794 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893800 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:27:52.893806 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Mar 21 12:27:52.893812 kernel: NUMA: Failed to initialise from firmware Mar 21 12:27:52.893818 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Mar 21 12:27:52.893824 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Mar 21 12:27:52.893830 kernel: Zone ranges: Mar 21 12:27:52.893836 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Mar 21 12:27:52.893843 kernel: DMA32 empty Mar 21 12:27:52.893848 kernel: Normal empty Mar 21 12:27:52.893854 kernel: Movable zone start for each node Mar 21 12:27:52.893860 kernel: Early memory node ranges Mar 21 12:27:52.893866 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] Mar 21 12:27:52.893872 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] Mar 21 12:27:52.893878 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] Mar 21 12:27:52.893884 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Mar 21 12:27:52.893890 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Mar 21 12:27:52.893896 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Mar 21 12:27:52.893902 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Mar 21 12:27:52.893908 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Mar 21 12:27:52.893915 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Mar 21 12:27:52.893921 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Mar 21 12:27:52.893927 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Mar 21 12:27:52.893935 kernel: psci: probing for conduit method from ACPI. Mar 21 12:27:52.893942 kernel: psci: PSCIv1.1 detected in firmware. Mar 21 12:27:52.893948 kernel: psci: Using standard PSCI v0.2 function IDs Mar 21 12:27:52.893956 kernel: psci: Trusted OS migration not required Mar 21 12:27:52.893962 kernel: psci: SMC Calling Convention v1.1 Mar 21 12:27:52.893968 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Mar 21 12:27:52.893975 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Mar 21 12:27:52.893981 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Mar 21 12:27:52.893988 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 21 12:27:52.893994 kernel: Detected PIPT I-cache on CPU0 Mar 21 12:27:52.894000 kernel: CPU features: detected: GIC system register CPU interface Mar 21 12:27:52.894007 kernel: CPU features: detected: Hardware dirty bit management Mar 21 12:27:52.894013 kernel: CPU features: detected: Spectre-v4 Mar 21 12:27:52.894020 kernel: CPU features: detected: Spectre-BHB Mar 21 12:27:52.894027 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 21 12:27:52.894033 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 21 12:27:52.894039 kernel: CPU features: detected: ARM erratum 1418040 Mar 21 12:27:52.894046 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 21 12:27:52.894052 kernel: alternatives: applying boot alternatives Mar 21 12:27:52.894059 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=93cb17f03b776356c0810b716fff0c7c2012572bbe395c702f6873d17674684f Mar 21 12:27:52.894066 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 21 12:27:52.894072 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 21 12:27:52.894079 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 21 12:27:52.894085 kernel: Fallback order for Node 0: 0 Mar 21 12:27:52.894092 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Mar 21 12:27:52.894098 kernel: Policy zone: DMA Mar 21 12:27:52.894105 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 21 12:27:52.894111 kernel: software IO TLB: area num 4. Mar 21 12:27:52.894117 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Mar 21 12:27:52.894124 kernel: Memory: 2387412K/2572288K available (10304K kernel code, 2186K rwdata, 8096K rodata, 38464K init, 897K bss, 184876K reserved, 0K cma-reserved) Mar 21 12:27:52.894131 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 21 12:27:52.894137 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 21 12:27:52.894144 kernel: rcu: RCU event tracing is enabled. Mar 21 12:27:52.894150 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 21 12:27:52.894157 kernel: Trampoline variant of Tasks RCU enabled. Mar 21 12:27:52.894163 kernel: Tracing variant of Tasks RCU enabled. Mar 21 12:27:52.894171 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 21 12:27:52.894177 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 21 12:27:52.894184 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 21 12:27:52.894190 kernel: GICv3: 256 SPIs implemented Mar 21 12:27:52.894196 kernel: GICv3: 0 Extended SPIs implemented Mar 21 12:27:52.894203 kernel: Root IRQ handler: gic_handle_irq Mar 21 12:27:52.894209 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Mar 21 12:27:52.894215 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Mar 21 12:27:52.894222 kernel: ITS [mem 0x08080000-0x0809ffff] Mar 21 12:27:52.894229 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Mar 21 12:27:52.894235 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Mar 21 12:27:52.894243 kernel: GICv3: using LPI property table @0x00000000400f0000 Mar 21 12:27:52.894249 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Mar 21 12:27:52.894256 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 21 12:27:52.894262 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 21 12:27:52.894268 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 21 12:27:52.894276 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 21 12:27:52.894282 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 21 12:27:52.894288 kernel: arm-pv: using stolen time PV Mar 21 12:27:52.894295 kernel: Console: colour dummy device 80x25 Mar 21 12:27:52.894302 kernel: ACPI: Core revision 20230628 Mar 21 12:27:52.894309 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 21 12:27:52.894317 kernel: pid_max: default: 32768 minimum: 301 Mar 21 12:27:52.894323 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 21 12:27:52.894330 kernel: landlock: Up and running. Mar 21 12:27:52.894336 kernel: SELinux: Initializing. Mar 21 12:27:52.894343 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 21 12:27:52.894349 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 21 12:27:52.894356 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 21 12:27:52.894362 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 21 12:27:52.894369 kernel: rcu: Hierarchical SRCU implementation. Mar 21 12:27:52.894377 kernel: rcu: Max phase no-delay instances is 400. Mar 21 12:27:52.894384 kernel: Platform MSI: ITS@0x8080000 domain created Mar 21 12:27:52.894390 kernel: PCI/MSI: ITS@0x8080000 domain created Mar 21 12:27:52.894397 kernel: Remapping and enabling EFI services. Mar 21 12:27:52.894403 kernel: smp: Bringing up secondary CPUs ... Mar 21 12:27:52.894409 kernel: Detected PIPT I-cache on CPU1 Mar 21 12:27:52.894416 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Mar 21 12:27:52.894423 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Mar 21 12:27:52.894430 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 21 12:27:52.894438 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 21 12:27:52.894445 kernel: Detected PIPT I-cache on CPU2 Mar 21 12:27:52.894456 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Mar 21 12:27:52.894476 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Mar 21 12:27:52.894486 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 21 12:27:52.894505 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Mar 21 12:27:52.894512 kernel: Detected PIPT I-cache on CPU3 Mar 21 12:27:52.894520 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Mar 21 12:27:52.894527 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Mar 21 12:27:52.894536 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 21 12:27:52.894542 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Mar 21 12:27:52.894554 kernel: smp: Brought up 1 node, 4 CPUs Mar 21 12:27:52.894561 kernel: SMP: Total of 4 processors activated. Mar 21 12:27:52.894568 kernel: CPU features: detected: 32-bit EL0 Support Mar 21 12:27:52.894575 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 21 12:27:52.894582 kernel: CPU features: detected: Common not Private translations Mar 21 12:27:52.894589 kernel: CPU features: detected: CRC32 instructions Mar 21 12:27:52.894597 kernel: CPU features: detected: Enhanced Virtualization Traps Mar 21 12:27:52.894604 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 21 12:27:52.894611 kernel: CPU features: detected: LSE atomic instructions Mar 21 12:27:52.894618 kernel: CPU features: detected: Privileged Access Never Mar 21 12:27:52.894625 kernel: CPU features: detected: RAS Extension Support Mar 21 12:27:52.894632 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Mar 21 12:27:52.894639 kernel: CPU: All CPU(s) started at EL1 Mar 21 12:27:52.894646 kernel: alternatives: applying system-wide alternatives Mar 21 12:27:52.894653 kernel: devtmpfs: initialized Mar 21 12:27:52.894661 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 21 12:27:52.894668 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 21 12:27:52.894675 kernel: pinctrl core: initialized pinctrl subsystem Mar 21 12:27:52.894682 kernel: SMBIOS 3.0.0 present. Mar 21 12:27:52.894689 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Mar 21 12:27:52.894695 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 21 12:27:52.894702 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 21 12:27:52.894709 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 21 12:27:52.894716 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 21 12:27:52.894724 kernel: audit: initializing netlink subsys (disabled) Mar 21 12:27:52.894731 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 Mar 21 12:27:52.894738 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 21 12:27:52.894745 kernel: cpuidle: using governor menu Mar 21 12:27:52.894752 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 21 12:27:52.894759 kernel: ASID allocator initialised with 32768 entries Mar 21 12:27:52.894766 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 21 12:27:52.894772 kernel: Serial: AMBA PL011 UART driver Mar 21 12:27:52.894779 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Mar 21 12:27:52.894787 kernel: Modules: 0 pages in range for non-PLT usage Mar 21 12:27:52.894794 kernel: Modules: 509248 pages in range for PLT usage Mar 21 12:27:52.894801 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 21 12:27:52.894808 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Mar 21 12:27:52.894815 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Mar 21 12:27:52.894821 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Mar 21 12:27:52.894828 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 21 12:27:52.894835 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Mar 21 12:27:52.894842 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Mar 21 12:27:52.894850 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Mar 21 12:27:52.894856 kernel: ACPI: Added _OSI(Module Device) Mar 21 12:27:52.894863 kernel: ACPI: Added _OSI(Processor Device) Mar 21 12:27:52.894870 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 21 12:27:52.894877 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 21 12:27:52.894884 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 21 12:27:52.894891 kernel: ACPI: Interpreter enabled Mar 21 12:27:52.894897 kernel: ACPI: Using GIC for interrupt routing Mar 21 12:27:52.894904 kernel: ACPI: MCFG table detected, 1 entries Mar 21 12:27:52.894911 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Mar 21 12:27:52.894919 kernel: printk: console [ttyAMA0] enabled Mar 21 12:27:52.894926 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 21 12:27:52.895048 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 21 12:27:52.895120 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Mar 21 12:27:52.895191 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Mar 21 12:27:52.895254 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Mar 21 12:27:52.895318 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Mar 21 12:27:52.895329 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Mar 21 12:27:52.895336 kernel: PCI host bridge to bus 0000:00 Mar 21 12:27:52.895405 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Mar 21 12:27:52.895481 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Mar 21 12:27:52.895544 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Mar 21 12:27:52.895612 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 21 12:27:52.895707 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Mar 21 12:27:52.895788 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Mar 21 12:27:52.895855 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Mar 21 12:27:52.895920 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Mar 21 12:27:52.895986 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Mar 21 12:27:52.896050 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Mar 21 12:27:52.896117 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Mar 21 12:27:52.896182 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Mar 21 12:27:52.896242 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Mar 21 12:27:52.896299 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Mar 21 12:27:52.896357 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Mar 21 12:27:52.896366 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Mar 21 12:27:52.896373 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Mar 21 12:27:52.896380 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Mar 21 12:27:52.896387 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Mar 21 12:27:52.896396 kernel: iommu: Default domain type: Translated Mar 21 12:27:52.896403 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 21 12:27:52.896410 kernel: efivars: Registered efivars operations Mar 21 12:27:52.896417 kernel: vgaarb: loaded Mar 21 12:27:52.896424 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 21 12:27:52.896430 kernel: VFS: Disk quotas dquot_6.6.0 Mar 21 12:27:52.896437 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 21 12:27:52.896444 kernel: pnp: PnP ACPI init Mar 21 12:27:52.896595 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Mar 21 12:27:52.896611 kernel: pnp: PnP ACPI: found 1 devices Mar 21 12:27:52.896618 kernel: NET: Registered PF_INET protocol family Mar 21 12:27:52.896625 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 21 12:27:52.896632 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 21 12:27:52.896640 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 21 12:27:52.896646 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 21 12:27:52.896653 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 21 12:27:52.896660 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 21 12:27:52.896669 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 21 12:27:52.896676 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 21 12:27:52.896683 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 21 12:27:52.896689 kernel: PCI: CLS 0 bytes, default 64 Mar 21 12:27:52.896696 kernel: kvm [1]: HYP mode not available Mar 21 12:27:52.896703 kernel: Initialise system trusted keyrings Mar 21 12:27:52.896710 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 21 12:27:52.896717 kernel: Key type asymmetric registered Mar 21 12:27:52.896723 kernel: Asymmetric key parser 'x509' registered Mar 21 12:27:52.896730 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Mar 21 12:27:52.896738 kernel: io scheduler mq-deadline registered Mar 21 12:27:52.896745 kernel: io scheduler kyber registered Mar 21 12:27:52.896752 kernel: io scheduler bfq registered Mar 21 12:27:52.896759 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Mar 21 12:27:52.896766 kernel: ACPI: button: Power Button [PWRB] Mar 21 12:27:52.896773 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Mar 21 12:27:52.896839 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Mar 21 12:27:52.896849 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 21 12:27:52.896856 kernel: thunder_xcv, ver 1.0 Mar 21 12:27:52.896865 kernel: thunder_bgx, ver 1.0 Mar 21 12:27:52.896872 kernel: nicpf, ver 1.0 Mar 21 12:27:52.896879 kernel: nicvf, ver 1.0 Mar 21 12:27:52.896949 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 21 12:27:52.897009 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-21T12:27:52 UTC (1742560072) Mar 21 12:27:52.897019 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 21 12:27:52.897026 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Mar 21 12:27:52.897032 kernel: watchdog: Delayed init of the lockup detector failed: -19 Mar 21 12:27:52.897041 kernel: watchdog: Hard watchdog permanently disabled Mar 21 12:27:52.897048 kernel: NET: Registered PF_INET6 protocol family Mar 21 12:27:52.897055 kernel: Segment Routing with IPv6 Mar 21 12:27:52.897062 kernel: In-situ OAM (IOAM) with IPv6 Mar 21 12:27:52.897069 kernel: NET: Registered PF_PACKET protocol family Mar 21 12:27:52.897075 kernel: Key type dns_resolver registered Mar 21 12:27:52.897082 kernel: registered taskstats version 1 Mar 21 12:27:52.897089 kernel: Loading compiled-in X.509 certificates Mar 21 12:27:52.897096 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 5eb113f0b3321dedaccf2566eff1e4f54032526e' Mar 21 12:27:52.897104 kernel: Key type .fscrypt registered Mar 21 12:27:52.897111 kernel: Key type fscrypt-provisioning registered Mar 21 12:27:52.897117 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 21 12:27:52.897124 kernel: ima: Allocated hash algorithm: sha1 Mar 21 12:27:52.897131 kernel: ima: No architecture policies found Mar 21 12:27:52.897138 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 21 12:27:52.897145 kernel: clk: Disabling unused clocks Mar 21 12:27:52.897151 kernel: Freeing unused kernel memory: 38464K Mar 21 12:27:52.897158 kernel: Run /init as init process Mar 21 12:27:52.897166 kernel: with arguments: Mar 21 12:27:52.897172 kernel: /init Mar 21 12:27:52.897179 kernel: with environment: Mar 21 12:27:52.897185 kernel: HOME=/ Mar 21 12:27:52.897192 kernel: TERM=linux Mar 21 12:27:52.897198 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 21 12:27:52.897206 systemd[1]: Successfully made /usr/ read-only. Mar 21 12:27:52.897217 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) Mar 21 12:27:52.897226 systemd[1]: Detected virtualization kvm. Mar 21 12:27:52.897233 systemd[1]: Detected architecture arm64. Mar 21 12:27:52.897240 systemd[1]: Running in initrd. Mar 21 12:27:52.897248 systemd[1]: No hostname configured, using default hostname. Mar 21 12:27:52.897255 systemd[1]: Hostname set to . Mar 21 12:27:52.897263 systemd[1]: Initializing machine ID from VM UUID. Mar 21 12:27:52.897270 systemd[1]: Queued start job for default target initrd.target. Mar 21 12:27:52.897277 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:27:52.897286 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:27:52.897294 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 21 12:27:52.897302 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 21 12:27:52.897309 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 21 12:27:52.897317 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 21 12:27:52.897326 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 21 12:27:52.897335 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 21 12:27:52.897343 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:27:52.897350 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:27:52.897357 systemd[1]: Reached target paths.target - Path Units. Mar 21 12:27:52.897365 systemd[1]: Reached target slices.target - Slice Units. Mar 21 12:27:52.897372 systemd[1]: Reached target swap.target - Swaps. Mar 21 12:27:52.897379 systemd[1]: Reached target timers.target - Timer Units. Mar 21 12:27:52.897387 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 21 12:27:52.897394 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 21 12:27:52.897403 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 21 12:27:52.897410 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 21 12:27:52.897418 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:27:52.897425 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 21 12:27:52.897433 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:27:52.897440 systemd[1]: Reached target sockets.target - Socket Units. Mar 21 12:27:52.897447 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 21 12:27:52.897455 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 21 12:27:52.897473 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 21 12:27:52.897481 systemd[1]: Starting systemd-fsck-usr.service... Mar 21 12:27:52.897488 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 21 12:27:52.897495 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 21 12:27:52.897503 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:27:52.897510 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 21 12:27:52.897518 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:27:52.897527 systemd[1]: Finished systemd-fsck-usr.service. Mar 21 12:27:52.897535 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 21 12:27:52.897543 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:27:52.897571 systemd-journald[237]: Collecting audit messages is disabled. Mar 21 12:27:52.897591 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:27:52.897599 systemd-journald[237]: Journal started Mar 21 12:27:52.897617 systemd-journald[237]: Runtime Journal (/run/log/journal/6cf78d4ffeae43a3b7691b8264777e42) is 5.9M, max 47.3M, 41.4M free. Mar 21 12:27:52.884573 systemd-modules-load[239]: Inserted module 'overlay' Mar 21 12:27:52.899445 systemd[1]: Started systemd-journald.service - Journal Service. Mar 21 12:27:52.900743 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 21 12:27:52.903841 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 21 12:27:52.906963 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 21 12:27:52.907629 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 21 12:27:52.908891 kernel: Bridge firewalling registered Mar 21 12:27:52.907780 systemd-modules-load[239]: Inserted module 'br_netfilter' Mar 21 12:27:52.914228 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 21 12:27:52.915752 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:27:52.920056 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 21 12:27:52.922971 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 21 12:27:52.938660 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:27:52.940177 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:27:52.945705 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:27:52.948745 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 21 12:27:52.950825 dracut-cmdline[270]: dracut-dracut-053 Mar 21 12:27:52.953600 dracut-cmdline[270]: 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=93cb17f03b776356c0810b716fff0c7c2012572bbe395c702f6873d17674684f Mar 21 12:27:52.989959 systemd-resolved[285]: Positive Trust Anchors: Mar 21 12:27:52.989977 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 21 12:27:52.990009 systemd-resolved[285]: 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 Mar 21 12:27:52.998523 systemd-resolved[285]: Defaulting to hostname 'linux'. Mar 21 12:27:52.999625 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 21 12:27:53.000921 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:27:53.022483 kernel: SCSI subsystem initialized Mar 21 12:27:53.027493 kernel: Loading iSCSI transport class v2.0-870. Mar 21 12:27:53.034497 kernel: iscsi: registered transport (tcp) Mar 21 12:27:53.050500 kernel: iscsi: registered transport (qla4xxx) Mar 21 12:27:53.050538 kernel: QLogic iSCSI HBA Driver Mar 21 12:27:53.091527 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 21 12:27:53.093656 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 21 12:27:53.124402 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 21 12:27:53.124446 kernel: device-mapper: uevent: version 1.0.3 Mar 21 12:27:53.124456 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 21 12:27:53.169499 kernel: raid6: neonx8 gen() 13999 MB/s Mar 21 12:27:53.186491 kernel: raid6: neonx4 gen() 15785 MB/s Mar 21 12:27:53.203492 kernel: raid6: neonx2 gen() 13195 MB/s Mar 21 12:27:53.220494 kernel: raid6: neonx1 gen() 10397 MB/s Mar 21 12:27:53.237488 kernel: raid6: int64x8 gen() 6779 MB/s Mar 21 12:27:53.254487 kernel: raid6: int64x4 gen() 7341 MB/s Mar 21 12:27:53.271486 kernel: raid6: int64x2 gen() 6105 MB/s Mar 21 12:27:53.288624 kernel: raid6: int64x1 gen() 5033 MB/s Mar 21 12:27:53.288640 kernel: raid6: using algorithm neonx4 gen() 15785 MB/s Mar 21 12:27:53.306552 kernel: raid6: .... xor() 12348 MB/s, rmw enabled Mar 21 12:27:53.306582 kernel: raid6: using neon recovery algorithm Mar 21 12:27:53.311765 kernel: xor: measuring software checksum speed Mar 21 12:27:53.311780 kernel: 8regs : 21658 MB/sec Mar 21 12:27:53.312487 kernel: 32regs : 21687 MB/sec Mar 21 12:27:53.313745 kernel: arm64_neon : 22539 MB/sec Mar 21 12:27:53.313758 kernel: xor: using function: arm64_neon (22539 MB/sec) Mar 21 12:27:53.367498 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 21 12:27:53.378266 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 21 12:27:53.380910 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:27:53.408738 systemd-udevd[461]: Using default interface naming scheme 'v255'. Mar 21 12:27:53.412526 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:27:53.417081 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 21 12:27:53.437923 dracut-pre-trigger[467]: rd.md=0: removing MD RAID activation Mar 21 12:27:53.463616 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 21 12:27:53.465935 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 21 12:27:53.515979 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:27:53.518610 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 21 12:27:53.540778 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 21 12:27:53.544017 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 21 12:27:53.545274 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:27:53.549151 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 21 12:27:53.551108 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 21 12:27:53.570449 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Mar 21 12:27:53.577640 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Mar 21 12:27:53.577749 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:27:53.573996 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 21 12:27:53.578568 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 21 12:27:53.578677 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:27:53.581900 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:27:53.583011 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 21 12:27:53.583170 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:27:53.590317 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:27:53.599061 kernel: BTRFS: device fsid bdcda679-e2cc-43ec-88ed-d0a5c8807e76 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (511) Mar 21 12:27:53.595888 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:27:53.603489 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (521) Mar 21 12:27:53.617733 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 21 12:27:53.619134 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:27:53.632368 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 21 12:27:53.639549 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 21 12:27:53.640777 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 21 12:27:53.650024 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 21 12:27:53.652000 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 21 12:27:53.654038 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:27:53.675500 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:27:53.681492 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:27:53.682566 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:27:54.686002 disk-uuid[552]: The operation has completed successfully. Mar 21 12:27:54.687254 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:27:54.713594 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 21 12:27:54.713701 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 21 12:27:54.735879 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 21 12:27:54.757189 sh[571]: Success Mar 21 12:27:54.771498 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 21 12:27:54.798343 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 21 12:27:54.801005 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 21 12:27:54.811675 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 21 12:27:54.817874 kernel: BTRFS info (device dm-0): first mount of filesystem bdcda679-e2cc-43ec-88ed-d0a5c8807e76 Mar 21 12:27:54.817905 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Mar 21 12:27:54.817915 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 21 12:27:54.819679 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 21 12:27:54.819702 kernel: BTRFS info (device dm-0): using free space tree Mar 21 12:27:54.823444 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 21 12:27:54.824727 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 21 12:27:54.825375 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 21 12:27:54.828240 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 21 12:27:54.850129 kernel: BTRFS info (device vda6): first mount of filesystem fea78075-4b56-496a-88c9-8f4cfa7493bf Mar 21 12:27:54.850171 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 21 12:27:54.850181 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:27:54.852477 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:27:54.858488 kernel: BTRFS info (device vda6): last unmount of filesystem fea78075-4b56-496a-88c9-8f4cfa7493bf Mar 21 12:27:54.861359 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 21 12:27:54.863196 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 21 12:27:54.933354 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 21 12:27:54.937430 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 21 12:27:54.964456 ignition[667]: Ignition 2.20.0 Mar 21 12:27:54.964479 ignition[667]: Stage: fetch-offline Mar 21 12:27:54.964511 ignition[667]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:54.964519 ignition[667]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:54.964687 ignition[667]: parsed url from cmdline: "" Mar 21 12:27:54.964693 ignition[667]: no config URL provided Mar 21 12:27:54.964698 ignition[667]: reading system config file "/usr/lib/ignition/user.ign" Mar 21 12:27:54.964705 ignition[667]: no config at "/usr/lib/ignition/user.ign" Mar 21 12:27:54.964727 ignition[667]: op(1): [started] loading QEMU firmware config module Mar 21 12:27:54.964732 ignition[667]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 21 12:27:54.971930 ignition[667]: op(1): [finished] loading QEMU firmware config module Mar 21 12:27:54.975114 ignition[667]: parsing config with SHA512: dd00d4609c95e2b62444448ed7020a8c6a98f78574e0534f90c38f74564583ed1be0dfa766b5c74ae2c0ffb98d9d30fb78f3851af5a1cd71cb219a73abe802d8 Mar 21 12:27:54.982511 unknown[667]: fetched base config from "system" Mar 21 12:27:54.982523 unknown[667]: fetched user config from "qemu" Mar 21 12:27:54.982715 ignition[667]: fetch-offline: fetch-offline passed Mar 21 12:27:54.983259 systemd-networkd[761]: lo: Link UP Mar 21 12:27:54.982798 ignition[667]: Ignition finished successfully Mar 21 12:27:54.983263 systemd-networkd[761]: lo: Gained carrier Mar 21 12:27:54.984066 systemd-networkd[761]: Enumeration completed Mar 21 12:27:54.984237 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 21 12:27:54.986150 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 21 12:27:54.986151 systemd-networkd[761]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:27:54.986155 systemd-networkd[761]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 21 12:27:54.986870 systemd-networkd[761]: eth0: Link UP Mar 21 12:27:54.986873 systemd-networkd[761]: eth0: Gained carrier Mar 21 12:27:54.986880 systemd-networkd[761]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:27:54.988499 systemd[1]: Reached target network.target - Network. Mar 21 12:27:54.989535 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 21 12:27:54.990286 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 21 12:27:54.997532 systemd-networkd[761]: eth0: DHCPv4 address 10.0.0.65/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 21 12:27:55.016623 ignition[768]: Ignition 2.20.0 Mar 21 12:27:55.016632 ignition[768]: Stage: kargs Mar 21 12:27:55.016785 ignition[768]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:55.016794 ignition[768]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:55.017320 ignition[768]: kargs: kargs passed Mar 21 12:27:55.017357 ignition[768]: Ignition finished successfully Mar 21 12:27:55.020498 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 21 12:27:55.022348 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 21 12:27:55.042479 ignition[778]: Ignition 2.20.0 Mar 21 12:27:55.042490 ignition[778]: Stage: disks Mar 21 12:27:55.042650 ignition[778]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:55.042659 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:55.044667 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 21 12:27:55.043213 ignition[778]: disks: disks passed Mar 21 12:27:55.045840 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 21 12:27:55.043252 ignition[778]: Ignition finished successfully Mar 21 12:27:55.047679 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 21 12:27:55.049713 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 21 12:27:55.051237 systemd[1]: Reached target sysinit.target - System Initialization. Mar 21 12:27:55.053252 systemd[1]: Reached target basic.target - Basic System. Mar 21 12:27:55.055526 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 21 12:27:55.076873 systemd-fsck[789]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 21 12:27:55.080669 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 21 12:27:55.083548 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 21 12:27:55.137756 kernel: EXT4-fs (vda9): mounted filesystem 3004295c-1fab-4723-a953-2dc6fc131037 r/w with ordered data mode. Quota mode: none. Mar 21 12:27:55.138222 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 21 12:27:55.139443 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 21 12:27:55.142516 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 21 12:27:55.145008 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 21 12:27:55.146016 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 21 12:27:55.146058 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 21 12:27:55.146080 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 21 12:27:55.156942 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 21 12:27:55.160113 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 21 12:27:55.164481 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (797) Mar 21 12:27:55.164509 kernel: BTRFS info (device vda6): first mount of filesystem fea78075-4b56-496a-88c9-8f4cfa7493bf Mar 21 12:27:55.164520 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 21 12:27:55.166132 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:27:55.168501 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:27:55.169277 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 21 12:27:55.208323 initrd-setup-root[822]: cut: /sysroot/etc/passwd: No such file or directory Mar 21 12:27:55.212505 initrd-setup-root[829]: cut: /sysroot/etc/group: No such file or directory Mar 21 12:27:55.216641 initrd-setup-root[836]: cut: /sysroot/etc/shadow: No such file or directory Mar 21 12:27:55.220171 initrd-setup-root[843]: cut: /sysroot/etc/gshadow: No such file or directory Mar 21 12:27:55.286590 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 21 12:27:55.288882 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 21 12:27:55.290448 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 21 12:27:55.310496 kernel: BTRFS info (device vda6): last unmount of filesystem fea78075-4b56-496a-88c9-8f4cfa7493bf Mar 21 12:27:55.330943 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 21 12:27:55.333585 ignition[912]: INFO : Ignition 2.20.0 Mar 21 12:27:55.333585 ignition[912]: INFO : Stage: mount Mar 21 12:27:55.333585 ignition[912]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:55.333585 ignition[912]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:55.339033 ignition[912]: INFO : mount: mount passed Mar 21 12:27:55.339033 ignition[912]: INFO : Ignition finished successfully Mar 21 12:27:55.336064 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 21 12:27:55.338813 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 21 12:27:55.957639 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 21 12:27:55.959135 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 21 12:27:55.977486 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (925) Mar 21 12:27:55.977520 kernel: BTRFS info (device vda6): first mount of filesystem fea78075-4b56-496a-88c9-8f4cfa7493bf Mar 21 12:27:55.977531 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 21 12:27:55.979067 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:27:55.981474 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:27:55.982322 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 21 12:27:56.009815 ignition[942]: INFO : Ignition 2.20.0 Mar 21 12:27:56.009815 ignition[942]: INFO : Stage: files Mar 21 12:27:56.011437 ignition[942]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:56.011437 ignition[942]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:56.011437 ignition[942]: DEBUG : files: compiled without relabeling support, skipping Mar 21 12:27:56.011437 ignition[942]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 21 12:27:56.011437 ignition[942]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 21 12:27:56.017714 ignition[942]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 21 12:27:56.017714 ignition[942]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 21 12:27:56.017714 ignition[942]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 21 12:27:56.017714 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 21 12:27:56.017714 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(4): [started] processing unit "docker.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(4): [finished] processing unit "docker.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Mar 21 12:27:56.017714 ignition[942]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Mar 21 12:27:56.013852 unknown[942]: wrote ssh authorized keys file for user: core Mar 21 12:27:56.039164 ignition[942]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 21 12:27:56.039164 ignition[942]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 21 12:27:56.039164 ignition[942]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Mar 21 12:27:56.039164 ignition[942]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Mar 21 12:27:56.039164 ignition[942]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Mar 21 12:27:56.039164 ignition[942]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 21 12:27:56.039164 ignition[942]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 21 12:27:56.039164 ignition[942]: INFO : files: files passed Mar 21 12:27:56.039164 ignition[942]: INFO : Ignition finished successfully Mar 21 12:27:56.034831 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 21 12:27:56.037046 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 21 12:27:56.039041 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 21 12:27:56.051505 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 21 12:27:56.056829 initrd-setup-root-after-ignition[970]: grep: /sysroot/oem/oem-release: No such file or directory Mar 21 12:27:56.051594 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 21 12:27:56.060165 initrd-setup-root-after-ignition[972]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:27:56.060165 initrd-setup-root-after-ignition[972]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:27:56.057257 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 21 12:27:56.066046 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:27:56.059359 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 21 12:27:56.061833 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 21 12:27:56.093043 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 21 12:27:56.093148 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 21 12:27:56.095308 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 21 12:27:56.097143 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 21 12:27:56.098934 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 21 12:27:56.099592 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 21 12:27:56.127597 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 21 12:27:56.129989 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 21 12:27:56.154180 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:27:56.155479 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:27:56.157640 systemd[1]: Stopped target timers.target - Timer Units. Mar 21 12:27:56.159427 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 21 12:27:56.159588 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 21 12:27:56.162123 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 21 12:27:56.164212 systemd[1]: Stopped target basic.target - Basic System. Mar 21 12:27:56.165909 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 21 12:27:56.167639 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 21 12:27:56.169614 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 21 12:27:56.171664 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 21 12:27:56.173587 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 21 12:27:56.175646 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 21 12:27:56.177645 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 21 12:27:56.179361 systemd[1]: Stopped target swap.target - Swaps. Mar 21 12:27:56.180922 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 21 12:27:56.181051 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 21 12:27:56.183385 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:27:56.184587 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:27:56.186612 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 21 12:27:56.186697 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:27:56.188656 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 21 12:27:56.188776 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 21 12:27:56.191444 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 21 12:27:56.191590 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 21 12:27:56.193957 systemd[1]: Stopped target paths.target - Path Units. Mar 21 12:27:56.195433 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 21 12:27:56.200491 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:27:56.201738 systemd[1]: Stopped target slices.target - Slice Units. Mar 21 12:27:56.203804 systemd[1]: Stopped target sockets.target - Socket Units. Mar 21 12:27:56.205428 systemd[1]: iscsid.socket: Deactivated successfully. Mar 21 12:27:56.205517 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 21 12:27:56.207143 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 21 12:27:56.207217 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 21 12:27:56.208775 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 21 12:27:56.208883 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 21 12:27:56.210661 systemd[1]: ignition-files.service: Deactivated successfully. Mar 21 12:27:56.210758 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 21 12:27:56.213094 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 21 12:27:56.214982 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 21 12:27:56.215104 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:27:56.228047 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 21 12:27:56.228946 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 21 12:27:56.229066 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:27:56.231038 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 21 12:27:56.231135 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 21 12:27:56.237412 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 21 12:27:56.237530 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 21 12:27:56.242615 ignition[997]: INFO : Ignition 2.20.0 Mar 21 12:27:56.242615 ignition[997]: INFO : Stage: umount Mar 21 12:27:56.242615 ignition[997]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:27:56.242615 ignition[997]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:27:56.242615 ignition[997]: INFO : umount: umount passed Mar 21 12:27:56.242615 ignition[997]: INFO : Ignition finished successfully Mar 21 12:27:56.240619 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 21 12:27:56.240713 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 21 12:27:56.245030 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 21 12:27:56.245394 systemd[1]: Stopped target network.target - Network. Mar 21 12:27:56.247047 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 21 12:27:56.247100 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 21 12:27:56.249061 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 21 12:27:56.249102 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 21 12:27:56.250580 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 21 12:27:56.250626 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 21 12:27:56.252221 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 21 12:27:56.252260 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 21 12:27:56.254169 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 21 12:27:56.255887 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 21 12:27:56.257712 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 21 12:27:56.257784 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 21 12:27:56.259603 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 21 12:27:56.259710 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 21 12:27:56.265391 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 21 12:27:56.265553 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 21 12:27:56.268676 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 21 12:27:56.268899 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 21 12:27:56.268933 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:27:56.272644 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 21 12:27:56.273583 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 21 12:27:56.273679 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 21 12:27:56.275906 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 21 12:27:56.275933 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:27:56.278448 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 21 12:27:56.279532 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 21 12:27:56.279605 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 21 12:27:56.281567 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 21 12:27:56.281609 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:27:56.284405 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 21 12:27:56.284445 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 21 12:27:56.286646 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:27:56.300147 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 21 12:27:56.301486 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 21 12:27:56.303511 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 21 12:27:56.303647 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:27:56.305942 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 21 12:27:56.305985 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 21 12:27:56.307128 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 21 12:27:56.307159 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:27:56.308886 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 21 12:27:56.308932 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 21 12:27:56.311967 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 21 12:27:56.312015 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 21 12:27:56.314665 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 21 12:27:56.314712 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:27:56.318226 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 21 12:27:56.319483 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 21 12:27:56.319543 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:27:56.322469 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 21 12:27:56.322516 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:27:56.336448 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 21 12:27:56.336570 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 21 12:27:56.338728 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 21 12:27:56.341051 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 21 12:27:56.357971 systemd[1]: Switching root. Mar 21 12:27:56.388451 systemd-journald[237]: Journal stopped Mar 21 12:27:57.066183 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Mar 21 12:27:57.066239 kernel: SELinux: policy capability network_peer_controls=1 Mar 21 12:27:57.066253 kernel: SELinux: policy capability open_perms=1 Mar 21 12:27:57.066263 kernel: SELinux: policy capability extended_socket_class=1 Mar 21 12:27:57.066272 kernel: SELinux: policy capability always_check_network=0 Mar 21 12:27:57.066281 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 21 12:27:57.066290 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 21 12:27:57.066298 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 21 12:27:57.066307 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 21 12:27:57.066316 kernel: audit: type=1403 audit(1742560076.454:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 21 12:27:57.066331 systemd[1]: Successfully loaded SELinux policy in 32.342ms. Mar 21 12:27:57.066350 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.309ms. Mar 21 12:27:57.066363 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) Mar 21 12:27:57.066374 systemd[1]: Detected virtualization kvm. Mar 21 12:27:57.066384 systemd[1]: Detected architecture arm64. Mar 21 12:27:57.066397 systemd[1]: Detected first boot. Mar 21 12:27:57.066407 systemd[1]: Initializing machine ID from VM UUID. Mar 21 12:27:57.066417 zram_generator::config[1042]: No configuration found. Mar 21 12:27:57.066427 kernel: NET: Registered PF_VSOCK protocol family Mar 21 12:27:57.066438 systemd[1]: Populated /etc with preset unit settings. Mar 21 12:27:57.066449 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 21 12:27:57.066459 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 21 12:27:57.066482 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 21 12:27:57.066492 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 21 12:27:57.066502 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 21 12:27:57.066512 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 21 12:27:57.066521 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 21 12:27:57.066533 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 21 12:27:57.066551 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 21 12:27:57.066563 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 21 12:27:57.066573 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 21 12:27:57.066583 systemd[1]: Created slice user.slice - User and Session Slice. Mar 21 12:27:57.066593 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:27:57.066605 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:27:57.066616 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 21 12:27:57.066626 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 21 12:27:57.066636 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 21 12:27:57.066646 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 21 12:27:57.066656 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Mar 21 12:27:57.066666 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:27:57.066676 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 21 12:27:57.066687 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 21 12:27:57.066697 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 21 12:27:57.066707 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 21 12:27:57.066716 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:27:57.066726 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 21 12:27:57.066736 systemd[1]: Reached target slices.target - Slice Units. Mar 21 12:27:57.066759 systemd[1]: Reached target swap.target - Swaps. Mar 21 12:27:57.066769 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 21 12:27:57.066779 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 21 12:27:57.066790 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 21 12:27:57.066800 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:27:57.066810 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 21 12:27:57.066820 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:27:57.066831 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 21 12:27:57.066841 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 21 12:27:57.066852 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 21 12:27:57.066862 systemd[1]: Mounting media.mount - External Media Directory... Mar 21 12:27:57.066872 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 21 12:27:57.066883 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 21 12:27:57.066893 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 21 12:27:57.066903 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). Mar 21 12:27:57.066913 systemd[1]: Reached target machines.target - Containers. Mar 21 12:27:57.066923 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 21 12:27:57.066933 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:27:57.066944 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 21 12:27:57.066954 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 21 12:27:57.066965 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:27:57.066975 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 21 12:27:57.066985 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:27:57.066995 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 21 12:27:57.067005 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:27:57.067015 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 21 12:27:57.067025 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 21 12:27:57.067035 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 21 12:27:57.067047 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 21 12:27:57.067057 systemd[1]: Stopped systemd-fsck-usr.service. Mar 21 12:27:57.067067 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). Mar 21 12:27:57.067077 kernel: fuse: init (API version 7.39) Mar 21 12:27:57.067086 kernel: loop: module loaded Mar 21 12:27:57.067095 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 21 12:27:57.067105 kernel: ACPI: bus type drm_connector registered Mar 21 12:27:57.067114 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 21 12:27:57.067124 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 21 12:27:57.067135 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 21 12:27:57.067145 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 21 12:27:57.067155 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 21 12:27:57.067179 systemd-journald[1107]: Collecting audit messages is disabled. Mar 21 12:27:57.067202 systemd-journald[1107]: Journal started Mar 21 12:27:57.067222 systemd-journald[1107]: Runtime Journal (/run/log/journal/6cf78d4ffeae43a3b7691b8264777e42) is 5.9M, max 47.3M, 41.4M free. Mar 21 12:27:56.861765 systemd[1]: Queued start job for default target multi-user.target. Mar 21 12:27:56.877443 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 21 12:27:56.877786 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 21 12:27:57.069674 systemd[1]: verity-setup.service: Deactivated successfully. Mar 21 12:27:57.069700 systemd[1]: Stopped verity-setup.service. Mar 21 12:27:57.075488 systemd[1]: Started systemd-journald.service - Journal Service. Mar 21 12:27:57.075359 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 21 12:27:57.076566 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 21 12:27:57.077761 systemd[1]: Mounted media.mount - External Media Directory. Mar 21 12:27:57.078809 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 21 12:27:57.079980 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 21 12:27:57.081168 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 21 12:27:57.082373 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 21 12:27:57.084802 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:27:57.086224 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 21 12:27:57.086393 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 21 12:27:57.087789 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:27:57.087939 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:27:57.089220 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 21 12:27:57.089367 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 21 12:27:57.090666 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:27:57.090817 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:27:57.092331 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 21 12:27:57.092492 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 21 12:27:57.093745 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:27:57.093894 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:27:57.095182 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 21 12:27:57.096680 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 21 12:27:57.098117 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 21 12:27:57.099575 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 21 12:27:57.110713 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 21 12:27:57.112959 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 21 12:27:57.114913 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 21 12:27:57.116094 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 21 12:27:57.116127 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 21 12:27:57.118001 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 21 12:27:57.125238 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 21 12:27:57.127226 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 21 12:27:57.128306 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:27:57.129496 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 21 12:27:57.131252 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 21 12:27:57.132476 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 21 12:27:57.139277 systemd-journald[1107]: Time spent on flushing to /var/log/journal/6cf78d4ffeae43a3b7691b8264777e42 is 18.385ms for 831 entries. Mar 21 12:27:57.139277 systemd-journald[1107]: System Journal (/var/log/journal/6cf78d4ffeae43a3b7691b8264777e42) is 8M, max 195.6M, 187.6M free. Mar 21 12:27:57.163588 systemd-journald[1107]: Received client request to flush runtime journal. Mar 21 12:27:57.163631 kernel: loop0: detected capacity change from 0 to 126448 Mar 21 12:27:57.136217 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 21 12:27:57.137321 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 21 12:27:57.138131 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 21 12:27:57.140607 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 21 12:27:57.145514 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 21 12:27:57.148517 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:27:57.150019 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 21 12:27:57.151770 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 21 12:27:57.153486 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 21 12:27:57.159293 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 21 12:27:57.160857 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:27:57.162199 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 21 12:27:57.166553 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 21 12:27:57.168705 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 21 12:27:57.171225 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 21 12:27:57.183487 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 21 12:27:57.187488 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 21 12:27:57.190214 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 21 12:27:57.191974 udevadm[1170]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 21 12:27:57.204169 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 21 12:27:57.206110 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 21 12:27:57.220945 kernel: loop1: detected capacity change from 0 to 103832 Mar 21 12:27:57.221839 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Mar 21 12:27:57.222083 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Mar 21 12:27:57.226050 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:27:57.258493 kernel: loop2: detected capacity change from 0 to 126448 Mar 21 12:27:57.263483 kernel: loop3: detected capacity change from 0 to 103832 Mar 21 12:27:57.266939 (sd-merge)[1183]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Mar 21 12:27:57.267250 (sd-merge)[1183]: Merged extensions into '/usr'. Mar 21 12:27:57.270799 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 21 12:27:57.275078 systemd[1]: Starting ensure-sysext.service... Mar 21 12:27:57.279588 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 21 12:27:57.294067 systemd[1]: Reload requested from client PID 1185 ('systemctl') (unit ensure-sysext.service)... Mar 21 12:27:57.294082 systemd[1]: Reloading... Mar 21 12:27:57.308134 systemd-tmpfiles[1186]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 21 12:27:57.308331 systemd-tmpfiles[1186]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 21 12:27:57.308970 systemd-tmpfiles[1186]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 21 12:27:57.309167 systemd-tmpfiles[1186]: ACLs are not supported, ignoring. Mar 21 12:27:57.309213 systemd-tmpfiles[1186]: ACLs are not supported, ignoring. Mar 21 12:27:57.312161 systemd-tmpfiles[1186]: Detected autofs mount point /boot during canonicalization of boot. Mar 21 12:27:57.312174 systemd-tmpfiles[1186]: Skipping /boot Mar 21 12:27:57.322982 systemd-tmpfiles[1186]: Detected autofs mount point /boot during canonicalization of boot. Mar 21 12:27:57.322996 systemd-tmpfiles[1186]: Skipping /boot Mar 21 12:27:57.356986 zram_generator::config[1216]: No configuration found. Mar 21 12:27:57.411511 ldconfig[1154]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 21 12:27:57.450100 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. Mar 21 12:27:57.500670 systemd[1]: Reloading finished in 206 ms. Mar 21 12:27:57.524506 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 21 12:27:57.541504 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:27:57.552494 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 21 12:27:57.569183 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 21 12:27:57.573709 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 21 12:27:57.588122 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 21 12:27:57.591601 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 21 12:27:57.594524 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 21 12:27:57.603760 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:27:57.606146 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:27:57.608787 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:27:57.613363 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:27:57.615058 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:27:57.615174 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). Mar 21 12:27:57.617320 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:27:57.625687 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 21 12:27:57.628049 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 21 12:27:57.630980 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:27:57.631129 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:27:57.632864 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:27:57.633002 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:27:57.634737 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:27:57.634896 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:27:57.641818 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:27:57.643323 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:27:57.646143 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:27:57.649067 augenrules[1288]: No rules Mar 21 12:27:57.660750 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:27:57.661699 systemd-udevd[1277]: Using default interface naming scheme 'v255'. Mar 21 12:27:57.661861 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:27:57.662038 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). Mar 21 12:27:57.663177 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 21 12:27:57.668603 systemd[1]: audit-rules.service: Deactivated successfully. Mar 21 12:27:57.669417 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 21 12:27:57.671894 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 21 12:27:57.674721 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 21 12:27:57.687301 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:27:57.689238 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:27:57.689983 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:27:57.692758 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:27:57.692923 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:27:57.694599 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:27:57.694742 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:27:57.696328 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 21 12:27:57.702764 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 21 12:27:57.717091 systemd[1]: Finished ensure-sysext.service. Mar 21 12:27:57.724889 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 21 12:27:57.726595 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:27:57.728093 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:27:57.733766 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 21 12:27:57.739702 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:27:57.745732 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:27:57.746845 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:27:57.746887 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). Mar 21 12:27:57.747146 systemd-resolved[1256]: Positive Trust Anchors: Mar 21 12:27:57.747362 systemd-resolved[1256]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 21 12:27:57.747440 systemd-resolved[1256]: 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 Mar 21 12:27:57.750716 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 21 12:27:57.756954 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 21 12:27:57.758172 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). Mar 21 12:27:57.758765 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:27:57.760743 augenrules[1328]: /sbin/augenrules: No change Mar 21 12:27:57.760772 systemd-resolved[1256]: Defaulting to hostname 'linux'. Mar 21 12:27:57.761012 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:27:57.762511 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 21 12:27:57.762703 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 21 12:27:57.765772 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:27:57.765941 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:27:57.767715 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:27:57.767874 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:27:57.768445 augenrules[1352]: No rules Mar 21 12:27:57.769254 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 21 12:27:57.770935 systemd[1]: audit-rules.service: Deactivated successfully. Mar 21 12:27:57.771130 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 21 12:27:57.776361 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Mar 21 12:27:57.777228 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:27:57.778822 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 21 12:27:57.778875 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 21 12:27:57.785486 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1308) Mar 21 12:27:57.814485 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 21 12:27:57.818165 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 21 12:27:57.842178 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 21 12:27:57.855806 systemd-networkd[1340]: lo: Link UP Mar 21 12:27:57.855815 systemd-networkd[1340]: lo: Gained carrier Mar 21 12:27:57.856981 systemd-networkd[1340]: Enumeration completed Mar 21 12:27:57.857674 systemd-networkd[1340]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:27:57.857739 systemd-networkd[1340]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 21 12:27:57.858288 systemd-networkd[1340]: eth0: Link UP Mar 21 12:27:57.858353 systemd-networkd[1340]: eth0: Gained carrier Mar 21 12:27:57.858402 systemd-networkd[1340]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:27:57.859716 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 21 12:27:57.861173 systemd[1]: Reached target network.target - Network. Mar 21 12:27:57.863441 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 21 12:27:57.865565 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 21 12:27:57.867397 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 21 12:27:57.869107 systemd[1]: Reached target time-set.target - System Time Set. Mar 21 12:27:57.880555 systemd-networkd[1340]: eth0: DHCPv4 address 10.0.0.65/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 21 12:27:57.881769 systemd-timesyncd[1342]: Network configuration changed, trying to establish connection. Mar 21 12:27:57.882408 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:27:57.443606 systemd-timesyncd[1342]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 21 12:27:57.451855 systemd-journald[1107]: Time jumped backwards, rotating. Mar 21 12:27:57.443653 systemd-timesyncd[1342]: Initial clock synchronization to Fri 2025-03-21 12:27:57.443531 UTC. Mar 21 12:27:57.445307 systemd-resolved[1256]: Clock change detected. Flushing caches. Mar 21 12:27:57.452806 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 21 12:27:57.454490 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 21 12:27:57.458604 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 21 12:27:57.481236 lvm[1384]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 21 12:27:57.498507 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:27:57.515534 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 21 12:27:57.517016 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:27:57.519569 systemd[1]: Reached target sysinit.target - System Initialization. Mar 21 12:27:57.520758 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 21 12:27:57.522046 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 21 12:27:57.523461 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 21 12:27:57.524669 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 21 12:27:57.525890 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 21 12:27:57.527098 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 21 12:27:57.527132 systemd[1]: Reached target paths.target - Path Units. Mar 21 12:27:57.528025 systemd[1]: Reached target timers.target - Timer Units. Mar 21 12:27:57.529861 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 21 12:27:57.532125 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 21 12:27:57.535284 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 21 12:27:57.536735 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 21 12:27:57.537948 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 21 12:27:57.540994 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 21 12:27:57.542379 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 21 12:27:57.544680 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 21 12:27:57.546280 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 21 12:27:57.547471 systemd[1]: Reached target sockets.target - Socket Units. Mar 21 12:27:57.548398 systemd[1]: Reached target basic.target - Basic System. Mar 21 12:27:57.549355 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 21 12:27:57.549391 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 21 12:27:57.550312 systemd[1]: Starting containerd.service - containerd container runtime... Mar 21 12:27:57.552227 lvm[1392]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 21 12:27:57.552527 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 21 12:27:57.555674 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 21 12:27:57.560608 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 21 12:27:57.561642 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). Mar 21 12:27:57.562599 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 21 12:27:57.565348 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 21 12:27:57.567656 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 21 12:27:57.572338 jq[1395]: false Mar 21 12:27:57.574191 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 21 12:27:57.576167 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 21 12:27:57.576759 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 21 12:27:57.579161 systemd[1]: Starting update-engine.service - Update Engine... Mar 21 12:27:57.582538 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 21 12:27:57.584724 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 21 12:27:57.588389 extend-filesystems[1396]: Found loop2 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found loop3 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda1 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda2 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda3 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found usr Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda4 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda6 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda7 Mar 21 12:27:57.590572 extend-filesystems[1396]: Found vda9 Mar 21 12:27:57.590572 extend-filesystems[1396]: Checking size of /dev/vda9 Mar 21 12:27:57.599880 dbus-daemon[1394]: [system] SELinux support is enabled Mar 21 12:27:57.590883 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 21 12:27:57.591066 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 21 12:27:57.611342 jq[1409]: true Mar 21 12:27:57.591318 systemd[1]: motdgen.service: Deactivated successfully. Mar 21 12:27:57.591555 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 21 12:27:57.594877 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 21 12:27:57.596695 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 21 12:27:57.602940 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 21 12:27:57.609899 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). Mar 21 12:27:57.609961 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 21 12:27:57.613053 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). Mar 21 12:27:57.613072 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 21 12:27:57.615488 extend-filesystems[1396]: Old size kept for /dev/vda9 Mar 21 12:27:57.623748 (ntainerd)[1416]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 21 12:27:57.623906 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 21 12:27:57.624103 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 21 12:27:57.635543 jq[1415]: true Mar 21 12:27:57.646495 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1305) Mar 21 12:27:57.652983 systemd-logind[1400]: Watching system buttons on /dev/input/event0 (Power Button) Mar 21 12:27:57.653455 systemd-logind[1400]: New seat seat0. Mar 21 12:27:57.655676 systemd[1]: Started systemd-logind.service - User Login Management. Mar 21 12:27:57.662151 update_engine[1404]: I20250321 12:27:57.661998 1404 main.cc:92] Flatcar Update Engine starting Mar 21 12:27:57.669156 systemd[1]: Started update-engine.service - Update Engine. Mar 21 12:27:57.671891 update_engine[1404]: I20250321 12:27:57.671840 1404 update_check_scheduler.cc:74] Next update check in 10m3s Mar 21 12:27:57.673582 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 21 12:27:57.688341 bash[1444]: Updated "/home/core/.ssh/authorized_keys" Mar 21 12:27:57.690955 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 21 12:27:57.693023 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 21 12:27:57.730175 locksmithd[1443]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 21 12:27:57.826785 containerd[1416]: time="2025-03-21T12:27:57Z" 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 Mar 21 12:27:57.829354 containerd[1416]: time="2025-03-21T12:27:57.829318016Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 21 12:27:57.838328 containerd[1416]: time="2025-03-21T12:27:57.838272176Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.6µs" Mar 21 12:27:57.838328 containerd[1416]: time="2025-03-21T12:27:57.838305856Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 21 12:27:57.838328 containerd[1416]: time="2025-03-21T12:27:57.838330096Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 21 12:27:57.838520 containerd[1416]: time="2025-03-21T12:27:57.838483216Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 21 12:27:57.838520 containerd[1416]: time="2025-03-21T12:27:57.838501416Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 21 12:27:57.838583 containerd[1416]: time="2025-03-21T12:27:57.838538776Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838614 containerd[1416]: time="2025-03-21T12:27:57.838589416Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838614 containerd[1416]: time="2025-03-21T12:27:57.838600936Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838877 containerd[1416]: time="2025-03-21T12:27:57.838841216Z" 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 Mar 21 12:27:57.838877 containerd[1416]: time="2025-03-21T12:27:57.838865376Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838915 containerd[1416]: time="2025-03-21T12:27:57.838876216Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838915 containerd[1416]: time="2025-03-21T12:27:57.838884496Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 21 12:27:57.838968 containerd[1416]: time="2025-03-21T12:27:57.838952816Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 21 12:27:57.839163 containerd[1416]: time="2025-03-21T12:27:57.839137496Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 21 12:27:57.839183 containerd[1416]: time="2025-03-21T12:27:57.839174296Z" 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 Mar 21 12:27:57.839200 containerd[1416]: time="2025-03-21T12:27:57.839184656Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 21 12:27:57.839228 containerd[1416]: time="2025-03-21T12:27:57.839216136Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 21 12:27:57.839493 containerd[1416]: time="2025-03-21T12:27:57.839474656Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 21 12:27:57.839570 containerd[1416]: time="2025-03-21T12:27:57.839553016Z" level=info msg="metadata content store policy set" policy=shared Mar 21 12:27:57.843744 containerd[1416]: time="2025-03-21T12:27:57.843710416Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 21 12:27:57.843783 containerd[1416]: time="2025-03-21T12:27:57.843754776Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 21 12:27:57.843783 containerd[1416]: time="2025-03-21T12:27:57.843769896Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 21 12:27:57.843829 containerd[1416]: time="2025-03-21T12:27:57.843783416Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 21 12:27:57.843829 containerd[1416]: time="2025-03-21T12:27:57.843795976Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 21 12:27:57.843829 containerd[1416]: time="2025-03-21T12:27:57.843806496Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 21 12:27:57.843829 containerd[1416]: time="2025-03-21T12:27:57.843826296Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 21 12:27:57.843894 containerd[1416]: time="2025-03-21T12:27:57.843839536Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 21 12:27:57.843894 containerd[1416]: time="2025-03-21T12:27:57.843852136Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 21 12:27:57.843894 containerd[1416]: time="2025-03-21T12:27:57.843865176Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 21 12:27:57.843894 containerd[1416]: time="2025-03-21T12:27:57.843874136Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 21 12:27:57.843894 containerd[1416]: time="2025-03-21T12:27:57.843888336Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 21 12:27:57.844039 containerd[1416]: time="2025-03-21T12:27:57.843994016Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 21 12:27:57.844039 containerd[1416]: time="2025-03-21T12:27:57.844022936Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 21 12:27:57.844039 containerd[1416]: time="2025-03-21T12:27:57.844035536Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 21 12:27:57.844100 containerd[1416]: time="2025-03-21T12:27:57.844049216Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 21 12:27:57.844100 containerd[1416]: time="2025-03-21T12:27:57.844060376Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 21 12:27:57.844100 containerd[1416]: time="2025-03-21T12:27:57.844069936Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 21 12:27:57.844100 containerd[1416]: time="2025-03-21T12:27:57.844080896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 21 12:27:57.844100 containerd[1416]: time="2025-03-21T12:27:57.844091056Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 21 12:27:57.844181 containerd[1416]: time="2025-03-21T12:27:57.844102056Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 21 12:27:57.844181 containerd[1416]: time="2025-03-21T12:27:57.844112936Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 21 12:27:57.844181 containerd[1416]: time="2025-03-21T12:27:57.844122776Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 21 12:27:57.846214 containerd[1416]: time="2025-03-21T12:27:57.846186176Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 21 12:27:57.846252 containerd[1416]: time="2025-03-21T12:27:57.846216536Z" level=info msg="Start snapshots syncer" Mar 21 12:27:57.846252 containerd[1416]: time="2025-03-21T12:27:57.846243336Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 21 12:27:57.846512 containerd[1416]: time="2025-03-21T12:27:57.846460736Z" 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\"}" Mar 21 12:27:57.846617 containerd[1416]: time="2025-03-21T12:27:57.846521856Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 21 12:27:57.846617 containerd[1416]: time="2025-03-21T12:27:57.846587216Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 21 12:27:57.846703 containerd[1416]: time="2025-03-21T12:27:57.846681216Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846709576Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846721896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846733816Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846745056Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846754696Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846770696Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846793896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 21 12:27:57.846800 containerd[1416]: time="2025-03-21T12:27:57.846804856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 21 12:27:57.846928 containerd[1416]: time="2025-03-21T12:27:57.846814416Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 21 12:27:57.847521 containerd[1416]: time="2025-03-21T12:27:57.847481936Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 21 12:27:57.847555 containerd[1416]: time="2025-03-21T12:27:57.847521816Z" 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 Mar 21 12:27:57.847555 containerd[1416]: time="2025-03-21T12:27:57.847532976Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:27:57.847555 containerd[1416]: time="2025-03-21T12:27:57.847543856Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:27:57.847555 containerd[1416]: time="2025-03-21T12:27:57.847551416Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 21 12:27:57.847628 containerd[1416]: time="2025-03-21T12:27:57.847575856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 21 12:27:57.847628 containerd[1416]: time="2025-03-21T12:27:57.847587656Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 21 12:27:57.847723 containerd[1416]: time="2025-03-21T12:27:57.847663136Z" level=info msg="runtime interface created" Mar 21 12:27:57.847723 containerd[1416]: time="2025-03-21T12:27:57.847672656Z" level=info msg="created NRI interface" Mar 21 12:27:57.847723 containerd[1416]: time="2025-03-21T12:27:57.847684256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 21 12:27:57.847723 containerd[1416]: time="2025-03-21T12:27:57.847695416Z" level=info msg="Connect containerd service" Mar 21 12:27:57.847830 containerd[1416]: time="2025-03-21T12:27:57.847726736Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 21 12:27:57.849375 containerd[1416]: time="2025-03-21T12:27:57.849069056Z" 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" Mar 21 12:27:57.952869 containerd[1416]: time="2025-03-21T12:27:57.952818056Z" level=info msg="Start subscribing containerd event" Mar 21 12:27:57.952973 containerd[1416]: time="2025-03-21T12:27:57.952885576Z" level=info msg="Start recovering state" Mar 21 12:27:57.953122 containerd[1416]: time="2025-03-21T12:27:57.953075616Z" level=info msg="Start event monitor" Mar 21 12:27:57.953122 containerd[1416]: time="2025-03-21T12:27:57.953095896Z" level=info msg="Start cni network conf syncer for default" Mar 21 12:27:57.953122 containerd[1416]: time="2025-03-21T12:27:57.953106896Z" level=info msg="Start streaming server" Mar 21 12:27:57.953122 containerd[1416]: time="2025-03-21T12:27:57.953116216Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 21 12:27:57.953122 containerd[1416]: time="2025-03-21T12:27:57.953124256Z" level=info msg="runtime interface starting up..." Mar 21 12:27:57.953229 containerd[1416]: time="2025-03-21T12:27:57.953130496Z" level=info msg="starting plugins..." Mar 21 12:27:57.953229 containerd[1416]: time="2025-03-21T12:27:57.953151136Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 21 12:27:57.953422 containerd[1416]: time="2025-03-21T12:27:57.953395536Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 21 12:27:57.953649 containerd[1416]: time="2025-03-21T12:27:57.953528536Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 21 12:27:57.953649 containerd[1416]: time="2025-03-21T12:27:57.953628856Z" level=info msg="containerd successfully booted in 0.127219s" Mar 21 12:27:57.956573 systemd[1]: Started containerd.service - containerd container runtime. Mar 21 12:27:58.093683 sshd_keygen[1413]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 21 12:27:58.113486 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 21 12:27:58.116186 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 21 12:27:58.130546 systemd[1]: issuegen.service: Deactivated successfully. Mar 21 12:27:58.130728 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 21 12:27:58.134288 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 21 12:27:58.152302 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 21 12:27:58.154923 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 21 12:27:58.156975 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Mar 21 12:27:58.158238 systemd[1]: Reached target getty.target - Login Prompts. Mar 21 12:27:59.072677 systemd-networkd[1340]: eth0: Gained IPv6LL Mar 21 12:27:59.074986 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 21 12:27:59.078006 systemd[1]: Reached target network-online.target - Network is Online. Mar 21 12:27:59.080407 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 21 12:27:59.082320 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 21 12:27:59.106787 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 21 12:27:59.107000 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 21 12:27:59.109295 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 21 12:27:59.111111 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 21 12:27:59.111313 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 21 12:27:59.113553 systemd[1]: Startup finished in 546ms (kernel) + 3.751s (initrd) + 3.133s (userspace) = 7.432s. Mar 21 12:28:05.149411 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 21 12:28:05.150637 systemd[1]: Started sshd@0-10.0.0.65:22-10.0.0.1:33210.service - OpenSSH per-connection server daemon (10.0.0.1:33210). Mar 21 12:28:05.232360 sshd[1507]: Accepted publickey for core from 10.0.0.1 port 33210 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:05.235866 sshd-session[1507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:05.250234 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 21 12:28:05.251239 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 21 12:28:05.256765 systemd-logind[1400]: New session 1 of user core. Mar 21 12:28:05.272467 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 21 12:28:05.274817 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 21 12:28:05.297827 (systemd)[1511]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 21 12:28:05.300227 systemd-logind[1400]: New session c1 of user core. Mar 21 12:28:05.407079 systemd[1511]: Queued start job for default target default.target. Mar 21 12:28:05.421356 systemd[1511]: Created slice app.slice - User Application Slice. Mar 21 12:28:05.421384 systemd[1511]: Reached target paths.target - Paths. Mar 21 12:28:05.421419 systemd[1511]: Reached target timers.target - Timers. Mar 21 12:28:05.422638 systemd[1511]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 21 12:28:05.431289 systemd[1511]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 21 12:28:05.431351 systemd[1511]: Reached target sockets.target - Sockets. Mar 21 12:28:05.431387 systemd[1511]: Reached target basic.target - Basic System. Mar 21 12:28:05.431418 systemd[1511]: Reached target default.target - Main User Target. Mar 21 12:28:05.431466 systemd[1511]: Startup finished in 125ms. Mar 21 12:28:05.431603 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 21 12:28:05.432908 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 21 12:28:05.493640 systemd[1]: Started sshd@1-10.0.0.65:22-10.0.0.1:33224.service - OpenSSH per-connection server daemon (10.0.0.1:33224). Mar 21 12:28:05.538114 sshd[1522]: Accepted publickey for core from 10.0.0.1 port 33224 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:05.539270 sshd-session[1522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:05.542964 systemd-logind[1400]: New session 2 of user core. Mar 21 12:28:05.554667 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 21 12:28:05.605459 sshd[1524]: Connection closed by 10.0.0.1 port 33224 Mar 21 12:28:05.605779 sshd-session[1522]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:05.615474 systemd[1]: sshd@1-10.0.0.65:22-10.0.0.1:33224.service: Deactivated successfully. Mar 21 12:28:05.617255 systemd[1]: session-2.scope: Deactivated successfully. Mar 21 12:28:05.618394 systemd-logind[1400]: Session 2 logged out. Waiting for processes to exit. Mar 21 12:28:05.619488 systemd[1]: Started sshd@2-10.0.0.65:22-10.0.0.1:33226.service - OpenSSH per-connection server daemon (10.0.0.1:33226). Mar 21 12:28:05.620213 systemd-logind[1400]: Removed session 2. Mar 21 12:28:05.669366 sshd[1529]: Accepted publickey for core from 10.0.0.1 port 33226 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:05.670393 sshd-session[1529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:05.674720 systemd-logind[1400]: New session 3 of user core. Mar 21 12:28:05.686623 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 21 12:28:05.734832 sshd[1532]: Connection closed by 10.0.0.1 port 33226 Mar 21 12:28:05.735144 sshd-session[1529]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:05.749381 systemd[1]: sshd@2-10.0.0.65:22-10.0.0.1:33226.service: Deactivated successfully. Mar 21 12:28:05.752668 systemd[1]: session-3.scope: Deactivated successfully. Mar 21 12:28:05.753240 systemd-logind[1400]: Session 3 logged out. Waiting for processes to exit. Mar 21 12:28:05.754841 systemd[1]: Started sshd@3-10.0.0.65:22-10.0.0.1:33232.service - OpenSSH per-connection server daemon (10.0.0.1:33232). Mar 21 12:28:05.756859 systemd-logind[1400]: Removed session 3. Mar 21 12:28:05.807155 sshd[1537]: Accepted publickey for core from 10.0.0.1 port 33232 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:05.808338 sshd-session[1537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:05.812509 systemd-logind[1400]: New session 4 of user core. Mar 21 12:28:05.828643 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 21 12:28:05.879134 sshd[1540]: Connection closed by 10.0.0.1 port 33232 Mar 21 12:28:05.879572 sshd-session[1537]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:05.888685 systemd[1]: sshd@3-10.0.0.65:22-10.0.0.1:33232.service: Deactivated successfully. Mar 21 12:28:05.890302 systemd[1]: session-4.scope: Deactivated successfully. Mar 21 12:28:05.892590 systemd-logind[1400]: Session 4 logged out. Waiting for processes to exit. Mar 21 12:28:05.893921 systemd[1]: Started sshd@4-10.0.0.65:22-10.0.0.1:33242.service - OpenSSH per-connection server daemon (10.0.0.1:33242). Mar 21 12:28:05.895057 systemd-logind[1400]: Removed session 4. Mar 21 12:28:05.938096 sshd[1545]: Accepted publickey for core from 10.0.0.1 port 33242 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:05.939394 sshd-session[1545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:05.943845 systemd-logind[1400]: New session 5 of user core. Mar 21 12:28:05.957642 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 21 12:28:06.022212 sudo[1549]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 21 12:28:06.022525 sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:28:06.034171 sudo[1549]: pam_unix(sudo:session): session closed for user root Mar 21 12:28:06.036235 sshd[1548]: Connection closed by 10.0.0.1 port 33242 Mar 21 12:28:06.036048 sshd-session[1545]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:06.051213 systemd[1]: sshd@4-10.0.0.65:22-10.0.0.1:33242.service: Deactivated successfully. Mar 21 12:28:06.054858 systemd[1]: session-5.scope: Deactivated successfully. Mar 21 12:28:06.055537 systemd-logind[1400]: Session 5 logged out. Waiting for processes to exit. Mar 21 12:28:06.057208 systemd[1]: Started sshd@5-10.0.0.65:22-10.0.0.1:33258.service - OpenSSH per-connection server daemon (10.0.0.1:33258). Mar 21 12:28:06.058741 systemd-logind[1400]: Removed session 5. Mar 21 12:28:06.118391 sshd[1554]: Accepted publickey for core from 10.0.0.1 port 33258 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:06.119493 sshd-session[1554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:06.122892 systemd-logind[1400]: New session 6 of user core. Mar 21 12:28:06.135637 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 21 12:28:06.185683 sudo[1559]: 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 Mar 21 12:28:06.185944 sudo[1559]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:28:06.188724 sudo[1559]: pam_unix(sudo:session): session closed for user root Mar 21 12:28:06.193243 sudo[1558]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Mar 21 12:28:06.193534 sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:28:06.200882 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 21 12:28:06.236514 augenrules[1581]: No rules Mar 21 12:28:06.237093 systemd[1]: audit-rules.service: Deactivated successfully. Mar 21 12:28:06.238469 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 21 12:28:06.239239 sudo[1558]: pam_unix(sudo:session): session closed for user root Mar 21 12:28:06.240310 sshd[1557]: Connection closed by 10.0.0.1 port 33258 Mar 21 12:28:06.240640 sshd-session[1554]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:06.255372 systemd[1]: sshd@5-10.0.0.65:22-10.0.0.1:33258.service: Deactivated successfully. Mar 21 12:28:06.256761 systemd[1]: session-6.scope: Deactivated successfully. Mar 21 12:28:06.258589 systemd-logind[1400]: Session 6 logged out. Waiting for processes to exit. Mar 21 12:28:06.260083 systemd[1]: Started sshd@6-10.0.0.65:22-10.0.0.1:33262.service - OpenSSH per-connection server daemon (10.0.0.1:33262). Mar 21 12:28:06.260893 systemd-logind[1400]: Removed session 6. Mar 21 12:28:06.314977 sshd[1589]: Accepted publickey for core from 10.0.0.1 port 33262 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:06.316035 sshd-session[1589]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:06.319934 systemd-logind[1400]: New session 7 of user core. Mar 21 12:28:06.333628 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 21 12:28:06.386580 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 21 12:28:06.400721 (dockerd)[1594]: 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 Mar 21 12:28:06.662939 dockerd[1594]: time="2025-03-21T12:28:06.662803736Z" level=info msg="Starting up" Mar 21 12:28:06.666675 dockerd[1594]: time="2025-03-21T12:28:06.666535416Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Mar 21 12:28:06.725212 dockerd[1594]: time="2025-03-21T12:28:06.724857856Z" level=info msg="Loading containers: start." Mar 21 12:28:06.911179 kernel: Initializing XFRM netlink socket Mar 21 12:28:07.006930 systemd-networkd[1340]: docker0: Link UP Mar 21 12:28:07.061680 dockerd[1594]: time="2025-03-21T12:28:07.061631256Z" level=info msg="Loading containers: done." Mar 21 12:28:07.083709 dockerd[1594]: time="2025-03-21T12:28:07.083656856Z" 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 Mar 21 12:28:07.083840 dockerd[1594]: time="2025-03-21T12:28:07.083745576Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Mar 21 12:28:07.083943 dockerd[1594]: time="2025-03-21T12:28:07.083909896Z" level=info msg="Daemon has completed initialization" Mar 21 12:28:07.116575 dockerd[1594]: time="2025-03-21T12:28:07.116413416Z" level=info msg="API listen on /run/docker.sock" Mar 21 12:28:07.116673 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 21 12:28:07.123812 sshd[1592]: Connection closed by 10.0.0.1 port 33262 Mar 21 12:28:07.125183 sshd-session[1589]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:07.138330 systemd[1]: sshd@6-10.0.0.65:22-10.0.0.1:33262.service: Deactivated successfully. Mar 21 12:28:07.142317 systemd[1]: session-7.scope: Deactivated successfully. Mar 21 12:28:07.144534 systemd-logind[1400]: Session 7 logged out. Waiting for processes to exit. Mar 21 12:28:07.145085 systemd[1]: Started sshd@7-10.0.0.65:22-10.0.0.1:33266.service - OpenSSH per-connection server daemon (10.0.0.1:33266). Mar 21 12:28:07.146562 systemd-logind[1400]: Removed session 7. Mar 21 12:28:07.204004 sshd[1794]: Accepted publickey for core from 10.0.0.1 port 33266 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:07.205139 sshd-session[1794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:07.209486 systemd-logind[1400]: New session 8 of user core. Mar 21 12:28:07.216597 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 21 12:28:08.683973 kernel: docker0: port 1(vethecf3ca7) entered blocking state Mar 21 12:28:08.684072 kernel: docker0: port 1(vethecf3ca7) entered disabled state Mar 21 12:28:08.684088 kernel: vethecf3ca7: entered allmulticast mode Mar 21 12:28:08.685458 kernel: vethecf3ca7: entered promiscuous mode Mar 21 12:28:08.685962 systemd-networkd[1340]: vethecf3ca7: Link UP Mar 21 12:28:08.737335 containerd[1416]: time="2025-03-21T12:28:08.737291576Z" level=info msg="connecting to shim b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe" address="unix:///run/containerd/s/043c2dca7a458d01a3400f3420e567d26fcec2becc13201f46814285eed0d956" namespace=moby protocol=ttrpc version=3 Mar 21 12:28:08.759597 systemd[1]: Started docker-b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe.scope - libcontainer container b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe. Mar 21 12:28:08.814476 kernel: eth0: renamed from vethe06cc26 Mar 21 12:28:08.829259 systemd-networkd[1340]: vethecf3ca7: Gained carrier Mar 21 12:28:08.829461 kernel: docker0: port 1(vethecf3ca7) entered blocking state Mar 21 12:28:08.829502 kernel: docker0: port 1(vethecf3ca7) entered forwarding state Mar 21 12:28:08.829500 systemd-networkd[1340]: docker0: Gained carrier Mar 21 12:28:08.834470 systemd-resolved[1256]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:28:08.865839 sshd[1797]: Connection closed by 10.0.0.1 port 33266 Mar 21 12:28:08.866129 sshd-session[1794]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:08.880426 systemd[1]: sshd@7-10.0.0.65:22-10.0.0.1:33266.service: Deactivated successfully. Mar 21 12:28:08.882083 systemd[1]: session-8.scope: Deactivated successfully. Mar 21 12:28:08.887616 systemd-logind[1400]: Session 8 logged out. Waiting for processes to exit. Mar 21 12:28:08.891811 systemd[1]: Started sshd@8-10.0.0.65:22-10.0.0.1:33282.service - OpenSSH per-connection server daemon (10.0.0.1:33282). Mar 21 12:28:08.892805 systemd-logind[1400]: Removed session 8. Mar 21 12:28:08.944057 sshd[1882]: Accepted publickey for core from 10.0.0.1 port 33282 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:08.945529 sshd-session[1882]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:08.950326 systemd-logind[1400]: New session 9 of user core. Mar 21 12:28:08.956593 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 21 12:28:09.009678 sshd[1885]: Connection closed by 10.0.0.1 port 33282 Mar 21 12:28:09.010240 sshd-session[1882]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:09.022223 systemd[1]: sshd@8-10.0.0.65:22-10.0.0.1:33282.service: Deactivated successfully. Mar 21 12:28:09.023603 systemd[1]: session-9.scope: Deactivated successfully. Mar 21 12:28:09.024215 systemd-logind[1400]: Session 9 logged out. Waiting for processes to exit. Mar 21 12:28:09.025856 systemd[1]: Started sshd@9-10.0.0.65:22-10.0.0.1:33284.service - OpenSSH per-connection server daemon (10.0.0.1:33284). Mar 21 12:28:09.028732 systemd-logind[1400]: Removed session 9. Mar 21 12:28:09.081689 sshd[1890]: Accepted publickey for core from 10.0.0.1 port 33284 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:09.082672 sshd-session[1890]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:09.087157 systemd-logind[1400]: New session 10 of user core. Mar 21 12:28:09.095588 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 21 12:28:09.145685 sudo[1895]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1416 Mar 21 12:28:09.146205 sudo[1895]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:28:09.154030 sudo[1895]: pam_unix(sudo:session): session closed for user root Mar 21 12:28:09.154268 containerd[1416]: time="2025-03-21T12:28:09.154195776Z" level=info msg="Stop CRI service" Mar 21 12:28:09.157197 dockerd[1594]: time="2025-03-21T12:28:09.155239496Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Mar 21 12:28:09.157197 dockerd[1594]: time="2025-03-21T12:28:09.155281056Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Mar 21 12:28:09.157197 dockerd[1594]: time="2025-03-21T12:28:09.155243016Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Mar 21 12:28:09.157197 dockerd[1594]: time="2025-03-21T12:28:09.155418056Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Mar 21 12:28:09.157526 sshd[1894]: Connection closed by 10.0.0.1 port 33284 Mar 21 12:28:09.157373 systemd[1]: containerd.service: Deactivated successfully. Mar 21 12:28:09.157523 systemd[1]: containerd.service: Unit process 1833 (containerd-shim) remains running after unit stopped. Mar 21 12:28:09.157788 systemd[1]: containerd.service: Consumed 383ms CPU time, 156.2M memory peak. Mar 21 12:28:09.158271 sshd-session[1890]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:09.168402 systemd[1]: sshd@9-10.0.0.65:22-10.0.0.1:33284.service: Deactivated successfully. Mar 21 12:28:09.169848 systemd[1]: session-10.scope: Deactivated successfully. Mar 21 12:28:09.171011 systemd-logind[1400]: Session 10 logged out. Waiting for processes to exit. Mar 21 12:28:09.172125 systemd[1]: Started sshd@10-10.0.0.65:22-10.0.0.1:33292.service - OpenSSH per-connection server daemon (10.0.0.1:33292). Mar 21 12:28:09.172862 systemd-logind[1400]: Removed session 10. Mar 21 12:28:09.226734 sshd[1901]: Accepted publickey for core from 10.0.0.1 port 33292 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:09.228004 sshd-session[1901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:09.232493 systemd-logind[1400]: New session 11 of user core. Mar 21 12:28:09.247627 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 21 12:28:09.302081 sshd[1904]: Connection closed by 10.0.0.1 port 33292 Mar 21 12:28:09.302521 sshd-session[1901]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:09.305198 systemd[1]: sshd@10-10.0.0.65:22-10.0.0.1:33292.service: Deactivated successfully. Mar 21 12:28:09.306795 systemd[1]: session-11.scope: Deactivated successfully. Mar 21 12:28:09.307417 systemd-logind[1400]: Session 11 logged out. Waiting for processes to exit. Mar 21 12:28:09.308278 systemd-logind[1400]: Removed session 11. Mar 21 12:28:10.144625 systemd-networkd[1340]: vethecf3ca7: Gained IPv6LL Mar 21 12:28:10.592650 systemd-networkd[1340]: docker0: Gained IPv6LL Mar 21 12:28:14.308228 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Mar 21 12:28:14.308628 systemd[1]: Stopping docker.service - Docker Application Container Engine... Mar 21 12:28:14.308779 dockerd[1594]: time="2025-03-21T12:28:14.308735376Z" level=info msg="Processing signal 'terminated'" Mar 21 12:28:15.320561 systemd[1]: Started sshd@11-10.0.0.65:22-10.0.0.1:59374.service - OpenSSH per-connection server daemon (10.0.0.1:59374). Mar 21 12:28:15.384147 sshd[1910]: Accepted publickey for core from 10.0.0.1 port 59374 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:15.385254 sshd-session[1910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:15.388712 systemd-logind[1400]: New session 12 of user core. Mar 21 12:28:15.399569 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 21 12:28:15.450536 sshd[1912]: Connection closed by 10.0.0.1 port 59374 Mar 21 12:28:15.450819 sshd-session[1910]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:15.453459 systemd[1]: sshd@11-10.0.0.65:22-10.0.0.1:59374.service: Deactivated successfully. Mar 21 12:28:15.455719 systemd[1]: session-12.scope: Deactivated successfully. Mar 21 12:28:15.456268 systemd-logind[1400]: Session 12 logged out. Waiting for processes to exit. Mar 21 12:28:15.457186 systemd-logind[1400]: Removed session 12. Mar 21 12:28:16.309577 dockerd[1594]: time="2025-03-21T12:28:16.309513336Z" level=error msg="Error sending stop (signal 15) to container" container=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe error="Cannot kill container b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\": unavailable" Mar 21 12:28:16.309577 dockerd[1594]: time="2025-03-21T12:28:16.309565856Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe Mar 21 12:28:21.465491 systemd[1]: Started sshd@12-10.0.0.65:22-10.0.0.1:59384.service - OpenSSH per-connection server daemon (10.0.0.1:59384). Mar 21 12:28:21.516305 sshd[1918]: Accepted publickey for core from 10.0.0.1 port 59384 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:21.517516 sshd-session[1918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:21.521132 systemd-logind[1400]: New session 13 of user core. Mar 21 12:28:21.532655 systemd[1]: Started session-13.scope - Session 13 of User core. Mar 21 12:28:21.586461 sshd[1920]: Connection closed by 10.0.0.1 port 59384 Mar 21 12:28:21.586744 sshd-session[1918]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:21.589850 systemd[1]: sshd@12-10.0.0.65:22-10.0.0.1:59384.service: Deactivated successfully. Mar 21 12:28:21.591343 systemd[1]: session-13.scope: Deactivated successfully. Mar 21 12:28:21.593983 systemd-logind[1400]: Session 13 logged out. Waiting for processes to exit. Mar 21 12:28:21.594908 systemd-logind[1400]: Removed session 13. Mar 21 12:28:26.310229 dockerd[1594]: time="2025-03-21T12:28:26.310140976Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe error="context deadline exceeded" Mar 21 12:28:26.310880 systemd[1]: docker-b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe.scope: Deactivated successfully. Mar 21 12:28:27.597549 systemd[1]: Started sshd@13-10.0.0.65:22-10.0.0.1:42964.service - OpenSSH per-connection server daemon (10.0.0.1:42964). Mar 21 12:28:27.649551 sshd[1926]: Accepted publickey for core from 10.0.0.1 port 42964 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:27.650906 sshd-session[1926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:27.655898 systemd-logind[1400]: New session 14 of user core. Mar 21 12:28:27.666600 systemd[1]: Started session-14.scope - Session 14 of User core. Mar 21 12:28:27.719418 sshd[1928]: Connection closed by 10.0.0.1 port 42964 Mar 21 12:28:27.719895 sshd-session[1926]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:27.722939 systemd[1]: sshd@13-10.0.0.65:22-10.0.0.1:42964.service: Deactivated successfully. Mar 21 12:28:27.724460 systemd[1]: session-14.scope: Deactivated successfully. Mar 21 12:28:27.725924 systemd-logind[1400]: Session 14 logged out. Waiting for processes to exit. Mar 21 12:28:27.726715 systemd-logind[1400]: Removed session 14. Mar 21 12:28:29.309479 dockerd[1594]: time="2025-03-21T12:28:29.309293816Z" level=error msg="Force shutdown daemon" Mar 21 12:28:29.310094 dockerd[1594]: time="2025-03-21T12:28:29.309624336Z" level=info msg="Daemon shutdown complete" Mar 21 12:28:29.314559 systemd[1]: docker.service: Deactivated successfully. Mar 21 12:28:29.315492 systemd[1]: Stopped docker.service - Docker Application Container Engine. Mar 21 12:28:29.315555 systemd[1]: docker.service: Consumed 862ms CPU time, 124M memory peak. Mar 21 12:28:29.315975 systemd[1]: docker.socket: Deactivated successfully. Mar 21 12:28:29.316246 systemd[1]: Closed docker.socket - Docker Socket for the API. Mar 21 12:28:29.316285 systemd[1]: Stopping docker.socket - Docker Socket for the API... Mar 21 12:28:29.317760 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 21 12:28:29.317917 systemd[1]: containerd.service: Found left-over process 1833 (containerd-shim) in control group while starting unit. Ignoring. Mar 21 12:28:29.317925 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Mar 21 12:28:29.326895 systemd[1]: Starting containerd.service - containerd container runtime... Mar 21 12:28:29.327276 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 21 12:28:29.337563 systemd[1]: containerd.service: Found left-over process 1833 (containerd-shim) in control group while starting unit. Ignoring. Mar 21 12:28:29.337574 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Mar 21 12:28:29.341275 (ntainerd)[1939]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 21 12:28:29.353557 containerd[1939]: time="2025-03-21T12:28:29Z" 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 Mar 21 12:28:29.353962 containerd[1939]: time="2025-03-21T12:28:29.353916976Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 21 12:28:29.361948 containerd[1939]: time="2025-03-21T12:28:29.361907816Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.48µs" Mar 21 12:28:29.361948 containerd[1939]: time="2025-03-21T12:28:29.361938576Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 21 12:28:29.362080 containerd[1939]: time="2025-03-21T12:28:29.361956136Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 21 12:28:29.362080 containerd[1939]: time="2025-03-21T12:28:29.361988576Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 21 12:28:29.362080 containerd[1939]: time="2025-03-21T12:28:29.362001136Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 21 12:28:29.362207 containerd[1939]: time="2025-03-21T12:28:29.362171336Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362233 containerd[1939]: time="2025-03-21T12:28:29.362204336Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362233 containerd[1939]: time="2025-03-21T12:28:29.362214496Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362442 containerd[1939]: time="2025-03-21T12:28:29.362393456Z" 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 Mar 21 12:28:29.362467 containerd[1939]: time="2025-03-21T12:28:29.362448616Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362467 containerd[1939]: time="2025-03-21T12:28:29.362461056Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362500 containerd[1939]: time="2025-03-21T12:28:29.362469136Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362500 containerd[1939]: time="2025-03-21T12:28:29.362493816Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362625 containerd[1939]: time="2025-03-21T12:28:29.362607376Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 21 12:28:29.362655 containerd[1939]: time="2025-03-21T12:28:29.362641416Z" 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 Mar 21 12:28:29.362679 containerd[1939]: time="2025-03-21T12:28:29.362653896Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 21 12:28:29.362702 containerd[1939]: time="2025-03-21T12:28:29.362681256Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 21 12:28:29.362984 containerd[1939]: time="2025-03-21T12:28:29.362968616Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 21 12:28:29.363020 containerd[1939]: time="2025-03-21T12:28:29.362992896Z" level=info msg="metadata content store policy set" policy=shared Mar 21 12:28:29.363128 containerd[1939]: time="2025-03-21T12:28:29.363106256Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 21 12:28:29.363518 containerd[1939]: time="2025-03-21T12:28:29.363450296Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 21 12:28:29.363518 containerd[1939]: time="2025-03-21T12:28:29.363481736Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 21 12:28:29.363518 containerd[1939]: time="2025-03-21T12:28:29.363499656Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 21 12:28:29.363518 containerd[1939]: time="2025-03-21T12:28:29.363518056Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 21 12:28:29.363587 containerd[1939]: time="2025-03-21T12:28:29.363534376Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 21 12:28:29.363587 containerd[1939]: time="2025-03-21T12:28:29.363547456Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 21 12:28:29.363587 containerd[1939]: time="2025-03-21T12:28:29.363563256Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 21 12:28:29.363587 containerd[1939]: time="2025-03-21T12:28:29.363577536Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 21 12:28:29.363654 containerd[1939]: time="2025-03-21T12:28:29.363591896Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 21 12:28:29.363654 containerd[1939]: time="2025-03-21T12:28:29.363604976Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 21 12:28:29.363654 containerd[1939]: time="2025-03-21T12:28:29.363618336Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 21 12:28:29.364123 containerd[1939]: time="2025-03-21T12:28:29.364068416Z" level=info msg="connecting to shim b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe" address="unix:///run/containerd/s/043c2dca7a458d01a3400f3420e567d26fcec2becc13201f46814285eed0d956" namespace=moby protocol=ttrpc version=3 Mar 21 12:28:29.370508 containerd[1939]: time="2025-03-21T12:28:29.370479416Z" level=info msg="cleaning leaked shim process" id=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe namespace=moby Mar 21 12:28:29.377968 containerd[1939]: time="2025-03-21T12:28:29.377918176Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Mar 21 12:28:29.378354 containerd[1939]: time="2025-03-21T12:28:29.378307016Z" level=info msg="shim disconnected" id=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe namespace=moby Mar 21 12:28:29.378863 containerd[1939]: time="2025-03-21T12:28:29.378839816Z" level=warning msg="cleaning up after shim disconnected" id=b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe namespace=moby Mar 21 12:28:29.378943 containerd[1939]: time="2025-03-21T12:28:29.378931136Z" level=info msg="cleaning up dead shim" namespace=moby Mar 21 12:28:29.387562 containerd[1939]: time="2025-03-21T12:28:29.387509176Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Mar 21 12:28:29.387680 containerd[1939]: time="2025-03-21T12:28:29.387653136Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 21 12:28:29.387710 containerd[1939]: time="2025-03-21T12:28:29.387688376Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 21 12:28:29.387710 containerd[1939]: time="2025-03-21T12:28:29.387702616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 21 12:28:29.387745 containerd[1939]: time="2025-03-21T12:28:29.387713256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 21 12:28:29.387745 containerd[1939]: time="2025-03-21T12:28:29.387724136Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 21 12:28:29.387745 containerd[1939]: time="2025-03-21T12:28:29.387733576Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 21 12:28:29.387801 containerd[1939]: time="2025-03-21T12:28:29.387754056Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 21 12:28:29.387801 containerd[1939]: time="2025-03-21T12:28:29.387765576Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 21 12:28:29.387801 containerd[1939]: time="2025-03-21T12:28:29.387777376Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 21 12:28:29.387801 containerd[1939]: time="2025-03-21T12:28:29.387787976Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 21 12:28:29.387801 containerd[1939]: time="2025-03-21T12:28:29.387798456Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 21 12:28:29.387881 containerd[1939]: time="2025-03-21T12:28:29.387851256Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 21 12:28:29.387881 containerd[1939]: time="2025-03-21T12:28:29.387865616Z" level=info msg="Start snapshots syncer" Mar 21 12:28:29.387918 containerd[1939]: time="2025-03-21T12:28:29.387894416Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 21 12:28:29.388127 containerd[1939]: time="2025-03-21T12:28:29.388096376Z" 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\"}" Mar 21 12:28:29.388210 containerd[1939]: time="2025-03-21T12:28:29.388158896Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 21 12:28:29.388231 containerd[1939]: time="2025-03-21T12:28:29.388222656Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 21 12:28:29.388292 containerd[1939]: time="2025-03-21T12:28:29.388266136Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 21 12:28:29.388326 containerd[1939]: time="2025-03-21T12:28:29.388292736Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 21 12:28:29.388326 containerd[1939]: time="2025-03-21T12:28:29.388304136Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 21 12:28:29.388326 containerd[1939]: time="2025-03-21T12:28:29.388315856Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 21 12:28:29.388376 containerd[1939]: time="2025-03-21T12:28:29.388327456Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 21 12:28:29.388376 containerd[1939]: time="2025-03-21T12:28:29.388343296Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 21 12:28:29.388376 containerd[1939]: time="2025-03-21T12:28:29.388353816Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 21 12:28:29.388453 containerd[1939]: time="2025-03-21T12:28:29.388377536Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 21 12:28:29.388453 containerd[1939]: time="2025-03-21T12:28:29.388389016Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 21 12:28:29.388453 containerd[1939]: time="2025-03-21T12:28:29.388408456Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 21 12:28:29.388504 containerd[1939]: time="2025-03-21T12:28:29.388461136Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 21 12:28:29.388504 containerd[1939]: time="2025-03-21T12:28:29.388476096Z" 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 Mar 21 12:28:29.388504 containerd[1939]: time="2025-03-21T12:28:29.388484456Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:28:29.388504 containerd[1939]: time="2025-03-21T12:28:29.388493456Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:28:29.388504 containerd[1939]: time="2025-03-21T12:28:29.388501336Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388511296Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388521616Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388536736Z" level=info msg="runtime interface created" Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388541736Z" level=info msg="created NRI interface" Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388549896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388561416Z" level=info msg="Connect containerd service" Mar 21 12:28:29.388595 containerd[1939]: time="2025-03-21T12:28:29.388593496Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 21 12:28:29.389081 containerd[1939]: time="2025-03-21T12:28:29.389058016Z" 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" Mar 21 12:28:29.396727 containerd[1939]: time="2025-03-21T12:28:29.396689816Z" level=info msg="Start subscribing containerd event" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396797776Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396847576Z" level=info msg="Start recovering state" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396894256Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396949016Z" level=info msg="Start event monitor" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396967576Z" level=info msg="Start cni network conf syncer for default" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396975896Z" level=info msg="Start streaming server" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396985016Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396991416Z" level=info msg="runtime interface starting up..." Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.396997376Z" level=info msg="starting plugins..." Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.397006096Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 21 12:28:29.397141 containerd[1939]: time="2025-03-21T12:28:29.397122216Z" level=info msg="containerd successfully booted in 0.043782s" Mar 21 12:28:29.397472 systemd[1]: Started containerd.service - containerd container runtime. Mar 21 12:28:29.400161 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 21 12:28:29.414778 (dockerd)[1988]: 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 Mar 21 12:28:29.437713 dockerd[1988]: time="2025-03-21T12:28:29.437656896Z" level=info msg="Starting up" Mar 21 12:28:29.438176 dockerd[1988]: time="2025-03-21T12:28:29.438138616Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Mar 21 12:28:29.462472 dockerd[1988]: time="2025-03-21T12:28:29.461923936Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Mar 21 12:28:29.468636 dockerd[1988]: time="2025-03-21T12:28:29.468603376Z" level=info msg="Loading containers: start." Mar 21 12:28:29.480060 systemd[1]: var-lib-docker-overlay2-142d801213452a046304cb24d1f9229a14817778c72b1f9889b9edec314f8ce2-merged.mount: Deactivated successfully. Mar 21 12:28:29.730503 dockerd[1988]: time="2025-03-21T12:28:29.729939496Z" level=info msg="Removing stale sandbox 5f3e5d27abb85e4a2b7b5e2816f720022cb87345c2c3c92c9656bc156e79801a (b2adbd05ce5e0cbe727572eece448289ffadf671774ca4b78336433dea310dbe)" Mar 21 12:28:29.734171 dockerd[1988]: time="2025-03-21T12:28:29.734132136Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint a0a877533864d37951923a226a5131193eef0986872fad5948a3ee89a00bebde 7a8c6520791bf722ab0b2d0e71b05877260504f6fe175952d762bef1b6122af5], retrying...." Mar 21 12:28:29.735684 systemd-networkd[1340]: vethecf3ca7: Link DOWN Mar 21 12:28:29.736584 kernel: docker0: port 1(vethecf3ca7) entered disabled state Mar 21 12:28:29.735691 systemd-networkd[1340]: vethecf3ca7: Lost carrier Mar 21 12:28:29.738458 kernel: vethecf3ca7 (unregistering): left allmulticast mode Mar 21 12:28:29.738509 kernel: vethecf3ca7 (unregistering): left promiscuous mode Mar 21 12:28:29.738523 kernel: docker0: port 1(vethecf3ca7) entered disabled state Mar 21 12:28:29.750776 systemd-networkd[1340]: docker0: Lost carrier Mar 21 12:28:29.857363 dockerd[1988]: time="2025-03-21T12:28:29.857305096Z" 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" Mar 21 12:28:29.963564 dockerd[1988]: time="2025-03-21T12:28:29.963516896Z" level=warning msg="error locating sandbox id 5f3e5d27abb85e4a2b7b5e2816f720022cb87345c2c3c92c9656bc156e79801a: sandbox 5f3e5d27abb85e4a2b7b5e2816f720022cb87345c2c3c92c9656bc156e79801a not found" Mar 21 12:28:29.963641 dockerd[1988]: time="2025-03-21T12:28:29.963620696Z" level=info msg="Loading containers: done." Mar 21 12:28:29.972309 dockerd[1988]: time="2025-03-21T12:28:29.972262856Z" 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 Mar 21 12:28:29.972446 dockerd[1988]: time="2025-03-21T12:28:29.972345096Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Mar 21 12:28:29.972446 dockerd[1988]: time="2025-03-21T12:28:29.972404816Z" level=info msg="Daemon has completed initialization" Mar 21 12:28:29.992104 dockerd[1988]: time="2025-03-21T12:28:29.992009056Z" level=info msg="API listen on /run/docker.sock" Mar 21 12:28:29.992136 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 21 12:28:30.453915 systemd[1]: run-docker-netns-5f3e5d27abb8.mount: Deactivated successfully. Mar 21 12:28:33.733413 systemd[1]: Started sshd@14-10.0.0.65:22-10.0.0.1:59568.service - OpenSSH per-connection server daemon (10.0.0.1:59568). Mar 21 12:28:33.787157 sshd[2196]: Accepted publickey for core from 10.0.0.1 port 59568 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:33.788281 sshd-session[2196]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:33.792141 systemd-logind[1400]: New session 15 of user core. Mar 21 12:28:33.801567 systemd[1]: Started session-15.scope - Session 15 of User core. Mar 21 12:28:33.854048 sshd[2198]: Connection closed by 10.0.0.1 port 59568 Mar 21 12:28:33.854484 sshd-session[2196]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:33.867345 systemd[1]: sshd@14-10.0.0.65:22-10.0.0.1:59568.service: Deactivated successfully. Mar 21 12:28:33.868634 systemd[1]: session-15.scope: Deactivated successfully. Mar 21 12:28:33.869261 systemd-logind[1400]: Session 15 logged out. Waiting for processes to exit. Mar 21 12:28:33.871681 systemd[1]: Started sshd@15-10.0.0.65:22-10.0.0.1:59572.service - OpenSSH per-connection server daemon (10.0.0.1:59572). Mar 21 12:28:33.872524 systemd-logind[1400]: Removed session 15. Mar 21 12:28:33.924083 sshd[2203]: Accepted publickey for core from 10.0.0.1 port 59572 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:33.925177 sshd-session[2203]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:33.929090 systemd-logind[1400]: New session 16 of user core. Mar 21 12:28:33.935656 systemd[1]: Started session-16.scope - Session 16 of User core. Mar 21 12:28:33.989105 sshd[2206]: Connection closed by 10.0.0.1 port 59572 Mar 21 12:28:33.989498 sshd-session[2203]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.002389 systemd[1]: sshd@15-10.0.0.65:22-10.0.0.1:59572.service: Deactivated successfully. Mar 21 12:28:34.003736 systemd[1]: session-16.scope: Deactivated successfully. Mar 21 12:28:34.005624 systemd-logind[1400]: Session 16 logged out. Waiting for processes to exit. Mar 21 12:28:34.007202 systemd[1]: Started sshd@16-10.0.0.65:22-10.0.0.1:59588.service - OpenSSH per-connection server daemon (10.0.0.1:59588). Mar 21 12:28:34.008000 systemd-logind[1400]: Removed session 16. Mar 21 12:28:34.057819 sshd[2211]: Accepted publickey for core from 10.0.0.1 port 59588 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:34.058920 sshd-session[2211]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:34.062962 systemd-logind[1400]: New session 17 of user core. Mar 21 12:28:34.076571 systemd[1]: Started session-17.scope - Session 17 of User core. Mar 21 12:28:34.131657 sshd[2214]: Connection closed by 10.0.0.1 port 59588 Mar 21 12:28:34.132099 sshd-session[2211]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.147146 systemd[1]: sshd@16-10.0.0.65:22-10.0.0.1:59588.service: Deactivated successfully. Mar 21 12:28:34.148642 systemd[1]: session-17.scope: Deactivated successfully. Mar 21 12:28:34.149317 systemd-logind[1400]: Session 17 logged out. Waiting for processes to exit. Mar 21 12:28:34.150880 systemd[1]: Started sshd@17-10.0.0.65:22-10.0.0.1:59604.service - OpenSSH per-connection server daemon (10.0.0.1:59604). Mar 21 12:28:34.151660 systemd-logind[1400]: Removed session 17. Mar 21 12:28:34.201405 sshd[2226]: Accepted publickey for core from 10.0.0.1 port 59604 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:34.202530 sshd-session[2226]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:34.206607 systemd-logind[1400]: New session 18 of user core. Mar 21 12:28:34.217627 systemd[1]: Started session-18.scope - Session 18 of User core. Mar 21 12:28:34.284516 systemd[1]: var-lib-docker-overlay2-36b0418ded5c01541c0fd75f1c8b620e768e4d080da1357f289f795363358d09\x2dinit-merged.mount: Deactivated successfully. Mar 21 12:28:34.308035 kernel: docker0: port 1(veth064b059) entered blocking state Mar 21 12:28:34.308179 kernel: docker0: port 1(veth064b059) entered disabled state Mar 21 12:28:34.308199 kernel: veth064b059: entered allmulticast mode Mar 21 12:28:34.310111 kernel: veth064b059: entered promiscuous mode Mar 21 12:28:34.310521 systemd-networkd[1340]: veth064b059: Link UP Mar 21 12:28:34.335555 containerd[1939]: time="2025-03-21T12:28:34.334994300Z" level=info msg="connecting to shim 1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5" address="unix:///run/containerd/s/3a3fd71f15d4afb78936dc23ccc616caa016f9791ea55afc3e2aa0ccb2e8ddef" namespace=moby protocol=ttrpc version=3 Mar 21 12:28:34.362648 systemd[1]: Started docker-1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5.scope - libcontainer container 1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5. Mar 21 12:28:34.406483 kernel: eth0: renamed from vethe53e383 Mar 21 12:28:34.417452 kernel: docker0: port 1(veth064b059) entered blocking state Mar 21 12:28:34.417495 kernel: docker0: port 1(veth064b059) entered forwarding state Mar 21 12:28:34.417460 systemd-networkd[1340]: veth064b059: Gained carrier Mar 21 12:28:34.417746 systemd-networkd[1340]: docker0: Gained carrier Mar 21 12:28:34.420686 systemd-resolved[1256]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:28:34.444207 sshd[2229]: Connection closed by 10.0.0.1 port 59604 Mar 21 12:28:34.444712 sshd-session[2226]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.455043 systemd[1]: sshd@17-10.0.0.65:22-10.0.0.1:59604.service: Deactivated successfully. Mar 21 12:28:34.457756 systemd[1]: session-18.scope: Deactivated successfully. Mar 21 12:28:34.458517 systemd-logind[1400]: Session 18 logged out. Waiting for processes to exit. Mar 21 12:28:34.460211 systemd[1]: Started sshd@18-10.0.0.65:22-10.0.0.1:59612.service - OpenSSH per-connection server daemon (10.0.0.1:59612). Mar 21 12:28:34.460980 systemd-logind[1400]: Removed session 18. Mar 21 12:28:34.509539 sshd[2307]: Accepted publickey for core from 10.0.0.1 port 59612 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:34.510659 sshd-session[2307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:34.515042 systemd-logind[1400]: New session 19 of user core. Mar 21 12:28:34.526576 systemd[1]: Started session-19.scope - Session 19 of User core. Mar 21 12:28:34.579418 sshd[2310]: Connection closed by 10.0.0.1 port 59612 Mar 21 12:28:34.579282 sshd-session[2307]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.588651 systemd[1]: sshd@18-10.0.0.65:22-10.0.0.1:59612.service: Deactivated successfully. Mar 21 12:28:34.589957 systemd[1]: session-19.scope: Deactivated successfully. Mar 21 12:28:34.591617 systemd-logind[1400]: Session 19 logged out. Waiting for processes to exit. Mar 21 12:28:34.593072 systemd[1]: Started sshd@19-10.0.0.65:22-10.0.0.1:59622.service - OpenSSH per-connection server daemon (10.0.0.1:59622). Mar 21 12:28:34.593815 systemd-logind[1400]: Removed session 19. Mar 21 12:28:34.650144 sshd[2315]: Accepted publickey for core from 10.0.0.1 port 59622 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:34.651172 sshd-session[2315]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:34.655468 systemd-logind[1400]: New session 20 of user core. Mar 21 12:28:34.670570 systemd[1]: Started session-20.scope - Session 20 of User core. Mar 21 12:28:34.720617 sudo[2319]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1939 Mar 21 12:28:34.720884 sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:28:34.723495 sudo[2319]: pam_unix(sudo:session): session closed for user root Mar 21 12:28:34.725555 sshd[2318]: Connection closed by 10.0.0.1 port 59622 Mar 21 12:28:34.741733 dockerd[1988]: time="2025-03-21T12:28:34.725934761Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Mar 21 12:28:34.741733 dockerd[1988]: time="2025-03-21T12:28:34.725965121Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Mar 21 12:28:34.741733 dockerd[1988]: time="2025-03-21T12:28:34.726078802Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Mar 21 12:28:34.741733 dockerd[1988]: time="2025-03-21T12:28:34.726107082Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Mar 21 12:28:34.725867 sshd-session[2315]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.745428 systemd[1]: containerd.service: Deactivated successfully. Mar 21 12:28:34.745544 systemd[1]: containerd.service: Unit process 2258 (containerd-shim) remains running after unit stopped. Mar 21 12:28:34.745753 systemd[1]: containerd.service: Consumed 168ms CPU time, 158.5M memory peak. Mar 21 12:28:34.746110 systemd[1]: sshd@19-10.0.0.65:22-10.0.0.1:59622.service: Deactivated successfully. Mar 21 12:28:34.747539 systemd[1]: session-20.scope: Deactivated successfully. Mar 21 12:28:34.748692 systemd-logind[1400]: Session 20 logged out. Waiting for processes to exit. Mar 21 12:28:34.750586 systemd[1]: Started sshd@20-10.0.0.65:22-10.0.0.1:59638.service - OpenSSH per-connection server daemon (10.0.0.1:59638). Mar 21 12:28:34.750992 systemd-logind[1400]: Removed session 20. Mar 21 12:28:34.806926 sshd[2325]: Accepted publickey for core from 10.0.0.1 port 59638 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:34.807981 sshd-session[2325]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:34.811287 systemd-logind[1400]: New session 21 of user core. Mar 21 12:28:34.825584 systemd[1]: Started session-21.scope - Session 21 of User core. Mar 21 12:28:34.878003 sshd[2328]: Connection closed by 10.0.0.1 port 59638 Mar 21 12:28:34.878460 sshd-session[2325]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:34.882007 systemd[1]: sshd@20-10.0.0.65:22-10.0.0.1:59638.service: Deactivated successfully. Mar 21 12:28:34.883689 systemd[1]: session-21.scope: Deactivated successfully. Mar 21 12:28:34.884470 systemd-logind[1400]: Session 21 logged out. Waiting for processes to exit. Mar 21 12:28:34.885275 systemd-logind[1400]: Removed session 21. Mar 21 12:28:36.128689 systemd-networkd[1340]: veth064b059: Gained IPv6LL Mar 21 12:28:39.808485 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Mar 21 12:28:39.808922 systemd[1]: Stopping docker.service - Docker Application Container Engine... Mar 21 12:28:39.809451 dockerd[1988]: time="2025-03-21T12:28:39.809130981Z" level=info msg="Processing signal 'terminated'" Mar 21 12:28:40.889283 systemd[1]: Started sshd@21-10.0.0.65:22-10.0.0.1:59650.service - OpenSSH per-connection server daemon (10.0.0.1:59650). Mar 21 12:28:40.941273 sshd[2334]: Accepted publickey for core from 10.0.0.1 port 59650 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:40.942319 sshd-session[2334]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:40.946458 systemd-logind[1400]: New session 22 of user core. Mar 21 12:28:40.956575 systemd[1]: Started session-22.scope - Session 22 of User core. Mar 21 12:28:41.007311 sshd[2336]: Connection closed by 10.0.0.1 port 59650 Mar 21 12:28:41.007576 sshd-session[2334]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:41.009770 systemd[1]: sshd@21-10.0.0.65:22-10.0.0.1:59650.service: Deactivated successfully. Mar 21 12:28:41.011100 systemd[1]: session-22.scope: Deactivated successfully. Mar 21 12:28:41.012327 systemd-logind[1400]: Session 22 logged out. Waiting for processes to exit. Mar 21 12:28:41.014969 systemd-logind[1400]: Removed session 22. Mar 21 12:28:41.810654 dockerd[1988]: time="2025-03-21T12:28:41.810533084Z" level=error msg="Error sending stop (signal 15) to container" container=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 error="Cannot kill container 1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\": unavailable" Mar 21 12:28:41.810977 dockerd[1988]: time="2025-03-21T12:28:41.810654764Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 Mar 21 12:28:42.683244 update_engine[1404]: I20250321 12:28:42.683161 1404 update_attempter.cc:509] Updating boot flags... Mar 21 12:28:42.704447 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2348) Mar 21 12:28:42.734512 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2352) Mar 21 12:28:42.765453 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2352) Mar 21 12:28:47.018709 systemd[1]: Started sshd@22-10.0.0.65:22-10.0.0.1:44118.service - OpenSSH per-connection server daemon (10.0.0.1:44118). Mar 21 12:28:47.073262 sshd[2357]: Accepted publickey for core from 10.0.0.1 port 44118 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:47.074384 sshd-session[2357]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:47.077909 systemd-logind[1400]: New session 23 of user core. Mar 21 12:28:47.097579 systemd[1]: Started session-23.scope - Session 23 of User core. Mar 21 12:28:47.150299 sshd[2359]: Connection closed by 10.0.0.1 port 44118 Mar 21 12:28:47.150804 sshd-session[2357]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:47.154177 systemd[1]: sshd@22-10.0.0.65:22-10.0.0.1:44118.service: Deactivated successfully. Mar 21 12:28:47.157154 systemd[1]: session-23.scope: Deactivated successfully. Mar 21 12:28:47.157856 systemd-logind[1400]: Session 23 logged out. Waiting for processes to exit. Mar 21 12:28:47.158604 systemd-logind[1400]: Removed session 23. Mar 21 12:28:51.812150 dockerd[1988]: time="2025-03-21T12:28:51.811711011Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 error="context deadline exceeded" Mar 21 12:28:51.813011 systemd[1]: docker-1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5.scope: Deactivated successfully. Mar 21 12:28:53.161575 systemd[1]: Started sshd@23-10.0.0.65:22-10.0.0.1:38558.service - OpenSSH per-connection server daemon (10.0.0.1:38558). Mar 21 12:28:53.205449 sshd[2365]: Accepted publickey for core from 10.0.0.1 port 38558 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:53.206564 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:53.210489 systemd-logind[1400]: New session 24 of user core. Mar 21 12:28:53.224572 systemd[1]: Started session-24.scope - Session 24 of User core. Mar 21 12:28:53.278450 sshd[2367]: Connection closed by 10.0.0.1 port 38558 Mar 21 12:28:53.278729 sshd-session[2365]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:53.281594 systemd[1]: sshd@23-10.0.0.65:22-10.0.0.1:38558.service: Deactivated successfully. Mar 21 12:28:53.282981 systemd[1]: session-24.scope: Deactivated successfully. Mar 21 12:28:53.284997 systemd-logind[1400]: Session 24 logged out. Waiting for processes to exit. Mar 21 12:28:53.285925 systemd-logind[1400]: Removed session 24. Mar 21 12:28:54.809985 dockerd[1988]: time="2025-03-21T12:28:54.809883774Z" level=error msg="Force shutdown daemon" Mar 21 12:28:54.812538 dockerd[1988]: time="2025-03-21T12:28:54.810313495Z" level=info msg="Daemon shutdown complete" Mar 21 12:28:54.813157 systemd[1]: docker.service: Deactivated successfully. Mar 21 12:28:54.814523 systemd[1]: Stopped docker.service - Docker Application Container Engine. Mar 21 12:28:54.814964 systemd[1]: docker.socket: Deactivated successfully. Mar 21 12:28:54.815224 systemd[1]: Closed docker.socket - Docker Socket for the API. Mar 21 12:28:54.815259 systemd[1]: Stopping docker.socket - Docker Socket for the API... Mar 21 12:28:54.816579 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 21 12:28:54.816735 systemd[1]: containerd.service: Found left-over process 2258 (containerd-shim) in control group while starting unit. Ignoring. Mar 21 12:28:54.816744 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Mar 21 12:28:54.822825 systemd[1]: Starting containerd.service - containerd container runtime... Mar 21 12:28:54.823525 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 21 12:28:54.832859 systemd[1]: containerd.service: Found left-over process 2258 (containerd-shim) in control group while starting unit. Ignoring. Mar 21 12:28:54.832872 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Mar 21 12:28:54.836785 (ntainerd)[2378]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 21 12:28:54.848769 containerd[2378]: time="2025-03-21T12:28:54Z" 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 Mar 21 12:28:54.849191 containerd[2378]: time="2025-03-21T12:28:54.849153491Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 21 12:28:54.857466 containerd[2378]: time="2025-03-21T12:28:54.857416259Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.64µs" Mar 21 12:28:54.857466 containerd[2378]: time="2025-03-21T12:28:54.857458939Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 21 12:28:54.857466 containerd[2378]: time="2025-03-21T12:28:54.857476619Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 21 12:28:54.857615 containerd[2378]: time="2025-03-21T12:28:54.857511179Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 21 12:28:54.857615 containerd[2378]: time="2025-03-21T12:28:54.857522259Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 21 12:28:54.857720 containerd[2378]: time="2025-03-21T12:28:54.857687539Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857746 containerd[2378]: time="2025-03-21T12:28:54.857720299Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857746 containerd[2378]: time="2025-03-21T12:28:54.857731099Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857952 containerd[2378]: time="2025-03-21T12:28:54.857923260Z" 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 Mar 21 12:28:54.857952 containerd[2378]: time="2025-03-21T12:28:54.857945020Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857998 containerd[2378]: time="2025-03-21T12:28:54.857956060Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857998 containerd[2378]: time="2025-03-21T12:28:54.857964340Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 21 12:28:54.857998 containerd[2378]: time="2025-03-21T12:28:54.857983820Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 21 12:28:54.858070 containerd[2378]: time="2025-03-21T12:28:54.858055900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 21 12:28:54.858093 containerd[2378]: time="2025-03-21T12:28:54.858080380Z" 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 Mar 21 12:28:54.858093 containerd[2378]: time="2025-03-21T12:28:54.858090500Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 21 12:28:54.858134 containerd[2378]: time="2025-03-21T12:28:54.858120420Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 21 12:28:54.858328 containerd[2378]: time="2025-03-21T12:28:54.858313340Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 21 12:28:54.858364 containerd[2378]: time="2025-03-21T12:28:54.858335220Z" level=info msg="metadata content store policy set" policy=shared Mar 21 12:28:54.858497 containerd[2378]: time="2025-03-21T12:28:54.858476260Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858508020Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858520220Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858531380Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858542980Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858553100Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 21 12:28:54.858564 containerd[2378]: time="2025-03-21T12:28:54.858564020Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 21 12:28:54.858726 containerd[2378]: time="2025-03-21T12:28:54.858575340Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 21 12:28:54.858726 containerd[2378]: time="2025-03-21T12:28:54.858585060Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 21 12:28:54.858726 containerd[2378]: time="2025-03-21T12:28:54.858594540Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 21 12:28:54.858726 containerd[2378]: time="2025-03-21T12:28:54.858603820Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 21 12:28:54.858726 containerd[2378]: time="2025-03-21T12:28:54.858614660Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 21 12:28:54.858874 containerd[2378]: time="2025-03-21T12:28:54.858799300Z" level=info msg="connecting to shim 1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5" address="unix:///run/containerd/s/3a3fd71f15d4afb78936dc23ccc616caa016f9791ea55afc3e2aa0ccb2e8ddef" namespace=moby protocol=ttrpc version=3 Mar 21 12:28:54.865636 containerd[2378]: time="2025-03-21T12:28:54.865604787Z" level=info msg="cleaning leaked shim process" id=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 namespace=moby Mar 21 12:28:54.873154 containerd[2378]: time="2025-03-21T12:28:54.872819434Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Mar 21 12:28:54.873270 containerd[2378]: time="2025-03-21T12:28:54.873207314Z" level=info msg="shim disconnected" id=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 namespace=moby Mar 21 12:28:54.873270 containerd[2378]: time="2025-03-21T12:28:54.873232674Z" level=warning msg="cleaning up after shim disconnected" id=1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5 namespace=moby Mar 21 12:28:54.873270 containerd[2378]: time="2025-03-21T12:28:54.873260314Z" level=info msg="cleaning up dead shim" namespace=moby Mar 21 12:28:54.882679 containerd[2378]: time="2025-03-21T12:28:54.882617883Z" level=error msg="copy shim log after reload" error="read /proc/self/fd/7: file already closed" namespace=moby Mar 21 12:28:54.882913 containerd[2378]: time="2025-03-21T12:28:54.882877883Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 21 12:28:54.882949 containerd[2378]: time="2025-03-21T12:28:54.882919083Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 21 12:28:54.882949 containerd[2378]: time="2025-03-21T12:28:54.882934083Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 21 12:28:54.882949 containerd[2378]: time="2025-03-21T12:28:54.882944803Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 21 12:28:54.883002 containerd[2378]: time="2025-03-21T12:28:54.882955563Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 21 12:28:54.883002 containerd[2378]: time="2025-03-21T12:28:54.882965803Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 21 12:28:54.883002 containerd[2378]: time="2025-03-21T12:28:54.882976603Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 21 12:28:54.883002 containerd[2378]: time="2025-03-21T12:28:54.882987083Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 21 12:28:54.883072 containerd[2378]: time="2025-03-21T12:28:54.883005443Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 21 12:28:54.883072 containerd[2378]: time="2025-03-21T12:28:54.883019083Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 21 12:28:54.883072 containerd[2378]: time="2025-03-21T12:28:54.883029283Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 21 12:28:54.883135 containerd[2378]: time="2025-03-21T12:28:54.883086043Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 21 12:28:54.883135 containerd[2378]: time="2025-03-21T12:28:54.883100283Z" level=info msg="Start snapshots syncer" Mar 21 12:28:54.883135 containerd[2378]: time="2025-03-21T12:28:54.883132083Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 21 12:28:54.883361 containerd[2378]: time="2025-03-21T12:28:54.883326924Z" 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\"}" Mar 21 12:28:54.883461 containerd[2378]: time="2025-03-21T12:28:54.883385404Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 21 12:28:54.883486 containerd[2378]: time="2025-03-21T12:28:54.883469404Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 21 12:28:54.883550 containerd[2378]: time="2025-03-21T12:28:54.883513044Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 21 12:28:54.883550 containerd[2378]: time="2025-03-21T12:28:54.883536644Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 21 12:28:54.883550 containerd[2378]: time="2025-03-21T12:28:54.883547884Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 21 12:28:54.883639 containerd[2378]: time="2025-03-21T12:28:54.883557804Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 21 12:28:54.883639 containerd[2378]: time="2025-03-21T12:28:54.883578844Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 21 12:28:54.883639 containerd[2378]: time="2025-03-21T12:28:54.883591844Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 21 12:28:54.883639 containerd[2378]: time="2025-03-21T12:28:54.883601884Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 21 12:28:54.883639 containerd[2378]: time="2025-03-21T12:28:54.883626564Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883642084Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883652084Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883690924Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883703844Z" 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 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883712244Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883722044Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883730044Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883739964Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883750204Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883765404Z" level=info msg="runtime interface created" Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883770604Z" level=info msg="created NRI interface" Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883779164Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883789684Z" level=info msg="Connect containerd service" Mar 21 12:28:54.883897 containerd[2378]: time="2025-03-21T12:28:54.883819244Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 21 12:28:54.884241 containerd[2378]: time="2025-03-21T12:28:54.884205964Z" 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" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892202772Z" level=info msg="Start subscribing containerd event" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892228532Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892256892Z" level=info msg="Start recovering state" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892302372Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892357932Z" level=info msg="Start event monitor" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892387172Z" level=info msg="Start cni network conf syncer for default" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892396372Z" level=info msg="Start streaming server" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892405492Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892412452Z" level=info msg="runtime interface starting up..." Mar 21 12:28:54.892410 containerd[2378]: time="2025-03-21T12:28:54.892418092Z" level=info msg="starting plugins..." Mar 21 12:28:54.892652 containerd[2378]: time="2025-03-21T12:28:54.892427492Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 21 12:28:54.892628 systemd[1]: Started containerd.service - containerd container runtime. Mar 21 12:28:54.892774 containerd[2378]: time="2025-03-21T12:28:54.892742772Z" level=info msg="containerd successfully booted in 0.044124s" Mar 21 12:28:54.894321 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 21 12:28:54.901740 (dockerd)[2425]: 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 Mar 21 12:28:54.923706 dockerd[2425]: time="2025-03-21T12:28:54.923649682Z" level=info msg="Starting up" Mar 21 12:28:54.925134 dockerd[2425]: time="2025-03-21T12:28:54.925102803Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Mar 21 12:28:54.947705 dockerd[2425]: time="2025-03-21T12:28:54.947458944Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Mar 21 12:28:54.953880 dockerd[2425]: time="2025-03-21T12:28:54.953858110Z" level=info msg="Loading containers: start." Mar 21 12:28:54.966402 systemd[1]: var-lib-docker-overlay2-36b0418ded5c01541c0fd75f1c8b620e768e4d080da1357f289f795363358d09-merged.mount: Deactivated successfully. Mar 21 12:28:55.211097 dockerd[2425]: time="2025-03-21T12:28:55.211005100Z" level=info msg="Removing stale sandbox 0235909e1ac52c61f1d6fae65032f91ce97dba11ce3975cb9abe50ebb3847d1f (1fd1409b8a2de0af884abd7b9a0826687313340a223750e1b5545d3f0420f6a5)" Mar 21 12:28:55.214460 dockerd[2425]: time="2025-03-21T12:28:55.214407463Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 0a49daf4d37917ef37b5c0e97850425e7d0fd88864da6f2b4f2f88bee125d3d5 e09a89802b11d6504c039e54a9bc3463a15bf2c0c450017682dc4ba4e3796d35], retrying...." Mar 21 12:28:55.217796 systemd-networkd[1340]: veth064b059: Link DOWN Mar 21 12:28:55.218542 kernel: docker0: port 1(veth064b059) entered disabled state Mar 21 12:28:55.217803 systemd-networkd[1340]: veth064b059: Lost carrier Mar 21 12:28:55.220326 kernel: veth064b059 (unregistering): left allmulticast mode Mar 21 12:28:55.220391 kernel: veth064b059 (unregistering): left promiscuous mode Mar 21 12:28:55.220410 kernel: docker0: port 1(veth064b059) entered disabled state Mar 21 12:28:55.228476 systemd-networkd[1340]: docker0: Lost carrier Mar 21 12:28:55.323266 dockerd[2425]: time="2025-03-21T12:28:55.323229200Z" 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" Mar 21 12:28:55.416509 dockerd[2425]: time="2025-03-21T12:28:55.416451242Z" level=warning msg="error locating sandbox id 5f3e5d27abb85e4a2b7b5e2816f720022cb87345c2c3c92c9656bc156e79801a: sandbox 5f3e5d27abb85e4a2b7b5e2816f720022cb87345c2c3c92c9656bc156e79801a not found" Mar 21 12:28:55.416509 dockerd[2425]: time="2025-03-21T12:28:55.416483562Z" level=warning msg="error locating sandbox id 0235909e1ac52c61f1d6fae65032f91ce97dba11ce3975cb9abe50ebb3847d1f: sandbox 0235909e1ac52c61f1d6fae65032f91ce97dba11ce3975cb9abe50ebb3847d1f not found" Mar 21 12:28:55.417128 dockerd[2425]: time="2025-03-21T12:28:55.416677202Z" level=info msg="Loading containers: done." Mar 21 12:28:55.425353 dockerd[2425]: time="2025-03-21T12:28:55.425308370Z" 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 Mar 21 12:28:55.425512 dockerd[2425]: time="2025-03-21T12:28:55.425392610Z" level=info msg="Docker daemon" commit=c710b88579fcb5e0d53f96dcae976d79323b9166 containerd-snapshotter=false storage-driver=overlay2 version=27.4.1 Mar 21 12:28:55.425512 dockerd[2425]: time="2025-03-21T12:28:55.425457010Z" level=info msg="Daemon has completed initialization" Mar 21 12:28:55.443451 dockerd[2425]: time="2025-03-21T12:28:55.443306106Z" level=info msg="API listen on /run/docker.sock" Mar 21 12:28:55.443563 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 21 12:28:55.940158 systemd[1]: run-docker-netns-0235909e1ac5.mount: Deactivated successfully. Mar 21 12:28:59.289558 systemd[1]: Started sshd@24-10.0.0.65:22-10.0.0.1:38574.service - OpenSSH per-connection server daemon (10.0.0.1:38574). Mar 21 12:28:59.333929 sshd[2633]: Accepted publickey for core from 10.0.0.1 port 38574 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:59.335024 sshd-session[2633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:59.339111 systemd-logind[1400]: New session 25 of user core. Mar 21 12:28:59.353670 systemd[1]: Started session-25.scope - Session 25 of User core. Mar 21 12:28:59.406110 sshd[2635]: Connection closed by 10.0.0.1 port 38574 Mar 21 12:28:59.406411 sshd-session[2633]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:59.420483 systemd[1]: sshd@24-10.0.0.65:22-10.0.0.1:38574.service: Deactivated successfully. Mar 21 12:28:59.422071 systemd[1]: session-25.scope: Deactivated successfully. Mar 21 12:28:59.423451 systemd-logind[1400]: Session 25 logged out. Waiting for processes to exit. Mar 21 12:28:59.424652 systemd[1]: Started sshd@25-10.0.0.65:22-10.0.0.1:38584.service - OpenSSH per-connection server daemon (10.0.0.1:38584). Mar 21 12:28:59.425502 systemd-logind[1400]: Removed session 25. Mar 21 12:28:59.476092 sshd[2640]: Accepted publickey for core from 10.0.0.1 port 38584 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:59.477131 sshd-session[2640]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:59.481130 systemd-logind[1400]: New session 26 of user core. Mar 21 12:28:59.489558 systemd[1]: Started session-26.scope - Session 26 of User core. Mar 21 12:28:59.541205 sshd[2643]: Connection closed by 10.0.0.1 port 38584 Mar 21 12:28:59.541604 sshd-session[2640]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:59.550507 systemd[1]: sshd@25-10.0.0.65:22-10.0.0.1:38584.service: Deactivated successfully. Mar 21 12:28:59.553885 systemd[1]: session-26.scope: Deactivated successfully. Mar 21 12:28:59.554532 systemd-logind[1400]: Session 26 logged out. Waiting for processes to exit. Mar 21 12:28:59.556283 systemd[1]: Started sshd@26-10.0.0.65:22-10.0.0.1:38590.service - OpenSSH per-connection server daemon (10.0.0.1:38590). Mar 21 12:28:59.558259 systemd-logind[1400]: Removed session 26. Mar 21 12:28:59.610334 sshd[2648]: Accepted publickey for core from 10.0.0.1 port 38590 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:59.611372 sshd-session[2648]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:59.615282 systemd-logind[1400]: New session 27 of user core. Mar 21 12:28:59.625596 systemd[1]: Started session-27.scope - Session 27 of User core. Mar 21 12:28:59.682155 sshd[2651]: Connection closed by 10.0.0.1 port 38590 Mar 21 12:28:59.681315 sshd-session[2648]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:59.694596 systemd[1]: sshd@26-10.0.0.65:22-10.0.0.1:38590.service: Deactivated successfully. Mar 21 12:28:59.696045 systemd[1]: session-27.scope: Deactivated successfully. Mar 21 12:28:59.697256 systemd-logind[1400]: Session 27 logged out. Waiting for processes to exit. Mar 21 12:28:59.698345 systemd[1]: Started sshd@27-10.0.0.65:22-10.0.0.1:38598.service - OpenSSH per-connection server daemon (10.0.0.1:38598). Mar 21 12:28:59.699149 systemd-logind[1400]: Removed session 27. Mar 21 12:28:59.751855 sshd[2663]: Accepted publickey for core from 10.0.0.1 port 38598 ssh2: RSA SHA256:MdsOSlIGNpcftqwP7ll+xX3Rmkua/0DX/UznjsKKr2Y Mar 21 12:28:59.752901 sshd-session[2663]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:28:59.756876 systemd-logind[1400]: New session 28 of user core. Mar 21 12:28:59.768569 systemd[1]: Started session-28.scope - Session 28 of User core. Mar 21 12:28:59.834965 systemd[1]: var-lib-docker-overlay2-0f620452a7bef55369d378d546f7ffc6dfdcc98427696698525806bb875c231c\x2dinit-merged.mount: Deactivated successfully. Mar 21 12:28:59.855252 kernel: docker0: port 1(vethc8801ab) entered blocking state Mar 21 12:28:59.855343 kernel: docker0: port 1(vethc8801ab) entered disabled state Mar 21 12:28:59.855379 kernel: vethc8801ab: entered allmulticast mode Mar 21 12:28:59.855982 kernel: vethc8801ab: entered promiscuous mode Mar 21 12:28:59.857634 systemd-networkd[1340]: vethc8801ab: Link UP Mar 21 12:28:59.884331 containerd[2378]: time="2025-03-21T12:28:59.884279797Z" level=info msg="connecting to shim 9328293825ee84c3a05ac49d10b35d21efa0d96473193212d85ff1c27a168f20" address="unix:///run/containerd/s/534efaf8197771b25a80b72634c29c0944e1551e89a951a4e3ab2cddb11de980" namespace=moby protocol=ttrpc version=3 Mar 21 12:28:59.906593 systemd[1]: Started docker-9328293825ee84c3a05ac49d10b35d21efa0d96473193212d85ff1c27a168f20.scope - libcontainer container 9328293825ee84c3a05ac49d10b35d21efa0d96473193212d85ff1c27a168f20. Mar 21 12:28:59.949477 kernel: eth0: renamed from veth8b431f6 Mar 21 12:28:59.956376 systemd-networkd[1340]: vethc8801ab: Gained carrier Mar 21 12:28:59.956502 kernel: docker0: port 1(vethc8801ab) entered blocking state Mar 21 12:28:59.956522 kernel: docker0: port 1(vethc8801ab) entered forwarding state Mar 21 12:28:59.956601 systemd-networkd[1340]: docker0: Gained carrier Mar 21 12:28:59.958813 systemd-resolved[1256]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:28:59.981312 sshd[2666]: Connection closed by 10.0.0.1 port 38598 Mar 21 12:28:59.981815 sshd-session[2663]: pam_unix(sshd:session): session closed for user core Mar 21 12:28:59.985015 systemd[1]: sshd@27-10.0.0.65:22-10.0.0.1:38598.service: Deactivated successfully. Mar 21 12:28:59.986635 systemd[1]: session-28.scope: Deactivated successfully. Mar 21 12:28:59.987253 systemd-logind[1400]: Session 28 logged out. Waiting for processes to exit. Mar 21 12:28:59.988117 systemd-logind[1400]: Removed session 28. Mar 21 12:29:01.280700 systemd-networkd[1340]: vethc8801ab: Gained IPv6LL