Nov 12 22:38:12.917693 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 12 22:38:12.917716 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Nov 12 21:07:55 -00 2024 Nov 12 22:38:12.917727 kernel: KASLR enabled Nov 12 22:38:12.917733 kernel: efi: EFI v2.7 by EDK II Nov 12 22:38:12.917739 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbbf018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40d98 Nov 12 22:38:12.917744 kernel: random: crng init done Nov 12 22:38:12.917751 kernel: secureboot: Secure boot disabled Nov 12 22:38:12.917757 kernel: ACPI: Early table checksum verification disabled Nov 12 22:38:12.917763 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Nov 12 22:38:12.917771 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 12 22:38:12.917777 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917783 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917799 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917806 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917814 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917822 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917828 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917835 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917841 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 22:38:12.917847 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 12 22:38:12.917853 kernel: NUMA: Failed to initialise from firmware Nov 12 22:38:12.917860 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:38:12.917866 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Nov 12 22:38:12.917872 kernel: Zone ranges: Nov 12 22:38:12.917878 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:38:12.917886 kernel: DMA32 empty Nov 12 22:38:12.917892 kernel: Normal empty Nov 12 22:38:12.917898 kernel: Movable zone start for each node Nov 12 22:38:12.917904 kernel: Early memory node ranges Nov 12 22:38:12.917910 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Nov 12 22:38:12.917916 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Nov 12 22:38:12.917923 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Nov 12 22:38:12.917929 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 12 22:38:12.917935 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 12 22:38:12.917941 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 12 22:38:12.917947 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 12 22:38:12.917954 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 12 22:38:12.917961 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 12 22:38:12.917968 kernel: psci: probing for conduit method from ACPI. Nov 12 22:38:12.918015 kernel: psci: PSCIv1.1 detected in firmware. Nov 12 22:38:12.918026 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 22:38:12.918033 kernel: psci: Trusted OS migration not required Nov 12 22:38:12.918040 kernel: psci: SMC Calling Convention v1.1 Nov 12 22:38:12.918048 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 12 22:38:12.918055 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 22:38:12.918061 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 22:38:12.918068 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 12 22:38:12.918215 kernel: Detected PIPT I-cache on CPU0 Nov 12 22:38:12.918222 kernel: CPU features: detected: GIC system register CPU interface Nov 12 22:38:12.918246 kernel: CPU features: detected: Hardware dirty bit management Nov 12 22:38:12.918253 kernel: CPU features: detected: Spectre-v4 Nov 12 22:38:12.918259 kernel: CPU features: detected: Spectre-BHB Nov 12 22:38:12.918266 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 12 22:38:12.918278 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 12 22:38:12.918285 kernel: CPU features: detected: ARM erratum 1418040 Nov 12 22:38:12.918291 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 12 22:38:12.918487 kernel: alternatives: applying boot alternatives Nov 12 22:38:12.918538 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=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:38:12.918549 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 22:38:12.918556 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 22:38:12.918563 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 22:38:12.918569 kernel: Fallback order for Node 0: 0 Nov 12 22:38:12.918576 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 12 22:38:12.918582 kernel: Policy zone: DMA Nov 12 22:38:12.918595 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 22:38:12.918602 kernel: software IO TLB: area num 4. Nov 12 22:38:12.918609 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Nov 12 22:38:12.918616 kernel: Memory: 2386324K/2572288K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39680K init, 897K bss, 185964K reserved, 0K cma-reserved) Nov 12 22:38:12.918622 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 12 22:38:12.918629 kernel: trace event string verifier disabled Nov 12 22:38:12.918636 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 22:38:12.918643 kernel: rcu: RCU event tracing is enabled. Nov 12 22:38:12.918650 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 12 22:38:12.918657 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 22:38:12.918664 kernel: Tracing variant of Tasks RCU enabled. Nov 12 22:38:12.918671 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 22:38:12.918679 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 12 22:38:12.918686 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 22:38:12.918692 kernel: GICv3: 256 SPIs implemented Nov 12 22:38:12.918699 kernel: GICv3: 0 Extended SPIs implemented Nov 12 22:38:12.918705 kernel: Root IRQ handler: gic_handle_irq Nov 12 22:38:12.918712 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 12 22:38:12.918719 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 12 22:38:12.918725 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 12 22:38:12.918732 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 22:38:12.918739 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Nov 12 22:38:12.918746 kernel: GICv3: using LPI property table @0x00000000400f0000 Nov 12 22:38:12.918754 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Nov 12 22:38:12.918761 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 22:38:12.918767 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:38:12.918774 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 12 22:38:12.918781 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 12 22:38:12.918787 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 12 22:38:12.918825 kernel: arm-pv: using stolen time PV Nov 12 22:38:12.918833 kernel: Console: colour dummy device 80x25 Nov 12 22:38:12.918840 kernel: ACPI: Core revision 20230628 Nov 12 22:38:12.918847 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 12 22:38:12.918854 kernel: pid_max: default: 32768 minimum: 301 Nov 12 22:38:12.918864 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 22:38:12.918871 kernel: landlock: Up and running. Nov 12 22:38:12.918878 kernel: SELinux: Initializing. Nov 12 22:38:12.918884 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:38:12.918892 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:38:12.918899 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 12 22:38:12.918906 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 12 22:38:12.918912 kernel: rcu: Hierarchical SRCU implementation. Nov 12 22:38:12.918919 kernel: rcu: Max phase no-delay instances is 400. Nov 12 22:38:12.918927 kernel: Platform MSI: ITS@0x8080000 domain created Nov 12 22:38:12.918934 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 12 22:38:12.918941 kernel: Remapping and enabling EFI services. Nov 12 22:38:12.918948 kernel: smp: Bringing up secondary CPUs ... Nov 12 22:38:12.918955 kernel: Detected PIPT I-cache on CPU1 Nov 12 22:38:12.918962 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 12 22:38:12.918968 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Nov 12 22:38:12.918976 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:38:12.918982 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 12 22:38:12.918991 kernel: Detected PIPT I-cache on CPU2 Nov 12 22:38:12.918998 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 12 22:38:12.919010 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Nov 12 22:38:12.919019 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:38:12.919026 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 12 22:38:12.919033 kernel: Detected PIPT I-cache on CPU3 Nov 12 22:38:12.919040 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 12 22:38:12.919047 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Nov 12 22:38:12.919055 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 12 22:38:12.919063 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 12 22:38:12.919071 kernel: smp: Brought up 1 node, 4 CPUs Nov 12 22:38:12.919078 kernel: SMP: Total of 4 processors activated. Nov 12 22:38:12.919085 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 22:38:12.919092 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 12 22:38:12.919099 kernel: CPU features: detected: Common not Private translations Nov 12 22:38:12.919106 kernel: CPU features: detected: CRC32 instructions Nov 12 22:38:12.919113 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 12 22:38:12.919122 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 12 22:38:12.919129 kernel: CPU features: detected: LSE atomic instructions Nov 12 22:38:12.919136 kernel: CPU features: detected: Privileged Access Never Nov 12 22:38:12.919143 kernel: CPU features: detected: RAS Extension Support Nov 12 22:38:12.919150 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 12 22:38:12.919157 kernel: CPU: All CPU(s) started at EL1 Nov 12 22:38:12.919165 kernel: alternatives: applying system-wide alternatives Nov 12 22:38:12.919172 kernel: devtmpfs: initialized Nov 12 22:38:12.919179 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 22:38:12.919188 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 12 22:38:12.919205 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 22:38:12.919213 kernel: SMBIOS 3.0.0 present. Nov 12 22:38:12.919220 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 12 22:38:12.919227 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 22:38:12.919235 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 22:38:12.919242 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 22:38:12.919249 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 22:38:12.919257 kernel: audit: initializing netlink subsys (disabled) Nov 12 22:38:12.919266 kernel: audit: type=2000 audit(0.019:1): state=initialized audit_enabled=0 res=1 Nov 12 22:38:12.919274 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 22:38:12.919281 kernel: cpuidle: using governor menu Nov 12 22:38:12.919288 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 22:38:12.919295 kernel: ASID allocator initialised with 32768 entries Nov 12 22:38:12.919302 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 22:38:12.919309 kernel: Serial: AMBA PL011 UART driver Nov 12 22:38:12.919316 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 12 22:38:12.919324 kernel: Modules: 0 pages in range for non-PLT usage Nov 12 22:38:12.919332 kernel: Modules: 508960 pages in range for PLT usage Nov 12 22:38:12.919340 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 22:38:12.919347 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 22:38:12.919354 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 22:38:12.919361 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 22:38:12.919368 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 22:38:12.919376 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 22:38:12.919383 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 22:38:12.919390 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 22:38:12.919398 kernel: ACPI: Added _OSI(Module Device) Nov 12 22:38:12.919406 kernel: ACPI: Added _OSI(Processor Device) Nov 12 22:38:12.919413 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 22:38:12.919420 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 22:38:12.919427 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 22:38:12.919434 kernel: ACPI: Interpreter enabled Nov 12 22:38:12.919441 kernel: ACPI: Using GIC for interrupt routing Nov 12 22:38:12.919448 kernel: ACPI: MCFG table detected, 1 entries Nov 12 22:38:12.919455 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 12 22:38:12.919462 kernel: printk: console [ttyAMA0] enabled Nov 12 22:38:12.919471 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 12 22:38:12.919716 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 22:38:12.920287 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 22:38:12.920370 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 22:38:12.920436 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 12 22:38:12.920500 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 12 22:38:12.920510 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 12 22:38:12.920521 kernel: PCI host bridge to bus 0000:00 Nov 12 22:38:12.920593 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 12 22:38:12.920654 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 22:38:12.920714 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 12 22:38:12.920772 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 12 22:38:12.920878 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 12 22:38:12.920964 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 12 22:38:12.921033 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 12 22:38:12.921099 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 12 22:38:12.921164 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 12 22:38:12.921247 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 12 22:38:12.921656 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 12 22:38:12.921842 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 12 22:38:12.921924 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 12 22:38:12.921984 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 22:38:12.922043 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 12 22:38:12.922053 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 22:38:12.922060 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 22:38:12.922068 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 22:38:12.922075 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 22:38:12.922083 kernel: iommu: Default domain type: Translated Nov 12 22:38:12.922093 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 22:38:12.922100 kernel: efivars: Registered efivars operations Nov 12 22:38:12.922108 kernel: vgaarb: loaded Nov 12 22:38:12.922115 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 22:38:12.922122 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 22:38:12.922130 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 22:38:12.922137 kernel: pnp: PnP ACPI init Nov 12 22:38:12.922238 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 12 22:38:12.922251 kernel: pnp: PnP ACPI: found 1 devices Nov 12 22:38:12.922262 kernel: NET: Registered PF_INET protocol family Nov 12 22:38:12.922269 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 22:38:12.922276 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 22:38:12.922284 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 22:38:12.922291 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 22:38:12.922298 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 22:38:12.922306 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 22:38:12.922313 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:38:12.922323 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:38:12.922330 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 22:38:12.922338 kernel: PCI: CLS 0 bytes, default 64 Nov 12 22:38:12.922349 kernel: kvm [1]: HYP mode not available Nov 12 22:38:12.922357 kernel: Initialise system trusted keyrings Nov 12 22:38:12.922365 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 22:38:12.922372 kernel: Key type asymmetric registered Nov 12 22:38:12.922379 kernel: Asymmetric key parser 'x509' registered Nov 12 22:38:12.922387 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 22:38:12.922396 kernel: io scheduler mq-deadline registered Nov 12 22:38:12.922404 kernel: io scheduler kyber registered Nov 12 22:38:12.922411 kernel: io scheduler bfq registered Nov 12 22:38:12.922419 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 22:38:12.922427 kernel: ACPI: button: Power Button [PWRB] Nov 12 22:38:12.922435 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 22:38:12.922523 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 12 22:38:12.922534 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 22:38:12.922541 kernel: thunder_xcv, ver 1.0 Nov 12 22:38:12.922550 kernel: thunder_bgx, ver 1.0 Nov 12 22:38:12.922559 kernel: nicpf, ver 1.0 Nov 12 22:38:12.922566 kernel: nicvf, ver 1.0 Nov 12 22:38:12.922641 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 22:38:12.922707 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T22:38:12 UTC (1731451092) Nov 12 22:38:12.922717 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 22:38:12.922724 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 12 22:38:12.922732 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 22:38:12.922739 kernel: watchdog: Hard watchdog permanently disabled Nov 12 22:38:12.922749 kernel: NET: Registered PF_INET6 protocol family Nov 12 22:38:12.922756 kernel: Segment Routing with IPv6 Nov 12 22:38:12.922764 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 22:38:12.922771 kernel: NET: Registered PF_PACKET protocol family Nov 12 22:38:12.922778 kernel: Key type dns_resolver registered Nov 12 22:38:12.922785 kernel: registered taskstats version 1 Nov 12 22:38:12.922810 kernel: Loading compiled-in X.509 certificates Nov 12 22:38:12.922818 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 27dd0d090d7a0971a24582c9198f7e80123ea69f' Nov 12 22:38:12.922826 kernel: Key type .fscrypt registered Nov 12 22:38:12.922835 kernel: Key type fscrypt-provisioning registered Nov 12 22:38:12.922842 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 22:38:12.922850 kernel: ima: Allocated hash algorithm: sha1 Nov 12 22:38:12.922857 kernel: ima: No architecture policies found Nov 12 22:38:12.922864 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 22:38:12.922872 kernel: clk: Disabling unused clocks Nov 12 22:38:12.922879 kernel: Freeing unused kernel memory: 39680K Nov 12 22:38:12.922887 kernel: Run /init as init process Nov 12 22:38:12.922896 kernel: with arguments: Nov 12 22:38:12.922904 kernel: /init Nov 12 22:38:12.922911 kernel: with environment: Nov 12 22:38:12.922918 kernel: HOME=/ Nov 12 22:38:12.922925 kernel: TERM=linux Nov 12 22:38:12.922932 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 22:38:12.922941 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:38:12.922951 systemd[1]: Detected virtualization kvm. Nov 12 22:38:12.922960 systemd[1]: Detected architecture arm64. Nov 12 22:38:12.922967 systemd[1]: Running in initrd. Nov 12 22:38:12.922975 systemd[1]: No hostname configured, using default hostname. Nov 12 22:38:12.922982 systemd[1]: Hostname set to . Nov 12 22:38:12.922990 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:38:12.922997 systemd[1]: Queued start job for default target initrd.target. Nov 12 22:38:12.923005 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:38:12.923013 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:38:12.923022 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 22:38:12.923030 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:38:12.923038 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 22:38:12.923046 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 22:38:12.923055 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 22:38:12.923063 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 22:38:12.923071 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:38:12.923080 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:38:12.923088 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:38:12.923096 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:38:12.923104 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:38:12.923111 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:38:12.923119 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:38:12.923127 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:38:12.923135 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:38:12.923143 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:38:12.923153 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:38:12.923161 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:38:12.923169 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:38:12.923177 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:38:12.923185 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 22:38:12.923225 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:38:12.923237 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 22:38:12.923245 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 22:38:12.923256 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:38:12.923264 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:38:12.923272 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:38:12.923280 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 22:38:12.923288 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:38:12.923296 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 22:38:12.923306 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:38:12.923314 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:38:12.923322 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 22:38:12.923330 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:38:12.923337 kernel: Bridge firewalling registered Nov 12 22:38:12.923368 systemd-journald[238]: Collecting audit messages is disabled. Nov 12 22:38:12.923390 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:38:12.923398 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:38:12.923406 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:38:12.923415 systemd-journald[238]: Journal started Nov 12 22:38:12.923435 systemd-journald[238]: Runtime Journal (/run/log/journal/4e02bd6d139e4d0e97122e3dfa042fc0) is 5.9M, max 47.3M, 41.4M free. Nov 12 22:38:12.897011 systemd-modules-load[239]: Inserted module 'overlay' Nov 12 22:38:12.914709 systemd-modules-load[239]: Inserted module 'br_netfilter' Nov 12 22:38:12.928167 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:38:12.928189 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:38:12.934904 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:38:12.936301 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:38:12.939215 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:38:12.941262 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:38:12.944531 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 22:38:12.948258 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:38:12.951221 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:38:12.958541 dracut-cmdline[275]: dracut-dracut-053 Nov 12 22:38:12.961046 dracut-cmdline[275]: 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=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:38:12.982277 systemd-resolved[278]: Positive Trust Anchors: Nov 12 22:38:12.982352 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:38:12.982384 systemd-resolved[278]: 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 Nov 12 22:38:12.987003 systemd-resolved[278]: Defaulting to hostname 'linux'. Nov 12 22:38:12.990452 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:38:12.991604 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:38:13.030822 kernel: SCSI subsystem initialized Nov 12 22:38:13.034822 kernel: Loading iSCSI transport class v2.0-870. Nov 12 22:38:13.042823 kernel: iscsi: registered transport (tcp) Nov 12 22:38:13.056821 kernel: iscsi: registered transport (qla4xxx) Nov 12 22:38:13.056844 kernel: QLogic iSCSI HBA Driver Nov 12 22:38:13.100821 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 22:38:13.105976 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 22:38:13.129295 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 22:38:13.129356 kernel: device-mapper: uevent: version 1.0.3 Nov 12 22:38:13.130364 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 22:38:13.181829 kernel: raid6: neonx8 gen() 15776 MB/s Nov 12 22:38:13.198834 kernel: raid6: neonx4 gen() 15648 MB/s Nov 12 22:38:13.215828 kernel: raid6: neonx2 gen() 13236 MB/s Nov 12 22:38:13.232821 kernel: raid6: neonx1 gen() 10458 MB/s Nov 12 22:38:13.249810 kernel: raid6: int64x8 gen() 6958 MB/s Nov 12 22:38:13.266814 kernel: raid6: int64x4 gen() 7343 MB/s Nov 12 22:38:13.283815 kernel: raid6: int64x2 gen() 6127 MB/s Nov 12 22:38:13.300913 kernel: raid6: int64x1 gen() 5056 MB/s Nov 12 22:38:13.300926 kernel: raid6: using algorithm neonx8 gen() 15776 MB/s Nov 12 22:38:13.318926 kernel: raid6: .... xor() 11890 MB/s, rmw enabled Nov 12 22:38:13.318940 kernel: raid6: using neon recovery algorithm Nov 12 22:38:13.323813 kernel: xor: measuring software checksum speed Nov 12 22:38:13.325019 kernel: 8regs : 17499 MB/sec Nov 12 22:38:13.325032 kernel: 32regs : 19641 MB/sec Nov 12 22:38:13.326243 kernel: arm64_neon : 26910 MB/sec Nov 12 22:38:13.326256 kernel: xor: using function: arm64_neon (26910 MB/sec) Nov 12 22:38:13.375823 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 22:38:13.386869 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:38:13.402962 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:38:13.414289 systemd-udevd[461]: Using default interface naming scheme 'v255'. Nov 12 22:38:13.417522 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:38:13.429219 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 22:38:13.440351 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Nov 12 22:38:13.466856 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:38:13.478917 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:38:13.518856 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:38:13.530454 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 22:38:13.543685 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 22:38:13.547066 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:38:13.548240 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:38:13.550511 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:38:13.557109 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 22:38:13.568734 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 12 22:38:13.571765 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 12 22:38:13.571898 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:38:13.568203 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:38:13.574309 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:38:13.574958 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:38:13.578658 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:38:13.580151 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:38:13.580284 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:38:13.582426 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:38:13.594155 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:38:13.598813 kernel: BTRFS: device fsid 337794e4-53df-462b-aefc-e93e6a958f34 devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (514) Nov 12 22:38:13.601819 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (508) Nov 12 22:38:13.608854 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 12 22:38:13.610831 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:38:13.616017 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 12 22:38:13.622769 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 12 22:38:13.623892 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 12 22:38:13.629184 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 22:38:13.646020 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 22:38:13.647837 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:38:13.659024 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:38:13.666547 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:38:14.669820 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 22:38:14.670705 disk-uuid[554]: The operation has completed successfully. Nov 12 22:38:14.689166 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 22:38:14.689269 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 22:38:14.712999 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 22:38:14.715589 sh[571]: Success Nov 12 22:38:14.728824 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 22:38:14.752565 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 22:38:14.765926 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 22:38:14.768208 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 22:38:14.778234 kernel: BTRFS info (device dm-0): first mount of filesystem 337794e4-53df-462b-aefc-e93e6a958f34 Nov 12 22:38:14.778266 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:38:14.778276 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 22:38:14.779981 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 22:38:14.780009 kernel: BTRFS info (device dm-0): using free space tree Nov 12 22:38:14.783503 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 22:38:14.784746 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 22:38:14.785442 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 22:38:14.788179 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 22:38:14.796616 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:38:14.796649 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:38:14.797369 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:38:14.799818 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:38:14.806246 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 22:38:14.808873 kernel: BTRFS info (device vda6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:38:14.813153 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 22:38:14.819995 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 22:38:14.883580 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:38:14.891087 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:38:14.909127 systemd-networkd[763]: lo: Link UP Nov 12 22:38:14.909138 systemd-networkd[763]: lo: Gained carrier Nov 12 22:38:14.909901 systemd-networkd[763]: Enumeration completed Nov 12 22:38:14.910321 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:38:14.910324 systemd-networkd[763]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:38:14.911773 systemd-networkd[763]: eth0: Link UP Nov 12 22:38:14.911776 systemd-networkd[763]: eth0: Gained carrier Nov 12 22:38:14.917961 ignition[663]: Ignition 2.20.0 Nov 12 22:38:14.911783 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:38:14.917967 ignition[663]: Stage: fetch-offline Nov 12 22:38:14.912576 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:38:14.918000 ignition[663]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:14.913758 systemd[1]: Reached target network.target - Network. Nov 12 22:38:14.918007 ignition[663]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:14.918164 ignition[663]: parsed url from cmdline: "" Nov 12 22:38:14.918167 ignition[663]: no config URL provided Nov 12 22:38:14.918172 ignition[663]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 22:38:14.918178 ignition[663]: no config at "/usr/lib/ignition/user.ign" Nov 12 22:38:14.918212 ignition[663]: op(1): [started] loading QEMU firmware config module Nov 12 22:38:14.918216 ignition[663]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 12 22:38:14.927080 ignition[663]: op(1): [finished] loading QEMU firmware config module Nov 12 22:38:14.932949 ignition[663]: parsing config with SHA512: 796cad7db815f8f01ee5011a47a7346182723d515ea0acbe31192a49f3f2faa38911f4b65f18e378b5b26292f331f90c2b77b2003f4f67315edfa47b9bcb0670 Nov 12 22:38:14.936853 systemd-networkd[763]: eth0: DHCPv4 address 10.0.0.102/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 12 22:38:14.939339 unknown[663]: fetched base config from "system" Nov 12 22:38:14.939355 unknown[663]: fetched user config from "qemu" Nov 12 22:38:14.939599 ignition[663]: fetch-offline: fetch-offline passed Nov 12 22:38:14.941687 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:38:14.939699 ignition[663]: Ignition finished successfully Nov 12 22:38:14.943692 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 12 22:38:14.956937 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 22:38:14.966339 ignition[769]: Ignition 2.20.0 Nov 12 22:38:14.966348 ignition[769]: Stage: kargs Nov 12 22:38:14.966497 ignition[769]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:14.966506 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:14.967049 ignition[769]: kargs: kargs passed Nov 12 22:38:14.967085 ignition[769]: Ignition finished successfully Nov 12 22:38:14.969848 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 22:38:14.971819 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 22:38:14.983635 ignition[778]: Ignition 2.20.0 Nov 12 22:38:14.983644 ignition[778]: Stage: disks Nov 12 22:38:14.983784 ignition[778]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:14.983818 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:14.984380 ignition[778]: disks: disks passed Nov 12 22:38:14.986849 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 22:38:14.984418 ignition[778]: Ignition finished successfully Nov 12 22:38:14.988564 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 22:38:14.989856 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:38:14.991746 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:38:14.993223 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:38:14.995011 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:38:15.003911 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 22:38:15.012953 systemd-fsck[789]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 22:38:15.017135 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 22:38:15.032892 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 22:38:15.077907 kernel: EXT4-fs (vda9): mounted filesystem be7e07bb-77fc-4aec-a4f6-d76dc4498784 r/w with ordered data mode. Quota mode: none. Nov 12 22:38:15.078361 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 22:38:15.079597 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 22:38:15.091883 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:38:15.093524 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 22:38:15.094612 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 22:38:15.094697 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 22:38:15.094720 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:38:15.099120 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 22:38:15.105281 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (797) Nov 12 22:38:15.102363 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 22:38:15.109467 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:38:15.109488 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:38:15.109498 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:38:15.113830 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:38:15.113667 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:38:15.146737 initrd-setup-root[821]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 22:38:15.149863 initrd-setup-root[828]: cut: /sysroot/etc/group: No such file or directory Nov 12 22:38:15.154360 initrd-setup-root[835]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 22:38:15.158255 initrd-setup-root[842]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 22:38:15.230705 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 22:38:15.236921 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 22:38:15.239212 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 22:38:15.243834 kernel: BTRFS info (device vda6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:38:15.257980 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 22:38:15.260495 ignition[910]: INFO : Ignition 2.20.0 Nov 12 22:38:15.260495 ignition[910]: INFO : Stage: mount Nov 12 22:38:15.262093 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:15.262093 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:15.262093 ignition[910]: INFO : mount: mount passed Nov 12 22:38:15.262093 ignition[910]: INFO : Ignition finished successfully Nov 12 22:38:15.263166 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 22:38:15.277905 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 22:38:15.777108 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 22:38:15.786957 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:38:15.793554 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (924) Nov 12 22:38:15.793586 kernel: BTRFS info (device vda6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:38:15.793597 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:38:15.795089 kernel: BTRFS info (device vda6): using free space tree Nov 12 22:38:15.796806 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 22:38:15.798170 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:38:15.813729 ignition[941]: INFO : Ignition 2.20.0 Nov 12 22:38:15.813729 ignition[941]: INFO : Stage: files Nov 12 22:38:15.815284 ignition[941]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:15.815284 ignition[941]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:15.815284 ignition[941]: DEBUG : files: compiled without relabeling support, skipping Nov 12 22:38:15.815284 ignition[941]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 22:38:15.815284 ignition[941]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 22:38:15.821397 ignition[941]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 22:38:15.821397 ignition[941]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 22:38:15.821397 ignition[941]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 22:38:15.821397 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:38:15.821397 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(4): [started] processing unit "docker.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(4): [finished] processing unit "docker.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Nov 12 22:38:15.821397 ignition[941]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Nov 12 22:38:15.817427 unknown[941]: wrote ssh authorized keys file for user: core Nov 12 22:38:15.840390 ignition[941]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 12 22:38:15.843551 ignition[941]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 12 22:38:15.845757 ignition[941]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Nov 12 22:38:15.845757 ignition[941]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Nov 12 22:38:15.845757 ignition[941]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Nov 12 22:38:15.845757 ignition[941]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:38:15.845757 ignition[941]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:38:15.845757 ignition[941]: INFO : files: files passed Nov 12 22:38:15.845757 ignition[941]: INFO : Ignition finished successfully Nov 12 22:38:15.846283 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 22:38:15.853987 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 22:38:15.855629 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 22:38:15.856921 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 22:38:15.857000 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 22:38:15.863439 initrd-setup-root-after-ignition[970]: grep: /sysroot/oem/oem-release: No such file or directory Nov 12 22:38:15.865414 initrd-setup-root-after-ignition[972]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:38:15.865414 initrd-setup-root-after-ignition[972]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:38:15.868263 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:38:15.867520 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:38:15.869661 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 22:38:15.879917 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 22:38:15.896447 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 22:38:15.896545 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 22:38:15.898613 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 22:38:15.900391 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 22:38:15.902069 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 22:38:15.902770 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 22:38:15.917619 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:38:15.924940 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 22:38:15.932047 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:38:15.933217 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:38:15.935151 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 22:38:15.936778 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 22:38:15.936911 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:38:15.939361 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 22:38:15.941307 systemd[1]: Stopped target basic.target - Basic System. Nov 12 22:38:15.942862 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 22:38:15.944496 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:38:15.946403 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 22:38:15.948268 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 22:38:15.949994 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:38:15.951854 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 22:38:15.953822 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 22:38:15.955516 systemd[1]: Stopped target swap.target - Swaps. Nov 12 22:38:15.956967 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 22:38:15.957084 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:38:15.959288 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:38:15.961111 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:38:15.962888 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 22:38:15.964762 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:38:15.965952 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 22:38:15.966056 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 22:38:15.968679 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 22:38:15.968811 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:38:15.970734 systemd[1]: Stopped target paths.target - Path Units. Nov 12 22:38:15.972294 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 22:38:15.975893 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:38:15.977136 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 22:38:15.979120 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 22:38:15.980633 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 22:38:15.980719 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:38:15.982200 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 22:38:15.982279 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:38:15.983731 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 22:38:15.983854 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:38:15.985551 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 22:38:15.985647 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 22:38:15.992949 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 22:38:15.993785 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 22:38:15.993931 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:38:15.996887 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 22:38:15.998375 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 22:38:15.998500 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:38:16.001672 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 22:38:16.004864 ignition[996]: INFO : Ignition 2.20.0 Nov 12 22:38:16.004864 ignition[996]: INFO : Stage: umount Nov 12 22:38:16.004864 ignition[996]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:38:16.004864 ignition[996]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 12 22:38:16.004864 ignition[996]: INFO : umount: umount passed Nov 12 22:38:16.004864 ignition[996]: INFO : Ignition finished successfully Nov 12 22:38:16.001913 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:38:16.008080 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 22:38:16.008807 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 22:38:16.008887 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 22:38:16.011147 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 22:38:16.011236 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 22:38:16.012458 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 22:38:16.012533 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 22:38:16.016083 systemd[1]: Stopped target network.target - Network. Nov 12 22:38:16.017361 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 22:38:16.017413 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 22:38:16.018987 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 22:38:16.019031 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 22:38:16.020600 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 22:38:16.020640 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 22:38:16.022318 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 22:38:16.022360 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 22:38:16.024198 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 22:38:16.024241 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 22:38:16.025919 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 22:38:16.027654 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 22:38:16.031824 systemd-networkd[763]: eth0: DHCPv6 lease lost Nov 12 22:38:16.033155 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 22:38:16.033283 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 22:38:16.034705 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 22:38:16.034732 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:38:16.043863 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 22:38:16.045142 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 22:38:16.045205 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:38:16.047094 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:38:16.049518 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 22:38:16.049612 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 22:38:16.053023 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 22:38:16.053100 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:38:16.054353 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 22:38:16.054402 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 22:38:16.056371 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 22:38:16.056415 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:38:16.058493 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 22:38:16.058577 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 22:38:16.064356 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 22:38:16.064477 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:38:16.065903 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 22:38:16.065941 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 22:38:16.067816 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 22:38:16.067846 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:38:16.068764 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 22:38:16.068867 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:38:16.071322 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 22:38:16.071365 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 22:38:16.074020 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:38:16.074066 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:38:16.087927 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 22:38:16.088903 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 22:38:16.088959 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:38:16.091012 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:38:16.091057 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:38:16.093127 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 22:38:16.093218 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 22:38:16.095345 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 22:38:16.097384 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 22:38:16.106044 systemd[1]: Switching root. Nov 12 22:38:16.136067 systemd-journald[238]: Journal stopped Nov 12 22:38:16.749359 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Nov 12 22:38:16.749413 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 22:38:16.749426 kernel: SELinux: policy capability open_perms=1 Nov 12 22:38:16.749436 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 22:38:16.749445 kernel: SELinux: policy capability always_check_network=0 Nov 12 22:38:16.749455 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 22:38:16.749467 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 22:38:16.749477 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 22:38:16.749486 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 22:38:16.749496 kernel: audit: type=1403 audit(1731451096.198:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 22:38:16.749508 systemd[1]: Successfully loaded SELinux policy in 33.522ms. Nov 12 22:38:16.749529 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.441ms. Nov 12 22:38:16.749540 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:38:16.749551 systemd[1]: Detected virtualization kvm. Nov 12 22:38:16.749561 systemd[1]: Detected architecture arm64. Nov 12 22:38:16.749576 systemd[1]: Detected first boot. Nov 12 22:38:16.749586 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:38:16.749597 zram_generator::config[1041]: No configuration found. Nov 12 22:38:16.749611 systemd[1]: Populated /etc with preset unit settings. Nov 12 22:38:16.749622 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 22:38:16.749632 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 22:38:16.749642 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 22:38:16.749653 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 22:38:16.749665 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 22:38:16.749675 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 22:38:16.749686 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 22:38:16.749696 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 22:38:16.749706 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 22:38:16.749717 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 22:38:16.749729 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 22:38:16.749739 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:38:16.749750 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:38:16.749760 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 22:38:16.749771 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 22:38:16.749781 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 22:38:16.749812 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:38:16.749824 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 12 22:38:16.749836 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:38:16.749846 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 22:38:16.749856 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 22:38:16.749867 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 22:38:16.749877 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 22:38:16.749887 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:38:16.749897 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:38:16.749907 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:38:16.749919 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:38:16.749929 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 22:38:16.749940 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 22:38:16.749950 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:38:16.749960 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:38:16.749971 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:38:16.749981 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 22:38:16.749992 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 22:38:16.750003 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 22:38:16.750015 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 22:38:16.750025 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 22:38:16.750035 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 22:38:16.750045 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 22:38:16.750056 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). Nov 12 22:38:16.750066 systemd[1]: Reached target machines.target - Containers. Nov 12 22:38:16.750077 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 22:38:16.750087 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:38:16.750100 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:38:16.750111 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 22:38:16.750121 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:38:16.750132 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:38:16.750142 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:38:16.750152 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 22:38:16.750162 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:38:16.750174 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 22:38:16.750189 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 22:38:16.750204 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 22:38:16.750214 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 22:38:16.750224 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 22:38:16.750234 kernel: fuse: init (API version 7.39) Nov 12 22:38:16.750244 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:38:16.750254 kernel: loop: module loaded Nov 12 22:38:16.750264 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:38:16.750274 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 22:38:16.750284 kernel: ACPI: bus type drm_connector registered Nov 12 22:38:16.750296 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 22:38:16.750306 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:38:16.750316 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 22:38:16.750328 systemd[1]: Stopped verity-setup.service. Nov 12 22:38:16.750339 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 22:38:16.750351 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 22:38:16.750361 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 22:38:16.750371 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 22:38:16.750381 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 22:38:16.750409 systemd-journald[1108]: Collecting audit messages is disabled. Nov 12 22:38:16.750430 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 22:38:16.750441 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 22:38:16.750453 systemd-journald[1108]: Journal started Nov 12 22:38:16.750474 systemd-journald[1108]: Runtime Journal (/run/log/journal/4e02bd6d139e4d0e97122e3dfa042fc0) is 5.9M, max 47.3M, 41.4M free. Nov 12 22:38:16.543573 systemd[1]: Queued start job for default target multi-user.target. Nov 12 22:38:16.560866 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 12 22:38:16.561222 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 22:38:16.752800 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:38:16.754784 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:38:16.756308 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 22:38:16.756459 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 22:38:16.757894 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:38:16.758036 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:38:16.759365 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:38:16.759515 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:38:16.760773 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:38:16.760944 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:38:16.764159 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 22:38:16.764332 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 22:38:16.765734 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:38:16.765933 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:38:16.767224 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:38:16.768615 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 22:38:16.770296 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 22:38:16.781987 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 22:38:16.786880 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 22:38:16.788856 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 22:38:16.789916 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 22:38:16.789955 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:38:16.791827 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 22:38:16.793949 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 22:38:16.795955 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 22:38:16.797003 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:38:16.798240 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 22:38:16.800309 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 22:38:16.801468 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:38:16.805884 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 22:38:16.807228 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:38:16.809060 systemd-journald[1108]: Time spent on flushing to /var/log/journal/4e02bd6d139e4d0e97122e3dfa042fc0 is 15.020ms for 824 entries. Nov 12 22:38:16.809060 systemd-journald[1108]: System Journal (/var/log/journal/4e02bd6d139e4d0e97122e3dfa042fc0) is 8.0M, max 195.6M, 187.6M free. Nov 12 22:38:16.833657 systemd-journald[1108]: Received client request to flush runtime journal. Nov 12 22:38:16.833688 kernel: loop0: detected capacity change from 0 to 116808 Nov 12 22:38:16.809066 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:38:16.812214 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 22:38:16.817886 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 22:38:16.820320 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:38:16.821702 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 22:38:16.823105 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 22:38:16.824658 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 22:38:16.826932 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 22:38:16.837883 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 22:38:16.840264 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 22:38:16.844894 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 22:38:16.854004 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 22:38:16.858807 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 22:38:16.860454 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:38:16.869810 kernel: loop1: detected capacity change from 0 to 113536 Nov 12 22:38:16.873520 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 22:38:16.874943 udevadm[1168]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 12 22:38:16.879232 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 22:38:16.883652 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 22:38:16.891988 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:38:16.899816 kernel: loop2: detected capacity change from 0 to 116808 Nov 12 22:38:16.906821 kernel: loop3: detected capacity change from 0 to 113536 Nov 12 22:38:16.909095 systemd-tmpfiles[1173]: ACLs are not supported, ignoring. Nov 12 22:38:16.909113 systemd-tmpfiles[1173]: ACLs are not supported, ignoring. Nov 12 22:38:16.915498 (sd-merge)[1175]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Nov 12 22:38:16.915863 (sd-merge)[1175]: Merged extensions into '/usr'. Nov 12 22:38:16.918817 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:38:16.922894 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 22:38:16.932037 systemd[1]: Starting ensure-sysext.service... Nov 12 22:38:16.936009 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:38:16.944430 systemd[1]: Reloading requested from client PID 1178 ('systemctl') (unit ensure-sysext.service)... Nov 12 22:38:16.944447 systemd[1]: Reloading... Nov 12 22:38:16.966710 systemd-tmpfiles[1179]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 22:38:16.967746 systemd-tmpfiles[1179]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 22:38:16.968429 systemd-tmpfiles[1179]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 22:38:16.968646 systemd-tmpfiles[1179]: ACLs are not supported, ignoring. Nov 12 22:38:16.968695 systemd-tmpfiles[1179]: ACLs are not supported, ignoring. Nov 12 22:38:16.971067 systemd-tmpfiles[1179]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:38:16.971080 systemd-tmpfiles[1179]: Skipping /boot Nov 12 22:38:16.981291 systemd-tmpfiles[1179]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:38:16.981327 systemd-tmpfiles[1179]: Skipping /boot Nov 12 22:38:17.014838 zram_generator::config[1210]: No configuration found. Nov 12 22:38:17.075960 ldconfig[1147]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 22:38:17.102239 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. Nov 12 22:38:17.138071 systemd[1]: Reloading finished in 193 ms. Nov 12 22:38:17.169501 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 22:38:17.182214 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:38:17.188488 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:38:17.191909 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 22:38:17.194985 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 22:38:17.197959 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:38:17.206020 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 22:38:17.218111 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 22:38:17.220657 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:38:17.223198 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:38:17.225511 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:38:17.228129 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:38:17.229930 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:38:17.234921 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 22:38:17.239198 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 22:38:17.240962 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:38:17.241088 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:38:17.242585 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:38:17.242711 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:38:17.248367 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:38:17.248565 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:38:17.248675 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:38:17.255744 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:38:17.256020 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:38:17.259787 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:38:17.268042 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:38:17.272711 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:38:17.278567 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:38:17.279775 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:38:17.279894 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:38:17.280492 systemd[1]: Finished ensure-sysext.service. Nov 12 22:38:17.281633 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 22:38:17.284261 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:38:17.284406 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:38:17.285862 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:38:17.285995 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:38:17.287316 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:38:17.287442 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:38:17.288742 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 22:38:17.294365 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:38:17.306025 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 12 22:38:17.307164 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). Nov 12 22:38:17.308291 augenrules[1291]: No rules Nov 12 22:38:17.308967 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:38:17.309196 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:38:17.339318 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 22:38:17.347976 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:38:17.349839 systemd-resolved[1249]: Positive Trust Anchors: Nov 12 22:38:17.350202 systemd-resolved[1249]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:38:17.350290 systemd-resolved[1249]: 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 Nov 12 22:38:17.350524 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 22:38:17.351697 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 12 22:38:17.353175 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 22:38:17.356560 systemd-resolved[1249]: Defaulting to hostname 'linux'. Nov 12 22:38:17.358170 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:38:17.359383 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:38:17.365831 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 22:38:17.369028 systemd-udevd[1299]: Using default interface naming scheme 'v255'. Nov 12 22:38:17.382485 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:38:17.393975 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:38:17.412827 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1317) Nov 12 22:38:17.415879 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 12 22:38:17.425135 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1314) Nov 12 22:38:17.425221 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1317) Nov 12 22:38:17.468857 systemd-networkd[1312]: lo: Link UP Nov 12 22:38:17.468864 systemd-networkd[1312]: lo: Gained carrier Nov 12 22:38:17.470136 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 22:38:17.471409 systemd-networkd[1312]: Enumeration completed Nov 12 22:38:17.471543 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:38:17.472831 systemd[1]: Reached target network.target - Network. Nov 12 22:38:17.477335 systemd-networkd[1312]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:38:17.477343 systemd-networkd[1312]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:38:17.479718 systemd-networkd[1312]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:38:17.479747 systemd-networkd[1312]: eth0: Link UP Nov 12 22:38:17.479749 systemd-networkd[1312]: eth0: Gained carrier Nov 12 22:38:17.479757 systemd-networkd[1312]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:38:17.486962 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 22:38:17.489950 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 22:38:17.499393 systemd-networkd[1312]: eth0: DHCPv4 address 10.0.0.102/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 12 22:38:17.501774 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 22:38:17.502340 systemd-timesyncd[1289]: Network configuration changed, trying to establish connection. Nov 12 22:38:17.503721 systemd-timesyncd[1289]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 12 22:38:17.503779 systemd-timesyncd[1289]: Initial clock synchronization to Tue 2024-11-12 22:38:17.238949 UTC. Nov 12 22:38:17.519079 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:38:17.535446 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 22:38:17.543027 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 22:38:17.555822 lvm[1340]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:38:17.557006 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:38:17.596418 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 22:38:17.597953 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:38:17.599026 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:38:17.600218 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 22:38:17.601445 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 22:38:17.602852 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 22:38:17.604002 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 22:38:17.605185 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 22:38:17.606387 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 22:38:17.606424 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:38:17.607306 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:38:17.609282 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 22:38:17.611672 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:38:17.622815 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 22:38:17.624988 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 22:38:17.626526 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:38:17.627682 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:38:17.628639 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:38:17.629574 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:38:17.629605 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:38:17.630605 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:38:17.632629 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 22:38:17.634048 lvm[1347]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:38:17.635972 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 22:38:17.637968 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 22:38:17.639300 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). Nov 12 22:38:17.642124 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 22:38:17.646825 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 22:38:17.647664 jq[1350]: false Nov 12 22:38:17.649001 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 22:38:17.654981 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 22:38:17.658951 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 22:38:17.659393 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 22:38:17.661160 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 22:38:17.663710 extend-filesystems[1351]: Found loop2 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found loop3 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda1 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda2 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda3 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found usr Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda4 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda6 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda7 Nov 12 22:38:17.663710 extend-filesystems[1351]: Found vda9 Nov 12 22:38:17.663710 extend-filesystems[1351]: Checking size of /dev/vda9 Nov 12 22:38:17.663977 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 22:38:17.667216 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 22:38:17.667377 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 22:38:17.686635 jq[1362]: true Nov 12 22:38:17.667633 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 22:38:17.667756 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 22:38:17.673851 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 22:38:17.686842 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 22:38:17.687372 dbus-daemon[1349]: [system] SELinux support is enabled Nov 12 22:38:17.687131 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 22:38:17.692145 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 22:38:17.693849 jq[1372]: true Nov 12 22:38:17.696936 extend-filesystems[1351]: Old size kept for /dev/vda9 Nov 12 22:38:17.709188 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1309) Nov 12 22:38:17.709303 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 22:38:17.709483 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 22:38:17.711747 (ntainerd)[1376]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:38:17.727498 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). Nov 12 22:38:17.727547 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 22:38:17.728987 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). Nov 12 22:38:17.729014 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 22:38:17.739075 systemd-logind[1356]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 22:38:17.739809 systemd-logind[1356]: New seat seat0. Nov 12 22:38:17.742208 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 22:38:17.742311 update_engine[1359]: I20241112 22:38:17.742146 1359 main.cc:92] Flatcar Update Engine starting Nov 12 22:38:17.747039 systemd[1]: Started update-engine.service - Update Engine. Nov 12 22:38:17.747228 update_engine[1359]: I20241112 22:38:17.747159 1359 update_check_scheduler.cc:74] Next update check in 4m44s Nov 12 22:38:17.756455 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 22:38:17.780395 bash[1399]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:38:17.780619 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 22:38:17.783624 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 12 22:38:17.801348 locksmithd[1398]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 22:38:17.904170 containerd[1376]: time="2024-11-12T22:38:17.904041000Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:38:17.928257 containerd[1376]: time="2024-11-12T22:38:17.927666240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929352 containerd[1376]: time="2024-11-12T22:38:17.929312240Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929352 containerd[1376]: time="2024-11-12T22:38:17.929346520Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:38:17.929440 containerd[1376]: time="2024-11-12T22:38:17.929363680Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:38:17.929534 containerd[1376]: time="2024-11-12T22:38:17.929514640Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:38:17.929556 containerd[1376]: time="2024-11-12T22:38:17.929536120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929604 containerd[1376]: time="2024-11-12T22:38:17.929590040Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929627 containerd[1376]: time="2024-11-12T22:38:17.929605960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929785 containerd[1376]: time="2024-11-12T22:38:17.929757320Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929785 containerd[1376]: time="2024-11-12T22:38:17.929776760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929852 containerd[1376]: time="2024-11-12T22:38:17.929804480Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929852 containerd[1376]: time="2024-11-12T22:38:17.929815800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.929900 containerd[1376]: time="2024-11-12T22:38:17.929886000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.930084 containerd[1376]: time="2024-11-12T22:38:17.930068120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:17.930184 containerd[1376]: time="2024-11-12T22:38:17.930162200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:17.930209 containerd[1376]: time="2024-11-12T22:38:17.930188040Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:38:17.930283 containerd[1376]: time="2024-11-12T22:38:17.930269720Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:38:17.930333 containerd[1376]: time="2024-11-12T22:38:17.930321000Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:38:17.933011 containerd[1376]: time="2024-11-12T22:38:17.932985400Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:38:17.933060 containerd[1376]: time="2024-11-12T22:38:17.933030400Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:38:17.933060 containerd[1376]: time="2024-11-12T22:38:17.933046800Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:38:17.933096 containerd[1376]: time="2024-11-12T22:38:17.933061040Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:38:17.933096 containerd[1376]: time="2024-11-12T22:38:17.933074800Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:38:17.933243 containerd[1376]: time="2024-11-12T22:38:17.933212600Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:38:17.933442 containerd[1376]: time="2024-11-12T22:38:17.933416320Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:38:17.933535 containerd[1376]: time="2024-11-12T22:38:17.933519400Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:38:17.933559 containerd[1376]: time="2024-11-12T22:38:17.933539280Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:38:17.933577 containerd[1376]: time="2024-11-12T22:38:17.933558640Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:38:17.933577 containerd[1376]: time="2024-11-12T22:38:17.933573040Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933610 containerd[1376]: time="2024-11-12T22:38:17.933585720Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933610 containerd[1376]: time="2024-11-12T22:38:17.933597640Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933609880Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933623160Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933634840Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933645680Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933656600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:38:17.933685 containerd[1376]: time="2024-11-12T22:38:17.933675760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933688200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933700880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933712640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933724440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933736640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933747280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933779 containerd[1376]: time="2024-11-12T22:38:17.933759120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933779480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933818920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933830960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933842120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933853320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933866680Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933885000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933896880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.933922 containerd[1376]: time="2024-11-12T22:38:17.933913880Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:38:17.934108 containerd[1376]: time="2024-11-12T22:38:17.934095440Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:38:17.934132 containerd[1376]: time="2024-11-12T22:38:17.934117040Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 12 22:38:17.934132 containerd[1376]: time="2024-11-12T22:38:17.934127000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:38:17.934181 containerd[1376]: time="2024-11-12T22:38:17.934142120Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:38:17.934181 containerd[1376]: time="2024-11-12T22:38:17.934151040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.934181 containerd[1376]: time="2024-11-12T22:38:17.934164480Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:38:17.934240 containerd[1376]: time="2024-11-12T22:38:17.934173560Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:38:17.934240 containerd[1376]: time="2024-11-12T22:38:17.934192960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:38:17.934563 containerd[1376]: time="2024-11-12T22:38:17.934500640Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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}" Nov 12 22:38:17.934563 containerd[1376]: time="2024-11-12T22:38:17.934551480Z" level=info msg="Connect containerd service" Nov 12 22:38:17.934689 containerd[1376]: time="2024-11-12T22:38:17.934579120Z" level=info msg="using legacy CRI server" Nov 12 22:38:17.934689 containerd[1376]: time="2024-11-12T22:38:17.934585600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:38:17.934855 containerd[1376]: time="2024-11-12T22:38:17.934838240Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:38:17.935426 containerd[1376]: time="2024-11-12T22:38:17.935400320Z" 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" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935665000Z" level=info msg="Start subscribing containerd event" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935714560Z" level=info msg="Start recovering state" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935774200Z" level=info msg="Start event monitor" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935784640Z" level=info msg="Start snapshots syncer" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935807560Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:38:17.939519 containerd[1376]: time="2024-11-12T22:38:17.935819040Z" level=info msg="Start streaming server" Nov 12 22:38:17.939699 containerd[1376]: time="2024-11-12T22:38:17.939669040Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:38:17.939731 containerd[1376]: time="2024-11-12T22:38:17.939724160Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:38:17.940925 containerd[1376]: time="2024-11-12T22:38:17.939778200Z" level=info msg="containerd successfully booted in 0.037941s" Nov 12 22:38:17.939859 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:38:18.320366 sshd_keygen[1373]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 22:38:18.338320 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 22:38:18.355011 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 22:38:18.359945 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 22:38:18.360134 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 22:38:18.362522 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 22:38:18.375299 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 22:38:18.378952 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 22:38:18.380893 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 12 22:38:18.382089 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 22:38:18.795951 systemd-networkd[1312]: eth0: Gained IPv6LL Nov 12 22:38:18.798281 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 22:38:18.799958 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 22:38:18.819091 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 12 22:38:18.821044 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 22:38:18.834117 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 12 22:38:18.834889 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 12 22:38:18.836220 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 22:38:18.837126 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 22:38:18.838859 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 22:38:18.839979 systemd[1]: Startup finished in 563ms (kernel) + 3.491s (initrd) + 2.677s (userspace) = 6.732s. Nov 12 22:38:25.228298 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 22:38:25.229361 systemd[1]: Started sshd@0-10.0.0.102:22-10.0.0.1:41064.service - OpenSSH per-connection server daemon (10.0.0.1:41064). Nov 12 22:38:25.294990 sshd[1451]: Accepted publickey for core from 10.0.0.1 port 41064 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:25.296446 sshd-session[1451]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:25.305085 systemd-logind[1356]: New session 1 of user core. Nov 12 22:38:25.306214 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 22:38:25.318996 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 22:38:25.327233 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 22:38:25.330338 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 22:38:25.336218 (systemd)[1455]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 22:38:25.401147 systemd[1455]: Queued start job for default target default.target. Nov 12 22:38:25.410616 systemd[1455]: Created slice app.slice - User Application Slice. Nov 12 22:38:25.410644 systemd[1455]: Reached target paths.target - Paths. Nov 12 22:38:25.410656 systemd[1455]: Reached target timers.target - Timers. Nov 12 22:38:25.411750 systemd[1455]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 22:38:25.420058 systemd[1455]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 22:38:25.420127 systemd[1455]: Reached target sockets.target - Sockets. Nov 12 22:38:25.420146 systemd[1455]: Reached target basic.target - Basic System. Nov 12 22:38:25.420181 systemd[1455]: Reached target default.target - Main User Target. Nov 12 22:38:25.420205 systemd[1455]: Startup finished in 79ms. Nov 12 22:38:25.420355 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 22:38:25.421586 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 22:38:25.480460 systemd[1]: Started sshd@1-10.0.0.102:22-10.0.0.1:41080.service - OpenSSH per-connection server daemon (10.0.0.1:41080). Nov 12 22:38:25.526471 sshd[1466]: Accepted publickey for core from 10.0.0.1 port 41080 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:25.527522 sshd-session[1466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:25.532181 systemd-logind[1356]: New session 2 of user core. Nov 12 22:38:25.539997 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 22:38:25.588577 sshd[1468]: Connection closed by 10.0.0.1 port 41080 Nov 12 22:38:25.588978 sshd-session[1466]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:25.600831 systemd[1]: sshd@1-10.0.0.102:22-10.0.0.1:41080.service: Deactivated successfully. Nov 12 22:38:25.602060 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 22:38:25.603842 systemd-logind[1356]: Session 2 logged out. Waiting for processes to exit. Nov 12 22:38:25.604157 systemd[1]: Started sshd@2-10.0.0.102:22-10.0.0.1:41096.service - OpenSSH per-connection server daemon (10.0.0.1:41096). Nov 12 22:38:25.605135 systemd-logind[1356]: Removed session 2. Nov 12 22:38:25.646406 sshd[1473]: Accepted publickey for core from 10.0.0.1 port 41096 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:25.647522 sshd-session[1473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:25.651080 systemd-logind[1356]: New session 3 of user core. Nov 12 22:38:25.656958 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 22:38:25.703391 sshd[1475]: Connection closed by 10.0.0.1 port 41096 Nov 12 22:38:25.703710 sshd-session[1473]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:25.712854 systemd[1]: sshd@2-10.0.0.102:22-10.0.0.1:41096.service: Deactivated successfully. Nov 12 22:38:25.714927 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 22:38:25.716059 systemd-logind[1356]: Session 3 logged out. Waiting for processes to exit. Nov 12 22:38:25.717239 systemd[1]: Started sshd@3-10.0.0.102:22-10.0.0.1:41106.service - OpenSSH per-connection server daemon (10.0.0.1:41106). Nov 12 22:38:25.718086 systemd-logind[1356]: Removed session 3. Nov 12 22:38:25.760369 sshd[1480]: Accepted publickey for core from 10.0.0.1 port 41106 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:25.761461 sshd-session[1480]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:25.765170 systemd-logind[1356]: New session 4 of user core. Nov 12 22:38:25.781936 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 22:38:25.832424 sshd[1482]: Connection closed by 10.0.0.1 port 41106 Nov 12 22:38:25.832734 sshd-session[1480]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:25.839837 systemd[1]: sshd@3-10.0.0.102:22-10.0.0.1:41106.service: Deactivated successfully. Nov 12 22:38:25.841069 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 22:38:25.843109 systemd-logind[1356]: Session 4 logged out. Waiting for processes to exit. Nov 12 22:38:25.844103 systemd[1]: Started sshd@4-10.0.0.102:22-10.0.0.1:41110.service - OpenSSH per-connection server daemon (10.0.0.1:41110). Nov 12 22:38:25.845206 systemd-logind[1356]: Removed session 4. Nov 12 22:38:25.886234 sshd[1487]: Accepted publickey for core from 10.0.0.1 port 41110 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:25.887246 sshd-session[1487]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:25.890631 systemd-logind[1356]: New session 5 of user core. Nov 12 22:38:25.899926 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 22:38:25.959857 sudo[1490]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 12 22:38:25.960123 sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:38:25.971738 sudo[1490]: pam_unix(sudo:session): session closed for user root Nov 12 22:38:25.973184 sshd[1489]: Connection closed by 10.0.0.1 port 41110 Nov 12 22:38:25.973588 sshd-session[1487]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:25.985094 systemd[1]: sshd@4-10.0.0.102:22-10.0.0.1:41110.service: Deactivated successfully. Nov 12 22:38:25.987167 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 22:38:25.988458 systemd-logind[1356]: Session 5 logged out. Waiting for processes to exit. Nov 12 22:38:25.999097 systemd[1]: Started sshd@5-10.0.0.102:22-10.0.0.1:41118.service - OpenSSH per-connection server daemon (10.0.0.1:41118). Nov 12 22:38:26.000078 systemd-logind[1356]: Removed session 5. Nov 12 22:38:26.037939 sshd[1495]: Accepted publickey for core from 10.0.0.1 port 41118 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:26.038941 sshd-session[1495]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:26.042901 systemd-logind[1356]: New session 6 of user core. Nov 12 22:38:26.051919 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 12 22:38:26.101169 sudo[1499]: 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 Nov 12 22:38:26.101431 sudo[1499]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:38:26.104220 sudo[1499]: pam_unix(sudo:session): session closed for user root Nov 12 22:38:26.108326 sudo[1498]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 12 22:38:26.108572 sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:38:26.124145 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:38:26.145353 augenrules[1521]: No rules Nov 12 22:38:26.146472 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:38:26.146653 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:38:26.147480 sudo[1498]: pam_unix(sudo:session): session closed for user root Nov 12 22:38:26.148526 sshd[1497]: Connection closed by 10.0.0.1 port 41118 Nov 12 22:38:26.148883 sshd-session[1495]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:26.159021 systemd[1]: sshd@5-10.0.0.102:22-10.0.0.1:41118.service: Deactivated successfully. Nov 12 22:38:26.160405 systemd[1]: session-6.scope: Deactivated successfully. Nov 12 22:38:26.161593 systemd-logind[1356]: Session 6 logged out. Waiting for processes to exit. Nov 12 22:38:26.162589 systemd[1]: Started sshd@6-10.0.0.102:22-10.0.0.1:41132.service - OpenSSH per-connection server daemon (10.0.0.1:41132). Nov 12 22:38:26.163380 systemd-logind[1356]: Removed session 6. Nov 12 22:38:26.204875 sshd[1529]: Accepted publickey for core from 10.0.0.1 port 41132 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:26.205966 sshd-session[1529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:26.209741 systemd-logind[1356]: New session 7 of user core. Nov 12 22:38:26.220932 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 12 22:38:26.287013 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 12 22:38:26.287098 (dockerd)[1533]: 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 Nov 12 22:38:26.532470 dockerd[1533]: time="2024-11-12T22:38:26.532346488Z" level=info msg="Starting up" Nov 12 22:38:26.606676 dockerd[1533]: time="2024-11-12T22:38:26.606635606Z" level=info msg="Loading containers: start." Nov 12 22:38:26.741819 kernel: Initializing XFRM netlink socket Nov 12 22:38:26.803995 systemd-networkd[1312]: docker0: Link UP Nov 12 22:38:26.836924 dockerd[1533]: time="2024-11-12T22:38:26.836825163Z" level=info msg="Loading containers: done." Nov 12 22:38:26.850723 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2717776165-merged.mount: Deactivated successfully. Nov 12 22:38:26.851872 dockerd[1533]: time="2024-11-12T22:38:26.851829510Z" 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 Nov 12 22:38:26.851939 dockerd[1533]: time="2024-11-12T22:38:26.851917577Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Nov 12 22:38:26.852031 dockerd[1533]: time="2024-11-12T22:38:26.852011181Z" level=info msg="Daemon has completed initialization" Nov 12 22:38:26.877648 dockerd[1533]: time="2024-11-12T22:38:26.877545326Z" level=info msg="API listen on /run/docker.sock" Nov 12 22:38:26.877721 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 12 22:38:26.884146 sshd[1531]: Connection closed by 10.0.0.1 port 41132 Nov 12 22:38:26.884634 sshd-session[1529]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:26.897988 systemd[1]: sshd@6-10.0.0.102:22-10.0.0.1:41132.service: Deactivated successfully. Nov 12 22:38:26.899344 systemd[1]: session-7.scope: Deactivated successfully. Nov 12 22:38:26.900466 systemd-logind[1356]: Session 7 logged out. Waiting for processes to exit. Nov 12 22:38:26.901618 systemd[1]: Started sshd@7-10.0.0.102:22-10.0.0.1:41142.service - OpenSSH per-connection server daemon (10.0.0.1:41142). Nov 12 22:38:26.902279 systemd-logind[1356]: Removed session 7. Nov 12 22:38:26.944414 sshd[1724]: Accepted publickey for core from 10.0.0.1 port 41142 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:26.945501 sshd-session[1724]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:26.948796 systemd-logind[1356]: New session 8 of user core. Nov 12 22:38:26.957977 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 12 22:38:28.270730 systemd[1]: var-lib-docker-overlay2-02f58cc8bb6ea01d0f6cc10ee1f44403bb20401cf5ba0aed1363c920b8bc32ea\x2dinit-merged.mount: Deactivated successfully. Nov 12 22:38:28.300819 kernel: docker0: port 1(vethf8500ae) entered blocking state Nov 12 22:38:28.300945 kernel: docker0: port 1(vethf8500ae) entered disabled state Nov 12 22:38:28.300965 kernel: vethf8500ae: entered allmulticast mode Nov 12 22:38:28.300981 kernel: vethf8500ae: entered promiscuous mode Nov 12 22:38:28.301213 systemd-networkd[1312]: vethf8500ae: Link UP Nov 12 22:38:28.395680 containerd[1376]: time="2024-11-12T22:38:28.395589727Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:38:28.395680 containerd[1376]: time="2024-11-12T22:38:28.395671886Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:38:28.396192 containerd[1376]: time="2024-11-12T22:38:28.395706899Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:38:28.396192 containerd[1376]: time="2024-11-12T22:38:28.395831207Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:38:28.415951 systemd[1]: Started docker-bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601.scope - libcontainer container bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601. Nov 12 22:38:28.465825 kernel: eth0: renamed from veth6828d57 Nov 12 22:38:28.472804 kernel: docker0: port 1(vethf8500ae) entered blocking state Nov 12 22:38:28.472848 kernel: docker0: port 1(vethf8500ae) entered forwarding state Nov 12 22:38:28.472823 systemd-networkd[1312]: vethf8500ae: Gained carrier Nov 12 22:38:28.473059 systemd-networkd[1312]: docker0: Gained carrier Nov 12 22:38:28.475626 systemd-resolved[1249]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:38:28.497819 sshd[1726]: Connection closed by 10.0.0.1 port 41142 Nov 12 22:38:28.498024 sshd-session[1724]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:28.508251 systemd[1]: sshd@7-10.0.0.102:22-10.0.0.1:41142.service: Deactivated successfully. Nov 12 22:38:28.509699 systemd[1]: session-8.scope: Deactivated successfully. Nov 12 22:38:28.510357 systemd-logind[1356]: Session 8 logged out. Waiting for processes to exit. Nov 12 22:38:28.512596 systemd[1]: Started sshd@8-10.0.0.102:22-10.0.0.1:41152.service - OpenSSH per-connection server daemon (10.0.0.1:41152). Nov 12 22:38:28.513429 systemd-logind[1356]: Removed session 8. Nov 12 22:38:28.555825 sshd[1804]: Accepted publickey for core from 10.0.0.1 port 41152 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:28.557410 sshd-session[1804]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:28.561309 systemd-logind[1356]: New session 9 of user core. Nov 12 22:38:28.570932 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 12 22:38:28.623897 sshd[1806]: Connection closed by 10.0.0.1 port 41152 Nov 12 22:38:28.624377 sshd-session[1804]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:28.632965 systemd[1]: sshd@8-10.0.0.102:22-10.0.0.1:41152.service: Deactivated successfully. Nov 12 22:38:28.636043 systemd[1]: session-9.scope: Deactivated successfully. Nov 12 22:38:28.636517 systemd-logind[1356]: Session 9 logged out. Waiting for processes to exit. Nov 12 22:38:28.639074 systemd[1]: Started sshd@9-10.0.0.102:22-10.0.0.1:41160.service - OpenSSH per-connection server daemon (10.0.0.1:41160). Nov 12 22:38:28.639758 systemd-logind[1356]: Removed session 9. Nov 12 22:38:28.681419 sshd[1811]: Accepted publickey for core from 10.0.0.1 port 41160 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:28.682620 sshd-session[1811]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:28.686000 systemd-logind[1356]: New session 10 of user core. Nov 12 22:38:28.695928 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 12 22:38:28.745281 sudo[1814]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1376 Nov 12 22:38:28.745548 sudo[1814]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:38:28.748572 sudo[1814]: pam_unix(sudo:session): session closed for user root Nov 12 22:38:28.749143 containerd[1376]: time="2024-11-12T22:38:28.748695360Z" level=info msg="Stop CRI service" Nov 12 22:38:28.749226 dockerd[1533]: time="2024-11-12T22:38:28.748866378Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Nov 12 22:38:28.749226 dockerd[1533]: time="2024-11-12T22:38:28.748916379Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Nov 12 22:38:28.749226 dockerd[1533]: time="2024-11-12T22:38:28.748949805Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Nov 12 22:38:28.749226 dockerd[1533]: time="2024-11-12T22:38:28.748976134Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Nov 12 22:38:28.750718 containerd[1376]: time="2024-11-12T22:38:28.749676584Z" level=info msg="Stop CRI service" Nov 12 22:38:28.750810 sshd[1813]: Connection closed by 10.0.0.1 port 41160 Nov 12 22:38:28.751177 sshd-session[1811]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:28.764020 systemd[1]: containerd.service: Deactivated successfully. Nov 12 22:38:28.764075 systemd[1]: containerd.service: Unit process 1758 (containerd-shim) remains running after unit stopped. Nov 12 22:38:28.764604 systemd[1]: sshd@9-10.0.0.102:22-10.0.0.1:41160.service: Deactivated successfully. Nov 12 22:38:28.765946 systemd[1]: session-10.scope: Deactivated successfully. Nov 12 22:38:28.767074 systemd-logind[1356]: Session 10 logged out. Waiting for processes to exit. Nov 12 22:38:28.768870 systemd[1]: Started sshd@10-10.0.0.102:22-10.0.0.1:41164.service - OpenSSH per-connection server daemon (10.0.0.1:41164). Nov 12 22:38:28.769613 systemd-logind[1356]: Removed session 10. Nov 12 22:38:28.812034 sshd[1820]: Accepted publickey for core from 10.0.0.1 port 41164 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:28.813231 sshd-session[1820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:28.816852 systemd-logind[1356]: New session 11 of user core. Nov 12 22:38:28.825928 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 12 22:38:28.876981 sshd[1822]: Connection closed by 10.0.0.1 port 41164 Nov 12 22:38:28.877274 sshd-session[1820]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:28.880159 systemd[1]: sshd@10-10.0.0.102:22-10.0.0.1:41164.service: Deactivated successfully. Nov 12 22:38:28.881595 systemd[1]: session-11.scope: Deactivated successfully. Nov 12 22:38:28.883293 systemd-logind[1356]: Session 11 logged out. Waiting for processes to exit. Nov 12 22:38:28.884139 systemd-logind[1356]: Removed session 11. Nov 12 22:38:29.868249 systemd-networkd[1312]: vethf8500ae: Gained IPv6LL Nov 12 22:38:30.251996 systemd-networkd[1312]: docker0: Gained IPv6LL Nov 12 22:38:34.014493 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Nov 12 22:38:34.015296 systemd[1]: Stopping docker.service - Docker Application Container Engine... Nov 12 22:38:34.015496 dockerd[1533]: time="2024-11-12T22:38:34.015424327Z" level=info msg="Processing signal 'terminated'" Nov 12 22:38:34.857064 systemd[1]: Started sshd@11-10.0.0.102:22-10.0.0.1:54264.service - OpenSSH per-connection server daemon (10.0.0.1:54264). Nov 12 22:38:34.900049 sshd[1827]: Accepted publickey for core from 10.0.0.1 port 54264 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:34.901158 sshd-session[1827]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:34.904637 systemd-logind[1356]: New session 12 of user core. Nov 12 22:38:34.921914 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 12 22:38:34.972766 sshd[1829]: Connection closed by 10.0.0.1 port 54264 Nov 12 22:38:34.973054 sshd-session[1827]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:34.975728 systemd[1]: sshd@11-10.0.0.102:22-10.0.0.1:54264.service: Deactivated successfully. Nov 12 22:38:34.978500 systemd[1]: session-12.scope: Deactivated successfully. Nov 12 22:38:34.980129 systemd-logind[1356]: Session 12 logged out. Waiting for processes to exit. Nov 12 22:38:34.981174 systemd-logind[1356]: Removed session 12. Nov 12 22:38:36.017107 dockerd[1533]: time="2024-11-12T22:38:36.016878123Z" level=error msg="Error sending stop (signal 15) to container" container=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 error="Cannot kill container bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\": unavailable" Nov 12 22:38:36.017107 dockerd[1533]: time="2024-11-12T22:38:36.016923228Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 Nov 12 22:38:40.968295 systemd[1]: Started sshd@12-10.0.0.102:22-10.0.0.1:54272.service - OpenSSH per-connection server daemon (10.0.0.1:54272). Nov 12 22:38:41.011519 sshd[1834]: Accepted publickey for core from 10.0.0.1 port 54272 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:41.012716 sshd-session[1834]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:41.016730 systemd-logind[1356]: New session 13 of user core. Nov 12 22:38:41.034958 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 12 22:38:41.088415 sshd[1836]: Connection closed by 10.0.0.1 port 54272 Nov 12 22:38:41.088718 sshd-session[1834]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:41.091588 systemd[1]: sshd@12-10.0.0.102:22-10.0.0.1:54272.service: Deactivated successfully. Nov 12 22:38:41.092996 systemd[1]: session-13.scope: Deactivated successfully. Nov 12 22:38:41.094494 systemd-logind[1356]: Session 13 logged out. Waiting for processes to exit. Nov 12 22:38:41.095537 systemd-logind[1356]: Removed session 13. Nov 12 22:38:46.017383 dockerd[1533]: time="2024-11-12T22:38:46.017307030Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 error="context deadline exceeded" Nov 12 22:38:46.018136 systemd[1]: docker-bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601.scope: Deactivated successfully. Nov 12 22:38:47.095063 systemd[1]: Started sshd@13-10.0.0.102:22-10.0.0.1:52556.service - OpenSSH per-connection server daemon (10.0.0.1:52556). Nov 12 22:38:47.137489 sshd[1841]: Accepted publickey for core from 10.0.0.1 port 52556 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:47.138534 sshd-session[1841]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:47.142881 systemd-logind[1356]: New session 14 of user core. Nov 12 22:38:47.155205 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 12 22:38:47.208184 sshd[1843]: Connection closed by 10.0.0.1 port 52556 Nov 12 22:38:47.208473 sshd-session[1841]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:47.210903 systemd[1]: sshd@13-10.0.0.102:22-10.0.0.1:52556.service: Deactivated successfully. Nov 12 22:38:47.212467 systemd[1]: session-14.scope: Deactivated successfully. Nov 12 22:38:47.213596 systemd-logind[1356]: Session 14 logged out. Waiting for processes to exit. Nov 12 22:38:47.215335 systemd-logind[1356]: Removed session 14. Nov 12 22:38:49.015930 dockerd[1533]: time="2024-11-12T22:38:49.015760234Z" level=error msg="Force shutdown daemon" Nov 12 22:38:49.016453 dockerd[1533]: time="2024-11-12T22:38:49.016314342Z" level=info msg="Daemon shutdown complete" Nov 12 22:38:49.019886 systemd[1]: docker.service: Deactivated successfully. Nov 12 22:38:49.020190 systemd[1]: Stopped docker.service - Docker Application Container Engine. Nov 12 22:38:49.020775 systemd[1]: docker.socket: Deactivated successfully. Nov 12 22:38:49.021138 systemd[1]: Closed docker.socket - Docker Socket for the API. Nov 12 22:38:49.021180 systemd[1]: Stopping docker.socket - Docker Socket for the API... Nov 12 22:38:49.023057 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:38:49.031988 systemd[1]: containerd.service: Found left-over process 1758 (containerd-shim) in control group while starting unit. Ignoring. Nov 12 22:38:49.032000 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 12 22:38:49.032896 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:38:49.033283 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:38:49.044108 systemd[1]: containerd.service: Found left-over process 1758 (containerd-shim) in control group while starting unit. Ignoring. Nov 12 22:38:49.044122 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 12 22:38:49.048057 (ntainerd)[1853]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:38:49.061881 containerd[1853]: time="2024-11-12T22:38:49.061831738Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:38:49.084246 containerd[1853]: time="2024-11-12T22:38:49.084198583Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085579415Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085610038Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085632027Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085667648Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085680921Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085705508Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:49.085839 containerd[1853]: time="2024-11-12T22:38:49.085715343Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.085869781Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.085882815Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.085896927Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.085906322Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.085932908Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086045 containerd[1853]: time="2024-11-12T22:38:49.086027978Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086158 containerd[1853]: time="2024-11-12T22:38:49.086119210Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:38:49.086177 containerd[1853]: time="2024-11-12T22:38:49.086157070Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:38:49.086198 containerd[1853]: time="2024-11-12T22:38:49.086175780Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:38:49.086198 containerd[1853]: time="2024-11-12T22:38:49.086190412Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:38:49.086377 containerd[1853]: time="2024-11-12T22:38:49.086341173Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:38:49.086377 containerd[1853]: time="2024-11-12T22:38:49.086375195Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:38:49.086435 containerd[1853]: time="2024-11-12T22:38:49.086389387Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:38:49.086435 containerd[1853]: time="2024-11-12T22:38:49.086404499Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:38:49.086435 containerd[1853]: time="2024-11-12T22:38:49.086417533Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:38:49.086501 containerd[1853]: time="2024-11-12T22:38:49.086485617Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:38:49.086714 containerd[1853]: time="2024-11-12T22:38:49.086690668Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:38:49.093243 containerd[1853]: time="2024-11-12T22:38:49.093211550Z" level=info msg="cleaning leaked shim process" id=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 namespace=moby Nov 12 22:38:49.100939 containerd[1853]: time="2024-11-12T22:38:49.100866753Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Nov 12 22:38:49.101306 containerd[1853]: time="2024-11-12T22:38:49.101252069Z" level=info msg="shim disconnected" id=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 namespace=moby Nov 12 22:38:49.101306 containerd[1853]: time="2024-11-12T22:38:49.101306321Z" level=warning msg="cleaning up after shim disconnected" id=bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601 namespace=moby Nov 12 22:38:49.101369 containerd[1853]: time="2024-11-12T22:38:49.101314836Z" level=info msg="cleaning up dead shim" namespace=moby Nov 12 22:38:49.110953 containerd[1853]: time="2024-11-12T22:38:49.110905978Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:38:49.110953 containerd[1853]: time="2024-11-12T22:38:49.110946157Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.110964188Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.110983058Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.110997810Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.111011243Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.111026475Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.111044465Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.111057139Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111074 containerd[1853]: time="2024-11-12T22:38:49.111071251Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111083445Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111106313Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111121345Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111133378Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111146332Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111162283Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111175716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111187350Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111199783Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111212257Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111229 containerd[1853]: time="2024-11-12T22:38:49.111225970Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111237803Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111250717Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111264030Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111281900Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111305768Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111326317Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111342228Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111406235Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111434220Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111446134Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111458047Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:38:49.111466 containerd[1853]: time="2024-11-12T22:38:49.111467242Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111680 containerd[1853]: time="2024-11-12T22:38:49.111481955Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:38:49.111680 containerd[1853]: time="2024-11-12T22:38:49.111492029Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:38:49.111680 containerd[1853]: time="2024-11-12T22:38:49.111502384Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:38:49.111850 containerd[1853]: time="2024-11-12T22:38:49.111761807Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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}" Nov 12 22:38:49.111850 containerd[1853]: time="2024-11-12T22:38:49.111840286Z" level=info msg="Connect containerd service" Nov 12 22:38:49.111982 containerd[1853]: time="2024-11-12T22:38:49.111877066Z" level=info msg="using legacy CRI server" Nov 12 22:38:49.111982 containerd[1853]: time="2024-11-12T22:38:49.111885022Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:38:49.112021 containerd[1853]: time="2024-11-12T22:38:49.111997163Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:38:49.112507 containerd[1853]: time="2024-11-12T22:38:49.112467595Z" 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" Nov 12 22:38:49.112639 containerd[1853]: time="2024-11-12T22:38:49.112585213Z" level=info msg="Start subscribing containerd event" Nov 12 22:38:49.112669 containerd[1853]: time="2024-11-12T22:38:49.112647260Z" level=info msg="Start recovering state" Nov 12 22:38:49.112724 containerd[1853]: time="2024-11-12T22:38:49.112707149Z" level=info msg="Start event monitor" Nov 12 22:38:49.112753 containerd[1853]: time="2024-11-12T22:38:49.112723420Z" level=info msg="Start snapshots syncer" Nov 12 22:38:49.112753 containerd[1853]: time="2024-11-12T22:38:49.112734414Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:38:49.112753 containerd[1853]: time="2024-11-12T22:38:49.112744569Z" level=info msg="Start streaming server" Nov 12 22:38:49.112825 containerd[1853]: time="2024-11-12T22:38:49.112763479Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:38:49.112825 containerd[1853]: time="2024-11-12T22:38:49.112820089Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:38:49.113224 containerd[1853]: time="2024-11-12T22:38:49.112878858Z" level=info msg="containerd successfully booted in 0.051551s" Nov 12 22:38:49.112955 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:38:49.125133 (dockerd)[1887]: 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 Nov 12 22:38:49.125443 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 12 22:38:49.149569 dockerd[1887]: time="2024-11-12T22:38:49.149520175Z" level=info msg="Starting up" Nov 12 22:38:49.174836 dockerd[1887]: time="2024-11-12T22:38:49.174695898Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Nov 12 22:38:49.180878 dockerd[1887]: time="2024-11-12T22:38:49.180845775Z" level=info msg="Loading containers: start." Nov 12 22:38:49.191247 systemd[1]: var-lib-docker-overlay2-02f58cc8bb6ea01d0f6cc10ee1f44403bb20401cf5ba0aed1363c920b8bc32ea-merged.mount: Deactivated successfully. Nov 12 22:38:49.450403 dockerd[1887]: time="2024-11-12T22:38:49.449858110Z" level=info msg="Removing stale sandbox 26d27fcbda49b5a71bb4d80196c2fba443f9eca9f0fa6f0e67b97c7212aaaf5f (bb74e0053087a0832aca2e9dd828835a0607f25a6552ac15307c13a1c549b601)" Nov 12 22:38:49.452886 dockerd[1887]: time="2024-11-12T22:38:49.452846933Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint eac4035103ef69027a383c4b600549446ef419e470f45263ac57dd039db871b8 209c6f692b52543a98859ab1dfa7aaedf76b13cbfc5fbb596dc4f5506cd1d072], retrying...." Nov 12 22:38:49.456114 kernel: docker0: port 1(vethf8500ae) entered disabled state Nov 12 22:38:49.455259 systemd-networkd[1312]: vethf8500ae: Link DOWN Nov 12 22:38:49.455264 systemd-networkd[1312]: vethf8500ae: Lost carrier Nov 12 22:38:49.457866 kernel: vethf8500ae (unregistering): left allmulticast mode Nov 12 22:38:49.457924 kernel: vethf8500ae (unregistering): left promiscuous mode Nov 12 22:38:49.457944 kernel: docker0: port 1(vethf8500ae) entered disabled state Nov 12 22:38:49.468601 systemd-networkd[1312]: docker0: Lost carrier Nov 12 22:38:49.581853 dockerd[1887]: time="2024-11-12T22:38:49.581809364Z" 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" Nov 12 22:38:49.660918 dockerd[1887]: time="2024-11-12T22:38:49.660881545Z" level=warning msg="error locating sandbox id 26d27fcbda49b5a71bb4d80196c2fba443f9eca9f0fa6f0e67b97c7212aaaf5f: sandbox 26d27fcbda49b5a71bb4d80196c2fba443f9eca9f0fa6f0e67b97c7212aaaf5f not found" Nov 12 22:38:49.661044 dockerd[1887]: time="2024-11-12T22:38:49.661006319Z" level=info msg="Loading containers: done." Nov 12 22:38:49.669630 dockerd[1887]: time="2024-11-12T22:38:49.669590592Z" 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 Nov 12 22:38:49.669748 dockerd[1887]: time="2024-11-12T22:38:49.669664153Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Nov 12 22:38:49.669748 dockerd[1887]: time="2024-11-12T22:38:49.669702133Z" level=info msg="Daemon has completed initialization" Nov 12 22:38:49.687205 dockerd[1887]: time="2024-11-12T22:38:49.687153970Z" level=info msg="API listen on /run/docker.sock" Nov 12 22:38:49.687470 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 12 22:38:50.165147 systemd[1]: run-docker-netns-26d27fcbda49.mount: Deactivated successfully. Nov 12 22:38:53.221067 systemd[1]: Started sshd@14-10.0.0.102:22-10.0.0.1:49964.service - OpenSSH per-connection server daemon (10.0.0.1:49964). Nov 12 22:38:53.264247 sshd[2086]: Accepted publickey for core from 10.0.0.1 port 49964 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:53.265284 sshd-session[2086]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:53.268780 systemd-logind[1356]: New session 15 of user core. Nov 12 22:38:53.275921 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 12 22:38:53.328206 sshd[2088]: Connection closed by 10.0.0.1 port 49964 Nov 12 22:38:53.328611 sshd-session[2086]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:53.338068 systemd[1]: sshd@14-10.0.0.102:22-10.0.0.1:49964.service: Deactivated successfully. Nov 12 22:38:53.339414 systemd[1]: session-15.scope: Deactivated successfully. Nov 12 22:38:53.341878 systemd-logind[1356]: Session 15 logged out. Waiting for processes to exit. Nov 12 22:38:53.342942 systemd[1]: Started sshd@15-10.0.0.102:22-10.0.0.1:49980.service - OpenSSH per-connection server daemon (10.0.0.1:49980). Nov 12 22:38:53.344108 systemd-logind[1356]: Removed session 15. Nov 12 22:38:53.385719 sshd[2093]: Accepted publickey for core from 10.0.0.1 port 49980 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:53.386841 sshd-session[2093]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:53.390662 systemd-logind[1356]: New session 16 of user core. Nov 12 22:38:53.410012 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 12 22:38:53.463025 sshd[2095]: Connection closed by 10.0.0.1 port 49980 Nov 12 22:38:53.463481 sshd-session[2093]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:53.479142 systemd[1]: sshd@15-10.0.0.102:22-10.0.0.1:49980.service: Deactivated successfully. Nov 12 22:38:53.480506 systemd[1]: session-16.scope: Deactivated successfully. Nov 12 22:38:53.483814 systemd-logind[1356]: Session 16 logged out. Waiting for processes to exit. Nov 12 22:38:53.484114 systemd[1]: Started sshd@16-10.0.0.102:22-10.0.0.1:49984.service - OpenSSH per-connection server daemon (10.0.0.1:49984). Nov 12 22:38:53.485146 systemd-logind[1356]: Removed session 16. Nov 12 22:38:53.527134 sshd[2100]: Accepted publickey for core from 10.0.0.1 port 49984 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:53.528885 sshd-session[2100]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:53.532866 systemd-logind[1356]: New session 17 of user core. Nov 12 22:38:53.541933 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 12 22:38:53.597579 sshd[2102]: Connection closed by 10.0.0.1 port 49984 Nov 12 22:38:53.597935 sshd-session[2100]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:53.608176 systemd[1]: sshd@16-10.0.0.102:22-10.0.0.1:49984.service: Deactivated successfully. Nov 12 22:38:53.609718 systemd[1]: session-17.scope: Deactivated successfully. Nov 12 22:38:53.612511 systemd-logind[1356]: Session 17 logged out. Waiting for processes to exit. Nov 12 22:38:53.613563 systemd[1]: Started sshd@17-10.0.0.102:22-10.0.0.1:49992.service - OpenSSH per-connection server daemon (10.0.0.1:49992). Nov 12 22:38:53.615922 systemd-logind[1356]: Removed session 17. Nov 12 22:38:53.658163 sshd[2114]: Accepted publickey for core from 10.0.0.1 port 49992 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:53.659308 sshd-session[2114]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:53.664602 systemd-logind[1356]: New session 18 of user core. Nov 12 22:38:53.685374 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 12 22:38:53.750531 systemd[1]: var-lib-docker-overlay2-a160f88aa4c4f21eba85a76e7fbaf9923430d371adfdd86a8f3d13a28aa586e7\x2dinit-merged.mount: Deactivated successfully. Nov 12 22:38:53.770946 kernel: docker0: port 1(veth3f9b25c) entered blocking state Nov 12 22:38:53.771048 kernel: docker0: port 1(veth3f9b25c) entered disabled state Nov 12 22:38:53.771068 kernel: veth3f9b25c: entered allmulticast mode Nov 12 22:38:53.771982 kernel: veth3f9b25c: entered promiscuous mode Nov 12 22:38:53.772257 systemd-networkd[1312]: veth3f9b25c: Link UP Nov 12 22:38:53.804856 containerd[1853]: time="2024-11-12T22:38:53.804733210Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:38:53.804856 containerd[1853]: time="2024-11-12T22:38:53.804832064Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:38:53.805288 containerd[1853]: time="2024-11-12T22:38:53.804913516Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:38:53.805426 containerd[1853]: time="2024-11-12T22:38:53.805359980Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:38:53.823979 systemd[1]: Started docker-a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6.scope - libcontainer container a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6. Nov 12 22:38:53.866821 kernel: eth0: renamed from veth3ca66ae Nov 12 22:38:53.874728 systemd-networkd[1312]: veth3f9b25c: Gained carrier Nov 12 22:38:53.874853 kernel: docker0: port 1(veth3f9b25c) entered blocking state Nov 12 22:38:53.874876 kernel: docker0: port 1(veth3f9b25c) entered forwarding state Nov 12 22:38:53.874981 systemd-networkd[1312]: docker0: Gained carrier Nov 12 22:38:53.877837 systemd-resolved[1249]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:38:53.899069 sshd[2116]: Connection closed by 10.0.0.1 port 49992 Nov 12 22:38:53.899781 sshd-session[2114]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:53.917098 systemd[1]: sshd@17-10.0.0.102:22-10.0.0.1:49992.service: Deactivated successfully. Nov 12 22:38:53.918585 systemd[1]: session-18.scope: Deactivated successfully. Nov 12 22:38:53.919175 systemd-logind[1356]: Session 18 logged out. Waiting for processes to exit. Nov 12 22:38:53.928103 systemd[1]: Started sshd@18-10.0.0.102:22-10.0.0.1:50002.service - OpenSSH per-connection server daemon (10.0.0.1:50002). Nov 12 22:38:53.929115 systemd-logind[1356]: Removed session 18. Nov 12 22:38:53.969012 sshd[2191]: Accepted publickey for core from 10.0.0.1 port 50002 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:53.970286 sshd-session[2191]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:53.974413 systemd-logind[1356]: New session 19 of user core. Nov 12 22:38:53.984936 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 12 22:38:54.038591 sshd[2193]: Connection closed by 10.0.0.1 port 50002 Nov 12 22:38:54.038444 sshd-session[2191]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:54.051106 systemd[1]: sshd@18-10.0.0.102:22-10.0.0.1:50002.service: Deactivated successfully. Nov 12 22:38:54.052658 systemd[1]: session-19.scope: Deactivated successfully. Nov 12 22:38:54.055406 systemd-logind[1356]: Session 19 logged out. Waiting for processes to exit. Nov 12 22:38:54.057418 systemd[1]: Started sshd@19-10.0.0.102:22-10.0.0.1:50008.service - OpenSSH per-connection server daemon (10.0.0.1:50008). Nov 12 22:38:54.060609 systemd-logind[1356]: Removed session 19. Nov 12 22:38:54.101898 sshd[2198]: Accepted publickey for core from 10.0.0.1 port 50008 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:54.103187 sshd-session[2198]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:54.107695 systemd-logind[1356]: New session 20 of user core. Nov 12 22:38:54.118656 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 12 22:38:54.169704 sudo[2201]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 1853 Nov 12 22:38:54.170318 sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:38:54.176843 sudo[2201]: pam_unix(sudo:session): session closed for user root Nov 12 22:38:54.178446 sshd[2200]: Connection closed by 10.0.0.1 port 50008 Nov 12 22:38:54.180352 sshd-session[2198]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:54.180704 dockerd[1887]: time="2024-11-12T22:38:54.178764820Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Nov 12 22:38:54.180704 dockerd[1887]: time="2024-11-12T22:38:54.178822147Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Nov 12 22:38:54.180704 dockerd[1887]: time="2024-11-12T22:38:54.178846391Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Nov 12 22:38:54.180704 dockerd[1887]: time="2024-11-12T22:38:54.178879035Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Nov 12 22:38:54.191898 systemd[1]: containerd.service: Deactivated successfully. Nov 12 22:38:54.191953 systemd[1]: containerd.service: Unit process 2145 (containerd-shim) remains running after unit stopped. Nov 12 22:38:54.192474 systemd[1]: sshd@19-10.0.0.102:22-10.0.0.1:50008.service: Deactivated successfully. Nov 12 22:38:54.194332 systemd[1]: session-20.scope: Deactivated successfully. Nov 12 22:38:54.195086 systemd-logind[1356]: Session 20 logged out. Waiting for processes to exit. Nov 12 22:38:54.203076 systemd[1]: Started sshd@20-10.0.0.102:22-10.0.0.1:50022.service - OpenSSH per-connection server daemon (10.0.0.1:50022). Nov 12 22:38:54.204734 systemd-logind[1356]: Removed session 20. Nov 12 22:38:54.244045 sshd[2207]: Accepted publickey for core from 10.0.0.1 port 50022 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:38:54.245160 sshd-session[2207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:38:54.248487 systemd-logind[1356]: New session 21 of user core. Nov 12 22:38:54.257942 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 12 22:38:54.309670 sshd[2209]: Connection closed by 10.0.0.1 port 50022 Nov 12 22:38:54.309930 sshd-session[2207]: pam_unix(sshd:session): session closed for user core Nov 12 22:38:54.313149 systemd[1]: sshd@20-10.0.0.102:22-10.0.0.1:50022.service: Deactivated successfully. Nov 12 22:38:54.315173 systemd[1]: session-21.scope: Deactivated successfully. Nov 12 22:38:54.315835 systemd-logind[1356]: Session 21 logged out. Waiting for processes to exit. Nov 12 22:38:54.316554 systemd-logind[1356]: Removed session 21. Nov 12 22:38:55.916380 systemd-networkd[1312]: veth3f9b25c: Gained IPv6LL Nov 12 22:38:59.233469 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Nov 12 22:38:59.233905 systemd[1]: Stopping docker.service - Docker Application Container Engine... Nov 12 22:38:59.234146 dockerd[1887]: time="2024-11-12T22:38:59.233954797Z" level=info msg="Processing signal 'terminated'" Nov 12 22:39:00.321117 systemd[1]: Started sshd@21-10.0.0.102:22-10.0.0.1:50032.service - OpenSSH per-connection server daemon (10.0.0.1:50032). Nov 12 22:39:00.364342 sshd[2214]: Accepted publickey for core from 10.0.0.1 port 50032 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:00.365491 sshd-session[2214]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:00.369103 systemd-logind[1356]: New session 22 of user core. Nov 12 22:39:00.377937 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 12 22:39:00.429835 sshd[2216]: Connection closed by 10.0.0.1 port 50032 Nov 12 22:39:00.430141 sshd-session[2214]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:00.433003 systemd[1]: sshd@21-10.0.0.102:22-10.0.0.1:50032.service: Deactivated successfully. Nov 12 22:39:00.436197 systemd[1]: session-22.scope: Deactivated successfully. Nov 12 22:39:00.436734 systemd-logind[1356]: Session 22 logged out. Waiting for processes to exit. Nov 12 22:39:00.437668 systemd-logind[1356]: Removed session 22. Nov 12 22:39:01.235342 dockerd[1887]: time="2024-11-12T22:39:01.235235633Z" level=error msg="Error sending stop (signal 15) to container" container=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 error="Cannot kill container a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\": unavailable" Nov 12 22:39:01.235659 dockerd[1887]: time="2024-11-12T22:39:01.235347403Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 Nov 12 22:39:03.110891 update_engine[1359]: I20241112 22:39:03.110780 1359 update_attempter.cc:509] Updating boot flags... Nov 12 22:39:03.130824 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2227) Nov 12 22:39:03.161854 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2229) Nov 12 22:39:03.184858 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2229) Nov 12 22:39:06.441047 systemd[1]: Started sshd@22-10.0.0.102:22-10.0.0.1:42176.service - OpenSSH per-connection server daemon (10.0.0.1:42176). Nov 12 22:39:06.484104 sshd[2236]: Accepted publickey for core from 10.0.0.1 port 42176 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:06.485191 sshd-session[2236]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:06.488588 systemd-logind[1356]: New session 23 of user core. Nov 12 22:39:06.498943 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 12 22:39:06.551647 sshd[2238]: Connection closed by 10.0.0.1 port 42176 Nov 12 22:39:06.552350 sshd-session[2236]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:06.555652 systemd[1]: sshd@22-10.0.0.102:22-10.0.0.1:42176.service: Deactivated successfully. Nov 12 22:39:06.557489 systemd[1]: session-23.scope: Deactivated successfully. Nov 12 22:39:06.559206 systemd-logind[1356]: Session 23 logged out. Waiting for processes to exit. Nov 12 22:39:06.560112 systemd-logind[1356]: Removed session 23. Nov 12 22:39:11.235680 dockerd[1887]: time="2024-11-12T22:39:11.235619406Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 error="context deadline exceeded" Nov 12 22:39:11.236312 systemd[1]: docker-a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6.scope: Deactivated successfully. Nov 12 22:39:12.562154 systemd[1]: Started sshd@23-10.0.0.102:22-10.0.0.1:60406.service - OpenSSH per-connection server daemon (10.0.0.1:60406). Nov 12 22:39:12.606131 sshd[2243]: Accepted publickey for core from 10.0.0.1 port 60406 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:12.607482 sshd-session[2243]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:12.611345 systemd-logind[1356]: New session 24 of user core. Nov 12 22:39:12.625988 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 12 22:39:12.680860 sshd[2245]: Connection closed by 10.0.0.1 port 60406 Nov 12 22:39:12.681356 sshd-session[2243]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:12.684413 systemd[1]: sshd@23-10.0.0.102:22-10.0.0.1:60406.service: Deactivated successfully. Nov 12 22:39:12.685885 systemd[1]: session-24.scope: Deactivated successfully. Nov 12 22:39:12.687072 systemd-logind[1356]: Session 24 logged out. Waiting for processes to exit. Nov 12 22:39:12.688000 systemd-logind[1356]: Removed session 24. Nov 12 22:39:14.235155 dockerd[1887]: time="2024-11-12T22:39:14.235099642Z" level=error msg="Force shutdown daemon" Nov 12 22:39:14.235606 dockerd[1887]: time="2024-11-12T22:39:14.235291212Z" level=info msg="Daemon shutdown complete" Nov 12 22:39:14.238467 systemd[1]: docker.service: Deactivated successfully. Nov 12 22:39:14.238733 systemd[1]: Stopped docker.service - Docker Application Container Engine. Nov 12 22:39:14.239277 systemd[1]: docker.socket: Deactivated successfully. Nov 12 22:39:14.239508 systemd[1]: Closed docker.socket - Docker Socket for the API. Nov 12 22:39:14.239544 systemd[1]: Stopping docker.socket - Docker Socket for the API... Nov 12 22:39:14.242024 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:39:14.253014 systemd[1]: containerd.service: Found left-over process 2145 (containerd-shim) in control group while starting unit. Ignoring. Nov 12 22:39:14.253027 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 12 22:39:14.253978 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:39:14.254244 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:39:14.265263 systemd[1]: containerd.service: Found left-over process 2145 (containerd-shim) in control group while starting unit. Ignoring. Nov 12 22:39:14.265275 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Nov 12 22:39:14.269201 (ntainerd)[2255]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:39:14.282863 containerd[2255]: time="2024-11-12T22:39:14.282769376Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:39:14.305428 containerd[2255]: time="2024-11-12T22:39:14.305386402Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306552 containerd[2255]: time="2024-11-12T22:39:14.306514859Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306552 containerd[2255]: time="2024-11-12T22:39:14.306547181Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:39:14.306618 containerd[2255]: time="2024-11-12T22:39:14.306561741Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:39:14.306618 containerd[2255]: time="2024-11-12T22:39:14.306597543Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:39:14.306618 containerd[2255]: time="2024-11-12T22:39:14.306610184Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306678 containerd[2255]: time="2024-11-12T22:39:14.306630105Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306678 containerd[2255]: time="2024-11-12T22:39:14.306639625Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306798 containerd[2255]: time="2024-11-12T22:39:14.306762391Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306798 containerd[2255]: time="2024-11-12T22:39:14.306783313Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306852 containerd[2255]: time="2024-11-12T22:39:14.306810874Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306852 containerd[2255]: time="2024-11-12T22:39:14.306821874Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306852 containerd[2255]: time="2024-11-12T22:39:14.306842676Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.306949 containerd[2255]: time="2024-11-12T22:39:14.306932800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:39:14.307053 containerd[2255]: time="2024-11-12T22:39:14.307029125Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:39:14.307053 containerd[2255]: time="2024-11-12T22:39:14.307046446Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:39:14.307096 containerd[2255]: time="2024-11-12T22:39:14.307065007Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:39:14.307096 containerd[2255]: time="2024-11-12T22:39:14.307080328Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:39:14.307237 containerd[2255]: time="2024-11-12T22:39:14.307219255Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:39:14.307272 containerd[2255]: time="2024-11-12T22:39:14.307251976Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:39:14.307272 containerd[2255]: time="2024-11-12T22:39:14.307265537Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:39:14.307315 containerd[2255]: time="2024-11-12T22:39:14.307280858Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:39:14.307315 containerd[2255]: time="2024-11-12T22:39:14.307292858Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:39:14.307352 containerd[2255]: time="2024-11-12T22:39:14.307340421Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:39:14.307655 containerd[2255]: time="2024-11-12T22:39:14.307613835Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:39:14.313486 containerd[2255]: time="2024-11-12T22:39:14.313450130Z" level=info msg="cleaning leaked shim process" id=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 namespace=moby Nov 12 22:39:14.320779 containerd[2255]: time="2024-11-12T22:39:14.320709458Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Nov 12 22:39:14.321140 containerd[2255]: time="2024-11-12T22:39:14.320979592Z" level=info msg="shim disconnected" id=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 namespace=moby Nov 12 22:39:14.321140 containerd[2255]: time="2024-11-12T22:39:14.321026634Z" level=warning msg="cleaning up after shim disconnected" id=a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6 namespace=moby Nov 12 22:39:14.321140 containerd[2255]: time="2024-11-12T22:39:14.321034234Z" level=info msg="cleaning up dead shim" namespace=moby Nov 12 22:39:14.330741 containerd[2255]: time="2024-11-12T22:39:14.330710244Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:39:14.330873 containerd[2255]: time="2024-11-12T22:39:14.330856532Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:39:14.330940 containerd[2255]: time="2024-11-12T22:39:14.330927415Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:39:14.330997 containerd[2255]: time="2024-11-12T22:39:14.330984858Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331057 containerd[2255]: time="2024-11-12T22:39:14.331044461Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331108 containerd[2255]: time="2024-11-12T22:39:14.331097464Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331151667Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331172108Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331186308Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331211710Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331224030Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331244991Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331259272Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331271673Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331283673Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331314715Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331326636Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331344836Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331357437Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.331801 containerd[2255]: time="2024-11-12T22:39:14.331368878Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331384278Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331399439Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331410520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331423080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331439441Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331461962Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331474723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331484804Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331539686Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331556007Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331565648Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331576168Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:39:14.332311 containerd[2255]: time="2024-11-12T22:39:14.331584529Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332560 containerd[2255]: time="2024-11-12T22:39:14.331595009Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:39:14.332560 containerd[2255]: time="2024-11-12T22:39:14.331604210Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:39:14.332560 containerd[2255]: time="2024-11-12T22:39:14.331614930Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:39:14.332616 containerd[2255]: time="2024-11-12T22:39:14.331979029Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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}" Nov 12 22:39:14.332616 containerd[2255]: time="2024-11-12T22:39:14.332110075Z" level=info msg="Connect containerd service" Nov 12 22:39:14.332616 containerd[2255]: time="2024-11-12T22:39:14.332164398Z" level=info msg="using legacy CRI server" Nov 12 22:39:14.332616 containerd[2255]: time="2024-11-12T22:39:14.332172438Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:39:14.332616 containerd[2255]: time="2024-11-12T22:39:14.332584499Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:39:14.333480 containerd[2255]: time="2024-11-12T22:39:14.333448543Z" 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" Nov 12 22:39:14.333818 containerd[2255]: time="2024-11-12T22:39:14.333660234Z" level=info msg="Start subscribing containerd event" Nov 12 22:39:14.333818 containerd[2255]: time="2024-11-12T22:39:14.333697596Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:39:14.333818 containerd[2255]: time="2024-11-12T22:39:14.333719317Z" level=info msg="Start recovering state" Nov 12 22:39:14.333818 containerd[2255]: time="2024-11-12T22:39:14.333776240Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:39:14.334001 containerd[2255]: time="2024-11-12T22:39:14.333984730Z" level=info msg="Start event monitor" Nov 12 22:39:14.334067 containerd[2255]: time="2024-11-12T22:39:14.334055774Z" level=info msg="Start snapshots syncer" Nov 12 22:39:14.334117 containerd[2255]: time="2024-11-12T22:39:14.334105376Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:39:14.334178 containerd[2255]: time="2024-11-12T22:39:14.334168539Z" level=info msg="Start streaming server" Nov 12 22:39:14.334396 containerd[2255]: time="2024-11-12T22:39:14.334380550Z" level=info msg="containerd successfully booted in 0.052097s" Nov 12 22:39:14.334701 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:39:14.342038 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 12 22:39:14.342203 (dockerd)[2290]: 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 Nov 12 22:39:14.367339 dockerd[2290]: time="2024-11-12T22:39:14.366541139Z" level=info msg="Starting up" Nov 12 22:39:14.390291 dockerd[2290]: time="2024-11-12T22:39:14.390244020Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Nov 12 22:39:14.396439 dockerd[2290]: time="2024-11-12T22:39:14.396405292Z" level=info msg="Loading containers: start." Nov 12 22:39:14.407681 systemd[1]: var-lib-docker-overlay2-a160f88aa4c4f21eba85a76e7fbaf9923430d371adfdd86a8f3d13a28aa586e7-merged.mount: Deactivated successfully. Nov 12 22:39:14.657041 dockerd[2290]: time="2024-11-12T22:39:14.656923246Z" level=info msg="Removing stale sandbox 30756d74c084e82e4250af43e6010ed736e584aca74aea6cfad4524ff8f98057 (a350812463597efd707298346ccf10efb3c51460b54d7fbdc2ebf85bf031e5a6)" Nov 12 22:39:14.660134 dockerd[2290]: time="2024-11-12T22:39:14.659953400Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 97176a3b3b6dda00c62c090418891872a83cb3757e3a88e4fc166b2b4915d170 32ba3452c499d1d429e3b05ff653c060a68ee7a16af75d84b00f9c33327f00d6], retrying...." Nov 12 22:39:14.661076 systemd-networkd[1312]: veth3f9b25c: Link DOWN Nov 12 22:39:14.661083 systemd-networkd[1312]: veth3f9b25c: Lost carrier Nov 12 22:39:14.661834 kernel: docker0: port 1(veth3f9b25c) entered disabled state Nov 12 22:39:14.663634 kernel: veth3f9b25c (unregistering): left allmulticast mode Nov 12 22:39:14.663691 kernel: veth3f9b25c (unregistering): left promiscuous mode Nov 12 22:39:14.663715 kernel: docker0: port 1(veth3f9b25c) entered disabled state Nov 12 22:39:14.674925 systemd-networkd[1312]: docker0: Lost carrier Nov 12 22:39:14.801000 dockerd[2290]: time="2024-11-12T22:39:14.800952061Z" 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" Nov 12 22:39:14.869004 dockerd[2290]: time="2024-11-12T22:39:14.868954305Z" level=warning msg="error locating sandbox id 26d27fcbda49b5a71bb4d80196c2fba443f9eca9f0fa6f0e67b97c7212aaaf5f: sandbox 26d27fcbda49b5a71bb4d80196c2fba443f9eca9f0fa6f0e67b97c7212aaaf5f not found" Nov 12 22:39:14.869004 dockerd[2290]: time="2024-11-12T22:39:14.868998227Z" level=warning msg="error locating sandbox id 30756d74c084e82e4250af43e6010ed736e584aca74aea6cfad4524ff8f98057: sandbox 30756d74c084e82e4250af43e6010ed736e584aca74aea6cfad4524ff8f98057 not found" Nov 12 22:39:14.869147 dockerd[2290]: time="2024-11-12T22:39:14.869086552Z" level=info msg="Loading containers: done." Nov 12 22:39:14.878340 dockerd[2290]: time="2024-11-12T22:39:14.878282737Z" 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 Nov 12 22:39:14.878482 dockerd[2290]: time="2024-11-12T22:39:14.878364622Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Nov 12 22:39:14.878482 dockerd[2290]: time="2024-11-12T22:39:14.878405064Z" level=info msg="Daemon has completed initialization" Nov 12 22:39:14.897619 dockerd[2290]: time="2024-11-12T22:39:14.897556794Z" level=info msg="API listen on /run/docker.sock" Nov 12 22:39:14.897745 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 12 22:39:15.381051 systemd[1]: run-docker-netns-30756d74c084.mount: Deactivated successfully. Nov 12 22:39:18.696079 systemd[1]: Started sshd@24-10.0.0.102:22-10.0.0.1:60412.service - OpenSSH per-connection server daemon (10.0.0.1:60412). Nov 12 22:39:18.739427 sshd[2490]: Accepted publickey for core from 10.0.0.1 port 60412 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:18.740545 sshd-session[2490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:18.743821 systemd-logind[1356]: New session 25 of user core. Nov 12 22:39:18.755922 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 12 22:39:18.809100 sshd[2492]: Connection closed by 10.0.0.1 port 60412 Nov 12 22:39:18.809522 sshd-session[2490]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:18.817899 systemd[1]: sshd@24-10.0.0.102:22-10.0.0.1:60412.service: Deactivated successfully. Nov 12 22:39:18.819227 systemd[1]: session-25.scope: Deactivated successfully. Nov 12 22:39:18.820557 systemd-logind[1356]: Session 25 logged out. Waiting for processes to exit. Nov 12 22:39:18.821610 systemd[1]: Started sshd@25-10.0.0.102:22-10.0.0.1:60428.service - OpenSSH per-connection server daemon (10.0.0.1:60428). Nov 12 22:39:18.822286 systemd-logind[1356]: Removed session 25. Nov 12 22:39:18.863693 sshd[2497]: Accepted publickey for core from 10.0.0.1 port 60428 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:18.864754 sshd-session[2497]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:18.868592 systemd-logind[1356]: New session 26 of user core. Nov 12 22:39:18.878922 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 12 22:39:18.930809 sshd[2499]: Connection closed by 10.0.0.1 port 60428 Nov 12 22:39:18.931105 sshd-session[2497]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:18.945044 systemd[1]: sshd@25-10.0.0.102:22-10.0.0.1:60428.service: Deactivated successfully. Nov 12 22:39:18.948189 systemd[1]: session-26.scope: Deactivated successfully. Nov 12 22:39:18.949957 systemd-logind[1356]: Session 26 logged out. Waiting for processes to exit. Nov 12 22:39:18.950496 systemd[1]: Started sshd@26-10.0.0.102:22-10.0.0.1:60442.service - OpenSSH per-connection server daemon (10.0.0.1:60442). Nov 12 22:39:18.951451 systemd-logind[1356]: Removed session 26. Nov 12 22:39:18.992659 sshd[2504]: Accepted publickey for core from 10.0.0.1 port 60442 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:18.993829 sshd-session[2504]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:18.997131 systemd-logind[1356]: New session 27 of user core. Nov 12 22:39:19.008915 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 12 22:39:19.064223 sshd[2506]: Connection closed by 10.0.0.1 port 60442 Nov 12 22:39:19.064520 sshd-session[2504]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:19.086036 systemd[1]: sshd@26-10.0.0.102:22-10.0.0.1:60442.service: Deactivated successfully. Nov 12 22:39:19.088104 systemd[1]: session-27.scope: Deactivated successfully. Nov 12 22:39:19.089256 systemd-logind[1356]: Session 27 logged out. Waiting for processes to exit. Nov 12 22:39:19.090304 systemd[1]: Started sshd@27-10.0.0.102:22-10.0.0.1:60456.service - OpenSSH per-connection server daemon (10.0.0.1:60456). Nov 12 22:39:19.090962 systemd-logind[1356]: Removed session 27. Nov 12 22:39:19.133323 sshd[2518]: Accepted publickey for core from 10.0.0.1 port 60456 ssh2: RSA SHA256:KPgpt/5uXhXBYNY6jU95wYzOWgpCWHnSiDnDh5jQRRc Nov 12 22:39:19.134497 sshd-session[2518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:39:19.137800 systemd-logind[1356]: New session 28 of user core. Nov 12 22:39:19.144929 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 12 22:39:19.209078 systemd[1]: var-lib-docker-overlay2-2ff2d52a402f6937eff1451c8fc74d94c9c2f172e3c304eb29f6803be9a40679\x2dinit-merged.mount: Deactivated successfully. Nov 12 22:39:19.231149 kernel: docker0: port 1(veth39ad8d1) entered blocking state Nov 12 22:39:19.231250 kernel: docker0: port 1(veth39ad8d1) entered disabled state Nov 12 22:39:19.231268 kernel: veth39ad8d1: entered allmulticast mode Nov 12 22:39:19.231282 kernel: veth39ad8d1: entered promiscuous mode Nov 12 22:39:19.232666 systemd-networkd[1312]: veth39ad8d1: Link UP Nov 12 22:39:19.267077 containerd[2255]: time="2024-11-12T22:39:19.266985348Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:39:19.267077 containerd[2255]: time="2024-11-12T22:39:19.267048550Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:39:19.267077 containerd[2255]: time="2024-11-12T22:39:19.267060631Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:39:19.267514 containerd[2255]: time="2024-11-12T22:39:19.267131914Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:39:19.289961 systemd[1]: Started docker-78b563b326a24379fa32dfff05772115e02950aa429799af3eb6865159365d16.scope - libcontainer container 78b563b326a24379fa32dfff05772115e02950aa429799af3eb6865159365d16. Nov 12 22:39:19.334847 kernel: eth0: renamed from veth2cb4060 Nov 12 22:39:19.341863 kernel: docker0: port 1(veth39ad8d1) entered blocking state Nov 12 22:39:19.341922 kernel: docker0: port 1(veth39ad8d1) entered forwarding state Nov 12 22:39:19.341925 systemd-networkd[1312]: veth39ad8d1: Gained carrier Nov 12 22:39:19.342124 systemd-networkd[1312]: docker0: Gained carrier Nov 12 22:39:19.344509 systemd-resolved[1249]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 12 22:39:19.365711 sshd[2520]: Connection closed by 10.0.0.1 port 60456 Nov 12 22:39:19.366095 sshd-session[2518]: pam_unix(sshd:session): session closed for user core Nov 12 22:39:19.369513 systemd[1]: sshd@27-10.0.0.102:22-10.0.0.1:60456.service: Deactivated successfully. Nov 12 22:39:19.371043 systemd[1]: session-28.scope: Deactivated successfully. Nov 12 22:39:19.371613 systemd-logind[1356]: Session 28 logged out. Waiting for processes to exit. Nov 12 22:39:19.372517 systemd-logind[1356]: Removed session 28.