May 9 00:27:12.868469 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Thu May 8 22:52:37 -00 2025 May 9 00:27:12.868492 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 verity.usrhash=56b660b06ded103a15fe25ebfbdecb898a20f374e429fec465c69b1a75d59c4b May 9 00:27:12.868504 kernel: BIOS-provided physical RAM map: May 9 00:27:12.868510 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 9 00:27:12.868516 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 9 00:27:12.868523 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 9 00:27:12.868530 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable May 9 00:27:12.868536 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved May 9 00:27:12.868542 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 9 00:27:12.868551 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved May 9 00:27:12.868557 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 9 00:27:12.868563 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 9 00:27:12.868569 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 9 00:27:12.868576 kernel: NX (Execute Disable) protection: active May 9 00:27:12.868583 kernel: APIC: Static calls initialized May 9 00:27:12.868593 kernel: SMBIOS 2.8 present. May 9 00:27:12.868600 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 May 9 00:27:12.868607 kernel: Hypervisor detected: KVM May 9 00:27:12.868614 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 9 00:27:12.868620 kernel: kvm-clock: using sched offset of 2198332523 cycles May 9 00:27:12.868627 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 9 00:27:12.868635 kernel: tsc: Detected 2794.748 MHz processor May 9 00:27:12.868642 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 9 00:27:12.868649 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 9 00:27:12.868656 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 May 9 00:27:12.868666 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 9 00:27:12.868673 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 9 00:27:12.868680 kernel: Using GB pages for direct mapping May 9 00:27:12.868687 kernel: ACPI: Early table checksum verification disabled May 9 00:27:12.868694 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) May 9 00:27:12.868701 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868708 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868715 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868724 kernel: ACPI: FACS 0x000000009CFE0000 000040 May 9 00:27:12.868731 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868738 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868745 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868752 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:27:12.868759 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] May 9 00:27:12.868766 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] May 9 00:27:12.868777 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] May 9 00:27:12.868786 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] May 9 00:27:12.868793 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] May 9 00:27:12.868800 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] May 9 00:27:12.868807 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] May 9 00:27:12.868814 kernel: No NUMA configuration found May 9 00:27:12.868821 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] May 9 00:27:12.868828 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] May 9 00:27:12.868838 kernel: Zone ranges: May 9 00:27:12.868845 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 9 00:27:12.868865 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] May 9 00:27:12.868872 kernel: Normal empty May 9 00:27:12.868879 kernel: Movable zone start for each node May 9 00:27:12.868886 kernel: Early memory node ranges May 9 00:27:12.868893 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 9 00:27:12.868900 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] May 9 00:27:12.868907 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] May 9 00:27:12.868918 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 9 00:27:12.868925 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 9 00:27:12.868932 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges May 9 00:27:12.868939 kernel: ACPI: PM-Timer IO Port: 0x608 May 9 00:27:12.868946 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 9 00:27:12.868953 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 9 00:27:12.868960 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 9 00:27:12.868967 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 9 00:27:12.868975 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 9 00:27:12.868984 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 9 00:27:12.868992 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 9 00:27:12.868999 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 9 00:27:12.869006 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 9 00:27:12.869013 kernel: TSC deadline timer available May 9 00:27:12.869020 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 9 00:27:12.869027 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 9 00:27:12.869034 kernel: kvm-guest: KVM setup pv remote TLB flush May 9 00:27:12.869041 kernel: kvm-guest: setup PV sched yield May 9 00:27:12.869051 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices May 9 00:27:12.869058 kernel: Booting paravirtualized kernel on KVM May 9 00:27:12.869066 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 9 00:27:12.869073 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 9 00:27:12.869080 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 May 9 00:27:12.869087 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 May 9 00:27:12.869094 kernel: pcpu-alloc: [0] 0 1 2 3 May 9 00:27:12.869101 kernel: kvm-guest: PV spinlocks enabled May 9 00:27:12.869108 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 9 00:27:12.869116 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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 verity.usrhash=56b660b06ded103a15fe25ebfbdecb898a20f374e429fec465c69b1a75d59c4b May 9 00:27:12.869127 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 9 00:27:12.869134 kernel: random: crng init done May 9 00:27:12.869141 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 9 00:27:12.869149 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 9 00:27:12.869156 kernel: Fallback order for Node 0: 0 May 9 00:27:12.869163 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 May 9 00:27:12.869170 kernel: Policy zone: DMA32 May 9 00:27:12.869177 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 9 00:27:12.869188 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2295K rwdata, 22740K rodata, 42864K init, 2328K bss, 136900K reserved, 0K cma-reserved) May 9 00:27:12.869195 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 9 00:27:12.869203 kernel: ftrace: allocating 37944 entries in 149 pages May 9 00:27:12.869212 kernel: ftrace: allocated 149 pages with 4 groups May 9 00:27:12.869219 kernel: Dynamic Preempt: voluntary May 9 00:27:12.869228 kernel: rcu: Preemptible hierarchical RCU implementation. May 9 00:27:12.869236 kernel: rcu: RCU event tracing is enabled. May 9 00:27:12.869244 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 9 00:27:12.869251 kernel: Trampoline variant of Tasks RCU enabled. May 9 00:27:12.869261 kernel: Rude variant of Tasks RCU enabled. May 9 00:27:12.869269 kernel: Tracing variant of Tasks RCU enabled. May 9 00:27:12.869276 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 9 00:27:12.869283 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 9 00:27:12.869290 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 9 00:27:12.869297 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 9 00:27:12.869304 kernel: Console: colour VGA+ 80x25 May 9 00:27:12.869311 kernel: printk: console [ttyS0] enabled May 9 00:27:12.869318 kernel: ACPI: Core revision 20230628 May 9 00:27:12.869328 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 9 00:27:12.869336 kernel: APIC: Switch to symmetric I/O mode setup May 9 00:27:12.869343 kernel: x2apic enabled May 9 00:27:12.869350 kernel: APIC: Switched APIC routing to: physical x2apic May 9 00:27:12.869357 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 9 00:27:12.869364 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 9 00:27:12.869372 kernel: kvm-guest: setup PV IPIs May 9 00:27:12.869390 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 9 00:27:12.869397 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 9 00:27:12.869405 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 9 00:27:12.869412 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 9 00:27:12.869420 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 9 00:27:12.869430 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 9 00:27:12.869444 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 9 00:27:12.869452 kernel: Spectre V2 : Mitigation: Retpolines May 9 00:27:12.869460 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 9 00:27:12.869470 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 9 00:27:12.869478 kernel: RETBleed: Mitigation: untrained return thunk May 9 00:27:12.869485 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 9 00:27:12.869493 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 9 00:27:12.869501 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 9 00:27:12.869509 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 9 00:27:12.869516 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 9 00:27:12.869524 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 9 00:27:12.869531 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 9 00:27:12.869541 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 9 00:27:12.869549 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 9 00:27:12.869556 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 9 00:27:12.869564 kernel: Freeing SMP alternatives memory: 32K May 9 00:27:12.869571 kernel: pid_max: default: 32768 minimum: 301 May 9 00:27:12.869579 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 9 00:27:12.869586 kernel: landlock: Up and running. May 9 00:27:12.869593 kernel: SELinux: Initializing. May 9 00:27:12.869601 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 00:27:12.869611 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 00:27:12.869619 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 9 00:27:12.869626 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 00:27:12.869634 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 00:27:12.869641 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 00:27:12.869649 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 9 00:27:12.869659 kernel: ... version: 0 May 9 00:27:12.869669 kernel: ... bit width: 48 May 9 00:27:12.869681 kernel: ... generic registers: 6 May 9 00:27:12.869688 kernel: ... value mask: 0000ffffffffffff May 9 00:27:12.869696 kernel: ... max period: 00007fffffffffff May 9 00:27:12.869703 kernel: ... fixed-purpose events: 0 May 9 00:27:12.869711 kernel: ... event mask: 000000000000003f May 9 00:27:12.869718 kernel: signal: max sigframe size: 1776 May 9 00:27:12.869725 kernel: rcu: Hierarchical SRCU implementation. May 9 00:27:12.869733 kernel: rcu: Max phase no-delay instances is 400. May 9 00:27:12.869740 kernel: smp: Bringing up secondary CPUs ... May 9 00:27:12.869748 kernel: smpboot: x86: Booting SMP configuration: May 9 00:27:12.869758 kernel: .... node #0, CPUs: #1 #2 #3 May 9 00:27:12.869765 kernel: smp: Brought up 1 node, 4 CPUs May 9 00:27:12.869773 kernel: smpboot: Max logical packages: 1 May 9 00:27:12.869780 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 9 00:27:12.869788 kernel: devtmpfs: initialized May 9 00:27:12.869795 kernel: x86/mm: Memory block size: 128MB May 9 00:27:12.869803 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 9 00:27:12.869810 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 9 00:27:12.869817 kernel: pinctrl core: initialized pinctrl subsystem May 9 00:27:12.869827 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 9 00:27:12.869835 kernel: audit: initializing netlink subsys (disabled) May 9 00:27:12.869842 kernel: audit: type=2000 audit(1746750432.842:1): state=initialized audit_enabled=0 res=1 May 9 00:27:12.869850 kernel: thermal_sys: Registered thermal governor 'step_wise' May 9 00:27:12.869869 kernel: thermal_sys: Registered thermal governor 'user_space' May 9 00:27:12.869876 kernel: cpuidle: using governor menu May 9 00:27:12.869883 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 9 00:27:12.869891 kernel: dca service started, version 1.12.1 May 9 00:27:12.869898 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) May 9 00:27:12.869909 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 9 00:27:12.869916 kernel: PCI: Using configuration type 1 for base access May 9 00:27:12.869924 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 9 00:27:12.869931 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 9 00:27:12.869939 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 9 00:27:12.869946 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 9 00:27:12.869954 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 9 00:27:12.869961 kernel: ACPI: Added _OSI(Module Device) May 9 00:27:12.869968 kernel: ACPI: Added _OSI(Processor Device) May 9 00:27:12.869979 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 9 00:27:12.869987 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 9 00:27:12.869994 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 9 00:27:12.870001 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 9 00:27:12.870009 kernel: ACPI: Interpreter enabled May 9 00:27:12.870016 kernel: ACPI: PM: (supports S0 S3 S5) May 9 00:27:12.870023 kernel: ACPI: Using IOAPIC for interrupt routing May 9 00:27:12.870031 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 9 00:27:12.870039 kernel: PCI: Using E820 reservations for host bridge windows May 9 00:27:12.870049 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 9 00:27:12.870057 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 9 00:27:12.870256 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 9 00:27:12.870386 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 9 00:27:12.870516 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 9 00:27:12.870527 kernel: PCI host bridge to bus 0000:00 May 9 00:27:12.870668 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 9 00:27:12.870789 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 9 00:27:12.871038 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 9 00:27:12.871153 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 9 00:27:12.871264 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 9 00:27:12.871375 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] May 9 00:27:12.871496 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 9 00:27:12.871637 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 9 00:27:12.871785 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 9 00:27:12.871953 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] May 9 00:27:12.872103 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] May 9 00:27:12.872262 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] May 9 00:27:12.872418 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 9 00:27:12.872606 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 9 00:27:12.872775 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] May 9 00:27:12.872984 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] May 9 00:27:12.873144 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] May 9 00:27:12.873315 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 9 00:27:12.873487 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] May 9 00:27:12.873647 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] May 9 00:27:12.873800 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] May 9 00:27:12.874011 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 9 00:27:12.874176 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] May 9 00:27:12.874339 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] May 9 00:27:12.874515 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] May 9 00:27:12.874681 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] May 9 00:27:12.874818 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 9 00:27:12.874959 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 9 00:27:12.875097 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 9 00:27:12.875219 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] May 9 00:27:12.875339 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] May 9 00:27:12.875493 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 9 00:27:12.875619 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] May 9 00:27:12.875629 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 9 00:27:12.875637 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 9 00:27:12.875649 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 9 00:27:12.875656 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 9 00:27:12.875664 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 9 00:27:12.875672 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 9 00:27:12.875679 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 9 00:27:12.875687 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 9 00:27:12.875695 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 9 00:27:12.875702 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 9 00:27:12.875710 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 9 00:27:12.875720 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 9 00:27:12.875728 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 9 00:27:12.875735 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 9 00:27:12.875743 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 9 00:27:12.875751 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 9 00:27:12.875758 kernel: iommu: Default domain type: Translated May 9 00:27:12.875766 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 9 00:27:12.875773 kernel: PCI: Using ACPI for IRQ routing May 9 00:27:12.875781 kernel: PCI: pci_cache_line_size set to 64 bytes May 9 00:27:12.875791 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 9 00:27:12.875799 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] May 9 00:27:12.875943 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 9 00:27:12.876067 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 9 00:27:12.876188 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 9 00:27:12.876198 kernel: vgaarb: loaded May 9 00:27:12.876206 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 9 00:27:12.876214 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 9 00:27:12.876226 kernel: clocksource: Switched to clocksource kvm-clock May 9 00:27:12.876234 kernel: VFS: Disk quotas dquot_6.6.0 May 9 00:27:12.876242 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 9 00:27:12.876249 kernel: pnp: PnP ACPI init May 9 00:27:12.876380 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 9 00:27:12.876391 kernel: pnp: PnP ACPI: found 6 devices May 9 00:27:12.876399 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 9 00:27:12.876409 kernel: NET: Registered PF_INET protocol family May 9 00:27:12.876425 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 9 00:27:12.876434 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 9 00:27:12.876450 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 9 00:27:12.876458 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 9 00:27:12.876465 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 9 00:27:12.876473 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 9 00:27:12.876481 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 00:27:12.876488 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 00:27:12.876496 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 9 00:27:12.876506 kernel: NET: Registered PF_XDP protocol family May 9 00:27:12.876623 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 9 00:27:12.876736 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 9 00:27:12.876846 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 9 00:27:12.877007 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 9 00:27:12.877120 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 9 00:27:12.877234 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] May 9 00:27:12.877245 kernel: PCI: CLS 0 bytes, default 64 May 9 00:27:12.877257 kernel: Initialise system trusted keyrings May 9 00:27:12.877264 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 9 00:27:12.877272 kernel: Key type asymmetric registered May 9 00:27:12.877279 kernel: Asymmetric key parser 'x509' registered May 9 00:27:12.877287 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 9 00:27:12.877294 kernel: io scheduler mq-deadline registered May 9 00:27:12.877302 kernel: io scheduler kyber registered May 9 00:27:12.877309 kernel: io scheduler bfq registered May 9 00:27:12.877317 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 9 00:27:12.877328 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 9 00:27:12.877335 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 9 00:27:12.877343 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 9 00:27:12.877350 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 9 00:27:12.877358 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 9 00:27:12.877366 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 9 00:27:12.877373 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 9 00:27:12.877381 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 9 00:27:12.877538 kernel: rtc_cmos 00:04: RTC can wake from S4 May 9 00:27:12.877555 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 9 00:27:12.877672 kernel: rtc_cmos 00:04: registered as rtc0 May 9 00:27:12.877784 kernel: rtc_cmos 00:04: setting system clock to 2025-05-09T00:27:12 UTC (1746750432) May 9 00:27:12.877913 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 9 00:27:12.877923 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 9 00:27:12.877931 kernel: NET: Registered PF_INET6 protocol family May 9 00:27:12.877939 kernel: Segment Routing with IPv6 May 9 00:27:12.877947 kernel: In-situ OAM (IOAM) with IPv6 May 9 00:27:12.877958 kernel: NET: Registered PF_PACKET protocol family May 9 00:27:12.877966 kernel: Key type dns_resolver registered May 9 00:27:12.877973 kernel: IPI shorthand broadcast: enabled May 9 00:27:12.877981 kernel: sched_clock: Marking stable (601002225, 104970910)->(718808539, -12835404) May 9 00:27:12.877989 kernel: registered taskstats version 1 May 9 00:27:12.877996 kernel: Loading compiled-in X.509 certificates May 9 00:27:12.878004 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: fe5c896a3ca06bb89ebdfb7ed85f611806e4c1cc' May 9 00:27:12.878012 kernel: Key type .fscrypt registered May 9 00:27:12.878019 kernel: Key type fscrypt-provisioning registered May 9 00:27:12.878029 kernel: ima: No TPM chip found, activating TPM-bypass! May 9 00:27:12.878037 kernel: ima: Allocated hash algorithm: sha1 May 9 00:27:12.878044 kernel: ima: No architecture policies found May 9 00:27:12.878052 kernel: clk: Disabling unused clocks May 9 00:27:12.878059 kernel: Freeing unused kernel image (initmem) memory: 42864K May 9 00:27:12.878067 kernel: Write protecting the kernel read-only data: 36864k May 9 00:27:12.878075 kernel: Freeing unused kernel image (rodata/data gap) memory: 1836K May 9 00:27:12.878082 kernel: Run /init as init process May 9 00:27:12.878090 kernel: with arguments: May 9 00:27:12.878100 kernel: /init May 9 00:27:12.878107 kernel: with environment: May 9 00:27:12.878114 kernel: HOME=/ May 9 00:27:12.878122 kernel: TERM=linux May 9 00:27:12.878129 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 9 00:27:12.878139 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) May 9 00:27:12.878149 systemd[1]: Detected virtualization kvm. May 9 00:27:12.878157 systemd[1]: Detected architecture x86-64. May 9 00:27:12.878167 systemd[1]: Running in initrd. May 9 00:27:12.878175 systemd[1]: No hostname configured, using default hostname. May 9 00:27:12.878183 systemd[1]: Hostname set to . May 9 00:27:12.878192 systemd[1]: Initializing machine ID from VM UUID. May 9 00:27:12.878200 systemd[1]: Queued start job for default target initrd.target. May 9 00:27:12.878208 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:27:12.878216 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:27:12.878225 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 9 00:27:12.878236 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 00:27:12.878256 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 9 00:27:12.878267 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 9 00:27:12.878277 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 9 00:27:12.878288 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 9 00:27:12.878297 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:27:12.878305 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 00:27:12.878314 systemd[1]: Reached target paths.target - Path Units. May 9 00:27:12.878322 systemd[1]: Reached target slices.target - Slice Units. May 9 00:27:12.878330 systemd[1]: Reached target swap.target - Swaps. May 9 00:27:12.878338 systemd[1]: Reached target timers.target - Timer Units. May 9 00:27:12.878347 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 9 00:27:12.878355 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 00:27:12.878368 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 9 00:27:12.878376 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 9 00:27:12.878385 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 00:27:12.878393 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 00:27:12.878402 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:27:12.878413 systemd[1]: Reached target sockets.target - Socket Units. May 9 00:27:12.878425 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 9 00:27:12.878435 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 00:27:12.878456 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 9 00:27:12.878467 systemd[1]: Starting systemd-fsck-usr.service... May 9 00:27:12.878478 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 00:27:12.878489 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 00:27:12.878500 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:27:12.878511 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 9 00:27:12.878523 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:27:12.878533 systemd[1]: Finished systemd-fsck-usr.service. May 9 00:27:12.878549 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 9 00:27:12.878578 systemd-journald[191]: Collecting audit messages is disabled. May 9 00:27:12.878604 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:27:12.878619 systemd-journald[191]: Journal started May 9 00:27:12.878641 systemd-journald[191]: Runtime Journal (/run/log/journal/6d40d514e6a44984a83b181c28163479) is 6.0M, max 48.4M, 42.3M free. May 9 00:27:12.879061 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 00:27:12.883313 systemd-modules-load[194]: Inserted module 'overlay' May 9 00:27:12.911633 systemd[1]: Started systemd-journald.service - Journal Service. May 9 00:27:12.914885 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 9 00:27:12.916421 systemd-modules-load[194]: Inserted module 'br_netfilter' May 9 00:27:12.917432 kernel: Bridge firewalling registered May 9 00:27:12.921261 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 00:27:12.921877 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:27:12.926744 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:27:12.928386 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 00:27:12.932054 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 00:27:12.933006 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:27:12.945251 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 00:27:12.947542 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:27:12.959003 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 00:27:12.960242 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:27:12.963146 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 9 00:27:12.976950 dracut-cmdline[230]: dracut-dracut-053 May 9 00:27:12.979350 dracut-cmdline[230]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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 verity.usrhash=56b660b06ded103a15fe25ebfbdecb898a20f374e429fec465c69b1a75d59c4b May 9 00:27:12.990409 systemd-resolved[223]: Positive Trust Anchors: May 9 00:27:12.990427 systemd-resolved[223]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 00:27:12.990465 systemd-resolved[223]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 9 00:27:12.992950 systemd-resolved[223]: Defaulting to hostname 'linux'. May 9 00:27:12.993955 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 00:27:12.999997 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 00:27:13.055883 kernel: SCSI subsystem initialized May 9 00:27:13.064876 kernel: Loading iSCSI transport class v2.0-870. May 9 00:27:13.074882 kernel: iscsi: registered transport (tcp) May 9 00:27:13.095095 kernel: iscsi: registered transport (qla4xxx) May 9 00:27:13.095116 kernel: QLogic iSCSI HBA Driver May 9 00:27:13.140718 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 9 00:27:13.152000 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 9 00:27:13.175878 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 9 00:27:13.175904 kernel: device-mapper: uevent: version 1.0.3 May 9 00:27:13.177894 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 9 00:27:13.216880 kernel: raid6: avx2x4 gen() 30706 MB/s May 9 00:27:13.233877 kernel: raid6: avx2x2 gen() 31548 MB/s May 9 00:27:13.250972 kernel: raid6: avx2x1 gen() 26135 MB/s May 9 00:27:13.251010 kernel: raid6: using algorithm avx2x2 gen() 31548 MB/s May 9 00:27:13.268972 kernel: raid6: .... xor() 20003 MB/s, rmw enabled May 9 00:27:13.269011 kernel: raid6: using avx2x2 recovery algorithm May 9 00:27:13.288883 kernel: xor: automatically using best checksumming function avx May 9 00:27:13.439901 kernel: Btrfs loaded, zoned=no, fsverity=no May 9 00:27:13.451675 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 9 00:27:13.465020 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:27:13.477382 systemd-udevd[413]: Using default interface naming scheme 'v255'. May 9 00:27:13.481844 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:27:13.488986 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 9 00:27:13.502779 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation May 9 00:27:13.534505 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 9 00:27:13.546963 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 00:27:13.609372 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:27:13.622005 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 9 00:27:13.632388 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 9 00:27:13.634399 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 9 00:27:13.639286 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:27:13.642231 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 00:27:13.646533 kernel: cryptd: max_cpu_qlen set to 1000 May 9 00:27:13.646557 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 9 00:27:13.648277 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 9 00:27:13.652998 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 9 00:27:13.663878 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:27:13.664739 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 9 00:27:13.668892 kernel: AVX2 version of gcm_enc/dec engaged. May 9 00:27:13.668919 kernel: AES CTR mode by8 optimization enabled May 9 00:27:13.679879 kernel: libata version 3.00 loaded. May 9 00:27:13.676308 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 9 00:27:13.676535 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:27:13.678489 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:27:13.679663 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 00:27:13.691356 kernel: ahci 0000:00:1f.2: version 3.0 May 9 00:27:13.691572 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 9 00:27:13.679801 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:27:13.694064 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (456) May 9 00:27:13.681146 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:27:13.695800 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:27:13.700838 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 9 00:27:13.701020 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 9 00:27:13.701160 kernel: BTRFS: device fsid 8d57db23-a0fc-4362-9769-38fbda5747c1 devid 1 transid 40 /dev/vda3 scanned by (udev-worker) (465) May 9 00:27:13.709889 kernel: scsi host0: ahci May 9 00:27:13.713885 kernel: scsi host1: ahci May 9 00:27:13.714069 kernel: scsi host2: ahci May 9 00:27:13.714352 kernel: scsi host3: ahci May 9 00:27:13.714591 kernel: scsi host4: ahci May 9 00:27:13.715371 kernel: scsi host5: ahci May 9 00:27:13.715547 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 May 9 00:27:13.715558 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 May 9 00:27:13.715568 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 May 9 00:27:13.715583 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 May 9 00:27:13.715593 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 May 9 00:27:13.715603 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 May 9 00:27:13.720940 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 9 00:27:13.753722 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:27:13.760083 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 9 00:27:13.769318 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 00:27:13.774254 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 9 00:27:13.775524 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 9 00:27:13.791079 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 9 00:27:13.794220 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:27:13.801899 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:27:13.809890 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:27:13.821806 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:27:14.023058 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 9 00:27:14.023192 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 9 00:27:14.023206 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 9 00:27:14.024892 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 9 00:27:14.024975 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 9 00:27:14.026221 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 9 00:27:14.026236 kernel: ata3.00: applying bridge limits May 9 00:27:14.027282 kernel: ata3.00: configured for UDMA/100 May 9 00:27:14.027881 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 9 00:27:14.031888 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 9 00:27:14.079894 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 9 00:27:14.080112 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 9 00:27:14.092887 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 9 00:27:14.814453 disk-uuid[558]: The operation has completed successfully. May 9 00:27:14.815585 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:27:14.843983 systemd[1]: disk-uuid.service: Deactivated successfully. May 9 00:27:14.844103 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 9 00:27:14.870010 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 9 00:27:14.873363 sh[591]: Success May 9 00:27:14.884901 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 9 00:27:14.915865 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 9 00:27:14.934186 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 9 00:27:14.938591 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 9 00:27:14.947374 kernel: BTRFS info (device dm-0): first mount of filesystem 8d57db23-a0fc-4362-9769-38fbda5747c1 May 9 00:27:14.947424 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 9 00:27:14.947439 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 9 00:27:14.948430 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 9 00:27:14.949882 kernel: BTRFS info (device dm-0): using free space tree May 9 00:27:14.954171 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 9 00:27:14.956420 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 9 00:27:14.970019 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 9 00:27:14.972963 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 9 00:27:14.981253 kernel: BTRFS info (device vda6): first mount of filesystem f16ac009-18be-48d6-89c7-f7afe3ecb605 May 9 00:27:14.981299 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 9 00:27:14.981314 kernel: BTRFS info (device vda6): using free space tree May 9 00:27:14.984900 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:27:14.993321 systemd[1]: mnt-oem.mount: Deactivated successfully. May 9 00:27:14.995093 kernel: BTRFS info (device vda6): last unmount of filesystem f16ac009-18be-48d6-89c7-f7afe3ecb605 May 9 00:27:15.004899 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 9 00:27:15.014059 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 9 00:27:15.067668 ignition[689]: Ignition 2.19.0 May 9 00:27:15.067678 ignition[689]: Stage: fetch-offline May 9 00:27:15.067716 ignition[689]: no configs at "/usr/lib/ignition/base.d" May 9 00:27:15.067726 ignition[689]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:15.067818 ignition[689]: parsed url from cmdline: "" May 9 00:27:15.067822 ignition[689]: no config URL provided May 9 00:27:15.067827 ignition[689]: reading system config file "/usr/lib/ignition/user.ign" May 9 00:27:15.067835 ignition[689]: no config at "/usr/lib/ignition/user.ign" May 9 00:27:15.067878 ignition[689]: op(1): [started] loading QEMU firmware config module May 9 00:27:15.067884 ignition[689]: op(1): executing: "modprobe" "qemu_fw_cfg" May 9 00:27:15.081069 ignition[689]: op(1): [finished] loading QEMU firmware config module May 9 00:27:15.081096 ignition[689]: QEMU firmware config was not found. Ignoring... May 9 00:27:15.083634 ignition[689]: parsing config with SHA512: eeefa7458dc64232acf9aa32589dca17589cfdf64793a4f9426e0ff9983fd55508e487ab9e1173d472df8f024f70c4e4bcdff6c68ac2891caf188c9a185a823f May 9 00:27:15.088700 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 00:27:15.092142 unknown[689]: fetched base config from "system" May 9 00:27:15.093168 unknown[689]: fetched user config from "qemu" May 9 00:27:15.093374 ignition[689]: fetch-offline: fetch-offline passed May 9 00:27:15.093487 ignition[689]: Ignition finished successfully May 9 00:27:15.098040 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 00:27:15.099409 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 9 00:27:15.120018 systemd-networkd[781]: lo: Link UP May 9 00:27:15.120027 systemd-networkd[781]: lo: Gained carrier May 9 00:27:15.121526 systemd-networkd[781]: Enumeration completed May 9 00:27:15.121620 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 00:27:15.121987 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:27:15.121990 systemd-networkd[781]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 00:27:15.123004 systemd-networkd[781]: eth0: Link UP May 9 00:27:15.123007 systemd-networkd[781]: eth0: Gained carrier May 9 00:27:15.123014 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:27:15.123304 systemd[1]: Reached target network.target - Network. May 9 00:27:15.124965 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 9 00:27:15.136997 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 9 00:27:15.143917 systemd-networkd[781]: eth0: DHCPv4 address 10.0.0.48/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 00:27:15.151492 ignition[784]: Ignition 2.19.0 May 9 00:27:15.151503 ignition[784]: Stage: kargs May 9 00:27:15.151683 ignition[784]: no configs at "/usr/lib/ignition/base.d" May 9 00:27:15.151694 ignition[784]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:15.152361 ignition[784]: kargs: kargs passed May 9 00:27:15.155754 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 9 00:27:15.152416 ignition[784]: Ignition finished successfully May 9 00:27:15.167987 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 9 00:27:15.180327 ignition[793]: Ignition 2.19.0 May 9 00:27:15.180338 ignition[793]: Stage: disks May 9 00:27:15.180544 ignition[793]: no configs at "/usr/lib/ignition/base.d" May 9 00:27:15.180558 ignition[793]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:15.183614 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 9 00:27:15.181269 ignition[793]: disks: disks passed May 9 00:27:15.185399 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 9 00:27:15.181320 ignition[793]: Ignition finished successfully May 9 00:27:15.187468 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 9 00:27:15.188848 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 00:27:15.190626 systemd[1]: Reached target sysinit.target - System Initialization. May 9 00:27:15.192932 systemd[1]: Reached target basic.target - Basic System. May 9 00:27:15.208041 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 9 00:27:15.219443 systemd-fsck[803]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 9 00:27:15.225269 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 9 00:27:15.239031 systemd[1]: Mounting sysroot.mount - /sysroot... May 9 00:27:15.335920 kernel: EXT4-fs (vda9): mounted filesystem 4cb03022-f5a4-4664-b5b4-bc39fcc2f946 r/w with ordered data mode. Quota mode: none. May 9 00:27:15.336080 systemd[1]: Mounted sysroot.mount - /sysroot. May 9 00:27:15.337071 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 9 00:27:15.344936 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 00:27:15.346537 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 9 00:27:15.347716 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 9 00:27:15.347752 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 9 00:27:15.358879 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (812) May 9 00:27:15.358899 kernel: BTRFS info (device vda6): first mount of filesystem f16ac009-18be-48d6-89c7-f7afe3ecb605 May 9 00:27:15.358910 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 9 00:27:15.358920 kernel: BTRFS info (device vda6): using free space tree May 9 00:27:15.347772 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 9 00:27:15.361823 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:27:15.354483 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 9 00:27:15.359830 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 9 00:27:15.363232 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 00:27:15.394271 initrd-setup-root[837]: cut: /sysroot/etc/passwd: No such file or directory May 9 00:27:15.398981 initrd-setup-root[844]: cut: /sysroot/etc/group: No such file or directory May 9 00:27:15.402338 initrd-setup-root[851]: cut: /sysroot/etc/shadow: No such file or directory May 9 00:27:15.405827 initrd-setup-root[858]: cut: /sysroot/etc/gshadow: No such file or directory May 9 00:27:15.480213 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 9 00:27:15.490974 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 9 00:27:15.492233 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 9 00:27:15.503920 kernel: BTRFS info (device vda6): last unmount of filesystem f16ac009-18be-48d6-89c7-f7afe3ecb605 May 9 00:27:15.515489 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 9 00:27:15.523498 ignition[928]: INFO : Ignition 2.19.0 May 9 00:27:15.523498 ignition[928]: INFO : Stage: mount May 9 00:27:15.525233 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:27:15.525233 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:15.525233 ignition[928]: INFO : mount: mount passed May 9 00:27:15.525233 ignition[928]: INFO : Ignition finished successfully May 9 00:27:15.526032 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 9 00:27:15.533986 systemd[1]: Starting ignition-files.service - Ignition (files)... May 9 00:27:15.946571 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 9 00:27:15.960038 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 00:27:15.967539 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (940) May 9 00:27:15.967574 kernel: BTRFS info (device vda6): first mount of filesystem f16ac009-18be-48d6-89c7-f7afe3ecb605 May 9 00:27:15.967589 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 9 00:27:15.969059 kernel: BTRFS info (device vda6): using free space tree May 9 00:27:15.971880 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:27:15.972694 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 00:27:15.998181 ignition[957]: INFO : Ignition 2.19.0 May 9 00:27:15.998181 ignition[957]: INFO : Stage: files May 9 00:27:16.000441 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:27:16.000441 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:16.000441 ignition[957]: DEBUG : files: compiled without relabeling support, skipping May 9 00:27:16.000441 ignition[957]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 9 00:27:16.000441 ignition[957]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 9 00:27:16.007973 ignition[957]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 9 00:27:16.007973 ignition[957]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 9 00:27:16.007973 ignition[957]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 9 00:27:16.007973 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 9 00:27:16.007973 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 9 00:27:16.007973 ignition[957]: INFO : files: op(4): [started] processing unit "coreos-metadata.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(4): [finished] processing unit "coreos-metadata.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(6): [started] processing unit "docker.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(6): [finished] processing unit "docker.service" May 9 00:27:16.007973 ignition[957]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" May 9 00:27:16.002002 unknown[957]: wrote ssh authorized keys file for user: core May 9 00:27:16.030416 ignition[957]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" May 9 00:27:16.032428 ignition[957]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 9 00:27:16.033984 ignition[957]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" May 9 00:27:16.033984 ignition[957]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" May 9 00:27:16.033984 ignition[957]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" May 9 00:27:16.033984 ignition[957]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" May 9 00:27:16.033984 ignition[957]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" May 9 00:27:16.033984 ignition[957]: INFO : files: files passed May 9 00:27:16.033984 ignition[957]: INFO : Ignition finished successfully May 9 00:27:16.035124 systemd[1]: Finished ignition-files.service - Ignition (files). May 9 00:27:16.043026 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 9 00:27:16.044909 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 9 00:27:16.046664 systemd[1]: ignition-quench.service: Deactivated successfully. May 9 00:27:16.046811 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 9 00:27:16.053702 initrd-setup-root-after-ignition[985]: grep: /sysroot/oem/oem-release: No such file or directory May 9 00:27:16.056330 initrd-setup-root-after-ignition[987]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 00:27:16.056330 initrd-setup-root-after-ignition[987]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 9 00:27:16.059801 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 00:27:16.061162 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 00:27:16.063219 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 9 00:27:16.083080 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 9 00:27:16.106505 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 9 00:27:16.106629 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 9 00:27:16.109470 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 9 00:27:16.112026 systemd[1]: Reached target initrd.target - Initrd Default Target. May 9 00:27:16.112540 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 9 00:27:16.113435 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 9 00:27:16.132632 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 00:27:16.141167 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 9 00:27:16.151032 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 9 00:27:16.153473 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:27:16.155976 systemd[1]: Stopped target timers.target - Timer Units. May 9 00:27:16.157916 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 9 00:27:16.158986 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 00:27:16.161658 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 9 00:27:16.163763 systemd[1]: Stopped target basic.target - Basic System. May 9 00:27:16.165678 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 9 00:27:16.167987 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 9 00:27:16.170380 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 9 00:27:16.172887 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 9 00:27:16.175020 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 9 00:27:16.177521 systemd[1]: Stopped target sysinit.target - System Initialization. May 9 00:27:16.179627 systemd[1]: Stopped target local-fs.target - Local File Systems. May 9 00:27:16.181661 systemd[1]: Stopped target swap.target - Swaps. May 9 00:27:16.183298 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 9 00:27:16.184342 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 9 00:27:16.186648 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 9 00:27:16.188820 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:27:16.191187 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 9 00:27:16.192180 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:27:16.194809 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 9 00:27:16.195840 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 9 00:27:16.198115 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 9 00:27:16.199218 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 9 00:27:16.201636 systemd[1]: Stopped target paths.target - Path Units. May 9 00:27:16.203392 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 9 00:27:16.204480 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:27:16.207209 systemd[1]: Stopped target slices.target - Slice Units. May 9 00:27:16.209056 systemd[1]: Stopped target sockets.target - Socket Units. May 9 00:27:16.210931 systemd[1]: iscsid.socket: Deactivated successfully. May 9 00:27:16.211824 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 9 00:27:16.213792 systemd[1]: iscsiuio.socket: Deactivated successfully. May 9 00:27:16.214705 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 00:27:16.216965 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 9 00:27:16.218167 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 00:27:16.220717 systemd[1]: ignition-files.service: Deactivated successfully. May 9 00:27:16.221734 systemd[1]: Stopped ignition-files.service - Ignition (files). May 9 00:27:16.235081 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 9 00:27:16.237804 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 9 00:27:16.239647 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 9 00:27:16.239903 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:27:16.243190 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 9 00:27:16.244368 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 9 00:27:16.249374 ignition[1011]: INFO : Ignition 2.19.0 May 9 00:27:16.250936 ignition[1011]: INFO : Stage: umount May 9 00:27:16.250936 ignition[1011]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:27:16.250936 ignition[1011]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:27:16.251940 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 9 00:27:16.257220 ignition[1011]: INFO : umount: umount passed May 9 00:27:16.257220 ignition[1011]: INFO : Ignition finished successfully May 9 00:27:16.252081 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 9 00:27:16.254341 systemd[1]: ignition-mount.service: Deactivated successfully. May 9 00:27:16.254474 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 9 00:27:16.257332 systemd[1]: Stopped target network.target - Network. May 9 00:27:16.259084 systemd[1]: ignition-disks.service: Deactivated successfully. May 9 00:27:16.259158 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 9 00:27:16.261104 systemd[1]: ignition-kargs.service: Deactivated successfully. May 9 00:27:16.261166 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 9 00:27:16.263460 systemd[1]: ignition-setup.service: Deactivated successfully. May 9 00:27:16.263520 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 9 00:27:16.265618 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 9 00:27:16.265675 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 9 00:27:16.267843 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 9 00:27:16.270139 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 9 00:27:16.271951 systemd-networkd[781]: eth0: DHCPv6 lease lost May 9 00:27:16.273290 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 9 00:27:16.273958 systemd[1]: systemd-networkd.service: Deactivated successfully. May 9 00:27:16.274107 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 9 00:27:16.276664 systemd[1]: sysroot-boot.service: Deactivated successfully. May 9 00:27:16.276790 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 9 00:27:16.279720 systemd[1]: systemd-resolved.service: Deactivated successfully. May 9 00:27:16.279949 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 9 00:27:16.283305 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 9 00:27:16.283371 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 9 00:27:16.284764 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 9 00:27:16.284825 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 9 00:27:16.293987 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 9 00:27:16.296038 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 9 00:27:16.296106 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 00:27:16.298297 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 9 00:27:16.298353 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 9 00:27:16.298798 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 9 00:27:16.298843 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 9 00:27:16.299235 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 9 00:27:16.299278 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:27:16.299669 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:27:16.309642 systemd[1]: network-cleanup.service: Deactivated successfully. May 9 00:27:16.309784 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 9 00:27:16.314646 systemd[1]: systemd-udevd.service: Deactivated successfully. May 9 00:27:16.314866 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:27:16.317166 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 9 00:27:16.317230 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 9 00:27:16.319192 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 9 00:27:16.319246 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:27:16.321546 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 9 00:27:16.321608 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 9 00:27:16.323812 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 9 00:27:16.323893 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 9 00:27:16.325779 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 9 00:27:16.325839 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:27:16.339996 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 9 00:27:16.341182 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 9 00:27:16.341249 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:27:16.343565 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 9 00:27:16.343626 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:27:16.345876 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 9 00:27:16.345935 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:27:16.348375 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 00:27:16.348435 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:27:16.351057 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 9 00:27:16.351189 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 9 00:27:16.353804 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 9 00:27:16.363013 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 9 00:27:16.371907 systemd[1]: Switching root. May 9 00:27:16.401629 systemd-journald[191]: Journal stopped May 9 00:27:17.242331 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). May 9 00:27:17.242394 kernel: SELinux: policy capability network_peer_controls=1 May 9 00:27:17.242412 kernel: SELinux: policy capability open_perms=1 May 9 00:27:17.242423 kernel: SELinux: policy capability extended_socket_class=1 May 9 00:27:17.242438 kernel: SELinux: policy capability always_check_network=0 May 9 00:27:17.242453 kernel: SELinux: policy capability cgroup_seclabel=1 May 9 00:27:17.242464 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 9 00:27:17.242475 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 9 00:27:17.242486 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 9 00:27:17.242498 kernel: audit: type=1403 audit(1746750436.485:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 9 00:27:17.242510 systemd[1]: Successfully loaded SELinux policy in 40.564ms. May 9 00:27:17.242535 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 13.318ms. May 9 00:27:17.242548 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) May 9 00:27:17.242560 systemd[1]: Detected virtualization kvm. May 9 00:27:17.242575 systemd[1]: Detected architecture x86-64. May 9 00:27:17.242588 systemd[1]: Detected first boot. May 9 00:27:17.242599 systemd[1]: Initializing machine ID from VM UUID. May 9 00:27:17.242612 zram_generator::config[1056]: No configuration found. May 9 00:27:17.242626 systemd[1]: Populated /etc with preset unit settings. May 9 00:27:17.242638 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 9 00:27:17.242650 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 9 00:27:17.242662 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 9 00:27:17.242677 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 9 00:27:17.242689 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 9 00:27:17.242701 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 9 00:27:17.242713 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 9 00:27:17.242725 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 9 00:27:17.242742 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 9 00:27:17.242754 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 9 00:27:17.242766 systemd[1]: Created slice user.slice - User and Session Slice. May 9 00:27:17.242778 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:27:17.242790 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:27:17.242802 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 9 00:27:17.242814 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 9 00:27:17.242826 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 9 00:27:17.242838 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 00:27:17.243797 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 9 00:27:17.243822 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:27:17.243835 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 9 00:27:17.243847 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 9 00:27:17.243871 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 9 00:27:17.243883 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 9 00:27:17.243895 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:27:17.243907 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 00:27:17.243923 systemd[1]: Reached target slices.target - Slice Units. May 9 00:27:17.243935 systemd[1]: Reached target swap.target - Swaps. May 9 00:27:17.243947 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 9 00:27:17.243959 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 9 00:27:17.243971 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 00:27:17.243983 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 00:27:17.243994 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:27:17.244006 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 9 00:27:17.244018 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 9 00:27:17.244033 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 9 00:27:17.244048 systemd[1]: Mounting media.mount - External Media Directory... May 9 00:27:17.244064 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.244084 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 9 00:27:17.244099 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 9 00:27:17.244116 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 9 00:27:17.244133 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 9 00:27:17.244148 systemd[1]: Reached target machines.target - Containers. May 9 00:27:17.244163 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 9 00:27:17.244182 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:27:17.244196 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 00:27:17.244212 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 9 00:27:17.244227 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:27:17.244242 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 00:27:17.244257 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:27:17.244273 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 9 00:27:17.244286 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:27:17.244302 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 9 00:27:17.244329 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 9 00:27:17.244341 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 9 00:27:17.244353 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 9 00:27:17.244365 systemd[1]: Stopped systemd-fsck-usr.service. May 9 00:27:17.244378 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 00:27:17.244389 kernel: fuse: init (API version 7.39) May 9 00:27:17.244402 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 00:27:17.244413 kernel: loop: module loaded May 9 00:27:17.244429 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 9 00:27:17.244441 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 9 00:27:17.244453 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 00:27:17.244465 systemd[1]: verity-setup.service: Deactivated successfully. May 9 00:27:17.244476 systemd[1]: Stopped verity-setup.service. May 9 00:27:17.244488 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.244500 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 9 00:27:17.244514 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 9 00:27:17.244548 systemd-journald[1126]: Collecting audit messages is disabled. May 9 00:27:17.244573 systemd[1]: Mounted media.mount - External Media Directory. May 9 00:27:17.244585 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 9 00:27:17.244597 systemd-journald[1126]: Journal started May 9 00:27:17.244621 systemd-journald[1126]: Runtime Journal (/run/log/journal/6d40d514e6a44984a83b181c28163479) is 6.0M, max 48.4M, 42.3M free. May 9 00:27:17.010500 systemd[1]: Queued start job for default target multi-user.target. May 9 00:27:17.038742 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 9 00:27:17.039212 systemd[1]: systemd-journald.service: Deactivated successfully. May 9 00:27:17.248060 systemd[1]: Started systemd-journald.service - Journal Service. May 9 00:27:17.248615 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 9 00:27:17.249900 kernel: ACPI: bus type drm_connector registered May 9 00:27:17.250485 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 9 00:27:17.252005 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:27:17.253743 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 9 00:27:17.254042 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 9 00:27:17.255696 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 9 00:27:17.257399 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:27:17.257571 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:27:17.259064 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 00:27:17.259241 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 00:27:17.260767 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:27:17.260955 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:27:17.262605 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 9 00:27:17.262768 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 9 00:27:17.264304 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:27:17.264518 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:27:17.266177 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 00:27:17.267735 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 9 00:27:17.269292 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 9 00:27:17.285049 systemd[1]: Reached target network-pre.target - Preparation for Network. May 9 00:27:17.293034 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 9 00:27:17.294957 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 9 00:27:17.296285 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 9 00:27:17.296386 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 00:27:17.298588 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 9 00:27:17.301030 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 9 00:27:17.308160 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 9 00:27:17.309554 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:27:17.310949 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 9 00:27:17.315694 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 9 00:27:17.317104 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 00:27:17.318589 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 9 00:27:17.319894 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 00:27:17.323997 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 00:27:17.327107 systemd-journald[1126]: Time spent on flushing to /var/log/journal/6d40d514e6a44984a83b181c28163479 is 12.383ms for 921 entries. May 9 00:27:17.327107 systemd-journald[1126]: System Journal (/var/log/journal/6d40d514e6a44984a83b181c28163479) is 8.0M, max 195.6M, 187.6M free. May 9 00:27:17.355990 systemd-journald[1126]: Received client request to flush runtime journal. May 9 00:27:17.328761 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 9 00:27:17.335985 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 9 00:27:17.341300 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:27:17.346384 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 9 00:27:17.348117 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 9 00:27:17.349846 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 9 00:27:17.363115 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 9 00:27:17.365341 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 9 00:27:17.367391 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 9 00:27:17.370895 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 9 00:27:17.376582 kernel: loop0: detected capacity change from 0 to 140768 May 9 00:27:17.377206 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 9 00:27:17.381076 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 00:27:17.386093 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. May 9 00:27:17.386145 udevadm[1179]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 9 00:27:17.386618 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. May 9 00:27:17.392588 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:27:17.402897 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 9 00:27:17.406132 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 9 00:27:17.408799 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 9 00:27:17.409767 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 9 00:27:17.431893 kernel: loop1: detected capacity change from 0 to 142488 May 9 00:27:17.438280 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 9 00:27:17.447390 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 00:27:17.464558 systemd-tmpfiles[1194]: ACLs are not supported, ignoring. May 9 00:27:17.464968 systemd-tmpfiles[1194]: ACLs are not supported, ignoring. May 9 00:27:17.468387 kernel: loop2: detected capacity change from 0 to 140768 May 9 00:27:17.471166 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:27:17.483084 kernel: loop3: detected capacity change from 0 to 142488 May 9 00:27:17.493200 (sd-merge)[1196]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 9 00:27:17.494478 (sd-merge)[1196]: Merged extensions into '/usr'. May 9 00:27:17.498757 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 9 00:27:17.506124 systemd[1]: Starting ensure-sysext.service... May 9 00:27:17.508478 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 00:27:17.519624 systemd[1]: Reloading requested from client PID 1199 ('systemctl') (unit ensure-sysext.service)... May 9 00:27:17.519648 systemd[1]: Reloading... May 9 00:27:17.537753 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 9 00:27:17.538146 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 9 00:27:17.539146 systemd-tmpfiles[1200]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 9 00:27:17.539442 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. May 9 00:27:17.539520 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. May 9 00:27:17.544789 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. May 9 00:27:17.545013 systemd-tmpfiles[1200]: Skipping /boot May 9 00:27:17.573476 systemd-tmpfiles[1200]: Detected autofs mount point /boot during canonicalization of boot. May 9 00:27:17.573830 systemd-tmpfiles[1200]: Skipping /boot May 9 00:27:17.579676 zram_generator::config[1228]: No configuration found. May 9 00:27:17.685909 ldconfig[1165]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 9 00:27:17.696520 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 9 00:27:17.745188 systemd[1]: Reloading finished in 225 ms. May 9 00:27:17.778063 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 9 00:27:17.785314 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:27:17.794947 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 9 00:27:17.797512 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 9 00:27:17.800017 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 9 00:27:17.805274 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 00:27:17.809343 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 9 00:27:17.813360 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.813527 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:27:17.815830 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:27:17.821258 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:27:17.827036 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:27:17.831052 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:27:17.834499 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 9 00:27:17.835773 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.837623 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:27:17.838969 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:27:17.840992 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:27:17.841175 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:27:17.843569 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:27:17.843945 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:27:17.854934 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.855163 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:27:17.869772 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:27:17.873451 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:27:17.876625 augenrules[1295]: No rules May 9 00:27:17.876497 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:27:17.878119 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:27:17.878360 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.879667 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 9 00:27:17.886124 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 9 00:27:17.888674 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 9 00:27:17.890595 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:27:17.890788 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:27:17.892514 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:27:17.892696 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:27:17.894538 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:27:17.894716 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:27:17.899029 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 9 00:27:17.906763 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.907032 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:27:17.921199 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:27:17.924426 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 00:27:17.929981 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:27:17.932264 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:27:17.933501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:27:17.933693 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 9 00:27:17.935898 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 9 00:27:17.937775 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:27:17.938207 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:27:17.940129 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 00:27:17.940358 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 00:27:17.942105 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:27:17.942275 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:27:17.944066 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:27:17.944238 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:27:17.946180 systemd-resolved[1271]: Positive Trust Anchors: May 9 00:27:17.946198 systemd-resolved[1271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 00:27:17.946231 systemd-resolved[1271]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 9 00:27:17.948362 systemd[1]: Finished ensure-sysext.service. May 9 00:27:17.951373 systemd-resolved[1271]: Defaulting to hostname 'linux'. May 9 00:27:17.952871 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 00:27:17.953019 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 00:27:17.967017 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 9 00:27:17.968131 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 9 00:27:17.968224 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 00:27:17.969650 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 9 00:27:17.971772 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 00:27:17.974499 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:27:17.977002 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 9 00:27:18.002224 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 9 00:27:18.017749 systemd-udevd[1325]: Using default interface naming scheme 'v255'. May 9 00:27:18.026266 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 9 00:27:18.027899 systemd[1]: Reached target time-set.target - System Time Set. May 9 00:27:18.037148 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:27:18.046257 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 00:27:18.064969 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 9 00:27:18.096886 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1338) May 9 00:27:18.117837 systemd-networkd[1336]: lo: Link UP May 9 00:27:18.117847 systemd-networkd[1336]: lo: Gained carrier May 9 00:27:18.119443 systemd-networkd[1336]: Enumeration completed May 9 00:27:18.119687 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 00:27:18.120557 systemd-networkd[1336]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:27:18.120853 systemd-networkd[1336]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 00:27:18.121185 systemd[1]: Reached target network.target - Network. May 9 00:27:18.121870 systemd-networkd[1336]: eth0: Link UP May 9 00:27:18.121875 systemd-networkd[1336]: eth0: Gained carrier May 9 00:27:18.121887 systemd-networkd[1336]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:27:18.130116 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 9 00:27:18.133026 systemd-networkd[1336]: eth0: DHCPv4 address 10.0.0.48/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 00:27:18.134009 systemd-timesyncd[1323]: Network configuration changed, trying to establish connection. May 9 00:27:19.080666 systemd-timesyncd[1323]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 9 00:27:19.080719 systemd-timesyncd[1323]: Initial clock synchronization to Fri 2025-05-09 00:27:19.080515 UTC. May 9 00:27:19.080817 systemd-resolved[1271]: Clock change detected. Flushing caches. May 9 00:27:19.086981 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 May 9 00:27:19.092939 kernel: ACPI: button: Power Button [PWRF] May 9 00:27:19.093999 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 00:27:19.101078 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 9 00:27:19.108899 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 9 00:27:19.114990 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 9 00:27:19.118577 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 9 00:27:19.133903 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 May 9 00:27:19.134837 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 9 00:27:19.139516 kernel: mousedev: PS/2 mouse device common for all mice May 9 00:27:19.155602 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:27:19.247788 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:27:19.248891 kernel: kvm_amd: TSC scaling supported May 9 00:27:19.248916 kernel: kvm_amd: Nested Virtualization enabled May 9 00:27:19.248929 kernel: kvm_amd: Nested Paging enabled May 9 00:27:19.248961 kernel: kvm_amd: LBR virtualization supported May 9 00:27:19.248981 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 9 00:27:19.248997 kernel: kvm_amd: Virtual GIF supported May 9 00:27:19.268885 kernel: EDAC MC: Ver: 3.0.0 May 9 00:27:19.303050 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 9 00:27:19.319123 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 9 00:27:19.328046 lvm[1372]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 9 00:27:19.356995 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 9 00:27:19.358529 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 00:27:19.359644 systemd[1]: Reached target sysinit.target - System Initialization. May 9 00:27:19.360787 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 9 00:27:19.362027 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 9 00:27:19.363457 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 9 00:27:19.364612 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 9 00:27:19.365924 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 9 00:27:19.367193 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 9 00:27:19.367222 systemd[1]: Reached target paths.target - Path Units. May 9 00:27:19.368132 systemd[1]: Reached target timers.target - Timer Units. May 9 00:27:19.369994 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 9 00:27:19.373194 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 00:27:19.383242 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 9 00:27:19.385549 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 9 00:27:19.387085 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 00:27:19.388231 systemd[1]: Reached target sockets.target - Socket Units. May 9 00:27:19.389198 systemd[1]: Reached target basic.target - Basic System. May 9 00:27:19.390223 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 9 00:27:19.390263 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 9 00:27:19.391380 systemd[1]: Starting containerd.service - containerd container runtime... May 9 00:27:19.393466 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 9 00:27:19.397056 lvm[1376]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 9 00:27:19.396985 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 9 00:27:19.399949 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 9 00:27:19.401941 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 9 00:27:19.403056 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 9 00:27:19.406054 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 9 00:27:19.408885 jq[1379]: false May 9 00:27:19.415069 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 9 00:27:19.420539 extend-filesystems[1380]: Found loop2 May 9 00:27:19.421615 extend-filesystems[1380]: Found loop3 May 9 00:27:19.421615 extend-filesystems[1380]: Found sr0 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda May 9 00:27:19.421615 extend-filesystems[1380]: Found vda1 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda2 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda3 May 9 00:27:19.421615 extend-filesystems[1380]: Found usr May 9 00:27:19.421615 extend-filesystems[1380]: Found vda4 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda6 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda7 May 9 00:27:19.421615 extend-filesystems[1380]: Found vda9 May 9 00:27:19.421615 extend-filesystems[1380]: Checking size of /dev/vda9 May 9 00:27:19.456953 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1332) May 9 00:27:19.437909 dbus-daemon[1378]: [system] SELinux support is enabled May 9 00:27:19.469832 extend-filesystems[1380]: Old size kept for /dev/vda9 May 9 00:27:19.422407 systemd[1]: Starting systemd-logind.service - User Login Management... May 9 00:27:19.428342 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 9 00:27:19.470656 update_engine[1397]: I20250509 00:27:19.465701 1397 main.cc:92] Flatcar Update Engine starting May 9 00:27:19.470656 update_engine[1397]: I20250509 00:27:19.468665 1397 update_check_scheduler.cc:74] Next update check in 10m16s May 9 00:27:19.434576 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 9 00:27:19.470989 jq[1399]: true May 9 00:27:19.435938 systemd[1]: Starting update-engine.service - Update Engine... May 9 00:27:19.445257 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 9 00:27:19.446486 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 9 00:27:19.456880 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 9 00:27:19.465337 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 9 00:27:19.465954 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 9 00:27:19.466289 systemd[1]: extend-filesystems.service: Deactivated successfully. May 9 00:27:19.466984 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 9 00:27:19.469279 systemd[1]: motdgen.service: Deactivated successfully. May 9 00:27:19.470100 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 9 00:27:19.472540 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 9 00:27:19.472754 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 9 00:27:19.485716 jq[1402]: true May 9 00:27:19.485970 systemd-logind[1386]: Watching system buttons on /dev/input/event1 (Power Button) May 9 00:27:19.485993 systemd-logind[1386]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 9 00:27:19.486429 systemd-logind[1386]: New seat seat0. May 9 00:27:19.488070 systemd[1]: Started systemd-logind.service - User Login Management. May 9 00:27:19.490824 (ntainerd)[1403]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 00:27:19.509152 dbus-daemon[1378]: [system] Successfully activated service 'org.freedesktop.systemd1' May 9 00:27:19.517426 systemd[1]: Started update-engine.service - Update Engine. May 9 00:27:19.520485 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 9 00:27:19.520638 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 9 00:27:19.522096 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 9 00:27:19.522197 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 9 00:27:19.533088 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 9 00:27:19.545229 bash[1428]: Updated "/home/core/.ssh/authorized_keys" May 9 00:27:19.547422 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 9 00:27:19.550287 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 9 00:27:19.559174 locksmithd[1427]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 9 00:27:19.675351 containerd[1403]: time="2025-05-09T00:27:19.675189655Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 9 00:27:19.697803 containerd[1403]: time="2025-05-09T00:27:19.697755793Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.699468 containerd[1403]: time="2025-05-09T00:27:19.699432598Z" 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.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:19.699468 containerd[1403]: time="2025-05-09T00:27:19.699460811Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 9 00:27:19.699520 containerd[1403]: time="2025-05-09T00:27:19.699475759Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 9 00:27:19.699650 containerd[1403]: time="2025-05-09T00:27:19.699626672Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 9 00:27:19.699650 containerd[1403]: time="2025-05-09T00:27:19.699647030Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.699737 containerd[1403]: time="2025-05-09T00:27:19.699714677Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:19.699737 containerd[1403]: time="2025-05-09T00:27:19.699734294Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.699969 containerd[1403]: time="2025-05-09T00:27:19.699941552Z" 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 May 9 00:27:19.699969 containerd[1403]: time="2025-05-09T00:27:19.699960167Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.700020 containerd[1403]: time="2025-05-09T00:27:19.699976408Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:19.700020 containerd[1403]: time="2025-05-09T00:27:19.699987048Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.700094 containerd[1403]: time="2025-05-09T00:27:19.700078189Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.700341 containerd[1403]: time="2025-05-09T00:27:19.700313210Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:19.700473 containerd[1403]: time="2025-05-09T00:27:19.700446319Z" 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 May 9 00:27:19.700473 containerd[1403]: time="2025-05-09T00:27:19.700465124Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 9 00:27:19.700582 containerd[1403]: time="2025-05-09T00:27:19.700560604Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 9 00:27:19.700633 containerd[1403]: time="2025-05-09T00:27:19.700618422Z" level=info msg="metadata content store policy set" policy=shared May 9 00:27:19.705626 containerd[1403]: time="2025-05-09T00:27:19.705591049Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 9 00:27:19.705663 containerd[1403]: time="2025-05-09T00:27:19.705628439Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 9 00:27:19.705663 containerd[1403]: time="2025-05-09T00:27:19.705649309Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 9 00:27:19.705713 containerd[1403]: time="2025-05-09T00:27:19.705662984Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 9 00:27:19.705713 containerd[1403]: time="2025-05-09T00:27:19.705675257Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 9 00:27:19.705814 containerd[1403]: time="2025-05-09T00:27:19.705785965Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 9 00:27:19.706032 containerd[1403]: time="2025-05-09T00:27:19.706005046Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 9 00:27:19.706143 containerd[1403]: time="2025-05-09T00:27:19.706117136Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 9 00:27:19.706143 containerd[1403]: time="2025-05-09T00:27:19.706135941Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 9 00:27:19.706190 containerd[1403]: time="2025-05-09T00:27:19.706161860Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 9 00:27:19.706190 containerd[1403]: time="2025-05-09T00:27:19.706175936Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706190 containerd[1403]: time="2025-05-09T00:27:19.706189392Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706242 containerd[1403]: time="2025-05-09T00:27:19.706201725Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706242 containerd[1403]: time="2025-05-09T00:27:19.706214709Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706242 containerd[1403]: time="2025-05-09T00:27:19.706227944Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706242 containerd[1403]: time="2025-05-09T00:27:19.706240668Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706332 containerd[1403]: time="2025-05-09T00:27:19.706253692Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706332 containerd[1403]: time="2025-05-09T00:27:19.706265675Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 9 00:27:19.706332 containerd[1403]: time="2025-05-09T00:27:19.706297965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706332 containerd[1403]: time="2025-05-09T00:27:19.706313194Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706332 containerd[1403]: time="2025-05-09T00:27:19.706325627Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706337580Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706350975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706365702Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706379318Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706392913Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706408583Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706426 containerd[1403]: time="2025-05-09T00:27:19.706422659Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706433790Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706448427Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706460570Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706474957Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706493712Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706506096Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706557 containerd[1403]: time="2025-05-09T00:27:19.706517096Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706563754Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706579924Z" 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 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706590614Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706602436Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706614108Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706629998Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706640077Z" level=info msg="NRI interface is disabled by configuration." May 9 00:27:19.706677 containerd[1403]: time="2025-05-09T00:27:19.706650426Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 9 00:27:19.706973 containerd[1403]: time="2025-05-09T00:27:19.706910604Z" 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}" May 9 00:27:19.706973 containerd[1403]: time="2025-05-09T00:27:19.706963113Z" level=info msg="Connect containerd service" May 9 00:27:19.707118 containerd[1403]: time="2025-05-09T00:27:19.706996946Z" level=info msg="using legacy CRI server" May 9 00:27:19.707118 containerd[1403]: time="2025-05-09T00:27:19.707003969Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 00:27:19.707118 containerd[1403]: time="2025-05-09T00:27:19.707102955Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 9 00:27:19.707666 containerd[1403]: time="2025-05-09T00:27:19.707632999Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 00:27:19.707988 containerd[1403]: time="2025-05-09T00:27:19.707947229Z" level=info msg="Start subscribing containerd event" May 9 00:27:19.708018 containerd[1403]: time="2025-05-09T00:27:19.707994958Z" level=info msg="Start recovering state" May 9 00:27:19.708089 containerd[1403]: time="2025-05-09T00:27:19.708070801Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 00:27:19.708220 containerd[1403]: time="2025-05-09T00:27:19.708093804Z" level=info msg="Start event monitor" May 9 00:27:19.708274 containerd[1403]: time="2025-05-09T00:27:19.708217506Z" level=info msg="Start snapshots syncer" May 9 00:27:19.708274 containerd[1403]: time="2025-05-09T00:27:19.708239066Z" level=info msg="Start cni network conf syncer for default" May 9 00:27:19.708274 containerd[1403]: time="2025-05-09T00:27:19.708252211Z" level=info msg="Start streaming server" May 9 00:27:19.708370 containerd[1403]: time="2025-05-09T00:27:19.708199712Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 00:27:19.711228 containerd[1403]: time="2025-05-09T00:27:19.711203457Z" level=info msg="containerd successfully booted in 0.037440s" May 9 00:27:19.711268 systemd[1]: Started containerd.service - containerd container runtime. May 9 00:27:19.781712 sshd_keygen[1395]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 9 00:27:19.805188 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 9 00:27:19.812146 systemd[1]: Starting issuegen.service - Generate /run/issue... May 9 00:27:19.820794 systemd[1]: issuegen.service: Deactivated successfully. May 9 00:27:19.821052 systemd[1]: Finished issuegen.service - Generate /run/issue. May 9 00:27:19.834064 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 9 00:27:19.843881 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 9 00:27:19.846528 systemd[1]: Started getty@tty1.service - Getty on tty1. May 9 00:27:19.848594 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 9 00:27:19.849846 systemd[1]: Reached target getty.target - Login Prompts. May 9 00:27:20.794051 systemd-networkd[1336]: eth0: Gained IPv6LL May 9 00:27:20.797571 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 9 00:27:20.799499 systemd[1]: Reached target network-online.target - Network is Online. May 9 00:27:20.813099 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 9 00:27:20.815351 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 9 00:27:20.834457 systemd[1]: coreos-metadata.service: Deactivated successfully. May 9 00:27:20.834719 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 9 00:27:20.836343 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 9 00:27:20.837882 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 9 00:27:20.839329 systemd[1]: Reached target multi-user.target - Multi-User System. May 9 00:27:20.840541 systemd[1]: Startup finished in 728ms (kernel) + 3.797s (initrd) + 3.448s (userspace) = 7.974s. May 9 00:27:26.687991 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 9 00:27:26.689515 systemd[1]: Started sshd@0-10.0.0.48:22-10.0.0.1:59286.service - OpenSSH per-connection server daemon (10.0.0.1:59286). May 9 00:27:26.732489 sshd[1478]: Accepted publickey for core from 10.0.0.1 port 59286 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:26.734287 sshd[1478]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:26.744074 systemd-logind[1386]: New session 1 of user core. May 9 00:27:26.745701 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 9 00:27:26.755263 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 9 00:27:26.766724 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 9 00:27:26.770292 systemd[1]: Starting user@500.service - User Manager for UID 500... May 9 00:27:26.778019 (systemd)[1482]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 9 00:27:26.877985 systemd[1482]: Queued start job for default target default.target. May 9 00:27:26.889106 systemd[1482]: Created slice app.slice - User Application Slice. May 9 00:27:26.889129 systemd[1482]: Reached target paths.target - Paths. May 9 00:27:26.889142 systemd[1482]: Reached target timers.target - Timers. May 9 00:27:26.890593 systemd[1482]: Starting dbus.socket - D-Bus User Message Bus Socket... May 9 00:27:26.901284 systemd[1482]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 9 00:27:26.901399 systemd[1482]: Reached target sockets.target - Sockets. May 9 00:27:26.901417 systemd[1482]: Reached target basic.target - Basic System. May 9 00:27:26.901453 systemd[1482]: Reached target default.target - Main User Target. May 9 00:27:26.901484 systemd[1482]: Startup finished in 117ms. May 9 00:27:26.902259 systemd[1]: Started user@500.service - User Manager for UID 500. May 9 00:27:26.904261 systemd[1]: Started session-1.scope - Session 1 of User core. May 9 00:27:26.979277 systemd[1]: Started sshd@1-10.0.0.48:22-10.0.0.1:59298.service - OpenSSH per-connection server daemon (10.0.0.1:59298). May 9 00:27:27.004728 sshd[1493]: Accepted publickey for core from 10.0.0.1 port 59298 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.006038 sshd[1493]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.010267 systemd-logind[1386]: New session 2 of user core. May 9 00:27:27.020110 systemd[1]: Started session-2.scope - Session 2 of User core. May 9 00:27:27.075284 sshd[1493]: pam_unix(sshd:session): session closed for user core May 9 00:27:27.083046 systemd[1]: sshd@1-10.0.0.48:22-10.0.0.1:59298.service: Deactivated successfully. May 9 00:27:27.085448 systemd[1]: session-2.scope: Deactivated successfully. May 9 00:27:27.087298 systemd-logind[1386]: Session 2 logged out. Waiting for processes to exit. May 9 00:27:27.097119 systemd[1]: Started sshd@2-10.0.0.48:22-10.0.0.1:59314.service - OpenSSH per-connection server daemon (10.0.0.1:59314). May 9 00:27:27.097888 systemd-logind[1386]: Removed session 2. May 9 00:27:27.123096 sshd[1500]: Accepted publickey for core from 10.0.0.1 port 59314 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.124428 sshd[1500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.127841 systemd-logind[1386]: New session 3 of user core. May 9 00:27:27.136970 systemd[1]: Started session-3.scope - Session 3 of User core. May 9 00:27:27.185630 sshd[1500]: pam_unix(sshd:session): session closed for user core May 9 00:27:27.195452 systemd[1]: sshd@2-10.0.0.48:22-10.0.0.1:59314.service: Deactivated successfully. May 9 00:27:27.196988 systemd[1]: session-3.scope: Deactivated successfully. May 9 00:27:27.198550 systemd-logind[1386]: Session 3 logged out. Waiting for processes to exit. May 9 00:27:27.199732 systemd[1]: Started sshd@3-10.0.0.48:22-10.0.0.1:59330.service - OpenSSH per-connection server daemon (10.0.0.1:59330). May 9 00:27:27.200694 systemd-logind[1386]: Removed session 3. May 9 00:27:27.230585 sshd[1507]: Accepted publickey for core from 10.0.0.1 port 59330 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.232101 sshd[1507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.235685 systemd-logind[1386]: New session 4 of user core. May 9 00:27:27.245108 systemd[1]: Started session-4.scope - Session 4 of User core. May 9 00:27:27.300196 sshd[1507]: pam_unix(sshd:session): session closed for user core May 9 00:27:27.315791 systemd[1]: sshd@3-10.0.0.48:22-10.0.0.1:59330.service: Deactivated successfully. May 9 00:27:27.317660 systemd[1]: session-4.scope: Deactivated successfully. May 9 00:27:27.319829 systemd-logind[1386]: Session 4 logged out. Waiting for processes to exit. May 9 00:27:27.331222 systemd[1]: Started sshd@4-10.0.0.48:22-10.0.0.1:59332.service - OpenSSH per-connection server daemon (10.0.0.1:59332). May 9 00:27:27.332152 systemd-logind[1386]: Removed session 4. May 9 00:27:27.358001 sshd[1514]: Accepted publickey for core from 10.0.0.1 port 59332 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.359729 sshd[1514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.363857 systemd-logind[1386]: New session 5 of user core. May 9 00:27:27.376097 systemd[1]: Started session-5.scope - Session 5 of User core. May 9 00:27:27.434411 sudo[1517]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 9 00:27:27.434741 sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:27:27.450287 sudo[1517]: pam_unix(sudo:session): session closed for user root May 9 00:27:27.452427 sshd[1514]: pam_unix(sshd:session): session closed for user core May 9 00:27:27.465169 systemd[1]: sshd@4-10.0.0.48:22-10.0.0.1:59332.service: Deactivated successfully. May 9 00:27:27.467022 systemd[1]: session-5.scope: Deactivated successfully. May 9 00:27:27.468448 systemd-logind[1386]: Session 5 logged out. Waiting for processes to exit. May 9 00:27:27.470008 systemd[1]: Started sshd@5-10.0.0.48:22-10.0.0.1:59336.service - OpenSSH per-connection server daemon (10.0.0.1:59336). May 9 00:27:27.470747 systemd-logind[1386]: Removed session 5. May 9 00:27:27.501430 sshd[1522]: Accepted publickey for core from 10.0.0.1 port 59336 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.503009 sshd[1522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.506811 systemd-logind[1386]: New session 6 of user core. May 9 00:27:27.516019 systemd[1]: Started session-6.scope - Session 6 of User core. May 9 00:27:27.569653 sudo[1526]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 9 00:27:27.570056 sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:27:27.573568 sudo[1526]: pam_unix(sudo:session): session closed for user root May 9 00:27:27.579614 sudo[1525]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules May 9 00:27:27.580063 sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:27:27.597128 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... May 9 00:27:27.599006 auditctl[1529]: No rules May 9 00:27:27.600175 systemd[1]: audit-rules.service: Deactivated successfully. May 9 00:27:27.600413 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. May 9 00:27:27.602298 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 9 00:27:27.633036 augenrules[1547]: No rules May 9 00:27:27.634902 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 9 00:27:27.636099 sudo[1525]: pam_unix(sudo:session): session closed for user root May 9 00:27:27.637802 sshd[1522]: pam_unix(sshd:session): session closed for user core May 9 00:27:27.651790 systemd[1]: sshd@5-10.0.0.48:22-10.0.0.1:59336.service: Deactivated successfully. May 9 00:27:27.653352 systemd[1]: session-6.scope: Deactivated successfully. May 9 00:27:27.654533 systemd-logind[1386]: Session 6 logged out. Waiting for processes to exit. May 9 00:27:27.660264 systemd[1]: Started sshd@6-10.0.0.48:22-10.0.0.1:59346.service - OpenSSH per-connection server daemon (10.0.0.1:59346). May 9 00:27:27.661158 systemd-logind[1386]: Removed session 6. May 9 00:27:27.686674 sshd[1555]: Accepted publickey for core from 10.0.0.1 port 59346 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:27.688249 sshd[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:27.692222 systemd-logind[1386]: New session 7 of user core. May 9 00:27:27.702091 systemd[1]: Started session-7.scope - Session 7 of User core. May 9 00:27:27.764141 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 00:27:27.764308 (dockerd)[1559]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 00:27:28.031281 dockerd[1559]: time="2025-05-09T00:27:28.031133808Z" level=info msg="Starting up" May 9 00:27:28.138532 dockerd[1559]: time="2025-05-09T00:27:28.138474979Z" level=info msg="Loading containers: start." May 9 00:27:28.250902 kernel: Initializing XFRM netlink socket May 9 00:27:28.335132 systemd-networkd[1336]: docker0: Link UP May 9 00:27:28.355917 dockerd[1559]: time="2025-05-09T00:27:28.355855222Z" level=info msg="Loading containers: done." May 9 00:27:28.371213 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3175339350-merged.mount: Deactivated successfully. May 9 00:27:28.373408 dockerd[1559]: time="2025-05-09T00:27:28.373346771Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 00:27:28.373497 dockerd[1559]: time="2025-05-09T00:27:28.373478689Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 May 9 00:27:28.373660 dockerd[1559]: time="2025-05-09T00:27:28.373628119Z" level=info msg="Daemon has completed initialization" May 9 00:27:28.417890 dockerd[1559]: time="2025-05-09T00:27:28.417773684Z" level=info msg="API listen on /run/docker.sock" May 9 00:27:28.418063 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 00:27:28.427472 sshd[1555]: pam_unix(sshd:session): session closed for user core May 9 00:27:28.440144 systemd[1]: sshd@6-10.0.0.48:22-10.0.0.1:59346.service: Deactivated successfully. May 9 00:27:28.441650 systemd[1]: session-7.scope: Deactivated successfully. May 9 00:27:28.443312 systemd-logind[1386]: Session 7 logged out. Waiting for processes to exit. May 9 00:27:28.458347 systemd[1]: Started sshd@7-10.0.0.48:22-10.0.0.1:59350.service - OpenSSH per-connection server daemon (10.0.0.1:59350). May 9 00:27:28.459445 systemd-logind[1386]: Removed session 7. May 9 00:27:28.489652 sshd[1701]: Accepted publickey for core from 10.0.0.1 port 59350 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:28.491515 sshd[1701]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:28.495925 systemd-logind[1386]: New session 8 of user core. May 9 00:27:28.510189 systemd[1]: Started session-8.scope - Session 8 of User core. May 9 00:27:30.330594 systemd[1]: var-lib-docker-overlay2-40e9b75664b2047af86e4b3610b7c5578522120756b19fcc6627bc4a2ed81910\x2dinit-merged.mount: Deactivated successfully. May 9 00:27:30.371416 kernel: docker0: port 1(vetha8e6b85) entered blocking state May 9 00:27:30.371545 kernel: docker0: port 1(vetha8e6b85) entered disabled state May 9 00:27:30.371567 kernel: vetha8e6b85: entered allmulticast mode May 9 00:27:30.371585 kernel: vetha8e6b85: entered promiscuous mode May 9 00:27:30.372371 systemd-networkd[1336]: vetha8e6b85: Link UP May 9 00:27:30.471323 containerd[1403]: time="2025-05-09T00:27:30.471212237Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:27:30.471323 containerd[1403]: time="2025-05-09T00:27:30.471289152Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:27:30.471323 containerd[1403]: time="2025-05-09T00:27:30.471304430Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:27:30.471906 containerd[1403]: time="2025-05-09T00:27:30.471409818Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:27:30.494215 systemd[1]: Started docker-fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52.scope - libcontainer container fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52. May 9 00:27:30.561898 kernel: eth0: renamed from veth9fdcb09 May 9 00:27:30.569987 kernel: docker0: port 1(vetha8e6b85) entered blocking state May 9 00:27:30.570083 kernel: docker0: port 1(vetha8e6b85) entered forwarding state May 9 00:27:30.570071 systemd-networkd[1336]: vetha8e6b85: Gained carrier May 9 00:27:30.570389 systemd-networkd[1336]: docker0: Gained carrier May 9 00:27:30.575066 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:27:30.609266 sshd[1701]: pam_unix(sshd:session): session closed for user core May 9 00:27:30.620986 systemd[1]: sshd@7-10.0.0.48:22-10.0.0.1:59350.service: Deactivated successfully. May 9 00:27:30.622694 systemd[1]: session-8.scope: Deactivated successfully. May 9 00:27:30.623439 systemd-logind[1386]: Session 8 logged out. Waiting for processes to exit. May 9 00:27:30.632142 systemd[1]: Started sshd@8-10.0.0.48:22-10.0.0.1:59356.service - OpenSSH per-connection server daemon (10.0.0.1:59356). May 9 00:27:30.633077 systemd-logind[1386]: Removed session 8. May 9 00:27:30.663095 sshd[1782]: Accepted publickey for core from 10.0.0.1 port 59356 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:30.664804 sshd[1782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:30.669401 systemd-logind[1386]: New session 9 of user core. May 9 00:27:30.685486 systemd[1]: Started session-9.scope - Session 9 of User core. May 9 00:27:30.750169 sshd[1782]: pam_unix(sshd:session): session closed for user core May 9 00:27:30.763078 systemd[1]: sshd@8-10.0.0.48:22-10.0.0.1:59356.service: Deactivated successfully. May 9 00:27:30.765025 systemd[1]: session-9.scope: Deactivated successfully. May 9 00:27:30.765668 systemd-logind[1386]: Session 9 logged out. Waiting for processes to exit. May 9 00:27:30.767719 systemd[1]: Started sshd@9-10.0.0.48:22-10.0.0.1:59372.service - OpenSSH per-connection server daemon (10.0.0.1:59372). May 9 00:27:30.768618 systemd-logind[1386]: Removed session 9. May 9 00:27:30.805368 sshd[1789]: Accepted publickey for core from 10.0.0.1 port 59372 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:30.807157 sshd[1789]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:30.811140 systemd-logind[1386]: New session 10 of user core. May 9 00:27:30.823017 systemd[1]: Started session-10.scope - Session 10 of User core. May 9 00:27:30.876198 sudo[1792]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/kill -SIGTERM 1403 May 9 00:27:30.876529 sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:27:30.880374 sudo[1792]: pam_unix(sudo:session): session closed for user root May 9 00:27:30.880516 containerd[1403]: time="2025-05-09T00:27:30.880395088Z" level=info msg="Stop CRI service" May 9 00:27:30.880839 dockerd[1559]: time="2025-05-09T00:27:30.880545490Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 9 00:27:30.880839 dockerd[1559]: time="2025-05-09T00:27:30.880594732Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 9 00:27:30.880839 dockerd[1559]: time="2025-05-09T00:27:30.880646579Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 9 00:27:30.880839 dockerd[1559]: time="2025-05-09T00:27:30.880669863Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 9 00:27:30.881324 containerd[1403]: time="2025-05-09T00:27:30.881049515Z" level=info msg="Stop CRI service" May 9 00:27:30.881324 containerd[1403]: time="2025-05-09T00:27:30.881113725Z" level=info msg="Event monitor stopped" May 9 00:27:30.881324 containerd[1403]: time="2025-05-09T00:27:30.881127792Z" level=info msg="Stream server stopped" May 9 00:27:30.882279 sshd[1789]: pam_unix(sshd:session): session closed for user core May 9 00:27:30.895399 systemd[1]: containerd.service: Deactivated successfully. May 9 00:27:30.895490 systemd[1]: containerd.service: Unit process 1736 (containerd-shim) remains running after unit stopped. May 9 00:27:30.896207 systemd[1]: sshd@9-10.0.0.48:22-10.0.0.1:59372.service: Deactivated successfully. May 9 00:27:30.897825 systemd[1]: session-10.scope: Deactivated successfully. May 9 00:27:30.899127 systemd-logind[1386]: Session 10 logged out. Waiting for processes to exit. May 9 00:27:30.907346 systemd[1]: Started sshd@10-10.0.0.48:22-10.0.0.1:59374.service - OpenSSH per-connection server daemon (10.0.0.1:59374). May 9 00:27:30.908094 systemd-logind[1386]: Removed session 10. May 9 00:27:30.933418 sshd[1798]: Accepted publickey for core from 10.0.0.1 port 59374 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:30.934914 sshd[1798]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:30.938771 systemd-logind[1386]: New session 11 of user core. May 9 00:27:30.948017 systemd[1]: Started session-11.scope - Session 11 of User core. May 9 00:27:31.005542 sshd[1798]: pam_unix(sshd:session): session closed for user core May 9 00:27:31.009159 systemd[1]: sshd@10-10.0.0.48:22-10.0.0.1:59374.service: Deactivated successfully. May 9 00:27:31.010786 systemd[1]: session-11.scope: Deactivated successfully. May 9 00:27:31.011345 systemd-logind[1386]: Session 11 logged out. Waiting for processes to exit. May 9 00:27:31.012350 systemd-logind[1386]: Removed session 11. May 9 00:27:31.866120 systemd-networkd[1336]: docker0: Gained IPv6LL May 9 00:27:32.186079 systemd-networkd[1336]: vetha8e6b85: Gained IPv6LL May 9 00:27:36.147977 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. May 9 00:27:36.148592 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 9 00:27:36.148682 dockerd[1559]: time="2025-05-09T00:27:36.148618248Z" level=info msg="Processing signal 'terminated'" May 9 00:27:37.031881 systemd[1]: Started sshd@11-10.0.0.48:22-10.0.0.1:47784.service - OpenSSH per-connection server daemon (10.0.0.1:47784). May 9 00:27:37.108903 sshd[1806]: Accepted publickey for core from 10.0.0.1 port 47784 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:37.109619 sshd[1806]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:37.147945 systemd-logind[1386]: New session 12 of user core. May 9 00:27:37.152071 systemd[1]: Started session-12.scope - Session 12 of User core. May 9 00:27:37.221033 sshd[1806]: pam_unix(sshd:session): session closed for user core May 9 00:27:37.231685 systemd[1]: sshd@11-10.0.0.48:22-10.0.0.1:47784.service: Deactivated successfully. May 9 00:27:37.233909 systemd[1]: session-12.scope: Deactivated successfully. May 9 00:27:37.237513 systemd-logind[1386]: Session 12 logged out. Waiting for processes to exit. May 9 00:27:37.240282 systemd-logind[1386]: Removed session 12. May 9 00:27:38.153283 dockerd[1559]: time="2025-05-09T00:27:38.153188549Z" level=error msg="Error sending stop (signal 15) to container" container=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 error="Cannot kill container fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52: connection error: desc = \"transport: Error while dialing: dial unix:///var/run/docker/libcontainerd/docker-containerd.sock: timeout\": unavailable" May 9 00:27:38.153283 dockerd[1559]: time="2025-05-09T00:27:38.153252078Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 May 9 00:27:43.233858 systemd[1]: Started sshd@12-10.0.0.48:22-10.0.0.1:47786.service - OpenSSH per-connection server daemon (10.0.0.1:47786). May 9 00:27:43.274330 sshd[1813]: Accepted publickey for core from 10.0.0.1 port 47786 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:43.276684 sshd[1813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:43.281342 systemd-logind[1386]: New session 13 of user core. May 9 00:27:43.292096 systemd[1]: Started session-13.scope - Session 13 of User core. May 9 00:27:43.348976 sshd[1813]: pam_unix(sshd:session): session closed for user core May 9 00:27:43.353483 systemd[1]: sshd@12-10.0.0.48:22-10.0.0.1:47786.service: Deactivated successfully. May 9 00:27:43.355987 systemd[1]: session-13.scope: Deactivated successfully. May 9 00:27:43.356688 systemd-logind[1386]: Session 13 logged out. Waiting for processes to exit. May 9 00:27:43.357608 systemd-logind[1386]: Removed session 13. May 9 00:27:48.154429 dockerd[1559]: time="2025-05-09T00:27:48.154350881Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 error="context deadline exceeded" May 9 00:27:48.155057 systemd[1]: docker-fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52.scope: Deactivated successfully. May 9 00:27:49.360556 systemd[1]: Started sshd@13-10.0.0.48:22-10.0.0.1:52670.service - OpenSSH per-connection server daemon (10.0.0.1:52670). May 9 00:27:49.391160 sshd[1820]: Accepted publickey for core from 10.0.0.1 port 52670 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:49.392637 sshd[1820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:49.396381 systemd-logind[1386]: New session 14 of user core. May 9 00:27:49.407993 systemd[1]: Started session-14.scope - Session 14 of User core. May 9 00:27:49.463095 sshd[1820]: pam_unix(sshd:session): session closed for user core May 9 00:27:49.467032 systemd[1]: sshd@13-10.0.0.48:22-10.0.0.1:52670.service: Deactivated successfully. May 9 00:27:49.468780 systemd[1]: session-14.scope: Deactivated successfully. May 9 00:27:49.469466 systemd-logind[1386]: Session 14 logged out. Waiting for processes to exit. May 9 00:27:49.470412 systemd-logind[1386]: Removed session 14. May 9 00:27:51.149231 dockerd[1559]: time="2025-05-09T00:27:51.149136934Z" level=error msg="Force shutdown daemon" May 9 00:27:51.149755 dockerd[1559]: time="2025-05-09T00:27:51.149390429Z" level=info msg="Daemon shutdown complete" May 9 00:27:51.159125 systemd[1]: docker.service: Deactivated successfully. May 9 00:27:51.159491 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 9 00:27:51.159563 systemd[1]: docker.service: Consumed 1.161s CPU time. May 9 00:27:51.160083 systemd[1]: docker.socket: Deactivated successfully. May 9 00:27:51.160370 systemd[1]: Closed docker.socket - Docker Socket for the API. May 9 00:27:51.160405 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 9 00:27:51.162299 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 00:27:51.162496 systemd[1]: containerd.service: Found left-over process 1736 (containerd-shim) in control group while starting unit. Ignoring. May 9 00:27:51.162505 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 00:27:51.171102 systemd[1]: Starting containerd.service - containerd container runtime... May 9 00:27:51.172754 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 00:27:51.181577 systemd[1]: containerd.service: Found left-over process 1736 (containerd-shim) in control group while starting unit. Ignoring. May 9 00:27:51.181590 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 00:27:51.186222 (ntainerd)[1832]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 00:27:51.204497 containerd[1832]: time="2025-05-09T00:27:51.204387057Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 9 00:27:51.231518 containerd[1832]: time="2025-05-09T00:27:51.231460399Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.233676 containerd[1832]: time="2025-05-09T00:27:51.233612305Z" 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.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:51.233676 containerd[1832]: time="2025-05-09T00:27:51.233665165Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 9 00:27:51.233785 containerd[1832]: time="2025-05-09T00:27:51.233687987Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 9 00:27:51.233785 containerd[1832]: time="2025-05-09T00:27:51.233726189Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 9 00:27:51.233785 containerd[1832]: time="2025-05-09T00:27:51.233743451Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.233785 containerd[1832]: time="2025-05-09T00:27:51.233773528Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:51.233938 containerd[1832]: time="2025-05-09T00:27:51.233787905Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.234102 containerd[1832]: time="2025-05-09T00:27:51.234062460Z" 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 May 9 00:27:51.234102 containerd[1832]: time="2025-05-09T00:27:51.234089040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.234169 containerd[1832]: time="2025-05-09T00:27:51.234107634Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:27:51.234169 containerd[1832]: time="2025-05-09T00:27:51.234122382Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.234169 containerd[1832]: time="2025-05-09T00:27:51.234152859Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.234342 containerd[1832]: time="2025-05-09T00:27:51.234306467Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 9 00:27:51.234539 containerd[1832]: time="2025-05-09T00:27:51.234501453Z" 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 May 9 00:27:51.234539 containerd[1832]: time="2025-05-09T00:27:51.234526340Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 9 00:27:51.234612 containerd[1832]: time="2025-05-09T00:27:51.234559382Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 9 00:27:51.234612 containerd[1832]: time="2025-05-09T00:27:51.234582926Z" level=info msg="metadata content store policy set" policy=shared May 9 00:27:51.234798 containerd[1832]: time="2025-05-09T00:27:51.234773183Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 9 00:27:51.234836 containerd[1832]: time="2025-05-09T00:27:51.234819249Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 9 00:27:51.234886 containerd[1832]: time="2025-05-09T00:27:51.234847282Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 9 00:27:51.234926 containerd[1832]: time="2025-05-09T00:27:51.234885654Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 9 00:27:51.234926 containerd[1832]: time="2025-05-09T00:27:51.234907886Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 9 00:27:51.235003 containerd[1832]: time="2025-05-09T00:27:51.234978869Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 9 00:27:51.235279 containerd[1832]: time="2025-05-09T00:27:51.235250859Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 9 00:27:51.245467 containerd[1832]: time="2025-05-09T00:27:51.245437706Z" level=info msg="cleaning leaked shim process" id=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 namespace=moby May 9 00:27:51.256846 containerd[1832]: time="2025-05-09T00:27:51.256629329Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 9 00:27:51.257087 containerd[1832]: time="2025-05-09T00:27:51.256976059Z" level=info msg="shim disconnected" id=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 namespace=moby May 9 00:27:51.257087 containerd[1832]: time="2025-05-09T00:27:51.257071869Z" level=warning msg="cleaning up after shim disconnected" id=fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52 namespace=moby May 9 00:27:51.257087 containerd[1832]: time="2025-05-09T00:27:51.257086216Z" level=info msg="cleaning up dead shim" namespace=moby May 9 00:27:51.270858 containerd[1832]: time="2025-05-09T00:27:51.270797384Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 9 00:27:51.270858 containerd[1832]: time="2025-05-09T00:27:51.270850864Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.270896259Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.270920254Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.270940623Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.270959799Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.270980608Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.271000906Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.271019440Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.271042404Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.271061229Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 9 00:27:51.271103 containerd[1832]: time="2025-05-09T00:27:51.271089281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271111173Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271131250Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271152831Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271170604Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271189419Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271213264Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271234273Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271253850Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271275791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271292222Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271308 containerd[1832]: time="2025-05-09T00:27:51.271308733Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271327819Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271349299Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271379816Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271398922Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271415523Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271495143Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271521492Z" 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 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271537021Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271556127Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271570715Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271587847Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271603005Z" level=info msg="NRI interface is disabled by configuration." May 9 00:27:51.271621 containerd[1832]: time="2025-05-09T00:27:51.271618274Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 9 00:27:51.272114 containerd[1832]: time="2025-05-09T00:27:51.272023554Z" 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}" May 9 00:27:51.272114 containerd[1832]: time="2025-05-09T00:27:51.272109736Z" level=info msg="Connect containerd service" May 9 00:27:51.272261 containerd[1832]: time="2025-05-09T00:27:51.272140093Z" level=info msg="using legacy CRI server" May 9 00:27:51.272261 containerd[1832]: time="2025-05-09T00:27:51.272150742Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 00:27:51.272325 containerd[1832]: time="2025-05-09T00:27:51.272292548Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 9 00:27:51.273006 containerd[1832]: time="2025-05-09T00:27:51.272962074Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 00:27:51.273154 containerd[1832]: time="2025-05-09T00:27:51.273120011Z" level=info msg="Start subscribing containerd event" May 9 00:27:51.273208 containerd[1832]: time="2025-05-09T00:27:51.273159825Z" level=info msg="Start recovering state" May 9 00:27:51.273208 containerd[1832]: time="2025-05-09T00:27:51.273206513Z" level=info msg="Start event monitor" May 9 00:27:51.273260 containerd[1832]: time="2025-05-09T00:27:51.273221351Z" level=info msg="Start snapshots syncer" May 9 00:27:51.273260 containerd[1832]: time="2025-05-09T00:27:51.273230247Z" level=info msg="Start cni network conf syncer for default" May 9 00:27:51.273260 containerd[1832]: time="2025-05-09T00:27:51.273239234Z" level=info msg="Start streaming server" May 9 00:27:51.273352 containerd[1832]: time="2025-05-09T00:27:51.273293556Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 00:27:51.273381 containerd[1832]: time="2025-05-09T00:27:51.273368306Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 00:27:51.273490 containerd[1832]: time="2025-05-09T00:27:51.273466510Z" level=info msg="containerd successfully booted in 0.069673s" May 9 00:27:51.273587 systemd[1]: Started containerd.service - containerd container runtime. May 9 00:27:51.283251 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 00:27:51.283412 (dockerd)[1866]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 00:27:51.331779 dockerd[1866]: time="2025-05-09T00:27:51.331710520Z" level=info msg="Starting up" May 9 00:27:51.368173 dockerd[1866]: time="2025-05-09T00:27:51.368123520Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 9 00:27:51.395236 dockerd[1866]: time="2025-05-09T00:27:51.395177245Z" level=info msg="Loading containers: start." May 9 00:27:51.412107 systemd[1]: var-lib-docker-overlay2-40e9b75664b2047af86e4b3610b7c5578522120756b19fcc6627bc4a2ed81910-merged.mount: Deactivated successfully. May 9 00:27:51.620022 dockerd[1866]: time="2025-05-09T00:27:51.619975512Z" level=info msg="Removing stale sandbox ae5e2dd3854325f6b7135a4ce89bec5bc22a2de3615cd114ea93f1c280c64434 (fb7afb4eca554f976976c54a3ee32b5f5a29d1ec517b184b3ebee0d22b773e52)" May 9 00:27:51.623479 systemd-networkd[1336]: vetha8e6b85: Link DOWN May 9 00:27:51.623491 systemd-networkd[1336]: vetha8e6b85: Lost carrier May 9 00:27:51.625988 dockerd[1866]: time="2025-05-09T00:27:51.625938858Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint bebd9b0e406df2660eb952eeed8287894dda9f9ad3b30691cd60506fcc59dd48 6965ba93357b3dd2cb2bb3bbdb17a1750aa75a85b8e46e78758756034b2cbbd7], retrying...." May 9 00:27:51.626445 kernel: docker0: port 1(vetha8e6b85) entered disabled state May 9 00:27:51.626505 kernel: vetha8e6b85 (unregistering): left allmulticast mode May 9 00:27:51.626544 kernel: vetha8e6b85 (unregistering): left promiscuous mode May 9 00:27:51.627321 kernel: docker0: port 1(vetha8e6b85) entered disabled state May 9 00:27:51.640607 systemd-networkd[1336]: docker0: Lost carrier May 9 00:27:51.757777 dockerd[1866]: time="2025-05-09T00:27:51.757614814Z" 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" May 9 00:27:51.825165 dockerd[1866]: time="2025-05-09T00:27:51.825115436Z" level=info msg="Loading containers: done." May 9 00:27:51.839424 dockerd[1866]: time="2025-05-09T00:27:51.839337082Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 00:27:51.839647 dockerd[1866]: time="2025-05-09T00:27:51.839473348Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 May 9 00:27:51.839647 dockerd[1866]: time="2025-05-09T00:27:51.839520025Z" level=info msg="Daemon has completed initialization" May 9 00:27:51.863163 dockerd[1866]: time="2025-05-09T00:27:51.863069798Z" level=info msg="API listen on /run/docker.sock" May 9 00:27:51.863295 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 00:27:52.356338 systemd[1]: run-docker-netns-ae5e2dd38543.mount: Deactivated successfully. May 9 00:27:55.478099 systemd[1]: Started sshd@14-10.0.0.48:22-10.0.0.1:45134.service - OpenSSH per-connection server daemon (10.0.0.1:45134). May 9 00:27:55.514347 sshd[2021]: Accepted publickey for core from 10.0.0.1 port 45134 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:55.516099 sshd[2021]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:55.521183 systemd-logind[1386]: New session 15 of user core. May 9 00:27:55.531176 systemd[1]: Started session-15.scope - Session 15 of User core. May 9 00:27:55.590068 sshd[2021]: pam_unix(sshd:session): session closed for user core May 9 00:27:55.610425 systemd[1]: sshd@14-10.0.0.48:22-10.0.0.1:45134.service: Deactivated successfully. May 9 00:27:55.612819 systemd[1]: session-15.scope: Deactivated successfully. May 9 00:27:55.615051 systemd-logind[1386]: Session 15 logged out. Waiting for processes to exit. May 9 00:27:55.638374 systemd[1]: Started sshd@15-10.0.0.48:22-10.0.0.1:45140.service - OpenSSH per-connection server daemon (10.0.0.1:45140). May 9 00:27:55.639601 systemd-logind[1386]: Removed session 15. May 9 00:27:55.668151 sshd[2028]: Accepted publickey for core from 10.0.0.1 port 45140 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:55.670744 sshd[2028]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:55.675030 systemd-logind[1386]: New session 16 of user core. May 9 00:27:55.685999 systemd[1]: Started session-16.scope - Session 16 of User core. May 9 00:27:55.742837 sshd[2028]: pam_unix(sshd:session): session closed for user core May 9 00:27:55.753515 systemd[1]: sshd@15-10.0.0.48:22-10.0.0.1:45140.service: Deactivated successfully. May 9 00:27:55.755240 systemd[1]: session-16.scope: Deactivated successfully. May 9 00:27:55.756911 systemd-logind[1386]: Session 16 logged out. Waiting for processes to exit. May 9 00:27:55.758193 systemd[1]: Started sshd@16-10.0.0.48:22-10.0.0.1:45156.service - OpenSSH per-connection server daemon (10.0.0.1:45156). May 9 00:27:55.758990 systemd-logind[1386]: Removed session 16. May 9 00:27:55.793325 sshd[2035]: Accepted publickey for core from 10.0.0.1 port 45156 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:55.795153 sshd[2035]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:55.799126 systemd-logind[1386]: New session 17 of user core. May 9 00:27:55.809004 systemd[1]: Started session-17.scope - Session 17 of User core. May 9 00:27:55.871640 sshd[2035]: pam_unix(sshd:session): session closed for user core May 9 00:27:55.880259 systemd[1]: sshd@16-10.0.0.48:22-10.0.0.1:45156.service: Deactivated successfully. May 9 00:27:55.882605 systemd[1]: session-17.scope: Deactivated successfully. May 9 00:27:55.884672 systemd-logind[1386]: Session 17 logged out. Waiting for processes to exit. May 9 00:27:55.896362 systemd[1]: Started sshd@17-10.0.0.48:22-10.0.0.1:45160.service - OpenSSH per-connection server daemon (10.0.0.1:45160). May 9 00:27:55.897749 systemd-logind[1386]: Removed session 17. May 9 00:27:55.925006 sshd[2049]: Accepted publickey for core from 10.0.0.1 port 45160 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:55.926503 sshd[2049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:55.930419 systemd-logind[1386]: New session 18 of user core. May 9 00:27:55.941003 systemd[1]: Started session-18.scope - Session 18 of User core. May 9 00:27:56.194120 systemd[1]: var-lib-docker-overlay2-a1478a061a3a96ec6296f31cf27251094c6a2010c64fe82ccef11e446f1d696f\x2dinit-merged.mount: Deactivated successfully. May 9 00:27:56.480435 kernel: docker0: port 1(veth048856b) entered blocking state May 9 00:27:56.480575 kernel: docker0: port 1(veth048856b) entered disabled state May 9 00:27:56.480595 kernel: veth048856b: entered allmulticast mode May 9 00:27:56.481939 kernel: veth048856b: entered promiscuous mode May 9 00:27:56.482199 systemd-networkd[1336]: veth048856b: Link UP May 9 00:27:56.523783 containerd[1832]: time="2025-05-09T00:27:56.523676705Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:27:56.524306 containerd[1832]: time="2025-05-09T00:27:56.523773120Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:27:56.524306 containerd[1832]: time="2025-05-09T00:27:56.523790073Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:27:56.524306 containerd[1832]: time="2025-05-09T00:27:56.523930692Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:27:56.543043 systemd[1]: Started docker-5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb.scope - libcontainer container 5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb. May 9 00:27:56.609904 kernel: eth0: renamed from veth0a64c99 May 9 00:27:56.626076 kernel: docker0: port 1(veth048856b) entered blocking state May 9 00:27:56.626137 kernel: docker0: port 1(veth048856b) entered forwarding state May 9 00:27:56.626152 systemd-networkd[1336]: veth048856b: Gained carrier May 9 00:27:56.626449 systemd-networkd[1336]: docker0: Gained carrier May 9 00:27:56.632101 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:27:56.669484 sshd[2049]: pam_unix(sshd:session): session closed for user core May 9 00:27:56.685464 systemd[1]: sshd@17-10.0.0.48:22-10.0.0.1:45160.service: Deactivated successfully. May 9 00:27:56.686918 systemd[1]: session-18.scope: Deactivated successfully. May 9 00:27:56.687602 systemd-logind[1386]: Session 18 logged out. Waiting for processes to exit. May 9 00:27:56.689452 systemd[1]: Started sshd@18-10.0.0.48:22-10.0.0.1:45166.service - OpenSSH per-connection server daemon (10.0.0.1:45166). May 9 00:27:56.690243 systemd-logind[1386]: Removed session 18. May 9 00:27:56.725770 sshd[2125]: Accepted publickey for core from 10.0.0.1 port 45166 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:56.727683 sshd[2125]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:56.731551 systemd-logind[1386]: New session 19 of user core. May 9 00:27:56.742063 systemd[1]: Started session-19.scope - Session 19 of User core. May 9 00:27:56.799342 sshd[2125]: pam_unix(sshd:session): session closed for user core May 9 00:27:56.806674 systemd[1]: sshd@18-10.0.0.48:22-10.0.0.1:45166.service: Deactivated successfully. May 9 00:27:56.808858 systemd[1]: session-19.scope: Deactivated successfully. May 9 00:27:56.809643 systemd-logind[1386]: Session 19 logged out. Waiting for processes to exit. May 9 00:27:56.817181 systemd[1]: Started sshd@19-10.0.0.48:22-10.0.0.1:45182.service - OpenSSH per-connection server daemon (10.0.0.1:45182). May 9 00:27:56.817713 systemd-logind[1386]: Removed session 19. May 9 00:27:56.848109 sshd[2132]: Accepted publickey for core from 10.0.0.1 port 45182 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:56.849599 sshd[2132]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:56.853994 systemd-logind[1386]: New session 20 of user core. May 9 00:27:56.872056 systemd[1]: Started session-20.scope - Session 20 of User core. May 9 00:27:56.924696 sudo[2135]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/kill -SIGHUP 1832 May 9 00:27:56.925059 sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:27:56.928011 sudo[2135]: pam_unix(sudo:session): session closed for user root May 9 00:27:56.929381 sshd[2132]: pam_unix(sshd:session): session closed for user core May 9 00:27:56.932818 dockerd[1866]: time="2025-05-09T00:27:56.932749199Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby May 9 00:27:56.932818 dockerd[1866]: time="2025-05-09T00:27:56.932784187Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby May 9 00:27:56.940097 dockerd[1866]: time="2025-05-09T00:27:56.932818342Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby May 9 00:27:56.940097 dockerd[1866]: time="2025-05-09T00:27:56.932839563Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby May 9 00:27:56.942489 systemd[1]: containerd.service: Deactivated successfully. May 9 00:27:56.942576 systemd[1]: containerd.service: Unit process 2078 (containerd-shim) remains running after unit stopped. May 9 00:27:56.943348 systemd[1]: sshd@19-10.0.0.48:22-10.0.0.1:45182.service: Deactivated successfully. May 9 00:27:56.945076 systemd[1]: session-20.scope: Deactivated successfully. May 9 00:27:56.945728 systemd-logind[1386]: Session 20 logged out. Waiting for processes to exit. May 9 00:27:56.961111 systemd[1]: Started sshd@20-10.0.0.48:22-10.0.0.1:45194.service - OpenSSH per-connection server daemon (10.0.0.1:45194). May 9 00:27:56.961682 systemd-logind[1386]: Removed session 20. May 9 00:27:56.989903 sshd[2141]: Accepted publickey for core from 10.0.0.1 port 45194 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:27:56.991465 sshd[2141]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:27:56.995961 systemd-logind[1386]: New session 21 of user core. May 9 00:27:57.006044 systemd[1]: Started session-21.scope - Session 21 of User core. May 9 00:27:57.060884 sshd[2141]: pam_unix(sshd:session): session closed for user core May 9 00:27:57.064108 systemd[1]: sshd@20-10.0.0.48:22-10.0.0.1:45194.service: Deactivated successfully. May 9 00:27:57.065736 systemd[1]: session-21.scope: Deactivated successfully. May 9 00:27:57.066275 systemd-logind[1386]: Session 21 logged out. Waiting for processes to exit. May 9 00:27:57.067140 systemd-logind[1386]: Removed session 21. May 9 00:27:57.978130 systemd-networkd[1336]: veth048856b: Gained IPv6LL May 9 00:28:02.193121 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. May 9 00:28:02.193714 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 9 00:28:02.193957 dockerd[1866]: time="2025-05-09T00:28:02.193832874Z" level=info msg="Processing signal 'terminated'" May 9 00:28:03.071342 systemd[1]: Started sshd@21-10.0.0.48:22-10.0.0.1:45198.service - OpenSSH per-connection server daemon (10.0.0.1:45198). May 9 00:28:03.102715 sshd[2148]: Accepted publickey for core from 10.0.0.1 port 45198 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:03.104201 sshd[2148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:03.107975 systemd-logind[1386]: New session 22 of user core. May 9 00:28:03.117993 systemd[1]: Started session-22.scope - Session 22 of User core. May 9 00:28:03.174067 sshd[2148]: pam_unix(sshd:session): session closed for user core May 9 00:28:03.177354 systemd[1]: sshd@21-10.0.0.48:22-10.0.0.1:45198.service: Deactivated successfully. May 9 00:28:03.179011 systemd[1]: session-22.scope: Deactivated successfully. May 9 00:28:03.179512 systemd-logind[1386]: Session 22 logged out. Waiting for processes to exit. May 9 00:28:03.180360 systemd-logind[1386]: Removed session 22. May 9 00:28:04.195710 dockerd[1866]: time="2025-05-09T00:28:04.195615567Z" level=error msg="Error sending stop (signal 15) to container" container=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb error="Cannot kill container 5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\": unavailable" May 9 00:28:04.195710 dockerd[1866]: time="2025-05-09T00:28:04.195691913Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb May 9 00:28:04.867826 update_engine[1397]: I20250509 00:28:04.867709 1397 update_attempter.cc:509] Updating boot flags... May 9 00:28:04.901898 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2160) May 9 00:28:04.934925 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2164) May 9 00:28:04.992901 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2164) May 9 00:28:09.186581 systemd[1]: Started sshd@22-10.0.0.48:22-10.0.0.1:47416.service - OpenSSH per-connection server daemon (10.0.0.1:47416). May 9 00:28:09.221460 sshd[2170]: Accepted publickey for core from 10.0.0.1 port 47416 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:09.223125 sshd[2170]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:09.227073 systemd-logind[1386]: New session 23 of user core. May 9 00:28:09.236987 systemd[1]: Started session-23.scope - Session 23 of User core. May 9 00:28:09.294059 sshd[2170]: pam_unix(sshd:session): session closed for user core May 9 00:28:09.298966 systemd[1]: sshd@22-10.0.0.48:22-10.0.0.1:47416.service: Deactivated successfully. May 9 00:28:09.300826 systemd[1]: session-23.scope: Deactivated successfully. May 9 00:28:09.301477 systemd-logind[1386]: Session 23 logged out. Waiting for processes to exit. May 9 00:28:09.302676 systemd-logind[1386]: Removed session 23. May 9 00:28:14.196614 dockerd[1866]: time="2025-05-09T00:28:14.196535162Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb error="context deadline exceeded" May 9 00:28:14.197524 systemd[1]: docker-5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb.scope: Deactivated successfully. May 9 00:28:15.305784 systemd[1]: Started sshd@23-10.0.0.48:22-10.0.0.1:43332.service - OpenSSH per-connection server daemon (10.0.0.1:43332). May 9 00:28:15.337262 sshd[2177]: Accepted publickey for core from 10.0.0.1 port 43332 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:15.339197 sshd[2177]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:15.343185 systemd-logind[1386]: New session 24 of user core. May 9 00:28:15.352992 systemd[1]: Started session-24.scope - Session 24 of User core. May 9 00:28:15.408492 sshd[2177]: pam_unix(sshd:session): session closed for user core May 9 00:28:15.412691 systemd[1]: sshd@23-10.0.0.48:22-10.0.0.1:43332.service: Deactivated successfully. May 9 00:28:15.414656 systemd[1]: session-24.scope: Deactivated successfully. May 9 00:28:15.415294 systemd-logind[1386]: Session 24 logged out. Waiting for processes to exit. May 9 00:28:15.416053 systemd-logind[1386]: Removed session 24. May 9 00:28:17.195206 dockerd[1866]: time="2025-05-09T00:28:17.195118334Z" level=error msg="Force shutdown daemon" May 9 00:28:17.195656 dockerd[1866]: time="2025-05-09T00:28:17.195397440Z" level=info msg="Daemon shutdown complete" May 9 00:28:17.201511 systemd[1]: docker.service: Deactivated successfully. May 9 00:28:17.201820 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 9 00:28:17.202397 systemd[1]: docker.socket: Deactivated successfully. May 9 00:28:17.202730 systemd[1]: Closed docker.socket - Docker Socket for the API. May 9 00:28:17.202764 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 9 00:28:17.204461 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 00:28:17.204634 systemd[1]: containerd.service: Found left-over process 2078 (containerd-shim) in control group while starting unit. Ignoring. May 9 00:28:17.204644 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 00:28:17.209221 systemd[1]: Starting containerd.service - containerd container runtime... May 9 00:28:17.210175 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 00:28:17.219862 systemd[1]: containerd.service: Found left-over process 2078 (containerd-shim) in control group while starting unit. Ignoring. May 9 00:28:17.219892 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 9 00:28:17.224696 (ntainerd)[2189]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 00:28:17.245055 containerd[2189]: time="2025-05-09T00:28:17.244990774Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 9 00:28:17.266985 containerd[2189]: time="2025-05-09T00:28:17.266934136Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.268862 containerd[2189]: time="2025-05-09T00:28:17.268820174Z" 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.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 9 00:28:17.268911 containerd[2189]: time="2025-05-09T00:28:17.268876921Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 9 00:28:17.268911 containerd[2189]: time="2025-05-09T00:28:17.268897038Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 9 00:28:17.268962 containerd[2189]: time="2025-05-09T00:28:17.268931484Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 9 00:28:17.268962 containerd[2189]: time="2025-05-09T00:28:17.268951241Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269013 containerd[2189]: time="2025-05-09T00:28:17.268979684Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:28:17.269013 containerd[2189]: time="2025-05-09T00:28:17.268994983Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269267 containerd[2189]: time="2025-05-09T00:28:17.269230628Z" 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 May 9 00:28:17.269267 containerd[2189]: time="2025-05-09T00:28:17.269259302Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269307 containerd[2189]: time="2025-05-09T00:28:17.269273078Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:28:17.269307 containerd[2189]: time="2025-05-09T00:28:17.269283859Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269351 containerd[2189]: time="2025-05-09T00:28:17.269307022Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269444 containerd[2189]: time="2025-05-09T00:28:17.269419995Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 9 00:28:17.269618 containerd[2189]: time="2025-05-09T00:28:17.269589776Z" 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 May 9 00:28:17.269618 containerd[2189]: time="2025-05-09T00:28:17.269611516Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 9 00:28:17.269668 containerd[2189]: time="2025-05-09T00:28:17.269630642Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 9 00:28:17.269668 containerd[2189]: time="2025-05-09T00:28:17.269647354Z" level=info msg="metadata content store policy set" policy=shared May 9 00:28:17.269831 containerd[2189]: time="2025-05-09T00:28:17.269804791Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 9 00:28:17.269888 containerd[2189]: time="2025-05-09T00:28:17.269854295Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 9 00:28:17.269911 containerd[2189]: time="2025-05-09T00:28:17.269895662Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 9 00:28:17.269932 containerd[2189]: time="2025-05-09T00:28:17.269921831Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 9 00:28:17.269952 containerd[2189]: time="2025-05-09T00:28:17.269940658Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 9 00:28:17.270006 containerd[2189]: time="2025-05-09T00:28:17.269989319Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 9 00:28:17.270351 containerd[2189]: time="2025-05-09T00:28:17.270312939Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 9 00:28:17.279797 containerd[2189]: time="2025-05-09T00:28:17.279766422Z" level=info msg="cleaning leaked shim process" id=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb namespace=moby May 9 00:28:17.289403 containerd[2189]: time="2025-05-09T00:28:17.289320984Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" May 9 00:28:17.290315 containerd[2189]: time="2025-05-09T00:28:17.290243845Z" level=info msg="shim disconnected" id=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb namespace=moby May 9 00:28:17.290315 containerd[2189]: time="2025-05-09T00:28:17.290312896Z" level=warning msg="cleaning up after shim disconnected" id=5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb namespace=moby May 9 00:28:17.290385 containerd[2189]: time="2025-05-09T00:28:17.290321883Z" level=info msg="cleaning up dead shim" namespace=moby May 9 00:28:17.302984 containerd[2189]: time="2025-05-09T00:28:17.302941607Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 9 00:28:17.302984 containerd[2189]: time="2025-05-09T00:28:17.302979168Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 9 00:28:17.302984 containerd[2189]: time="2025-05-09T00:28:17.302992272Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303005517Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303018032Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303030465Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303044532Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303058377Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303070019Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303081811Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303094996Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 9 00:28:17.303122 containerd[2189]: time="2025-05-09T00:28:17.303116087Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303132958Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303144710Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303157785Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303169367Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303181870Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303194614Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303207349Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303220604Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303233939Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303251592Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303271629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303284303Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303303 containerd[2189]: time="2025-05-09T00:28:17.303298110Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303320592Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303331934Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303344076Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303403008Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303431341Z" 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 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303442903Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303454855Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303464633Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303480634Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303493929Z" level=info msg="NRI interface is disabled by configuration." May 9 00:28:17.303564 containerd[2189]: time="2025-05-09T00:28:17.303506022Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 9 00:28:17.303832 containerd[2189]: time="2025-05-09T00:28:17.303771413Z" 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}" May 9 00:28:17.303937 containerd[2189]: time="2025-05-09T00:28:17.303841524Z" level=info msg="Connect containerd service" May 9 00:28:17.303937 containerd[2189]: time="2025-05-09T00:28:17.303893864Z" level=info msg="using legacy CRI server" May 9 00:28:17.303937 containerd[2189]: time="2025-05-09T00:28:17.303902901Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 00:28:17.304039 containerd[2189]: time="2025-05-09T00:28:17.304021865Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 9 00:28:17.304493 containerd[2189]: time="2025-05-09T00:28:17.304465041Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 9 00:28:17.304654 containerd[2189]: time="2025-05-09T00:28:17.304603502Z" level=info msg="Start subscribing containerd event" May 9 00:28:17.304775 containerd[2189]: time="2025-05-09T00:28:17.304664497Z" level=info msg="Start recovering state" May 9 00:28:17.304775 containerd[2189]: time="2025-05-09T00:28:17.304749157Z" level=info msg="Start event monitor" May 9 00:28:17.304814 containerd[2189]: time="2025-05-09T00:28:17.304776779Z" level=info msg="Start snapshots syncer" May 9 00:28:17.304814 containerd[2189]: time="2025-05-09T00:28:17.304787198Z" level=info msg="Start cni network conf syncer for default" May 9 00:28:17.304814 containerd[2189]: time="2025-05-09T00:28:17.304797447Z" level=info msg="Start streaming server" May 9 00:28:17.304942 containerd[2189]: time="2025-05-09T00:28:17.304749818Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 00:28:17.304993 containerd[2189]: time="2025-05-09T00:28:17.304979321Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 00:28:17.305089 containerd[2189]: time="2025-05-09T00:28:17.305047530Z" level=info msg="containerd successfully booted in 0.061400s" May 9 00:28:17.305182 systemd[1]: Started containerd.service - containerd container runtime. May 9 00:28:17.314061 systemd[1]: Starting docker.service - Docker Application Container Engine... May 9 00:28:17.314215 (dockerd)[2223]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU May 9 00:28:17.356599 dockerd[2223]: time="2025-05-09T00:28:17.356531454Z" level=info msg="Starting up" May 9 00:28:17.521674 dockerd[2223]: time="2025-05-09T00:28:17.521585206Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 9 00:28:17.543559 dockerd[2223]: time="2025-05-09T00:28:17.543526975Z" level=info msg="Loading containers: start." May 9 00:28:17.699650 systemd[1]: var-lib-docker-overlay2-a1478a061a3a96ec6296f31cf27251094c6a2010c64fe82ccef11e446f1d696f-merged.mount: Deactivated successfully. May 9 00:28:17.934959 dockerd[2223]: time="2025-05-09T00:28:17.934909292Z" level=info msg="Removing stale sandbox e5fe0d7c0e4dc512d456ae6f9472d713de0e9ea2551a667502888c7d5b6fd297 (5b0035ee855a43a693d1ce035f241c3e9adcd5a7bcd154a8cde3fbe15b3a95bb)" May 9 00:28:17.939979 dockerd[2223]: time="2025-05-09T00:28:17.939946754Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint b2345e11d907553a534c1ecf7c38bdefc01ee477757449613f357d367f72ded2 6895cc3119c22e117ee3e93b466b5008e42e341856f0838bef5fd199983a680a], retrying...." May 9 00:28:17.944119 systemd-networkd[1336]: veth048856b: Link DOWN May 9 00:28:17.944129 systemd-networkd[1336]: veth048856b: Lost carrier May 9 00:28:17.946576 kernel: docker0: port 1(veth048856b) entered disabled state May 9 00:28:17.946624 kernel: veth048856b (unregistering): left allmulticast mode May 9 00:28:17.946643 kernel: veth048856b (unregistering): left promiscuous mode May 9 00:28:17.948171 kernel: docker0: port 1(veth048856b) entered disabled state May 9 00:28:17.957580 systemd-networkd[1336]: docker0: Lost carrier May 9 00:28:18.047859 dockerd[2223]: time="2025-05-09T00:28:18.047822249Z" 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" May 9 00:28:18.104482 dockerd[2223]: time="2025-05-09T00:28:18.104439949Z" level=info msg="Loading containers: done." May 9 00:28:18.118673 dockerd[2223]: time="2025-05-09T00:28:18.118614147Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 May 9 00:28:18.118888 dockerd[2223]: time="2025-05-09T00:28:18.118730707Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 May 9 00:28:18.118888 dockerd[2223]: time="2025-05-09T00:28:18.118774640Z" level=info msg="Daemon has completed initialization" May 9 00:28:18.140445 dockerd[2223]: time="2025-05-09T00:28:18.140347368Z" level=info msg="API listen on /run/docker.sock" May 9 00:28:18.140533 systemd[1]: Started docker.service - Docker Application Container Engine. May 9 00:28:18.419669 systemd[1]: run-docker-netns-e5fe0d7c0e4d.mount: Deactivated successfully. May 9 00:28:21.425765 systemd[1]: Started sshd@24-10.0.0.48:22-10.0.0.1:43334.service - OpenSSH per-connection server daemon (10.0.0.1:43334). May 9 00:28:21.460315 sshd[2377]: Accepted publickey for core from 10.0.0.1 port 43334 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:21.462014 sshd[2377]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:21.466117 systemd-logind[1386]: New session 25 of user core. May 9 00:28:21.476011 systemd[1]: Started session-25.scope - Session 25 of User core. May 9 00:28:21.532691 sshd[2377]: pam_unix(sshd:session): session closed for user core May 9 00:28:21.554527 systemd[1]: sshd@24-10.0.0.48:22-10.0.0.1:43334.service: Deactivated successfully. May 9 00:28:21.556112 systemd[1]: session-25.scope: Deactivated successfully. May 9 00:28:21.557379 systemd-logind[1386]: Session 25 logged out. Waiting for processes to exit. May 9 00:28:21.558686 systemd[1]: Started sshd@25-10.0.0.48:22-10.0.0.1:43344.service - OpenSSH per-connection server daemon (10.0.0.1:43344). May 9 00:28:21.559427 systemd-logind[1386]: Removed session 25. May 9 00:28:21.589515 sshd[2384]: Accepted publickey for core from 10.0.0.1 port 43344 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:21.590968 sshd[2384]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:21.594585 systemd-logind[1386]: New session 26 of user core. May 9 00:28:21.605012 systemd[1]: Started session-26.scope - Session 26 of User core. May 9 00:28:21.660655 sshd[2384]: pam_unix(sshd:session): session closed for user core May 9 00:28:21.671822 systemd[1]: sshd@25-10.0.0.48:22-10.0.0.1:43344.service: Deactivated successfully. May 9 00:28:21.673571 systemd[1]: session-26.scope: Deactivated successfully. May 9 00:28:21.675069 systemd-logind[1386]: Session 26 logged out. Waiting for processes to exit. May 9 00:28:21.676302 systemd[1]: Started sshd@26-10.0.0.48:22-10.0.0.1:43348.service - OpenSSH per-connection server daemon (10.0.0.1:43348). May 9 00:28:21.677239 systemd-logind[1386]: Removed session 26. May 9 00:28:21.708077 sshd[2391]: Accepted publickey for core from 10.0.0.1 port 43348 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:21.709525 sshd[2391]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:21.713274 systemd-logind[1386]: New session 27 of user core. May 9 00:28:21.726999 systemd[1]: Started session-27.scope - Session 27 of User core. May 9 00:28:21.787325 sshd[2391]: pam_unix(sshd:session): session closed for user core May 9 00:28:21.798613 systemd[1]: sshd@26-10.0.0.48:22-10.0.0.1:43348.service: Deactivated successfully. May 9 00:28:21.800253 systemd[1]: session-27.scope: Deactivated successfully. May 9 00:28:21.801791 systemd-logind[1386]: Session 27 logged out. Waiting for processes to exit. May 9 00:28:21.817456 systemd[1]: Started sshd@27-10.0.0.48:22-10.0.0.1:43362.service - OpenSSH per-connection server daemon (10.0.0.1:43362). May 9 00:28:21.818391 systemd-logind[1386]: Removed session 27. May 9 00:28:21.843512 sshd[2405]: Accepted publickey for core from 10.0.0.1 port 43362 ssh2: RSA SHA256:DDCZN0plE/BEMxYIQ2bJR31edNsyikwHOR0emkziD+w May 9 00:28:21.845301 sshd[2405]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:28:21.849461 systemd-logind[1386]: New session 28 of user core. May 9 00:28:21.866991 systemd[1]: Started session-28.scope - Session 28 of User core. May 9 00:28:21.943128 systemd[1]: var-lib-docker-overlay2-9636798da98e8e1e53c5cb4b1431d75220ba55523285dd164c532f4c945bf74d\x2dinit-merged.mount: Deactivated successfully. May 9 00:28:21.960692 systemd[1]: var-lib-docker-overlay2-9636798da98e8e1e53c5cb4b1431d75220ba55523285dd164c532f4c945bf74d-merged.mount: Deactivated successfully. May 9 00:28:21.975029 kernel: docker0: port 1(veth83c46ec) entered blocking state May 9 00:28:21.975114 kernel: docker0: port 1(veth83c46ec) entered disabled state May 9 00:28:21.975134 kernel: veth83c46ec: entered allmulticast mode May 9 00:28:21.976519 kernel: veth83c46ec: entered promiscuous mode May 9 00:28:21.976779 systemd-networkd[1336]: veth83c46ec: Link UP May 9 00:28:22.021338 containerd[2189]: time="2025-05-09T00:28:22.021160659Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:28:22.021338 containerd[2189]: time="2025-05-09T00:28:22.021243475Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:28:22.021338 containerd[2189]: time="2025-05-09T00:28:22.021258864Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:28:22.021849 containerd[2189]: time="2025-05-09T00:28:22.021405831Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:28:22.044085 systemd[1]: Started docker-392aa838fbce9d34a95b5e5872dd1b5ccc2addb8e037fe6e0d2f0055b5efe8b7.scope - libcontainer container 392aa838fbce9d34a95b5e5872dd1b5ccc2addb8e037fe6e0d2f0055b5efe8b7. May 9 00:28:22.117900 kernel: eth0: renamed from vethdca56ba May 9 00:28:22.127935 kernel: docker0: port 1(veth83c46ec) entered blocking state May 9 00:28:22.128004 kernel: docker0: port 1(veth83c46ec) entered forwarding state May 9 00:28:22.128036 systemd-networkd[1336]: veth83c46ec: Gained carrier May 9 00:28:22.128547 systemd-networkd[1336]: docker0: Gained carrier May 9 00:28:22.133354 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:28:22.166711 sshd[2405]: pam_unix(sshd:session): session closed for user core May 9 00:28:22.170502 systemd[1]: sshd@27-10.0.0.48:22-10.0.0.1:43362.service: Deactivated successfully. May 9 00:28:22.172434 systemd[1]: session-28.scope: Deactivated successfully. May 9 00:28:22.173136 systemd-logind[1386]: Session 28 logged out. Waiting for processes to exit. May 9 00:28:22.173991 systemd-logind[1386]: Removed session 28.